2011-03-21 10:51:32,416 ObmOfficer INFO - authenticateUser
2011-03-21 10:51:32,418 LoginHandler INFO - method: doLogin
2011-03-21 10:51:32,421 SessionManagement INFO - [CUID 187]
[email protected] logged in from funis. auth type: OBM DB (mail:
[email protected]) on obm-sync 2.3.24
2011-03-21 10:51:32,422 SyncServlet INFO - handler responded to
login/doLogin in 3ms.
2011-03-21 10:51:32,429 LoginHandler INFO - method: doLogout
2011-03-21 10:51:32,429 SessionManagement INFO - [CUID 187] logout.
2011-03-21 10:51:32,429 SyncServlet INFO - handler responded to
login/doLogout in 0ms.
2011-03-21 10:51:32,430 ObmOfficer INFO - authorize
2011-03-21 10:51:32,443 ObmOfficer INFO - authorize
2011-03-21 10:51:32,443 ObmOfficer INFO - authorize
2011-03-21 10:51:32,446 CalendarSyncSource INFO - obmSyncSource ctor
2011-03-21 10:51:32,447 CalendarSyncSource INFO - getName: obm_cal_ical
2011-03-21 10:51:32,448 ContactSyncSource INFO - obmSyncSource ctor
2011-03-21 10:51:32,449 ContactSyncSource INFO - getName: obm_book_vcard
2011-03-21 10:51:32,954 CalendarSyncSource INFO - getsourceuri:
obm_cal_ical
2011-03-21 10:51:32,954 CalendarSyncSource INFO - Begin an
OBM-Funambol Calendar sync
2011-03-21 10:51:32,954 LoginHandler INFO - method: doLogin
2011-03-21 10:51:32,956 SessionManagement INFO - [CUID 188]
[email protected] logged in from funis. auth type: OBM DB (mail:
[email protected]) on obm-sync 2.3.24
2011-03-21 10:51:32,957 SyncServlet INFO - handler responded to
login/doLogin in 2ms.
2011-03-21 10:51:32,957 CalendarClient INFO - getUserEmail(token)
2011-03-21 10:51:32,963 SyncServlet INFO - handler responded to
calendar/getUserEmail in 1ms.
2011-03-21 10:51:32,963 CalendarSyncSource INFO - device charset : UTF-8
2011-03-21 10:51:32,963 CalendarSyncSource INFO - beginSync end.
2011-03-21 10:51:32,963 CalendarSyncSource INFO - getsourceuri:
obm_cal_ical
2011-03-21 10:51:32,963 CalendarSyncSource INFO -
getNewSyncItemKeys(166392014041FEE1F8000050BF1977E0/[email protected] ,
2011-03-21 10:38:49.463 , 2011-03-21 10:51:32.404) => null
2011-03-21 10:51:32,963 CalendarSyncSource INFO -
getUpdatedSyncItemKeys(166392014041FEE1F8000050BF1977E0/[email protected] ,
2011-03-21 10:38:49.463 , 2011-03-21 10:51:32.404)
2011-03-21 10:51:32,969 CalendarBindingImpl INFO - [CUID 188] Calendar
: getSync([email protected], Mon Mar 21 10:38:49 GMT 2011)
2011-03-21 10:51:32,974 CalendarBindingImpl INFO - [CUID 188] Calendar
: getSync([email protected]) => 0 upd, 0 rmed.
2011-03-21 10:51:32,974 SyncServlet INFO - handler responded to
calendar/getSync in 4ms.
2011-03-21 10:51:32,974 CalendarManager INFO - getSync(test, Mon Mar
21 10:38:49 GMT 2011 (since == 2011-03-21 10:38:49.463)) => upd: 0 del: 0
2011-03-21 10:51:32,974 CalendarSyncSource INFO - returning 0 key(s)
2011-03-21 10:51:32,974 CalendarSyncSource INFO -
getDeletedSyncItemKeys(166392014041FEE1F8000050BF1977E0/[email protected] ,
2011-03-21 10:38:49.463 , 2011-03-21 10:51:32.404)
2011-03-21 10:51:32,974 CalendarSyncSource INFO - returning 0 key(s)
2011-03-21 10:51:32,974 CalendarSyncSource INFO -
getSyncItemKeysFromTwin(166392014041FEE1F8000050BF1977E0/[email protected])
2011-03-21 10:51:32,974 CalendarSyncSource INFO - foundFromSync:
BEGIN:VCALENDAR
VERSION:1.0
BEGIN:VEVENT
SUMMARY:Accueil spie
CLASS:PUBLIC
DESCRIPTION:
DTSTART:20110325T090000Z
DTEND:20110325T110000Z
TRANSP:0
AALARM:;;;
LOCATION:
END:VEVENT
END:VCALENDAR
2011-03-21 10:51:32,974 CalendarSyncSource INFO - ===> syncItemKey:
{ keyValue: }
2011-03-21 10:51:32,974 CalendarSyncSource INFO - pda sent:
BEGIN:VCALENDAR
VERSION:1.0
BEGIN:VEVENT
SUMMARY:Accueil spie
CLASS:PUBLIC
DESCRIPTION:
DTSTART:20110325T090000Z
DTEND:20110325T110000Z
TRANSP:0
AALARM:;;;
LOCATION:
END:VEVENT
END:VCALENDAR
2011-03-21 10:51:32,974 CalendarSyncSource INFO - Parsing version 1.0
as xvcalendar
2011-03-21 10:51:32,975 MyCalContentConverter INFO - restoring dtstart
& end : 20110325T090000Z & 20110325T110000Z
2011-03-21 10:51:32,975 CalendarSyncSource INFO - calContent.uid: null
2011-03-21 10:51:32,975 CalendarManager INFO - duration: null
2011-03-21 10:51:32,975 CalendarHelper INFO - parsed
'20110325T090000Z' as 'Fri Mar 25 09:00:00 GMT 2011'
2011-03-21 10:51:32,975 CalendarHelper INFO - parsed
'20110325T110000Z' as 'Fri Mar 25 11:00:00 GMT 2011'
2011-03-21 10:51:32,975 CalendarManager INFO - alert export : 0
2011-03-21 10:51:32,983 CalendarDaoJdbcImpl INFO - Found 0 results
with title Accueil spie date: Fri Mar 25 09:00:00 GMT 2011 duration:
7200 domain_id: 2
2011-03-21 10:51:32,983 CalendarBindingImpl INFO - [CUID 188] found 0
twinkeys
2011-03-21 10:51:32,984 SyncServlet INFO - handler responded to
calendar/getEventTwinKeys in 2ms.
2011-03-21 10:51:32,984 CalendarSyncSource INFO - returning 0 key(s)
2011-03-21 10:51:32,984 CalendarSyncSource INFO -
addSyncItem(166392014041FEE1F8000050BF1977E0/[email protected] , 117449413)
2011-03-21 10:51:32,984 CalendarSyncSource INFO - foundFromSync:
BEGIN:VCALENDAR
VERSION:1.0
BEGIN:VEVENT
SUMMARY:Accueil spie
CLASS:PUBLIC
DESCRIPTION:
DTSTART:20110325T090000Z
DTEND:20110325T110000Z
TRANSP:0
AALARM:;;;
LOCATION:
END:VEVENT
END:VCALENDAR
2011-03-21 10:51:32,984 CalendarSyncSource INFO - ===> syncItemKey:
{ keyValue: 117449413 }
2011-03-21 10:51:32,984 CalendarSyncSource INFO - pda sent:
BEGIN:VCALENDAR
VERSION:1.0
BEGIN:VEVENT
SUMMARY:Accueil spie
CLASS:PUBLIC
DESCRIPTION:
DTSTART:20110325T090000Z
DTEND:20110325T110000Z
TRANSP:0
AALARM:;;;
LOCATION:
END:VEVENT
END:VCALENDAR
2011-03-21 10:51:32,984 CalendarSyncSource INFO - Parsing version 1.0
as xvcalendar
2011-03-21 10:51:32,984 MyCalContentConverter INFO - restoring dtstart
& end : 20110325T090000Z & 20110325T110000Z
2011-03-21 10:51:32,984 CalendarSyncSource INFO - calContent.uid: null
2011-03-21 10:51:32,984 CalendarManager INFO - duration: null
2011-03-21 10:51:32,984 CalendarHelper INFO - parsed
'20110325T090000Z' as 'Fri Mar 25 09:00:00 GMT 2011'
2011-03-21 10:51:32,984 CalendarHelper INFO - parsed
'20110325T110000Z' as 'Fri Mar 25 11:00:00 GMT 2011'
2011-03-21 10:51:32,984 CalendarManager INFO - alert export : 0
2011-03-21 10:51:32,998 CalendarDaoJdbcImpl INFO - create with token
bab3188a-3adc-4554-b89a-baf93d76e33c from funis for
[email protected]
2011-03-21 10:51:32,998 CalendarDaoJdbcImpl INFO - try to create with
calendar owner:[email protected]
2011-03-21 10:51:33,002 CalendarDaoJdbcImpl INFO - [CUID 188] Adding
[email protected] as attendee
2011-03-21 10:51:33,004 SettingDao INFO - return 21 settings for
userid: test at ifremer.fr
2011-03-21 10:51:33,011 MailService ERROR - Empty address
javax.mail.internet.AddressException: Empty address in string ``''
at
javax.mail.internet.InternetAddress.checkAddress(InternetAddress.java:1091)
at
javax.mail.internet.InternetAddress.parse(InternetAddress.java:1033)
at
javax.mail.internet.InternetAddress.parseHeader(InternetAddress.java:600)
at
javax.mail.internet.MimeMessage.getAddressHeader(MimeMessage.java:690)
at javax.mail.internet.MimeMessage.getFrom(MimeMessage.java:358)
at com.sun.mail.smtp.SMTPTransport.mailFrom(SMTPTransport.java:1306)
at com.sun.mail.smtp.SMTPTransport.sendMessage(SMTPTransport.java:886)
at org.obm.sync.ObmSmtpProvider.sendEmail(ObmSmtpProvider.java:55)
at fr.aliacom.obm.common.MailService.sendMessage(MailService.java:31)
at
org.obm.sync.server.mailer.EventChangeMailer.sendNotificationMessage(EventChangeMailer.java:213)
at
org.obm.sync.server.mailer.EventChangeMailer.sendNotificationMessageToAttendee(EventChangeMailer.java:192)
at
org.obm.sync.server.mailer.EventChangeMailer.notifyAcceptedNewUsers(EventChangeMailer.java:76)
at
fr.aliacom.obm.common.calendar.EventChangeHandler.notifyCreate(EventChangeHandler.java:52)
at
fr.aliacom.obm.common.calendar.EventChangeHandler.create(EventChangeHandler.java:43)
at
fr.aliacom.obm.common.calendar.CalendarBindingImpl.createInternalEvent(CalendarBindingImpl.java:441)
at
fr.aliacom.obm.common.calendar.CalendarBindingImpl.createEvent(CalendarBindingImpl.java:379)
at
fr.aliacom.obm.common.calendar.CalendarBindingImpl$$EnhancerByGuice$$3a3c386.CGLIB$createEvent$1(<generated>)
at
fr.aliacom.obm.common.calendar.CalendarBindingImpl$$EnhancerByGuice$$3a3c386$$FastClassByGuice$$91570965.invoke(<generated>)
at
com.google.inject.internal.cglib.proxy.MethodProxy.invokeSuper(MethodProxy.java:228)
at
com.google.inject.InterceptorStackCallback$InterceptedMethodInvocation.proceed(InterceptorStackCallback.java:64)
at
org.obm.sync.server.transactional.TransactionalInterceptor.invoke(TransactionalInterceptor.java:44)
at
com.google.inject.InterceptorStackCallback$InterceptedMethodInvocation.proceed(InterceptorStackCallback.java:64)
at
com.google.inject.InterceptorStackCallback.intercept(InterceptorStackCallback.java:44)
at
fr.aliacom.obm.common.calendar.CalendarBindingImpl$$EnhancerByGuice$$3a3c386.createEvent(<generated>)
at
org.obm.sync.server.handler.EventHandler.createEvent(EventHandler.java:355)
at
org.obm.sync.server.handler.EventHandler.searchAndInvokeMethod(EventHandler.java:124)
at
org.obm.sync.server.handler.EventHandler.handle(EventHandler.java:102)
at org.obm.sync.server.SyncServlet.handleQuery(SyncServlet.java:114)
at org.obm.sync.server.SyncServlet.service(SyncServlet.java:73)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at
org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
at
org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
at
org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
at
org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
at
org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at
org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293)
at
org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:883)
at
org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:722)
at
org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:2214)
at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
2011-03-21 10:51:33,011 CalendarBindingImpl INFO - [CUID 188] Calendar
: internal event[Accueil spie] created
2011-03-21 10:51:33,012 SyncServlet INFO - handler responded to
calendar/createEvent in 22ms.
2011-03-21 10:51:33,025 SyncServlet INFO - handler responded to
calendar/getEventFromId in 7ms.
2011-03-21 10:51:33,026 CalendarManager INFO - bd -> pda - obmToFound:
Accueil spie date: Fri Mar 25 09:00:00 GMT 2011 7200
2011-03-21 10:51:33,026 CalendarHelper INFO - date: Fri Mar 25
09:00:00 GMT 2011 converted to 20110325T090000Z
2011-03-21 10:51:33,026 CalendarHelper INFO - date: Fri Mar 25
11:00:00 GMT 2011 converted to 20110325T110000Z
2011-03-21 10:51:33,026 CalendarManager INFO - computed dt end: Fri
Mar 25 11:00:00 GMT 2011
2011-03-21 10:51:33,026 CalendarSyncSource INFO - created with id : 7184
2011-03-21 10:51:33,026 CalendarSyncSource INFO - sending syncitem to pda:
BEGIN:VCALENDAR
VERSION:1.0
BEGIN:VEVENT
UID:7184
SUMMARY:Accueil spie
DESCRIPTION:
LOCATION:
CATEGORIES:
CLASS:PUBLIC
DTSTART:20110325T090000Z
DTEND:20110325T110000Z
PRIORITY:1
TRANSP:0
STATUS:0
X-MICROSOFT-CDO-BUSYSTATUS:2
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR
2011-03-21 10:51:33,026 CalendarSyncSource INFO - commit sync
2011-03-21 10:51:33,026 ContactSyncSource INFO - getsourceuri:
obm_book_vcard
2011-03-21 10:51:33,026 ContactSyncSource INFO - device charset : UTF-8
2011-03-21 10:51:33,026 ContactSyncSource INFO - - Begin an OBM
Contact sync -
2011-03-21 10:51:33,026 LoginHandler INFO - method: doLogin
2011-03-21 10:51:33,028 SessionManagement INFO - [CUID 189]
[email protected] logged in from funis. auth type: OBM DB (mail:
[email protected]) on obm-sync 2.3.24
2011-03-21 10:51:33,029 SyncServlet INFO - handler responded to
login/doLogin in 2ms.
2011-03-21 10:51:33,029 ContactManager INFO - device timezone set to:
sun.util.calendar.ZoneInfo[id="Europe/Paris",offset=3600000,dstSavings=3600000,useDaylight=true,transitions=184,lastRule=java.util.SimpleTimeZone[id=Europe/Paris,offset=3600000,dstSavings=3600000,useDaylight=true,startYear=0,startMode=2,startMonth=2,startDay=-1,startDayOfWeek=1,startTime=3600000,startTimeMode=2,endMode=2,endMonth=9,endDay=-1,endDayOfWeek=1,endTime=3600000,endTimeMode=2]]
2011-03-21 10:51:33,030 ContactSyncSource INFO - getsourceuri:
obm_book_vcard
2011-03-21 10:51:33,030 ContactSyncSource INFO -
getUpdatedSyncItemKeys(166392014041FEE1F8000050BF1977E0/[email protected] ,
2011-03-21 10:38:49.463 , 2011-03-21 10:51:32.404)
2011-03-21 10:51:33,037 AddressBookBindingImpl INFO - [CUID 189]
AddressBook : getSync()
2011-03-21 10:51:33,052 ContactDao INFO - returning 0 contact(s) updated
2011-03-21 10:51:33,052 ContactDao INFO - returning 0 contact(s) archived
2011-03-21 10:51:33,053 ContactDao INFO - Returning 0 contact(s) deleted
2011-03-21 10:51:33,054 SyncServlet INFO - handler responded to
book/getSync in 16ms.
2011-03-21 10:51:33,054 ContactSyncSource INFO - returning 0 key(s)
2011-03-21 10:51:33,054 ContactSyncSource INFO -
getDeletedSyncItemKeys(166392014041FEE1F8000050BF1977E0/[email protected] ,
2011-03-21 10:38:49.463 , 2011-03-21 10:51:32.404)
2011-03-21 10:51:33,054 ContactSyncSource INFO - returning 0 key(s)
2011-03-21 10:51:33,054 ContactSyncSource INFO - commit sync
2011-03-21 10:51:33,513 LoginHandler INFO - method: doLogout
2011-03-21 10:51:33,513 SessionManagement INFO - [CUID 188] logout.
2011-03-21 10:51:33,513 SyncServlet INFO - handler responded to
login/doLogout in 0ms.
2011-03-21 10:51:33,514 CalendarSyncSource INFO - getsourceuri:
obm_cal_ical
2011-03-21 10:51:33,522 LoginHandler INFO - method: doLogout
2011-03-21 10:51:33,522 SessionManagement INFO - [CUID 189] logout.
2011-03-21 10:51:33,522 SyncServlet INFO - handler responded to
login/doLogout in 0ms.
2011-03-21 10:51:33,522 ContactSyncSource INFO - getsourceuri:
obm_book_vcard
2011-03-21 10:51:33,523 ObmOfficer INFO - unAuthenticate
2011-03-21 10:52:53,069 PingThread INFO - 10 unused connections pinged.
2011-03-21 10:54:53,069 PingThread INFO - 10 unused connections pinged.
2011-03-21 10:56:53,068 PingThread INFO - 10 unused connections pinged.