15:19:06+1751267946.27750 [core,debugMgr:__construct] Debugger is alive. 15:19:06+1751267946.27770 [core:_loadSetting] Complete license checking. 15:19:06+1751267946.27790 [core,objectMgr:__construct] Initializing object manager. 15:19:06+1751267946.27890 [core,objectMgr:__construct] Connected to database. 15:19:06+1751267946.28000 [core,objectMgr:__construct] Character set changed to UTF8. 15:19:06+1751267946.28040 [core,objectMgr:__construct] Switched to database. 15:19:06+1751267946.28040 [core,objectMgr:_queryRun] Sql : SHOW TABLES LIKE 'core__config' 15:19:06+1751267946.28140 [core,objectMgr:_queryRun] Time taken : 0.00094079971313477 seconds. 15:19:06+1751267946.28140 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.28150 [core,objectMgr:_queryRun] Sql : SHOW TABLES LIKE 'core__module' 15:19:06+1751267946.28240 [core,objectMgr:_queryRun] Time taken : 0.00079798698425293 seconds. 15:19:06+1751267946.28240 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.28240 [core,moduleMgr:_loadModules] Loading all modules. 15:19:06+1751267946.28240 [core,moduleMgr:_getPhysicalList] Getting modules in database. 15:19:06+1751267946.28260 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__module` WHERE (`ts_Deleted` IS NULL) ORDER BY `name` 15:19:06+1751267946.28290 [core,objectMgr:_queryRun] Time taken : 0.00030899047851562 seconds. 15:19:06+1751267946.28300 [core,objectMgr:_queryRun] Number of record returned : 3 15:19:06+1751267946.28300 [core,objectMgr:objectSysSearch] Time taken to fetch results : 5.1021575927734E-5 seconds. 15:19:06+1751267946.28310 [core,moduleMgr:_getPhysicalList] Gotten modules in database. 15:19:06+1751267946.28310 [core,moduleMgr:_getPhysicalList] Getting modules in main directory. 15:19:06+1751267946.28340 [core,moduleMgr:_getPhysicalList] Gotten modules in main directory. 15:19:06+1751267946.28340 [core,moduleMgr:_getPhysicalList] Getting modules in alternate directory. 15:19:06+1751267946.28370 [core,moduleMgr:_getPhysicalList] Gotten modules in alternate directory. 15:19:06+1751267946.28380 [core,moduleMgr:_loadModules] Loaded all modules. 15:19:06+1751267946.28390 [core,objectMgr:_queryRun] Sql : SHOW TABLES LIKE 'core__process' 15:19:06+1751267946.28460 [core,objectMgr:_queryRun] Time taken : 0.00069808959960938 seconds. 15:19:06+1751267946.28460 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.28480 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__process` WHERE (`lock` < '1751264346') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.28570 [core,objectMgr:_queryRun] Time taken : 0.00085282325744629 seconds. 15:19:06+1751267946.28570 [core,objectMgr:_queryRun] Number of record returned : 0 15:19:06+1751267946.28580 [core,objectMgr:objectSysSearch] Time taken to fetch results : 1.7881393432617E-5 seconds. 15:19:06+1751267946.28600 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__process` WHERE (`lock` > '1751268846') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.28700 [core,objectMgr:_queryRun] Time taken : 0.00092411041259766 seconds. 15:19:06+1751267946.28700 [core,objectMgr:_queryRun] Number of record returned : 0 15:19:06+1751267946.28700 [core,objectMgr:objectSysSearch] Time taken to fetch results : 1.0967254638672E-5 seconds. 15:19:06+1751267946.28750 [core,objectMgr:_queryRun] Sql : SHOW TABLES LIKE 'core__session' 15:19:06+1751267946.28830 [core,objectMgr:_queryRun] Time taken : 0.00073003768920898 seconds. 15:19:06+1751267946.28830 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.28840 [core,objectMgr:_queryRun] Sql : UPDATE `core__session` SET `lastAccessed` = '2025-06-30 15:19:06' WHERE (`ts_Deleted` IS NULL) AND (`sessionId` = 'tsas2k44v9g2snqquavth184b2') 15:19:06+1751267946.28910 [core,objectMgr:_queryRun] Time taken : 0.00064611434936523 seconds. 15:19:06+1751267946.28920 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.28930 [core,objectMgr:_queryRun] Sql : SHOW TABLES LIKE 'core__upload' 15:19:06+1751267946.29000 [core,objectMgr:_queryRun] Time taken : 0.00073099136352539 seconds. 15:19:06+1751267946.29010 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.29010 [core,eventMgr:moduleLoad] Calling 'OnModuleLoad' event. 15:19:06+1751267946.29030 [core,eventMgr:_callEvent] Calling 'onModuleLoad' event for module 'singleSignOn'. 15:19:06+1751267946.29120 [core,eventMgr:_callEvent] Called 'onModuleLoad' event for module 'singleSignOn'. 15:19:06+1751267946.29150 [core,eventMgr:_callEvent] Calling 'onModuleLoad' event for module 'userMgr'. 15:19:06+1751267946.29200 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/userMgr/classes/userMgrAuthHandler.cls.php'. 15:19:06+1751267946.29550 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/userMgr/classes/userMgrAuthHandler.cls.php'. 15:19:06+1751267946.29590 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__session` WHERE (`sessionId` = 'tsas2k44v9g2snqquavth184b2') AND (`key` = 'userMgr') AND (`subkey` = 'auth.userId') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.29660 [core,objectMgr:_queryRun] Time taken : 0.00067710876464844 seconds. 15:19:06+1751267946.29670 [core,objectMgr:_queryRun] Number of record returned : 0 15:19:06+1751267946.29670 [core,objectMgr:objectSysSearch] Time taken to fetch results : 1.8835067749023E-5 seconds. 15:19:06+1751267946.29700 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__session` WHERE (`sessionId` = 'tsas2k44v9g2snqquavth184b2') AND (`key` = 'userMgr') AND (`subkey` = 'auth.unique') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.29790 [core,objectMgr:_queryRun] Time taken : 0.00084304809570312 seconds. 15:19:06+1751267946.29790 [core,objectMgr:_queryRun] Number of record returned : 0 15:19:06+1751267946.29800 [core,objectMgr:objectSysSearch] Time taken to fetch results : 1.5974044799805E-5 seconds. 15:19:06+1751267946.29800 [core,authMgr:registerHandlier] Registered : Handlier 'userMgrAuthHandler' registered successfully. 15:19:06+1751267946.29810 [core,eventMgr:_callEvent] Called 'onModuleLoad' event for module 'userMgr'. 15:19:06+1751267946.29820 [core,eventMgr:_callAllEvents] Time taken : 0.0080411434173584 seconds. 15:19:06+1751267946.29830 [core,eventMgr:moduleLoad] Called 'OnModuleLoad' event. 15:19:06+1751267946.29830 [core,eventMgr:moduleInitialize] Calling 'OnModuleInitialize' event. 15:19:06+1751267946.29840 [core,eventMgr:_callEvent] Calling 'onModuleInitialize' event for module 'singleSignOn'. 15:19:06+1751267946.29930 [core,eventMgr:_callEvent] Called 'onModuleInitialize' event for module 'singleSignOn'. 15:19:06+1751267946.29930 [core,eventMgr:_callEvent] Calling 'onModuleInitialize' event for module 'system'. 15:19:06+1751267946.30000 [core,configMgr:getBooleanValue] Getting boolean value for key 'system : Users can edit configuration' from database. 15:19:06+1751267946.30010 [core,configMgr:getValue] Getting value for key 'system : Users can edit configuration' from database. 15:19:06+1751267946.30030 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__config` WHERE (`key` = 'system') AND (`subkey` = 'Users can edit configuration') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.30060 [core,objectMgr:_queryRun] Time taken : 0.00030994415283203 seconds. 15:19:06+1751267946.30060 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.30070 [core,objectMgr:objectSysSearch] Time taken to fetch results : 3.7908554077148E-5 seconds. 15:19:06+1751267946.30070 [core,configMgr:getValue] Gotten value for key 'system : Users can edit configuration' from database. 15:19:06+1751267946.30070 [core,configMgr:getBooleanValue] Gotten boolean value for key 'system : Users can edit configuration' from database. 15:19:06+1751267946.30080 [core,eventMgr:_callEvent] Called 'onModuleInitialize' event for module 'system'. 15:19:06+1751267946.30090 [core,eventMgr:_callEvent] Calling 'onModuleInitialize' event for module 'userMgr'. 15:19:06+1751267946.30140 [core,eventMgr:_callEvent] Called 'onModuleInitialize' event for module 'userMgr'. 15:19:06+1751267946.30150 [core,eventMgr:_callAllEvents] Time taken : 0.0032088756561279 seconds. 15:19:06+1751267946.30150 [core,eventMgr:moduleInitialize] Called 'OnModuleInitialize' event. 15:19:06+1751267946.30160 [core,eventMgr:moduleLoadService] Calling 'OnModuleLoadService' event. 15:19:06+1751267946.30170 [core,eventMgr:_callEvent] Calling 'onModuleLoadService' event for module 'singleSignOn'. 15:19:06+1751267946.30240 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/services/updateFromLDAP.svc.php'. 15:19:06+1751267946.30450 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/services/updateFromLDAP.svc.php'. 15:19:06+1751267946.30460 [core,serviceMgr:register] Service [singleSignOn.updateFromLDAP] registered. 15:19:06+1751267946.30460 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/services/getLDAPUserAuthentication.svc.php'. 15:19:06+1751267946.30530 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/services/getLDAPUserAuthentication.svc.php'. 15:19:06+1751267946.30540 [core,serviceMgr:register] Service [singleSignOn.getLDAPUserAuthentication] registered. 15:19:06+1751267946.30550 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/services/validateHashKey.svc.php'. 15:19:06+1751267946.30630 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/services/validateHashKey.svc.php'. 15:19:06+1751267946.30630 [core,serviceMgr:register] Service [singleSignOn.validateHashKey] registered. 15:19:06+1751267946.30640 [core,eventMgr:_callEvent] Called 'onModuleLoadService' event for module 'singleSignOn'. 15:19:06+1751267946.30640 [core,eventMgr:_callEvent] Calling 'onModuleLoadService' event for module 'system'. 15:19:06+1751267946.30720 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/modules/system/services/sendNotification.svc.php'. 15:19:06+1751267946.30800 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/modules/system/services/sendNotification.svc.php'. 15:19:06+1751267946.30800 [core,serviceMgr:register] Service [system.sendNotification] registered. 15:19:06+1751267946.30810 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/modules/system/services/sendPushNotification.svc.php'. 15:19:06+1751267946.30870 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/modules/system/services/sendPushNotification.svc.php'. 15:19:06+1751267946.30890 [core,serviceMgr:register] Service [system.sendPushNotification] registered. 15:19:06+1751267946.30890 [core,eventMgr:_callEvent] Called 'onModuleLoadService' event for module 'system'. 15:19:06+1751267946.30910 [core,eventMgr:_callAllEvents] Time taken : 0.0074508190155029 seconds. 15:19:06+1751267946.30910 [core,eventMgr:moduleLoadService] Called 'OnModuleLoadService' event. 15:19:06+1751267946.30910 [core,objectMgr:_queryRun] Sql : LOCK TABLE `core__process` WRITE 15:19:06+1751267946.30940 [core,objectMgr:_queryRun] Time taken : 0.00028586387634277 seconds. 15:19:06+1751267946.30950 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.30960 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__process` WHERE (`name` = 'sys.upgrade') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.31010 [core,objectMgr:_queryRun] Time taken : 0.00052213668823242 seconds. 15:19:06+1751267946.31010 [core,objectMgr:_queryRun] Number of record returned : 0 15:19:06+1751267946.31020 [core,objectMgr:objectSysSearch] Time taken to fetch results : 1.8119812011719E-5 seconds. 15:19:06+1751267946.31030 [core,objectMgr:_queryRun] Sql : SELECT `COLUMN_NAME`, `DATA_TYPE`, `CHARACTER_MAXIMUM_LENGTH` from `INFORMATION_SCHEMA`.`COLUMNS` WHERE `TABLE_SCHEMA` = 'wpsso_db' AND `TABLE_NAME` = 'core__process' 15:19:06+1751267946.31390 [core,objectMgr:_queryRun] Time taken : 0.0036051273345947 seconds. 15:19:06+1751267946.31400 [core,objectMgr:_queryRun] Number of record returned : 7 15:19:06+1751267946.31440 [core,objectMgr:_queryRun] Sql : INSERT INTO `core__process` (`id`, `name`, `lock`, `ts_Created`, `ts_Modified`, `ts_Synchronised`) VALUES ('cf576568b1dc99e378eff49a959f199d', 'sys.upgrade', '1751267946', '2025-06-30 15:19:06', '2025-06-30 15:19:06', '2025-06-30 15:19:06') 15:19:06+1751267946.31500 [core,objectMgr:_queryRun] Time taken : 0.00050210952758789 seconds. 15:19:06+1751267946.31500 [core,objectMgr:_queryRun] Number of record affected : 1 15:19:06+1751267946.31510 [core,objectMgr:_queryRun] Sql : UNLOCK TABLES 15:19:06+1751267946.31540 [core,objectMgr:_queryRun] Time taken : 0.00032997131347656 seconds. 15:19:06+1751267946.31540 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.31550 [core,eventMgr:moduleUpgrade] Calling 'OnModuleUpgrade' event. 15:19:06+1751267946.31560 [core,eventMgr:_callEvent] Calling 'onModuleUpgrade' event for module 'singleSignOn'. 15:19:06+1751267946.31670 [core,eventMgr:_callEvent] Called 'onModuleUpgrade' event for module 'singleSignOn'. 15:19:06+1751267946.31680 [core,objectMgr:_queryRun] Sql : LOCK TABLE `core__process` WRITE 15:19:06+1751267946.31720 [core,objectMgr:_queryRun] Time taken : 0.00033783912658691 seconds. 15:19:06+1751267946.31730 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.31750 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__process` WHERE (`name` = 'sys.upgrade') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.31850 [core,objectMgr:_queryRun] Time taken : 0.0010080337524414 seconds. 15:19:06+1751267946.31850 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.31860 [core,objectMgr:objectSysSearch] Time taken to fetch results : 4.6968460083008E-5 seconds. 15:19:06+1751267946.31890 [core,objectMgr:_queryRun] Sql : UPDATE `core__process` SET `name` = 'sys.upgrade', `lock` = '1751267946', `ts_Created` = '2025-06-30 15:19:06', `ts_Modified` = '2025-06-30 15:19:06', `ts_Synchronised` = '2025-06-30 15:19:06' WHERE (`id` = 'cf576568b1dc99e378eff49a959f199d') 15:19:06+1751267946.31940 [core,objectMgr:_queryRun] Time taken : 0.00052189826965332 seconds. 15:19:06+1751267946.31950 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.31950 [core,objectMgr:_queryRun] Sql : UNLOCK TABLES 15:19:06+1751267946.31970 [core,objectMgr:_queryRun] Time taken : 0.00021100044250488 seconds. 15:19:06+1751267946.31970 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.31990 [core,eventMgr:_callEvent] Calling 'onModuleUpgrade' event for module 'system'. 15:19:06+1751267946.32200 [core,eventMgr:_callEvent] Called 'onModuleUpgrade' event for module 'system'. 15:19:06+1751267946.32200 [core,objectMgr:_queryRun] Sql : LOCK TABLE `core__process` WRITE 15:19:06+1751267946.32240 [core,objectMgr:_queryRun] Time taken : 0.00037002563476562 seconds. 15:19:06+1751267946.32250 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.32260 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__process` WHERE (`name` = 'sys.upgrade') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.32330 [core,objectMgr:_queryRun] Time taken : 0.00073790550231934 seconds. 15:19:06+1751267946.32340 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.32340 [core,objectMgr:objectSysSearch] Time taken to fetch results : 2.8133392333984E-5 seconds. 15:19:06+1751267946.32360 [core,objectMgr:_queryRun] Sql : UPDATE `core__process` SET `name` = 'sys.upgrade', `lock` = '1751267946', `ts_Created` = '2025-06-30 15:19:06', `ts_Modified` = '2025-06-30 15:19:06', `ts_Synchronised` = '2025-06-30 15:19:06' WHERE (`id` = 'cf576568b1dc99e378eff49a959f199d') 15:19:06+1751267946.32410 [core,objectMgr:_queryRun] Time taken : 0.00054407119750977 seconds. 15:19:06+1751267946.32420 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.32420 [core,objectMgr:_queryRun] Sql : UNLOCK TABLES 15:19:06+1751267946.32490 [core,objectMgr:_queryRun] Time taken : 0.00065994262695312 seconds. 15:19:06+1751267946.32500 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.32510 [core,eventMgr:_callEvent] Calling 'onModuleUpgrade' event for module 'userMgr'. 15:19:06+1751267946.32600 [core,eventMgr:_callEvent] Called 'onModuleUpgrade' event for module 'userMgr'. 15:19:06+1751267946.32610 [core,objectMgr:_queryRun] Sql : LOCK TABLE `core__process` WRITE 15:19:06+1751267946.32640 [core,objectMgr:_queryRun] Time taken : 0.00028300285339355 seconds. 15:19:06+1751267946.32640 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.32650 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__process` WHERE (`name` = 'sys.upgrade') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.32720 [core,objectMgr:_queryRun] Time taken : 0.00058698654174805 seconds. 15:19:06+1751267946.32720 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.32730 [core,objectMgr:objectSysSearch] Time taken to fetch results : 5.1021575927734E-5 seconds. 15:19:06+1751267946.32760 [core,objectMgr:_queryRun] Sql : UPDATE `core__process` SET `name` = 'sys.upgrade', `lock` = '1751267946', `ts_Created` = '2025-06-30 15:19:06', `ts_Modified` = '2025-06-30 15:19:06', `ts_Synchronised` = '2025-06-30 15:19:06' WHERE (`id` = 'cf576568b1dc99e378eff49a959f199d') 15:19:06+1751267946.32800 [core,objectMgr:_queryRun] Time taken : 0.00043702125549316 seconds. 15:19:06+1751267946.32810 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.32810 [core,objectMgr:_queryRun] Sql : UNLOCK TABLES 15:19:06+1751267946.32840 [core,objectMgr:_queryRun] Time taken : 0.00024104118347168 seconds. 15:19:06+1751267946.32840 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.32840 [core,eventMgr:moduleUpgrade] Called 'OnModuleUpgrade' event. 15:19:06+1751267946.32850 [core,objectMgr:_queryRun] Sql : LOCK TABLE `core__process` WRITE 15:19:06+1751267946.32890 [core,objectMgr:_queryRun] Time taken : 0.00030016899108887 seconds. 15:19:06+1751267946.32890 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.32910 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__process` WHERE (`name` = 'sys.upgrade') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.32970 [core,objectMgr:_queryRun] Time taken : 0.00060701370239258 seconds. 15:19:06+1751267946.32970 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.32980 [core,objectMgr:objectSysSearch] Time taken to fetch results : 2.7894973754883E-5 seconds. 15:19:06+1751267946.32990 [core,objectMgr:_queryRun] Sql : DELETE FROM `core__process` WHERE (`id` = 'cf576568b1dc99e378eff49a959f199d') 15:19:06+1751267946.33040 [core,objectMgr:_queryRun] Time taken : 0.00049209594726562 seconds. 15:19:06+1751267946.33040 [core,objectMgr:_queryRun] Number of record affected : 1 15:19:06+1751267946.33050 [core,objectMgr:_queryRun] Sql : UNLOCK TABLES 15:19:06+1751267946.33080 [core,objectMgr:_queryRun] Time taken : 0.00023794174194336 seconds. 15:19:06+1751267946.33080 [core,objectMgr:_queryRun] Number of record affected : 0 15:19:06+1751267946.33080 [core,eventMgr:moduleLoaded] Calling 'OnModuleLoaded' event. 15:19:06+1751267946.33110 [core,eventMgr:_callAllEvents] Time taken : 0.00019216537475586 seconds. 15:19:06+1751267946.33110 [core,eventMgr:moduleLoaded] Called 'OnModuleLoaded' event. 15:19:06+1751267946.33170 [core,eventMgr:modulePreProcess] Calling 'OnModulePreProcess' event. 15:19:06+1751267946.33190 [core,eventMgr:_callAllEvents] Time taken : 0.0001380443572998 seconds. 15:19:06+1751267946.33190 [core,eventMgr:modulePreProcess] Called 'OnModulePreProcess' event. 15:19:06+1751267946.33190 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/ssoSignIn.php'. 15:19:06+1751267946.33360 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__user.ldr.php'. 15:19:06+1751267946.33460 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__user.ldr.php'. 15:19:06+1751267946.33470 [core,objectLoader:getLoader] Loaded : sso__user. 15:19:06+1751267946.33480 [core,objectLoader:getLoader] Loader for 'singleSignOn:sso__user' requested. 15:19:06+1751267946.33490 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__group.ldr.php'. 15:19:06+1751267946.33580 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__group.ldr.php'. 15:19:06+1751267946.33590 [core,objectLoader:getLoader] Loaded : sso__group. 15:19:06+1751267946.33600 [core,objectLoader:getLoader] Loader for 'singleSignOn:sso__group' requested. 15:19:06+1751267946.33610 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__userGroup.ldr.php'. 15:19:06+1751267946.33680 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__userGroup.ldr.php'. 15:19:06+1751267946.33680 [core,objectLoader:getLoader] Loaded : sso__userGroup. 15:19:06+1751267946.33690 [core,objectLoader:getLoader] Loader for 'singleSignOn:sso__userGroup' requested. 15:19:06+1751267946.33700 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__securityToken.ldr.php'. 15:19:06+1751267946.33800 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__securityToken.ldr.php'. 15:19:06+1751267946.33800 [core,objectLoader:getLoader] Loaded : sso__securityToken. 15:19:06+1751267946.33810 [core,objectLoader:getLoader] Loader for 'singleSignOn:sso__securityToken' requested. 15:19:06+1751267946.33830 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__application.ldr.php'. 15:19:06+1751267946.33940 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__application.ldr.php'. 15:19:06+1751267946.33950 [core,objectLoader:getLoader] Loaded : sso__application. 15:19:06+1751267946.33960 [core,objectLoader:getLoader] Loader for 'singleSignOn:sso__application' requested. 15:19:06+1751267946.33970 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__login.ldr.php'. 15:19:06+1751267946.34070 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__login.ldr.php'. 15:19:06+1751267946.34080 [core,objectLoader:getLoader] Loaded : sso__login. 15:19:06+1751267946.34090 [core,objectLoader:getLoader] Loader for 'singleSignOn:sso__login' requested. 15:19:06+1751267946.34110 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__groupApplication.ldr.php'. 15:19:06+1751267946.34200 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/loaders/sso__groupApplication.ldr.php'. 15:19:06+1751267946.34200 [core,objectLoader:getLoader] Loaded : sso__groupApplication. 15:19:06+1751267946.34210 [core,objectLoader:getLoader] Loader for 'singleSignOn:sso__groupApplication' requested. 15:19:06+1751267946.34220 [core,objectMgr:_queryRun] Sql : SHOW TABLES LIKE 'sso__application' 15:19:06+1751267946.34310 [core,objectMgr:_queryRun] Time taken : 0.0007781982421875 seconds. 15:19:06+1751267946.34310 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.34340 [core,objectMgr:_queryRun] Sql : SELECT * FROM `sso__application` WHERE (`account` = 'ServicePortal') AND (`enabled` = '1') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.34390 [core,objectMgr:_queryRun] Time taken : 0.00037693977355957 seconds. 15:19:06+1751267946.34390 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.34390 [core,objectMgr:objectSysSearch] Time taken to fetch results : 3.3855438232422E-5 seconds. 15:19:06+1751267946.34400 [core,serviceMgr:call] Calling service [singleSignOn.validateHashKey]. 15:19:06+1751267946.34430 [core,serviceMgr:call] Called service [singleSignOn.validateHashKey]. Time taken : 0.00023794174194336 seconds. 15:19:06+1751267946.34460 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__session` WHERE (`sessionId` = 'tsas2k44v9g2snqquavth184b2') AND (`key` = 'singleSignOn') AND (`subkey` = 'sso.userId') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.34570 [core,objectMgr:_queryRun] Time taken : 0.0010428428649902 seconds. 15:19:06+1751267946.34570 [core,objectMgr:_queryRun] Number of record returned : 0 15:19:06+1751267946.34580 [core,objectMgr:objectSysSearch] Time taken to fetch results : 1.6927719116211E-5 seconds. 15:19:06+1751267946.34590 [core,objectMgr:_queryRun] Sql : SHOW TABLES LIKE 'sso__user' 15:19:06+1751267946.34680 [core,objectMgr:_queryRun] Time taken : 0.00081610679626465 seconds. 15:19:06+1751267946.34680 [core,objectMgr:_queryRun] Number of record returned : 1 15:19:06+1751267946.34700 [core,objectMgr:_queryRun] Sql : SELECT * FROM `sso__user` WHERE (`id` IS NULL) AND (`enabled` = '1') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.34730 [core,objectMgr:_queryRun] Time taken : 0.00034999847412109 seconds. 15:19:06+1751267946.34740 [core,objectMgr:_queryRun] Number of record returned : 0 15:19:06+1751267946.34740 [core,objectMgr:objectSysSearch] Time taken to fetch results : 1.0013580322266E-5 seconds. 15:19:06+1751267946.34740 [core,form:__construct] Creating form 'sso_signIn'. 15:19:06+1751267946.34750 [core,form:__construct] Created form 'sso_signIn'. 15:19:06+1751267946.34750 [core,form:setHandlier] Submission handlier for form 'sso_signIn' set. 15:19:06+1751267946.34750 [core,form:addHidden] Hidden input 'backUrl' added to form 'sso_signIn'. 15:19:06+1751267946.34750 [core,form:addInput] Input 'account' being added to form 'sso_signIn'. 15:19:06+1751267946.34770 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/classes/cInput/cInput_text.cls.php'. 15:19:06+1751267946.34870 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/classes/cInput/cInput_text.cls.php'. 15:19:06+1751267946.34880 [core,form:addInput] Input 'account' added to form 'sso_signIn'. 15:19:06+1751267946.34880 [core,form:addInput] Input 'password' being added to form 'sso_signIn'. 15:19:06+1751267946.34900 [core:req] Requiring file '/var/www/html/sso.opennet.com.sg/www/classes/cInput/cInput_password.cls.php'. 15:19:06+1751267946.35000 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/classes/cInput/cInput_password.cls.php'. 15:19:06+1751267946.35010 [core,form:addInput] Input 'password' added to form 'sso_signIn'. 15:19:06+1751267946.35010 [core,form:addAction] Action 'signIn' added to form 'sso_signIn'. 15:19:06+1751267946.35020 [core,form:addAction] Action 'cancel' added to form 'sso_signIn'. 15:19:06+1751267946.35020 [core,eventMgr:formCreate] Calling 'onFormCreate_sso_signIn' event. 15:19:06+1751267946.35050 [core,eventMgr:_callAllEvents] Time taken : 0.00024914741516113 seconds. 15:19:06+1751267946.35050 [core,eventMgr:formCreate] Called 'onFormCreate_sso_signIn' event. 15:19:06+1751267946.35180 [core,objectMgr:_queryRun] Sql : SELECT * FROM `core__session` WHERE (`sessionId` = 'tsas2k44v9g2snqquavth184b2') AND (`key` = 'core') AND (`subkey` = 'notices') AND (`ts_Deleted` IS NULL) 15:19:06+1751267946.35300 [core,objectMgr:_queryRun] Time taken : 0.0010719299316406 seconds. 15:19:06+1751267946.35300 [core,objectMgr:_queryRun] Number of record returned : 0 15:19:06+1751267946.35310 [core,objectMgr:objectSysSearch] Time taken to fetch results : 1.8119812011719E-5 seconds. 15:19:06+1751267946.35330 [core,eventMgr:templateStart] Calling 'onTemplateStart' event. 15:19:06+1751267946.35350 [core,eventMgr:_callAllEvents] Time taken : 0.00016188621520996 seconds. 15:19:06+1751267946.35350 [core,eventMgr:templateStart] Called 'onTemplateStart' event. 15:19:06+1751267946.35360 [core,form:__toString] Rendering form 'sso_signIn'. 15:19:06+1751267946.35370 [core,form:__toString] Rendering hidden input 'backUrl'. 15:19:06+1751267946.35380 [core,form:__toString] Rendered hidden input 'backUrl'. 15:19:06+1751267946.35380 [core,form:__toString] Rendering input 'account'. 15:19:06+1751267946.35390 [core,form:__toString] Rendered input 'account'. 15:19:06+1751267946.35390 [core,form:__toString] Rendering input 'password'. 15:19:06+1751267946.35400 [core,form:__toString] Rendered input 'password'. 15:19:06+1751267946.35400 [core,form:__toString] Rendered form 'sso_signIn'. 15:19:06+1751267946.35410 [core,eventMgr:templateStop] Calling 'onTemplateStop' event. 15:19:06+1751267946.35430 [core,eventMgr:_callEvent] Calling 'onTemplateStop' event for module 'system'. 15:19:06+1751267946.35520 [core,eventMgr:_callEvent] Called 'onTemplateStop' event for module 'system'. 15:19:06+1751267946.35530 [core,eventMgr:_callAllEvents] Time taken : 0.0011930465698242 seconds. 15:19:06+1751267946.35530 [core,eventMgr:templateStop] Called 'onTemplateStop' event. 15:19:06+1751267946.35560 [core:req] Required file '/var/www/html/sso.opennet.com.sg/www/coreModules/singleSignOn/ssoSignIn.php'. 15:19:06+1751267946.35560 [core,eventMgr:modulePostProcess] Calling 'OnModulePostProcess' event. 15:19:06+1751267946.35590 [core,eventMgr:_callAllEvents] Time taken : 0.00026702880859375 seconds. 15:19:06+1751267946.35600 [core,eventMgr:modulePostProcess] Called 'OnModulePostProcess' event. 15:19:06+1751267946.35670 [core,objectMgr:shutdown] Total number of queries executed : 39. 15:19:06+1751267946.35670 [core,objectMgr:shutdown] Total time spent running queries : 0.025717258453369 seconds. 15:19:06+1751267946.35680 [core,objectMgr:shutdown] Total time spent fetching results : 0.00040364265441895 seconds. 15:19:06+1751267946.35680 [core,objectMgr:shutdown] Total time spent on database : 0.026120901107788 seconds. PHP Initialize time : 0.070187091827393 seconds. PHP processing time : 0.081372976303101 seconds. Debugging completed for "/singleSignOn/signIn/ServicePortal/f123bedbf1de0eef72686addc722fad6915cd983.30062025151904/b320c09949befddb54a8523c5fc450df/aHR0cHM6Ly9yZXN0cmljdGVkLm9wZW5uZXQuY29tLnNn"