Logs
From CEDPS
Contents |
Non-Best Practices Logs
Below are some examples showing the type of data and format of existing logs.
Some lines have been broken into multiple lines for better readability. This is indicated with a backwards slash ('\').
globus-gatekeeper.log
TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- Notice: 6: Got connection 128.105.121.51 at Thu Nov 9 00:06:28 2006 TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- Notice: 5: Authenticated globus user: /DC=org/DC=doegrids/OU=People/CN=Somebody (Grid Exerciser) 771829 TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- PRIMA INFO Mapping service "https://cmssrv08.fnal.gov:8443/gums/services GUMSAuthorizationServicePort" returned local user "gridex" for globus user "/DC=org/DC=doegrids/OU=People CN=Somebody (Grid Exerciser) 771829" TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- Notice: 0: GRID_SECURITY_HTTP_BODY_FD=8 TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- Notice: 5: Requested service: jobmanager-condor TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- Notice: 5: Authorized as local user: gridex TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- Notice: 5: Authorized as local uid: 10657 TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- Notice: 5: and local gid: 10657 TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- Notice: 0: executing /storage/local/data1/osg-ce/globus/libexec/globus-job-manager TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- Notice: 0: GATEKEEPER_JM_ID 2006-11-09.00:06:28.0000006577.0000000000 for DC=org/DC=doegrids/OU=People/CN=Alan De Smet (Grid Exerciser) 771829 on 128.105.121.51 TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- Notice: 0: GRID_SECURITY_CONTEXT_FD=11 TIME: Thu Nov 9 00:06:28 2006 PID: 6577 -- Notice: 0: Child 6639 started
Proposed replacement logs: See "Best Practices" document.
Globus accounting.log
JMA 2006/11/09 00:07:30 GATEKEEPER_JM_ID 2006-11-09.00:07:24.0000008206.0000000000 for /DC=org/DC=doegrids/OU=People/CN=Alan De Smet (Grid Exerciser) 771 829 on 128.105.121.51 JMA 2006/11/09 00:07:30 GATEKEEPER_JM_ID 2006-11-09.00:07:24.0000008206.0000000000 mapped to gridex (10657, 10657) JMA 2006/11/09 00:07:30 GATEKEEPER_JM_ID 2006-11-09.00:07:24.0000008206.0000000000 has GRAM_SCRIPT_JOB_ID 29406 manager type condor JMA 2006/11/09 00:07:31 GATEKEEPER_JM_ID 2006-11-09.00:07:24.0000008206.0000000000 JM exiting
Proposed new Log format: None, as this info in in the gatekeeper logs.
globus-condor.log
<c>
<a n="MyType"><s>SubmitEvent</s></a>
<a n="EventTypeNumber"><i>0</i></a>
<a n="EventTime"><s>2006-10-09T09:34:14</s></a>
<a n="Cluster"><i>3</i></a>
<a n="Proc"><i>0</i></a>
<a n="Subproc"><i>0</i></a>
<a n="SubmitHost"><s><131.225.207.100:52299></s></a>
</c>
Proposed changes: coming soon
gridftp-auth.log
[13443] Thu Nov 9 01:00:24 2006 :: New connection from: somehost.gov:56010 [13443] Thu Nov 9 01:00:25 2006 :: DN /DC=org/DC=doegrids/OU=People/CN=Somebody 123456 successfully authorized. [13443] Thu Nov 9 01:00:25 2006 :: User uscmspool381 successfully authorized. [13443] Thu Nov 9 01:00:25 2006 :: Starting to transfer "/home/somefile/07e923d0a1.0.stdout". [13443] Thu Nov 9 01:00:25 2006 :: Finished transferring "/home/somefile/007e923d0a1.0.stdout". [13443] Thu Nov 9 01:00:25 2006 :: Starting to transfer "/home/somefile/07e923d0a1.0.stderr". [13443] Thu Nov 9 01:00:25 2006 :: Finished transferring "/home/somefile/007e923d0a1.0.stderr". [13443] Thu Nov 9 01:00:32 2006 :: Closed connection from somehost.gov:56010
gridftp.log
DATE=20061109062536.658714 HOST=somehost.gov PROG=globus-gridftp-server NL.EVNT=FTP_INFO
START=20061109062536.535314 USER=mis FILE=/tmp/gridcat-gsiftp-test.itbgridcat.16860.remote BUFFER=0
BLOCK=262144 NBYTES=28 VOLUME=/ STREAMS=1 STRIPES=1 DEST=[129.79.4.64] TYPE=STOR CODE=226
We propose to combine gridftp.log and gridftp-auth.log into a single file. See "Best Practices" Document
Condor StarterLog
11/9 09:35:58 ******************************************************
11/9 09:35:58 ** condor_starter (CONDOR_STARTER) STARTING UP
11/9 09:35:58 ** /storage/local/data1/osg-ce/condor/sbin/condor_starter
11/9 09:35:58 ** $CondorVersion: 6.8.1 Sep 17 2006 $
11/9 09:35:58 ** $CondorPlatform: I386-LINUX_RHEL3 $
11/9 09:35:58 ** PID = 1745
11/9 09:35:58 ** Log last touched 11/9 09:35:13
11/9 09:35:58 ******************************************************
11/9 09:35:58 Using config source: /storage/local/data1/osg-ce/condor/etc/condor_config
11/9 09:35:58 Using local config sources:
11/9 09:35:58 /storage/local/data1/osg-ce/condor/local.cmssrv09/condor_config.local
11/9 09:35:58 DaemonCore: Command Socket at <131.225.207.100:55137>
11/9 09:35:58 Starter running a job under a schedd listening at <131.225.207.100:53175>
11/9 09:35:58 Done setting resource limits
11/9 09:35:58 Starter running a local job with no shadow
11/9 09:35:58 Reading job ClassAd from "STDIN"
11/9 09:35:58 Found ClassAd data in "STDIN"
11/9 09:35:58 Starting a LOCAL universe job with ID: 29779.0
11/9 09:35:58 IWD: /home/mis
11/9 09:35:58 Output file: /home/mis/.globus/job/cmssrv09.fnal.gov/1561.1163086554/stdout
11/9 09:35:58 Error file: /home/mis/.globus/job/cmssrv09.fnal.gov/1561.1163086554/stderr
11/9 09:35:58 Asked to write event of number 1.
11/9 09:35:58 About to exec /bin/sh -c useddirs="' '/storage/local/data1/osg-ce/OSG.DIRS/app' '/storage/local/data1/osg-ce/OSG.DIRS/data' '/storage/local/data1/osg-ce/OSG.DIRS/data' '/storage/local/data1/osg-ce/OSG.DIRS/wn_tmp' '/storage/local/data1/osg-ce"' ';' 'echo' 'useddirs' ':' '$useddirs' ';' ':' ';' ':' 'Do' 'not' 'bother' 'unmountable' 'disks' ';' ':' 'echo' 'df' 'is:' ';' ':' 'df' '-k' ';' ':' ';' 'i=0' ';' 'for' 'dir' 'in' '$useddirs' ';' 'do' 'i=`expr' '$i' '+' '1`' ';' 'echo' '-n' '$i:DIR_listing_$dir;' 'thedirname=`df' '-k' '$dir' '|' 'grep' '-v' '-i' 'Filesystem`' ';' 'if' '[' '!' '-d' '$dir' ']' ';' 'then' 'rdir=$dir' ';' 'k=0' ';' 'while' ':' ';' 'do' '[' '-d' '$rdir' ']' '&&' 'break' ';' '[' '$rdir' '=' '"/"' '-o' '$rdir' '=' '"."' ']' '&&' '{' 'echo' '-n' 'Dot_or_slash' '$rdir' ';' 'break' ';' '}' ';' 'rdir=`dirname' '$rdir`' ';' 'k=`expr' '$k' '+' '1`' ';' '[' '$k' '-gt' '100' ']' '&&' '{' 'echo' '-n' 'Too_deep' ';' 'break' ';' '}' ';' 'done' ';' 'echo' '$rdir' '|' 'grep' '-q' '"^/"' ';' '[' '$?' '-eq' '0' ']' '||' '{' 'echo' '-n' 'dir_does_not_start_with_slash' ';' '}' ';' 'thedirname=`df' '-k' '$rdir' '|' 'grep' '-v' '-i' 'Filesystem`' ';' 'fi' ';' ':' 'if' 'it' 'is' 'automount,' 'give' 'up' 'and' 'use' '/' ';' 'echo' '$thedirname' '|' 'grep' '-q' 'automount' ';' '[' '$?' '-eq' '0' ']' '&&' 'thedirname=`df' '-k' '/' '|' 'grep' '-v' '-i' 'Filesystem`' ';' 'echo' '$thedirname' ';' 'done' ';' 'echo' 'df' 'listing' 'done:' ';
11/9 09:35:58 Create_Process succeeded, pid=1746
11/9 09:35:58 Process exited, pid=1746, status=0
11/9 09:35:58 Asked to write event of number 5.
11/9 09:35:58 All jobs have exited... starter exiting
11/9 09:35:58 **** condor_starter (condor_STARTER) EXITING WITH STATUS 100
Question: What tools (if any) use this log file?
Condor Log Example
000 (17661.000.000) 09/22 16:44:14 Job submitted from host: <129.74.20.97:9629>
001 (17661.000.000) 09/22 16:44:30 Job executing on host: <129.74.143.227:9614>
...
004 (17661.000.000) 09/22 16:45:13 Job was evicted.
(0) Job was not checkpointed.
Usr 0 00:00:09, Sys 0 00:00:04 - Run Remote Usage
Usr 0 00:00:00, Sys 0 00:00:00 - Run Local Usage
0 - Run Bytes Sent By Job
1556 - Run Bytes Received By Job
...
009 (17661.000.000) 09/22 16:45:13 Job was aborted by the user.
via condor_rm (by user tfaltemi)
...
000 (17662.000.000) 09/22 16:45:36 Job submitted from host: <129.74.20.97:9629>
...
001 (17662.000.000) 09/22 16:46:28 Job executing on host: <129.74.221.139:9417>
...
006 (17662.000.000) 09/22 16:46:36 Image size of job updated: 17580
...
005 (17662.000.000) 09/22 17:03:53 Job terminated.
(1) Normal termination (return value 0)
Usr 0 00:16:34, Sys 0 00:00:04 - Run Remote Usage
Usr 0 00:00:00, Sys 0 00:00:00 - Run Local Usage
Usr 0 00:16:34, Sys 0 00:00:04 - Total Remote Usage
Usr 0 00:00:00, Sys 0 00:00:00 - Total Local Usage
296898 - Run Bytes Sent By Job
1556 - Run Bytes Received By Job
296898 - Total Bytes Sent By Job
1556 - Total Bytes Received By Job
Best-Practices Logs
These logs conform (or at least attempt to conform) to the CEDPS LoggingBestPractices.
gridftp server
see: http://www-unix.mcs.anl.gov/~mlink/bugs/5225-gridftp.log
MDS4 (within a GT4 container) Log Example (Sept 12, 2007)
ts=2007-09-12T11:51:24.538924-06:00 event=org.globus.container.engine.start msg="Starting SOAP server" address=http://192.168.1.106:20202/wsrf/services/ ts=2007-09-12T11:51:24.541421-06:00 gid=65254045 event=org.globus.wsrf.getRP.start service=ContainerRegistryService ts=2007-09-12T11:51:24.541473-06:00 gid=65254045 event=org.globus.wsrf.getRP.end status=0 service=ContainerRegistryService ts=2007-09-12T11:51:24.588898-06:00 gid=1452120146 event=org.globus.mds.index.start ts=2007-09-12T11:51:24.588898-06:00 gid=1452120146 event=org.globus.mds.index.processConfigFile.start status=0 msg="Reading config from: /usr/local/gt-current/etc /globus_wsrf_mds_index/hierarchy.xml" ts=2007-09-12T11:51:24.588910-06:00 gid=1452120146 event=org.globus.mds.index.processConfigFile.end status=0 ts=2007-09-12T11:51:24.588911-06:00 gid=1452120146 event=org.globus.mds.index.performDefaultRegistrations.start ts=2007-09-12T11:51:24.591826-06:00 gid=1452120146 event=org.globus.wsrf.queryRP.start service=DefaultIndexService ts=2007-09-12T11:51:24.592044-06:00 gid=1452120146 event=org.globus.wsrf.RP.end status=0 service=DefaultIndexService ts=2007-09-12T11:51:24.668777-06:00 gid=1994106504 event=org.globus.mds.archiver.start service=ArchiveService ts=2007-09-12T11:51:24.670624-06:00 gid=1994106504 event=org.globus.mds.archiver.addDocuments.start service=ArchiveService ts=2007-09-12T11:51:24.670789-06:00 gid=1994106504 event=org.globus.mds.archiver.addDocuments.end status=0 service=ArchiveService ts=2007-09-12T11:51:24.729430-06:00 gid=-1935500443 event=org.globus.mds.archiver.getDocuments.start service=ArchiveService ts=2007-09-12T11:51:24.729443-06:00 gid=-1935500443 event=org.globus.mds.archiver.getDocuments.end status=0 service=ArchiveService ts=2007-09-12T11:51:24.782965-06:00 gid=-1374753579 event=org.globus.mds.archiver.queryArchive.start service=ArchiveService ts=2007-09-12T11:51:24.783064-06:00 gid=-1374753579 event=org.globus.mds.archiver.queryArchive.end status=0 service=ArchiveService ts=2007-09-12T11:51:24.807474-06:00 gid=544751542 event=org.globus.mds.archiver.queryArchive.start service=ArchiveService ts=2007-09-12T11:51:24.807491-06:00 gid=544751542 event=org.globus.mds.archiver.queryArchive.end status=0 service=ArchiveService ts=2007-09-12T11:51:24.818032-06:00 event=org.globus.container.end status=0 ts=2007-09-12T11:51:24.819646-06:00 event=org.globus.container.engine.end status=0 msg="Stopping server" address=http://192.168.1.106:20202/wsrf/services/
NOTE: actions taken are: GT4 container startup, mds4 index query, mds4 archiver document addition (x2), mds4 archiver document retrievel, mds4 archiver query, GT4 container shutdown
Below is a failed archive retrieval (a forced code path threw this error):
ts=2007-09-13T09:44:32.461986-06:00 gid=-2112445426 event=org.globus.mds.archiver.getDocuments.start service=ArchiveService ts=2007-09-13T09:44:32.461987-06:00 gid=-2112445426 event=org.globus.mds.archiver.getDocuments.end status=-1 msg="Invalid parameter"
C Core Log Example (Aug 16)
ts=2007-08-16T13:11:42.643255Z id=10270 event=globus_service_engine.start engine_id=30912 contact=http://localhost:30003/
ts=2007-08-16T13:12:20.648918Z id=10270 event=globus_service_engine.end engine_id=30912 status=0
ts=2007-08-16T13:17:31.824379Z id=10324 event=globus_service_engine.start engine_id=64077 contact=http://localhost:30003/
ts=2007-08-16T13:18:02.404602Z id=10324 event=globus_service_engine.session.start engine_id=64077 session_id=22 client=localhost:51869
ts=2007-08-16T13:18:02.477603Z id=10324 event=globus_service_engine.process.start session_id=22 method=POST uri=/wsrf/services/WidgetService
ts=2007-08-16T13:18:02.575615Z id=10324 event=globus_service_engine.process.end session_id=22 status=0
ts=2007-08-16T13:18:02.576034Z id=10324 event=globus_service_engine.session.end session_id=22 engine_id=64077 status=0
ts=2007-08-16T13:18:19.578218Z id=10324 event=globus_service_engine.end engine_id=64077 status=0
ts=2007-08-16T14:14:01.965133Z id=27042 event=globus_service_engine.start engine_id=27819 contact=https://localhost:30003/
ts=2007-08-16T14:14:09.062330Z id=27042 event=globus_service_engine.end engine_id=27819 status=0
ts=2007-08-16T14:14:10.577909Z id=27045 event=globus_service_engine.start engine_id=15848 contact=http://localhost:30003/
ts=2007-08-16T14:14:29.187516Z id=27045 event=globus_service_engine.session.start engine_id=15848 session_id=108 client=localhost:53328
ts=2007-08-16T14:14:29.267401Z id=27045 event=globus_service_engine.process.start session_id=108 method=POST uri=/wsrf/services/WidgetService
ts=2007-08-16T14:14:29.527364Z id=27045 event=globus_service_engine.map_operation.start session_id=108 operation={http://widgets.com}createWidget
ts=2007-08-16T14:14:29.527405Z id=27045 event=globus_service_engine.map_operation.end session_id=108 operation={http://widgets.com}createWidget status=0
ts=2007-08-16T14:14:29.709855Z id=27045 event=globus_service_engine.process.end session_id=108 status=0
ts=2007-08-16T14:14:29.722700Z id=27045 event=globus_service_engine.session.end session_id=108 engine_id=15848 status=0
ts=2007-08-16T14:14:47.024247Z id=27045 event=globus_service_engine.end engine_id=15848 status=0
java core example Sept 10
see http://www-unix.mcs.anl.gov/~ranantha/cedpsLogging/sample-container-log.txt
# Start container with correct configuration
ts=2007-09-10T13:14:45.85594-05:00 event=org.globus.container.start
ts=2007-09-10T13:14:45.88989-05:00 event=org.globus.container.engine.start msg="Starting SOAP server" address=https://192.168.1.102:8443/wsrf/services/
# GetRP on COntainerRegistry Service for list of services
ts=2007-09-10T13:14:45.90111-05:00 event=org.globus.security.authn.transport.start
ts=2007-09-10T13:14:45.90311-05:00 event=org.globus.security.authn.transport.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
ts=2007-09-10T13:14:45.90391-05:00 gid=-1038708743 event=org.globus.wsrf.getRP.start service=ContainerRegistryService
ts=2007-09-10T13:14:45.90431-05:00 gid=-1038708743 event=org.globus.wsrf.getRP.end status=0 service=ContainerRegistryService
# stop container
ts=2007-09-10T13:14:45.92834-05:00 event=org.globus.container.end status=0
ts=2007-09-10T13:14:45.93596-05:00 event=org.globus.container.engine.end status=0 msg="Stopping server" address=https://192.168.1.102:8443/wsrf/services/
# start container with poor credential configuration
ts=2007-09-10T13:15:48.148424-05:00 event=org.globus.container.start
ts=2007-09-10T13:15:48.150928-05:00 event=org.globus.container.end status=-1 msg="[JWSCORE-114] Failed to start container: [JWSCORE-200] Container failed to initialize [Caused by: [JWSSEC-250] Failed to load certificate/key file. Certificate "L:\etc\containercert.pem" and key is "L:\etc\containerkey.pem" [Caused by: [JGLOBUS-7] Failed to load credentials. [Caused by: L:\etc\containercert.pem (The system cannot find the file specified)]]]"
# start container with correct credential, poor gridmap configuration
ts=2007-09-10T13:16:16.176154-05:00 event=org.globus.container.start
ts=2007-09-10T13:16:16.179970-05:00 event=org.globus.container.engine.start msg="Starting SOAP server" address=https://192.168.1.102:8443/wsrf/services/
# GetRP on COntainerRegistry Service for list of services
ts=2007-09-10T13:16:16.181161-05:00 event=org.globus.security.authn.transport.start
ts=2007-09-10T13:16:16.181362-05:00 event=org.globus.security.authn.transport.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
ts=2007-09-10T13:16:16.181442-05:00 gid=1656411329 event=org.globus.wsrf.getRP.start service=ContainerRegistryService
ts=2007-09-10T13:16:16.181472-05:00 gid=1656411329 event=org.globus.wsrf.getRP.end status=0 service=ContainerRegistryService
# Create counter authN
ts=2007-09-10T13:16:16.219887-05:00 event=org.globus.security.authn.transport.start
ts=2007-09-10T13:16:16.220007-05:00 event=org.globus.security.authn.transport.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
# Create counter authN policy
ts=2007-09-10T13:16:16.220738-05:00 gid=-1065664515 event=org.globus.authn.policy.start service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-09-10T13:16:16.220738-05:00 gid=-1065664515 event=org.globus.authn.policy.end status=0 service=SecureCounterService operation={http://counter.com}createCounter
# authz fails because of poor gridmap configuration
ts=2007-09-10T13:16:16.220738-05:00 gid=-1065664515 event=org.globus.authz.start DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef" service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-09-10T13:16:16.220939-05:00 gid=-1065664515 event=org.globus.authz.end status=-1 msg="[JWSSEC-161] Not authorized" reason=[JWSSEC-185] Exceptions thrown by PDPs which did not return permit decision.
[JWSSEC-111] Error getting gridmap file; nested exception is:
org.globus.wsrf.config.ConfigException: [Caused by: L:\etc\grid-security\grid-mapfile (The system cannot find the path specified)] DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef" service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-09-10T13:16:16.221069-05:00 event=org.globus.security.authn.transport.start
ts=2007-09-10T13:16:16.221069-05:00 event=org.globus.security.authn.transport.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
# stop container
ts=2007-09-10T13:16:16.239856-05:00 event=org.globus.container.end status=0
ts=2007-09-10T13:16:16.240507-05:00 event=org.globus.container.engine.end status=0 msg="Stopping server" address=https://192.168.1.102:8443/wsrf/services/
# start container with correct credentials and gridmap
ts=2007-09-10T13:17:28.248949-05:00 event=org.globus.container.start
ts=2007-09-10T13:17:28.252985-05:00 event=org.globus.container.engine.start msg="Starting SOAP server" address=https://192.168.1.102:8443/wsrf/services/
# GetRP on COntainerRegistry Service for list of services
ts=2007-09-10T13:17:28.255679-05:00 event=org.globus.security.authn.transport.start
ts=2007-09-10T13:17:28.256169-05:00 event=org.globus.security.authn.transport.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
ts=2007-09-10T13:17:28.256330-05:00 gid=-1621356496 event=org.globus.wsrf.getRP.start service=ContainerRegistryService
ts=2007-09-10T13:17:28.256510-05:00 gid=-1621356496 event=org.globus.wsrf.getRP.end status=0 service=ContainerRegistryService
# create counter authN
ts=2007-09-10T13:17:28.257952-05:00 event=org.globus.security.authn.transport.start
ts=2007-09-10T13:17:28.258132-05:00 event=org.globus.security.authn.transport.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
# create counter authN policy
ts=2007-09-10T13:17:28.258843-05:00 gid=1707140967 event=org.globus.authn.policy.start service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-09-10T13:17:28.258843-05:00 gid=1707140967 event=org.globus.authn.policy.end status=0 service=SecureCounterService operation={http://counter.com}createCounter
# create counter authz
ts=2007-09-10T13:17:28.258843-05:00 gid=1707140967 event=org.globus.authz.start DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef" service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-09-10T13:17:28.259023-05:00 gid=1707140967 event=org.globus.authz.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef" service=SecureCounterService operation={http://counter.com}createCounter
# create counter application log
ts=2007-09-10T13:17:28.259023-05:00 gid=1707140967 event=org.samples.counter.create.start
ts=2007-09-10T13:17:28.259063-05:00 gid=1707140967 event=org.samples.counter.create.end status=0
# response log
ts=2007-09-10T13:17:28.259224-05:00 event=org.globus.security.authn.transport.start
ts=2007-09-10T13:17:28.259224-05:00 event=org.globus.security.authn.transport.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
# stop container
ts=2007-09-10T13:17:28.262639-05:00 event=org.globus.container.end status=0
ts=2007-09-10T13:17:28.263330-05:00 event=org.globus.container.engine.end status=0 msg="Stopping server" address=https://192.168.1.102:8443/wsrf/services/
java Core Log Example 1 (Aug 16)
ts=2007-06-08T01:37:56.676274-05:00 level=INFO event=org.globus.server.container.start msg="Starting SOAP server" address=https://192.168.1.100:8443/wsrf/services/
ts=2007-06-08T01:37:56.677376-05:00 level=INFO event=org.globus.security.authn.start
ts=2007-06-08T01:37:56.677546-05:00 level=INFO event=org.globus.security.authn.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
ts=2007-06-08T01:37:56.677656-05:00 level=INFO event=org.globus.wsrf.getRP.start service=ContainerRegistryService
ts=2007-06-08T01:37:56.677696-05:00 level=INFO event=org.globus.wsrf.getRP.end status=0 service=ContainerRegistryService
ts=2007-06-08T01:37:56.719527-05:00 level=INFO event=org.globus.security.authn.start
ts=2007-06-08T01:37:56.719677-05:00 level=INFO event=org.globus.security.authn.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
ts=2007-06-08T01:37:56.720448-05:00 level=INFO event=org.globus.authn.policy.start service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-06-08T01:37:56.720448-05:00 level=INFO event=org.globus.authn.policy.end status=0 service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-06-08T01:37:56.720448-05:00 level=INFO event=org.globus.authz.start DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef" service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-06-08T01:37:56.720468-05:00 level=INFO event=org.globus.authz.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef" service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-06-08T01:37:56.720468-05:00 level=INFO event=org.samples.counter.create.start
ts=2007-06-08T01:37:56.720658-05:00 level=INFO event=org.samples.counter.create.end status=0
ts=2007-06-08T01:37:56.720838-05:00 level=INFO event=org.globus.security.authn.start
ts=2007-06-08T01:37:56.720838-05:00 level=INFO event=org.globus.security.authn.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
ts=2007-06-08T01:37:56.775197-05:00 level=INFO event=org.globus.wsrf.container.end status=0 msg="Container shutting down..."
java Core Log Example 2 (Aug 16)
ts=2007-06-08T01:31:43.303128-05:00 level=INFO event=org.globus.server.container.start msg="Starting SOAP server" address=https://192.168.1.100:8443/wsrf/services/
ts=2007-06-08T01:31:43.327182-05:00 level=INFO event=org.globus.security.authn.start
ts=2007-06-08T01:31:43.327663-05:00 level=INFO event=org.globus.security.authn.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
ts=2007-06-08T01:31:43.328715-05:00 level=INFO event=org.globus.authn.policy.start service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-06-08T01:31:43.328715-05:00 level=INFO event=org.globus.authn.policy.end status=0 service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-06-08T01:31:43.328715-05:00 level=INFO event=org.globus.authz.start DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef" service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-06-08T01:31:43.328735-05:00 level=INFO event=org.globus.authz.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef" service=SecureCounterService operation={http://counter.com}createCounter
ts=2007-06-08T01:31:43.328755-05:00 level=INFO event=org.samples.counter.create.start
ts=2007-06-08T01:31:43.328795-05:00 level=INFO event=org.samples.counter.create.end status=0
ts=2007-06-08T01:31:43.329025-05:00 level=INFO event=org.globus.security.authn.start
ts=2007-06-08T01:31:43.329025-05:00 level=INFO event=org.globus.security.authn.end status=0 DN="/C=US/O=Globus Alliance/OU=User/CN=101497d3dcd.3dcd5aef"
ts=2007-06-08T01:31:43.341032-05:00 level=INFO event=org.globus.wsrf.container.end status=0 msg="Container shutting down..."
GRAM4 Log Example - Without Staging (Sep 11)
ts=2007-08-31T11:03:27.210035-05:00 level=INFO event=org.globus.execution.job.creation.start session.id=null jobSubmission.id=b7053372-57db-11dc-988d-0013d4b94663 service=ManagedJobFactoryService class=factory.ManagedJobFactoryService thread=ServiceThread-21 method=createManagedJob line=95 ts=2007-08-31T11:03:27.210435-05:00 level=INFO event=org.globus.execution.job.creation.end status=0 jobResource.id=b768b820-57db-11dc-8952-8ebe5b02eda1 session.id=null jobSubmission.id=b7053372-57db-11dc-988d-0013d4b94663 service=ManagedJobFactoryService class=factory.ManagedJobFactoryService thread=ServiceThread-21 method=createManagedJob line=368 ts=2007-08-31T11:03:27.210462-05:00 level=INFO event=org.globus.execution.job.processing.start jobResource.id=b768b820-57db-11dc-8952-8ebe5b02eda1 class=exec.StateMachine thread=RunQueueThread_0 method=logStartProcessing line=3556 ts=2007-08-31T11:03:27.210485-05:00 level=INFO event=org.globus.execution.job.processing.submission.start jobResource.id=b768b820-57db-11dc-8952-8ebe5b02eda1 class=exec.StateMachine thread=RunQueueThread_2 method=logStartSubmitting line=3566 ts=2007-08-31T11:03:27.210826-05:00 level=INFO event=org.globus.execution.job.processing.submission.end status=0 jobResource.id=b768b820-57db-11dc-8952-8ebe5b02eda1 id=b7d63b20-57db-11dc-b073-0013d4c3b957:13935 class=exec.StateMachine thread=RunQueueThread_2 method=logEndSubmitting line=3679 ts=2007-08-31T11:03:27.213085-05:00 level=INFO event=org.globus.execution.job.processing.end status=0 jobResource.id=b768b820-57db-11dc-8952-8ebe5b02eda1 class=exec.StateMachine thread=RunQueueThread_5 method=logJobSucceeded line=3690
WebMDS Log Example (Oct 11)
ts=2007-10-11T02:32:56.176565-07:00 id=507818027 event=org.globus.mds.webmds.WebmdsServlet.start service=jndi:/localhost/webmds/ operation=init
ts=2007-10-11T02:32:56.177014-07:00 id=507818027 event=org.globus.mds.webmds.WebmdsServlet.end status=0 service=jndi:/localhost/webmds/ operation=init
ts=2007-10-11T02:32:56.177022-07:00 id=1397960199 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.177087-07:00 id=1397960199 event=org.globus.mds.webmds.WebmdsServlet.end status=0 service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.177808-07:00 id=888913684 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.178242-07:00 id=888913684 event=org.globus.mds.webmds.WebmdsServlet.end status=0 service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.178275-07:00 id=139530406 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.178277-07:00 id=139530406 event=org.globus.mds.webmds.WebmdsServlet.end status=-1 msg="The 'testxslnull' XML source has not been configured to be used as a source of XSL. To change this, set the suitableForXSL parameter to 'true' in the config entry for 'testxslnull' on the WebMDS server." service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.178281-07:00 id=1837945628 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.178281-07:00 id=1837945628 event=org.globus.mds.webmds.WebmdsServlet.end status=-1 msg="The 'testxslfalse' XML source has not been configured to be used as a source of XSL. To change this, set the suitableForXSL parameter to 'true' in the config entry for 'testxslfalse' on the WebMDS server." service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.203062-07:00 id=445287400 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.204593-07:00 id=445287400 event=org.globus.mds.webmds.WebmdsServlet.end status=0 service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.204602-07:00 id=1126675347 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.204687-07:00 id=1126675347 event=org.globus.mds.webmds.WebmdsServlet.end status=0 service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.204694-07:00 id=2013548897 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.204787-07:00 id=2013548897 event=org.globus.mds.webmds.WebmdsServlet.end status=0 service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.204792-07:00 id=2118006829 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.204866-07:00 id=2118006829 event=org.globus.mds.webmds.WebmdsServlet.end status=0 service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.240546-07:00 id=53980933 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.240804-07:00 id=53980933 event=org.globus.mds.webmds.WebmdsServlet.end status=-1 msg="org.oasis.wsrf.properties.UnknownQueryExpressionDialectFaultType: Remote exception while attempting to run query '/' on remote resource Address: https://mds.globus.org:8443/wsrf/services/DefaultIndexService caused by [0: org.oasis.wsrf.faults.BaseFaultType: Query dialect not supported: http://www.globus.org/namespaces/2006/07/TargetedXPath]" service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.290882-07:00 id=1915458356 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.290965-07:00 id=1915458356 event=org.globus.mds.webmds.WebmdsServlet.end status=-1 msg="org.oasis.wsrf.faults.BaseFaultType: ; nested exception is: java.io.EOFException / Remote exception while attempting to run query '/' on remote resource Address: https://mds.globus.org:8444/wsrf/services/DefaultIndexService" service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.332880-07:00 id=102462484 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.332880-07:00 id=102462484 event=org.globus.mds.webmds.WebmdsServlet.end status=-1 msg="No info argument was specified to WebMDS. This probably indicates an error in the HTML form or URL used to invoke WebMDS" service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.353539-07:00 id=291882730 event=org.globus.mds.webmds.WebmdsServlet.start service=http://localhost:8080/webmds/webmds operation=get
ts=2007-10-11T02:32:56.353540-07:00 id=291882730 event=org.globus.mds.webmds.WebmdsServlet.end status=-1 msg="Bad namespace mapping parameter '//foo('" service=http://localhost:8080/webmds/webmds operation=get
