[ https://issues.apache.org/jira/browse/OFBIZ-10320?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Jacques Le Roux updated OFBIZ-10320: ------------------------------------ Description: Work Steps: # In the login page: [https://www.myowndomain.com/webtools/control/main], then click login, then click "forget your passwords". # Then fill in the User Name that I have already created before, which is associated with a primary email address. # Then click "continue" and then hit "email password"; # As I have set the fields correctly in the file general.properties and also in database table ofbiz.system_property, I receive a system email telling me to "click here to reset password" with a link. Then click the link and following the instructions to fill in the new passwords and then the following error messages pop up. Generally speaking, the error is about java.lang.NullPointerException. (BTW, I tried it a couple of times with different locales like en and zh_CN but all failed.) Here below I highlighted somethings strange, where I have added some debugging texts (in yellow background) to see what's going on. Obviously, in my debugging text Tag ---801--- the keyValue is missing (null). Maybe that's why it throws a java.lang.NullPointerException. However, when I compare the codes (where it concerns) of EntityCrypto.java and LoginWorker.java between 11.04 and 11.03, {color:#f79232}I could not find the difference So I do NOT understand why it works in my 11.03 but not 11.04.{color} {code} 2018-03-28 10:00:59,870 |http-nio-443-exec-4 |ServerHitBin |I| Visit delegatorName=default, ServerHitBin delegatorName=default 2018-03-28 10:00:59,878 |http-nio-443-exec-4 |ControlServlet |T| [[[forgotPassword_step2(Domain:https://www.myowndomain.com)] Request Done- total:0.146,since last([forgotPassword_s...):0.146]] 2018-03-28 10:01:02,961 |http-nio-443-exec-1 |ControlServlet |T| [[[forgotPassword_step3(Domain:https://www.myowndomain.com)] Request Begun, encoding=[UTF-8]- total:0.0,since last(Begin):0.0]] {color:#f79232}2018-03-28 10:01:02,988 |http-nio-443-exec-1 |EntityCrypto |I| ---804---originalKeyName---is---loginSecretKeyString{color} {color:#f79232}2018-03-28 10:01:02,988 |http-nio-443-exec-1 |EntityCrypto |I| ---805---originalKeyName---is---loginSecretKeyString{color} {color:#f79232}2018-03-28 10:01:02,988 |http-nio-443-exec-1 |EntityCrypto |I| [---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@327dc55e|mailto:---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@327dc55e]{color} 2018-03-28 10:01:03,066 |http-nio-443-exec-1 |ServiceDispatcher |I| Service auth failed for userLoginId [MyCreatedUser] because UserLogin record currentPassword fields did not match; note that the UserLogin object passed into a service may need to have the currentPassword encrypted. 2018-03-28 10:01:03,067 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/getUserPreferenceGroup] finished in [2] milliseconds 2018-03-28 10:01:03,076 |http-nio-443-exec-1 |ScreenFactory |I| Got 1 screens in 0.009s from: file:/D:/apache-ofbiz-16.11.04/applications/securityext/widget/EmailSecurityScreens.xml 2018-03-28 10:01:03,137 |http-nio-443-exec-1 |UtilProperties |I| ResourceBundle EmailPasswordUiLabels (en) created in 0.061s with 6 properties 2018-03-28 10:01:03,217 |http-nio-443-exec-1 |UtilProperties |I| ResourceBundle SecurityUiLabels (en) created in 0.08s with 58 properties 2018-03-28 10:01:03,238 |http-nio-443-exec-1 |EmailServices |I| Expanded email subject to: New Password Sent (MyCreatedUser) 2018-03-28 10:01:07,710 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/sendMailHiddenInLog] finished in [4471] milliseconds 2018-03-28 10:01:07,711 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/sendMailHiddenInLogFromScreen] finished in [4678] milliseconds 2018-03-28 10:01:07,722 |http-nio-443-exec-1 |RequestHandler |I| Ran Event [java:org.apache.ofbiz.securityext.login.LoginEvents#forgotPassword] from [request], result is [success] 2018-03-28 10:01:07,738 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.001s, 16 requests, 14 views in file:/D:/apache-ofbiz-16.11.04/specialpurpose/birt/webapp/ordermgr/WEB-INF/controller.xml 2018-03-28 10:01:07,763 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.006s, 367 requests, 147 views in file:/D:/apache-ofbiz-16.11.04/applications/order/webapp/ordermgr/WEB-INF/controller.xml 2018-03-28 10:01:07,794 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.001s, 45 requests, 22 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/common-controller.xml 2018-03-28 10:01:07,808 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.0s, 0 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/handlers-controller.xml 2018-03-28 10:01:07,837 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.0s, 4 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/applications/commonext/webapp/WEB-INF/controller.xml 2018-03-28 10:01:07,860 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.005s, 379 requests, 154 views in file:/D:/apache-ofbiz-16.11.04/applications/content/webapp/content/WEB-INF/controller.xml 2018-03-28 10:01:07,911 |http-nio-443-exec-1 |RequestHandler |I| Rendering View [login]. Hidden sessionId by default. 2018-03-28 10:01:07,933 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/getVisualThemeResources] finished in [19] milliseconds 2018-03-28 10:01:07,949 |http-nio-443-exec-1 |ScreenFactory |I| Got 26 screens in 0.014s from: file:/D:/apache-ofbiz-16.11.04/framework/common/widget/CommonScreens.xml 2018-03-28 10:01:07,957 |http-nio-443-exec-1 |ScreenFactory |I| Got 4 screens in 0.007s from: file:/D:/apache-ofbiz-16.11.04/applications/order/widget/ordermgr/CommonScreens.xml 2018-03-28 10:01:07,966 |http-nio-443-exec-1 |ScreenFactory |I| Got 1 screens in 0.008s from: file:/D:/apache-ofbiz-16.11.04/applications/commonext/widget/CommonScreens.xml 2018-03-28 10:01:07,967 |http-nio-443-exec-1 |PrimaryKeyFinder |I| Returning null because found incomplete primary key in find: [GenericEntity:PartyNameView][partyId,null()] 2018-03-28 10:01:08,066 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/getLastSystemInfoNote] finished in [21] milliseconds 2018-03-28 10:01:08,106 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/getVisualThemeResources] finished in [31] milliseconds 2018-03-28 10:01:08,398 |http-nio-443-exec-1 |ServerHitBin |I| Visit delegatorName=default, ServerHitBin delegatorName=default 2018-03-28 10:01:08,411 |http-nio-443-exec-1 |ControlServlet |T| [[[forgotPassword_step3(Domain:https://www.myowndomain.com)] Request Done- total:5.45,since last([forgotPassword_s...):5.45]] 2018-03-28 10:01:43,926 |http-nio-443-exec-10 |ControlEventListener |I| Creating session: hidden sessionId by default. 2018-03-28 10:01:43,927 |http-nio-443-exec-10 |ControlServlet |T| [[[passwordChange(Domain:https://www.myowndomain.com)] Request Begun, encoding=[UTF-8]- total:0.0,since last(Begin):0.0]] 2018-03-28 10:01:43,930 |http-nio-443-exec-10 |VisitHandler |I| Found visitorId [10200] in cookie 2018-03-28 10:01:43,967 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.004s, 258 requests, 105 views in file:/D:/apache-ofbiz-16.11.04/applications/party/webapp/partymgr/WEB-INF/controller.xml 2018-03-28 10:01:43,983 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.001s, 45 requests, 22 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/common-controller.xml 2018-03-28 10:01:43,997 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.0s, 0 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/handlers-controller.xml 2018-03-28 10:01:44,013 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.0s, 30 requests, 13 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/security-controller.xml 2018-03-28 10:01:44,027 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.0s, 4 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/applications/commonext/webapp/WEB-INF/controller.xml 2018-03-28 10:01:44,051 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.006s, 379 requests, 154 views in file:/D:/apache-ofbiz-16.11.04/applications/content/webapp/content/WEB-INF/controller.xml 2018-03-28 10:01:44,052 |http-nio-443-exec-10 |RequestHandler |I| This is the first request in this visit. Hidden sessionId by default. 2018-03-28 10:01:44,056 |http-nio-443-exec-10 |RequestHandler |I| Rendering View [requirePasswordChange]. Hidden sessionId by default. 2018-03-28 10:01:44,061 |http-nio-443-exec-10 |ServiceDispatcher |T| Sync service [partymgr/getUserPreferenceGroup] finished in [1] milliseconds 2018-03-28 10:01:44,084 |http-nio-443-exec-10 |ServiceDispatcher |T| Sync service [partymgr/getVisualThemeResources] finished in [22] milliseconds 2018-03-28 10:01:44,121 |http-nio-443-exec-10 |ScreenFactory |I| Got 26 screens in 0.019s from: file:/D:/apache-ofbiz-16.11.04/framework/common/widget/CommonScreens.xml {color:#59afe1}2018-03-28 10:01:44,123 |http-nio-443-exec-10 |PrimaryKeyFinder |I| Returning null because found incomplete primary key in find: [GenericEntity:Enumeration][enumId,null()]{color} 2018-03-28 10:01:44,136 |http-nio-443-exec-10 |ScreenFactory |I| Got 4 screens in 0.012s from: file:/D:/apache-ofbiz-16.11.04/applications/party/widget/partymgr/CommonScreens.xml 2018-03-28 10:01:44,316 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle PartyUiLabels (zh_CN_#Hans) created in 0.18s with 0 properties 2018-03-28 10:01:44,476 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle MarketingUiLabels (zh_CN_#Hans) created in 0.16s with 281 properties 2018-03-28 10:01:44,800 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle AccountingUiLabels (zh_CN_#Hans) created in 0.261s with 0 properties 2018-03-28 10:01:44,924 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle WorkEffortUiLabels (zh_CN_#Hans) created in 0.123s with 0 properties 2018-03-28 10:01:45,030 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle ContentUiLabels (zh_CN_#Hans) created in 0.103s with 0 properties 2018-03-28 10:01:45,085 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle SecurityUiLabels (zh_CN_#Hans) created in 0.05s with 0 properties 2018-03-28 10:01:45,251 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle CommonUiLabels (zh_CN_#Hans) created in 0.165s with 0 properties 2018-03-28 10:01:45,413 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle OrderUiLabels (zh_CN_#Hans) created in 0.162s with 0 properties 2018-03-28 10:01:46,189 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle ProductUiLabels (zh_CN_#Hans) created in 0.775s with 0 properties 2018-03-28 10:01:46,307 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle HumanResUiLabels (zh_CN_#Hans) created in 0.117s with 319 properties 2018-03-28 10:01:46,316 |http-nio-443-exec-10 |ScreenFactory |I| Got 1 screens in 0.008s from: file:/D:/apache-ofbiz-16.11.04/applications/commonext/widget/CommonScreens.xml 2018-03-28 10:01:46,384 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle CommonExtUiLabels (zh_CN_#Hans) created in 0.068s with 0 properties 2018-03-28 10:01:46,385 |http-nio-443-exec-10 |PrimaryKeyFinder |I| Returning null because found incomplete primary key in find: [GenericEntity:PartyNameView][partyId,null()] 2018-03-28 10:01:46,471 |http-nio-443-exec-10 |ServiceDispatcher |T| Sync service [partymgr/getLastSystemInfoNote] finished in [14] milliseconds 2018-03-28 10:01:46,695 |http-nio-443-exec-10 |ServiceDispatcher |T| Sync service [partymgr/getVisualThemeResources] finished in [1] milliseconds 2018-03-28 10:01:47,791 |http-nio-443-exec-10 |ServerHitBin |I| Visit delegatorName=default, ServerHitBin delegatorName=default 2018-03-28 10:01:47,795 |http-nio-443-exec-10 |ControlServlet |T| [[[passwordChange(Domain:https://www.myowndomain.com)] Request Done- total:3.868,since last([passwordChange(D...):3.868]] 2018-03-28 10:02:01,101 |http-nio-443-exec-2 |ControlServlet |T| [[[login(Domain:https://www. myowndomain.com)] Request Begun, encoding=[UTF-8]- total:0.0,since last(Begin):0.0]] 2018-03-28 10:02:01,131 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.006s, 258 requests, 105 views in file:/D:/apache-ofbiz-16.11.04/applications/party/webapp/partymgr/WEB-INF/controller.xml 2018-03-28 10:02:01,150 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.0s, 45 requests, 22 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/common-controller.xml 2018-03-28 10:02:01,166 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.0s, 0 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/handlers-controller.xml 2018-03-28 10:02:01,181 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.0s, 30 requests, 13 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/security-controller.xml 2018-03-28 10:02:01,195 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.0s, 4 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/applications/commonext/webapp/WEB-INF/controller.xml 2018-03-28 10:02:01,235 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.021s, 379 requests, 154 views in file:/D:/apache-ofbiz-16.11.04/applications/content/webapp/content/WEB-INF/controller.xml {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |LoginWorker |I| ---801---KeyValue---is---{color} {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto |I| ---803---KeyName and encryptMethod and encryptedString---are--- - TRUE - aJ2+NE8uB+1IAeKogPERRw=={color} {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto |I| ---804---originalKeyName---is---{color} {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto |I| ---805---originalKeyName---is---{color} {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto |I| [---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@39915efb|mailto:---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@39915efb]{color} 2018-03-28 10:02:01,249 |http-nio-443-exec-2 |JavaEventHandler |E| Problems Processing Event java.lang.NullPointerException at org.apache.ofbiz.entity.util.EntityCrypto$ShiroStorageHandler.getHashedKeyName(EntityCrypto.java:253) ~[ofbiz.jar:?] at org.apache.ofbiz.entity.util.EntityCrypto.findKey(EntityCrypto.java:160) ~[ofbiz.jar:?] at org.apache.ofbiz.entity.util.EntityCrypto.doDecrypt(EntityCrypto.java:144) ~[ofbiz.jar:?] at org.apache.ofbiz.entity.util.EntityCrypto.decrypt(EntityCrypto.java:127) ~[ofbiz.jar:?] at org.apache.ofbiz.webapp.control.LoginWorker.login(LoginWorker.java:391) ~[ofbiz.jar:?] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_131] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_131] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_131] at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_131] at org.apache.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:100) [ofbiz.jar:?] at org.apache.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:80) [ofbiz.jar:?] at org.apache.ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:732) [ofbiz.jar:?] at org.apache.ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:453) [ofbiz.jar:?] at org.apache.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:210) [ofbiz.jar:?] at org.apache.ofbiz.webapp.control.ControlServlet.doPost(ControlServlet.java:85) [ofbiz.jar:?] at javax.servlet.http.HttpServlet.service(HttpServlet.java:707) [javax.servlet-api-3.1.0.jar:3.1.0] at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) [javax.servlet-api-3.1.0.jar:3.1.0] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:292) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) [tomcat-embed-websocket-8.0.39.jar:8.0.39] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:240) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:196) [ofbiz.jar:?] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:240) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.ofbiz.webapp.control.ControlFilter.doFilter(ControlFilter.java:156) [ofbiz.jar:?] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:240) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:212) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:94) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:504) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:141) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:88) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:620) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:502) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1104) [tomcat-coyote-8.0.42.jar:8.0.42] at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:684) [tomcat-coyote-8.0.42.jar:8.0.42] at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1519) [tomcat-coyote-8.0.42.jar:8.0.42] at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:1475) [tomcat-coyote-8.0.42.jar:8.0.42] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_131] at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) [tomcat-util-8.0.42.jar:8.0.42] at java.lang.Thread.run(Thread.java:748) [?:1.8.0_131] {code} was: Work Steps: # In the login page: [https://www.myowndomain.com/webtools/control/main], then click login, then click "forget your passwords". # Then fill in the User Name that I have already created before, which is associated with a primary email address. # Then click "continue" and then hit "email password"; # As I have set the fields correctly in the file general.properties and also in database table ofbiz.system_property, I receive a system email telling me to "click here to reset password" with a link. Then click the link and following the instructions to fill in the new passwords and then the following error messages pop up. Generally speaking, the error is about java.lang.NullPointerException. (BTW, I tried it a couple of times with different locales like en and zh_CN but all failed.) Here below I highlighted somethings strange, where I have added some debugging texts (in yellow background) to see what's going on. Obviously, in my debugging text Tag ---801--- the keyValue is missing (null). Maybe that's why it throws a java.lang.NullPointerException. However, when I compare the codes (where it concerns) of EntityCrypto.java and LoginWorker.java between 11.04 and 11.03, {color:#f79232}I could not find the difference So I do NOT understand why it works in my 11.03 but not 11.04.{color} 2018-03-28 10:00:59,870 |http-nio-443-exec-4 |ServerHitBin |I| Visit delegatorName=default, ServerHitBin delegatorName=default 2018-03-28 10:00:59,878 |http-nio-443-exec-4 |ControlServlet |T| [[[forgotPassword_step2(Domain:https://www.myowndomain.com)] Request Done- total:0.146,since last([forgotPassword_s...):0.146]] 2018-03-28 10:01:02,961 |http-nio-443-exec-1 |ControlServlet |T| [[[forgotPassword_step3(Domain:https://www.myowndomain.com)] Request Begun, encoding=[UTF-8]- total:0.0,since last(Begin):0.0]] {color:#f79232}2018-03-28 10:01:02,988 |http-nio-443-exec-1 |EntityCrypto |I| ---804---originalKeyName---is---loginSecretKeyString{color} {color:#f79232}2018-03-28 10:01:02,988 |http-nio-443-exec-1 |EntityCrypto |I| ---805---originalKeyName---is---loginSecretKeyString{color} {color:#f79232}2018-03-28 10:01:02,988 |http-nio-443-exec-1 |EntityCrypto |I| [---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@327dc55e|mailto:---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@327dc55e]{color} 2018-03-28 10:01:03,066 |http-nio-443-exec-1 |ServiceDispatcher |I| Service auth failed for userLoginId [MyCreatedUser] because UserLogin record currentPassword fields did not match; note that the UserLogin object passed into a service may need to have the currentPassword encrypted. 2018-03-28 10:01:03,067 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/getUserPreferenceGroup] finished in [2] milliseconds 2018-03-28 10:01:03,076 |http-nio-443-exec-1 |ScreenFactory |I| Got 1 screens in 0.009s from: file:/D:/apache-ofbiz-16.11.04/applications/securityext/widget/EmailSecurityScreens.xml 2018-03-28 10:01:03,137 |http-nio-443-exec-1 |UtilProperties |I| ResourceBundle EmailPasswordUiLabels (en) created in 0.061s with 6 properties 2018-03-28 10:01:03,217 |http-nio-443-exec-1 |UtilProperties |I| ResourceBundle SecurityUiLabels (en) created in 0.08s with 58 properties 2018-03-28 10:01:03,238 |http-nio-443-exec-1 |EmailServices |I| Expanded email subject to: New Password Sent (MyCreatedUser) 2018-03-28 10:01:07,710 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/sendMailHiddenInLog] finished in [4471] milliseconds 2018-03-28 10:01:07,711 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/sendMailHiddenInLogFromScreen] finished in [4678] milliseconds 2018-03-28 10:01:07,722 |http-nio-443-exec-1 |RequestHandler |I| Ran Event [java:org.apache.ofbiz.securityext.login.LoginEvents#forgotPassword] from [request], result is [success] 2018-03-28 10:01:07,738 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.001s, 16 requests, 14 views in file:/D:/apache-ofbiz-16.11.04/specialpurpose/birt/webapp/ordermgr/WEB-INF/controller.xml 2018-03-28 10:01:07,763 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.006s, 367 requests, 147 views in file:/D:/apache-ofbiz-16.11.04/applications/order/webapp/ordermgr/WEB-INF/controller.xml 2018-03-28 10:01:07,794 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.001s, 45 requests, 22 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/common-controller.xml 2018-03-28 10:01:07,808 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.0s, 0 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/handlers-controller.xml 2018-03-28 10:01:07,837 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.0s, 4 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/applications/commonext/webapp/WEB-INF/controller.xml 2018-03-28 10:01:07,860 |http-nio-443-exec-1 |ConfigXMLReader |I| controller loaded: 0.005s, 379 requests, 154 views in file:/D:/apache-ofbiz-16.11.04/applications/content/webapp/content/WEB-INF/controller.xml 2018-03-28 10:01:07,911 |http-nio-443-exec-1 |RequestHandler |I| Rendering View [login]. Hidden sessionId by default. 2018-03-28 10:01:07,933 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/getVisualThemeResources] finished in [19] milliseconds 2018-03-28 10:01:07,949 |http-nio-443-exec-1 |ScreenFactory |I| Got 26 screens in 0.014s from: file:/D:/apache-ofbiz-16.11.04/framework/common/widget/CommonScreens.xml 2018-03-28 10:01:07,957 |http-nio-443-exec-1 |ScreenFactory |I| Got 4 screens in 0.007s from: file:/D:/apache-ofbiz-16.11.04/applications/order/widget/ordermgr/CommonScreens.xml 2018-03-28 10:01:07,966 |http-nio-443-exec-1 |ScreenFactory |I| Got 1 screens in 0.008s from: file:/D:/apache-ofbiz-16.11.04/applications/commonext/widget/CommonScreens.xml 2018-03-28 10:01:07,967 |http-nio-443-exec-1 |PrimaryKeyFinder |I| Returning null because found incomplete primary key in find: [GenericEntity:PartyNameView][partyId,null()] 2018-03-28 10:01:08,066 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/getLastSystemInfoNote] finished in [21] milliseconds 2018-03-28 10:01:08,106 |http-nio-443-exec-1 |ServiceDispatcher |T| Sync service [order/getVisualThemeResources] finished in [31] milliseconds 2018-03-28 10:01:08,398 |http-nio-443-exec-1 |ServerHitBin |I| Visit delegatorName=default, ServerHitBin delegatorName=default 2018-03-28 10:01:08,411 |http-nio-443-exec-1 |ControlServlet |T| [[[forgotPassword_step3(Domain:https://www.myowndomain.com)] Request Done- total:5.45,since last([forgotPassword_s...):5.45]] 2018-03-28 10:01:43,926 |http-nio-443-exec-10 |ControlEventListener |I| Creating session: hidden sessionId by default. 2018-03-28 10:01:43,927 |http-nio-443-exec-10 |ControlServlet |T| [[[passwordChange(Domain:https://www.myowndomain.com)] Request Begun, encoding=[UTF-8]- total:0.0,since last(Begin):0.0]] 2018-03-28 10:01:43,930 |http-nio-443-exec-10 |VisitHandler |I| Found visitorId [10200] in cookie 2018-03-28 10:01:43,967 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.004s, 258 requests, 105 views in file:/D:/apache-ofbiz-16.11.04/applications/party/webapp/partymgr/WEB-INF/controller.xml 2018-03-28 10:01:43,983 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.001s, 45 requests, 22 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/common-controller.xml 2018-03-28 10:01:43,997 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.0s, 0 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/handlers-controller.xml 2018-03-28 10:01:44,013 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.0s, 30 requests, 13 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/security-controller.xml 2018-03-28 10:01:44,027 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.0s, 4 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/applications/commonext/webapp/WEB-INF/controller.xml 2018-03-28 10:01:44,051 |http-nio-443-exec-10 |ConfigXMLReader |I| controller loaded: 0.006s, 379 requests, 154 views in file:/D:/apache-ofbiz-16.11.04/applications/content/webapp/content/WEB-INF/controller.xml 2018-03-28 10:01:44,052 |http-nio-443-exec-10 |RequestHandler |I| This is the first request in this visit. Hidden sessionId by default. 2018-03-28 10:01:44,056 |http-nio-443-exec-10 |RequestHandler |I| Rendering View [requirePasswordChange]. Hidden sessionId by default. 2018-03-28 10:01:44,061 |http-nio-443-exec-10 |ServiceDispatcher |T| Sync service [partymgr/getUserPreferenceGroup] finished in [1] milliseconds 2018-03-28 10:01:44,084 |http-nio-443-exec-10 |ServiceDispatcher |T| Sync service [partymgr/getVisualThemeResources] finished in [22] milliseconds 2018-03-28 10:01:44,121 |http-nio-443-exec-10 |ScreenFactory |I| Got 26 screens in 0.019s from: file:/D:/apache-ofbiz-16.11.04/framework/common/widget/CommonScreens.xml {color:#59afe1}2018-03-28 10:01:44,123 |http-nio-443-exec-10 |PrimaryKeyFinder |I| Returning null because found incomplete primary key in find: [GenericEntity:Enumeration][enumId,null()]{color} 2018-03-28 10:01:44,136 |http-nio-443-exec-10 |ScreenFactory |I| Got 4 screens in 0.012s from: file:/D:/apache-ofbiz-16.11.04/applications/party/widget/partymgr/CommonScreens.xml 2018-03-28 10:01:44,316 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle PartyUiLabels (zh_CN_#Hans) created in 0.18s with 0 properties 2018-03-28 10:01:44,476 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle MarketingUiLabels (zh_CN_#Hans) created in 0.16s with 281 properties 2018-03-28 10:01:44,800 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle AccountingUiLabels (zh_CN_#Hans) created in 0.261s with 0 properties 2018-03-28 10:01:44,924 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle WorkEffortUiLabels (zh_CN_#Hans) created in 0.123s with 0 properties 2018-03-28 10:01:45,030 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle ContentUiLabels (zh_CN_#Hans) created in 0.103s with 0 properties 2018-03-28 10:01:45,085 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle SecurityUiLabels (zh_CN_#Hans) created in 0.05s with 0 properties 2018-03-28 10:01:45,251 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle CommonUiLabels (zh_CN_#Hans) created in 0.165s with 0 properties 2018-03-28 10:01:45,413 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle OrderUiLabels (zh_CN_#Hans) created in 0.162s with 0 properties 2018-03-28 10:01:46,189 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle ProductUiLabels (zh_CN_#Hans) created in 0.775s with 0 properties 2018-03-28 10:01:46,307 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle HumanResUiLabels (zh_CN_#Hans) created in 0.117s with 319 properties 2018-03-28 10:01:46,316 |http-nio-443-exec-10 |ScreenFactory |I| Got 1 screens in 0.008s from: file:/D:/apache-ofbiz-16.11.04/applications/commonext/widget/CommonScreens.xml 2018-03-28 10:01:46,384 |http-nio-443-exec-10 |UtilProperties |I| ResourceBundle CommonExtUiLabels (zh_CN_#Hans) created in 0.068s with 0 properties 2018-03-28 10:01:46,385 |http-nio-443-exec-10 |PrimaryKeyFinder |I| Returning null because found incomplete primary key in find: [GenericEntity:PartyNameView][partyId,null()] 2018-03-28 10:01:46,471 |http-nio-443-exec-10 |ServiceDispatcher |T| Sync service [partymgr/getLastSystemInfoNote] finished in [14] milliseconds 2018-03-28 10:01:46,695 |http-nio-443-exec-10 |ServiceDispatcher |T| Sync service [partymgr/getVisualThemeResources] finished in [1] milliseconds 2018-03-28 10:01:47,791 |http-nio-443-exec-10 |ServerHitBin |I| Visit delegatorName=default, ServerHitBin delegatorName=default 2018-03-28 10:01:47,795 |http-nio-443-exec-10 |ControlServlet |T| [[[passwordChange(Domain:https://www.myowndomain.com)] Request Done- total:3.868,since last([passwordChange(D...):3.868]] 2018-03-28 10:02:01,101 |http-nio-443-exec-2 |ControlServlet |T| [[[login(Domain:https://www. myowndomain.com)] Request Begun, encoding=[UTF-8]- total:0.0,since last(Begin):0.0]] 2018-03-28 10:02:01,131 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.006s, 258 requests, 105 views in file:/D:/apache-ofbiz-16.11.04/applications/party/webapp/partymgr/WEB-INF/controller.xml 2018-03-28 10:02:01,150 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.0s, 45 requests, 22 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/common-controller.xml 2018-03-28 10:02:01,166 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.0s, 0 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/handlers-controller.xml 2018-03-28 10:02:01,181 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.0s, 30 requests, 13 views in file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/security-controller.xml 2018-03-28 10:02:01,195 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.0s, 4 requests, 0 views in file:/D:/apache-ofbiz-16.11.04/applications/commonext/webapp/WEB-INF/controller.xml 2018-03-28 10:02:01,235 |http-nio-443-exec-2 |ConfigXMLReader |I| controller loaded: 0.021s, 379 requests, 154 views in file:/D:/apache-ofbiz-16.11.04/applications/content/webapp/content/WEB-INF/controller.xml {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |LoginWorker |I| ---801---KeyValue---is---{color} {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto |I| ---803---KeyName and encryptMethod and encryptedString---are--- - TRUE - aJ2+NE8uB+1IAeKogPERRw=={color} {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto |I| ---804---originalKeyName---is---{color} {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto |I| ---805---originalKeyName---is---{color} {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto |I| [---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@39915efb|mailto:---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@39915efb]{color} 2018-03-28 10:02:01,249 |http-nio-443-exec-2 |JavaEventHandler |E| Problems Processing Event java.lang.NullPointerException at org.apache.ofbiz.entity.util.EntityCrypto$ShiroStorageHandler.getHashedKeyName(EntityCrypto.java:253) ~[ofbiz.jar:?] at org.apache.ofbiz.entity.util.EntityCrypto.findKey(EntityCrypto.java:160) ~[ofbiz.jar:?] at org.apache.ofbiz.entity.util.EntityCrypto.doDecrypt(EntityCrypto.java:144) ~[ofbiz.jar:?] at org.apache.ofbiz.entity.util.EntityCrypto.decrypt(EntityCrypto.java:127) ~[ofbiz.jar:?] at org.apache.ofbiz.webapp.control.LoginWorker.login(LoginWorker.java:391) ~[ofbiz.jar:?] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_131] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_131] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_131] at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_131] at org.apache.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:100) [ofbiz.jar:?] at org.apache.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:80) [ofbiz.jar:?] at org.apache.ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:732) [ofbiz.jar:?] at org.apache.ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:453) [ofbiz.jar:?] at org.apache.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:210) [ofbiz.jar:?] at org.apache.ofbiz.webapp.control.ControlServlet.doPost(ControlServlet.java:85) [ofbiz.jar:?] at javax.servlet.http.HttpServlet.service(HttpServlet.java:707) [javax.servlet-api-3.1.0.jar:3.1.0] at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) [javax.servlet-api-3.1.0.jar:3.1.0] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:292) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) [tomcat-embed-websocket-8.0.39.jar:8.0.39] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:240) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:196) [ofbiz.jar:?] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:240) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.ofbiz.webapp.control.ControlFilter.doFilter(ControlFilter.java:156) [ofbiz.jar:?] at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:240) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:212) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:94) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:504) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:141) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:88) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:620) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:502) [tomcat-catalina-8.0.42.jar:8.0.42] at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1104) [tomcat-coyote-8.0.42.jar:8.0.42] at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:684) [tomcat-coyote-8.0.42.jar:8.0.42] at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1519) [tomcat-coyote-8.0.42.jar:8.0.42] at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:1475) [tomcat-coyote-8.0.42.jar:8.0.42] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_131] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_131] at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) [tomcat-util-8.0.42.jar:8.0.42] at java.lang.Thread.run(Thread.java:748) [?:1.8.0_131] > A potential bug in Email ForgotPassword Notification > ---------------------------------------------------- > > Key: OFBIZ-10320 > URL: https://issues.apache.org/jira/browse/OFBIZ-10320 > Project: OFBiz > Issue Type: Bug > Affects Versions: 16.11.04 > Environment: OS: Windows Server 2008 R2 > Database: MySQL 5.7 > JDK: 1.8.0_131 > Reporter: Schumann Ye > Priority: Minor > > Work Steps: > # In the login page: [https://www.myowndomain.com/webtools/control/main], > then click login, then click "forget your passwords". > # Then fill in the User Name that I have already created before, which is > associated with a primary email address. > # Then click "continue" and then hit "email password"; > # As I have set the fields correctly in the file general.properties and also > in database table ofbiz.system_property, I receive a system email telling me > to "click here to reset password" with a link. > Then click the link and following the instructions to fill in the new > passwords and then the following error messages pop up. Generally speaking, > the error is about java.lang.NullPointerException. > (BTW, I tried it a couple of times with different locales like en and zh_CN > but all failed.) > > Here below I highlighted somethings strange, where I have added some > debugging texts (in yellow background) to see what's going on. > Obviously, in my debugging text Tag ---801--- the keyValue is missing (null). > Maybe that's why it throws a java.lang.NullPointerException. > > However, when I compare the codes (where it concerns) of EntityCrypto.java > and LoginWorker.java between 11.04 and 11.03, {color:#f79232}I could not find > the difference So I do NOT understand why it works in my 11.03 but not > 11.04.{color} > > > {code} > 2018-03-28 10:00:59,870 |http-nio-443-exec-4 |ServerHitBin > |I| Visit delegatorName=default, ServerHitBin delegatorName=default > 2018-03-28 10:00:59,878 |http-nio-443-exec-4 |ControlServlet > |T| [[[forgotPassword_step2(Domain:https://www.myowndomain.com)] Request > Done- total:0.146,since last([forgotPassword_s...):0.146]] > 2018-03-28 10:01:02,961 |http-nio-443-exec-1 |ControlServlet > |T| [[[forgotPassword_step3(Domain:https://www.myowndomain.com)] Request > Begun, encoding=[UTF-8]- total:0.0,since last(Begin):0.0]] > {color:#f79232}2018-03-28 10:01:02,988 |http-nio-443-exec-1 |EntityCrypto > |I| ---804---originalKeyName---is---loginSecretKeyString{color} > {color:#f79232}2018-03-28 10:01:02,988 |http-nio-443-exec-1 |EntityCrypto > |I| ---805---originalKeyName---is---loginSecretKeyString{color} > {color:#f79232}2018-03-28 10:01:02,988 |http-nio-443-exec-1 |EntityCrypto > |I| > [---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@327dc55e|mailto:---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@327dc55e]{color} > 2018-03-28 10:01:03,066 |http-nio-443-exec-1 |ServiceDispatcher > |I| Service auth failed for userLoginId [MyCreatedUser] because UserLogin > record currentPassword fields did not match; note that the UserLogin object > passed into a service may need to have the currentPassword encrypted. > 2018-03-28 10:01:03,067 |http-nio-443-exec-1 |ServiceDispatcher > |T| Sync service [order/getUserPreferenceGroup] finished in [2] milliseconds > 2018-03-28 10:01:03,076 |http-nio-443-exec-1 |ScreenFactory > |I| Got 1 screens in 0.009s from: > file:/D:/apache-ofbiz-16.11.04/applications/securityext/widget/EmailSecurityScreens.xml > 2018-03-28 10:01:03,137 |http-nio-443-exec-1 |UtilProperties > |I| ResourceBundle EmailPasswordUiLabels (en) created in 0.061s with 6 > properties > 2018-03-28 10:01:03,217 |http-nio-443-exec-1 |UtilProperties > |I| ResourceBundle SecurityUiLabels (en) created in 0.08s with 58 properties > 2018-03-28 10:01:03,238 |http-nio-443-exec-1 |EmailServices > |I| Expanded email subject to: New Password Sent (MyCreatedUser) > 2018-03-28 10:01:07,710 |http-nio-443-exec-1 |ServiceDispatcher > |T| Sync service [order/sendMailHiddenInLog] finished in [4471] milliseconds > 2018-03-28 10:01:07,711 |http-nio-443-exec-1 |ServiceDispatcher > |T| Sync service [order/sendMailHiddenInLogFromScreen] finished in [4678] > milliseconds > 2018-03-28 10:01:07,722 |http-nio-443-exec-1 |RequestHandler > |I| Ran Event > [java:org.apache.ofbiz.securityext.login.LoginEvents#forgotPassword] from > [request], result is [success] > 2018-03-28 10:01:07,738 |http-nio-443-exec-1 |ConfigXMLReader > |I| controller loaded: 0.001s, 16 requests, 14 views in > file:/D:/apache-ofbiz-16.11.04/specialpurpose/birt/webapp/ordermgr/WEB-INF/controller.xml > 2018-03-28 10:01:07,763 |http-nio-443-exec-1 |ConfigXMLReader > |I| controller loaded: 0.006s, 367 requests, 147 views in > file:/D:/apache-ofbiz-16.11.04/applications/order/webapp/ordermgr/WEB-INF/controller.xml > 2018-03-28 10:01:07,794 |http-nio-443-exec-1 |ConfigXMLReader > |I| controller loaded: 0.001s, 45 requests, 22 views in > file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/common-controller.xml > 2018-03-28 10:01:07,808 |http-nio-443-exec-1 |ConfigXMLReader > |I| controller loaded: 0.0s, 0 requests, 0 views in > file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/handlers-controller.xml > 2018-03-28 10:01:07,837 |http-nio-443-exec-1 |ConfigXMLReader > |I| controller loaded: 0.0s, 4 requests, 0 views in > file:/D:/apache-ofbiz-16.11.04/applications/commonext/webapp/WEB-INF/controller.xml > 2018-03-28 10:01:07,860 |http-nio-443-exec-1 |ConfigXMLReader > |I| controller loaded: 0.005s, 379 requests, 154 views in > file:/D:/apache-ofbiz-16.11.04/applications/content/webapp/content/WEB-INF/controller.xml > 2018-03-28 10:01:07,911 |http-nio-443-exec-1 |RequestHandler > |I| Rendering View [login]. Hidden sessionId by default. > 2018-03-28 10:01:07,933 |http-nio-443-exec-1 |ServiceDispatcher > |T| Sync service [order/getVisualThemeResources] finished in [19] milliseconds > 2018-03-28 10:01:07,949 |http-nio-443-exec-1 |ScreenFactory > |I| Got 26 screens in 0.014s from: > file:/D:/apache-ofbiz-16.11.04/framework/common/widget/CommonScreens.xml > 2018-03-28 10:01:07,957 |http-nio-443-exec-1 |ScreenFactory > |I| Got 4 screens in 0.007s from: > file:/D:/apache-ofbiz-16.11.04/applications/order/widget/ordermgr/CommonScreens.xml > 2018-03-28 10:01:07,966 |http-nio-443-exec-1 |ScreenFactory > |I| Got 1 screens in 0.008s from: > file:/D:/apache-ofbiz-16.11.04/applications/commonext/widget/CommonScreens.xml > 2018-03-28 10:01:07,967 |http-nio-443-exec-1 |PrimaryKeyFinder > |I| Returning null because found incomplete primary key in find: > [GenericEntity:PartyNameView][partyId,null()] > 2018-03-28 10:01:08,066 |http-nio-443-exec-1 |ServiceDispatcher > |T| Sync service [order/getLastSystemInfoNote] finished in [21] milliseconds > 2018-03-28 10:01:08,106 |http-nio-443-exec-1 |ServiceDispatcher > |T| Sync service [order/getVisualThemeResources] finished in [31] milliseconds > 2018-03-28 10:01:08,398 |http-nio-443-exec-1 |ServerHitBin > |I| Visit delegatorName=default, ServerHitBin delegatorName=default > 2018-03-28 10:01:08,411 |http-nio-443-exec-1 |ControlServlet > |T| [[[forgotPassword_step3(Domain:https://www.myowndomain.com)] Request > Done- total:5.45,since last([forgotPassword_s...):5.45]] > 2018-03-28 10:01:43,926 |http-nio-443-exec-10 |ControlEventListener > |I| Creating session: hidden sessionId by default. > 2018-03-28 10:01:43,927 |http-nio-443-exec-10 |ControlServlet > |T| [[[passwordChange(Domain:https://www.myowndomain.com)] Request Begun, > encoding=[UTF-8]- total:0.0,since last(Begin):0.0]] > 2018-03-28 10:01:43,930 |http-nio-443-exec-10 |VisitHandler > |I| Found visitorId [10200] in cookie > 2018-03-28 10:01:43,967 |http-nio-443-exec-10 |ConfigXMLReader > |I| controller loaded: 0.004s, 258 requests, 105 views in > file:/D:/apache-ofbiz-16.11.04/applications/party/webapp/partymgr/WEB-INF/controller.xml > 2018-03-28 10:01:43,983 |http-nio-443-exec-10 |ConfigXMLReader > |I| controller loaded: 0.001s, 45 requests, 22 views in > file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/common-controller.xml > 2018-03-28 10:01:43,997 |http-nio-443-exec-10 |ConfigXMLReader > |I| controller loaded: 0.0s, 0 requests, 0 views in > file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/handlers-controller.xml > 2018-03-28 10:01:44,013 |http-nio-443-exec-10 |ConfigXMLReader > |I| controller loaded: 0.0s, 30 requests, 13 views in > file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/security-controller.xml > 2018-03-28 10:01:44,027 |http-nio-443-exec-10 |ConfigXMLReader > |I| controller loaded: 0.0s, 4 requests, 0 views in > file:/D:/apache-ofbiz-16.11.04/applications/commonext/webapp/WEB-INF/controller.xml > 2018-03-28 10:01:44,051 |http-nio-443-exec-10 |ConfigXMLReader > |I| controller loaded: 0.006s, 379 requests, 154 views in > file:/D:/apache-ofbiz-16.11.04/applications/content/webapp/content/WEB-INF/controller.xml > 2018-03-28 10:01:44,052 |http-nio-443-exec-10 |RequestHandler > |I| This is the first request in this visit. Hidden sessionId by default. > 2018-03-28 10:01:44,056 |http-nio-443-exec-10 |RequestHandler > |I| Rendering View [requirePasswordChange]. Hidden sessionId by default. > 2018-03-28 10:01:44,061 |http-nio-443-exec-10 |ServiceDispatcher > |T| Sync service [partymgr/getUserPreferenceGroup] finished in [1] > milliseconds > 2018-03-28 10:01:44,084 |http-nio-443-exec-10 |ServiceDispatcher > |T| Sync service [partymgr/getVisualThemeResources] finished in [22] > milliseconds > 2018-03-28 10:01:44,121 |http-nio-443-exec-10 |ScreenFactory > |I| Got 26 screens in 0.019s from: > file:/D:/apache-ofbiz-16.11.04/framework/common/widget/CommonScreens.xml > {color:#59afe1}2018-03-28 10:01:44,123 |http-nio-443-exec-10 > |PrimaryKeyFinder |I| Returning null because found incomplete > primary key in find: [GenericEntity:Enumeration][enumId,null()]{color} > 2018-03-28 10:01:44,136 |http-nio-443-exec-10 |ScreenFactory > |I| Got 4 screens in 0.012s from: > file:/D:/apache-ofbiz-16.11.04/applications/party/widget/partymgr/CommonScreens.xml > 2018-03-28 10:01:44,316 |http-nio-443-exec-10 |UtilProperties > |I| ResourceBundle PartyUiLabels (zh_CN_#Hans) created in 0.18s with 0 > properties > 2018-03-28 10:01:44,476 |http-nio-443-exec-10 |UtilProperties > |I| ResourceBundle MarketingUiLabels (zh_CN_#Hans) created in 0.16s with 281 > properties > 2018-03-28 10:01:44,800 |http-nio-443-exec-10 |UtilProperties > |I| ResourceBundle AccountingUiLabels (zh_CN_#Hans) created in 0.261s with 0 > properties > 2018-03-28 10:01:44,924 |http-nio-443-exec-10 |UtilProperties > |I| ResourceBundle WorkEffortUiLabels (zh_CN_#Hans) created in 0.123s with 0 > properties > 2018-03-28 10:01:45,030 |http-nio-443-exec-10 |UtilProperties > |I| ResourceBundle ContentUiLabels (zh_CN_#Hans) created in 0.103s with 0 > properties > 2018-03-28 10:01:45,085 |http-nio-443-exec-10 |UtilProperties > |I| ResourceBundle SecurityUiLabels (zh_CN_#Hans) created in 0.05s with 0 > properties > 2018-03-28 10:01:45,251 |http-nio-443-exec-10 |UtilProperties > |I| ResourceBundle CommonUiLabels (zh_CN_#Hans) created in 0.165s with 0 > properties > 2018-03-28 10:01:45,413 |http-nio-443-exec-10 |UtilProperties > |I| ResourceBundle OrderUiLabels (zh_CN_#Hans) created in 0.162s with 0 > properties > 2018-03-28 10:01:46,189 |http-nio-443-exec-10 |UtilProperties > |I| ResourceBundle ProductUiLabels (zh_CN_#Hans) created in 0.775s with 0 > properties > 2018-03-28 10:01:46,307 |http-nio-443-exec-10 |UtilProperties > |I| ResourceBundle HumanResUiLabels (zh_CN_#Hans) created in 0.117s with 319 > properties > 2018-03-28 10:01:46,316 |http-nio-443-exec-10 |ScreenFactory > |I| Got 1 screens in 0.008s from: > file:/D:/apache-ofbiz-16.11.04/applications/commonext/widget/CommonScreens.xml > 2018-03-28 10:01:46,384 |http-nio-443-exec-10 |UtilProperties > |I| ResourceBundle CommonExtUiLabels (zh_CN_#Hans) created in 0.068s with 0 > properties > 2018-03-28 10:01:46,385 |http-nio-443-exec-10 |PrimaryKeyFinder > |I| Returning null because found incomplete primary key in find: > [GenericEntity:PartyNameView][partyId,null()] > 2018-03-28 10:01:46,471 |http-nio-443-exec-10 |ServiceDispatcher > |T| Sync service [partymgr/getLastSystemInfoNote] finished in [14] > milliseconds > 2018-03-28 10:01:46,695 |http-nio-443-exec-10 |ServiceDispatcher > |T| Sync service [partymgr/getVisualThemeResources] finished in [1] > milliseconds > 2018-03-28 10:01:47,791 |http-nio-443-exec-10 |ServerHitBin > |I| Visit delegatorName=default, ServerHitBin delegatorName=default > 2018-03-28 10:01:47,795 |http-nio-443-exec-10 |ControlServlet > |T| [[[passwordChange(Domain:https://www.myowndomain.com)] Request Done- > total:3.868,since last([passwordChange(D...):3.868]] > 2018-03-28 10:02:01,101 |http-nio-443-exec-2 |ControlServlet > |T| [[[login(Domain:https://www. myowndomain.com)] Request Begun, > encoding=[UTF-8]- total:0.0,since last(Begin):0.0]] > 2018-03-28 10:02:01,131 |http-nio-443-exec-2 |ConfigXMLReader > |I| controller loaded: 0.006s, 258 requests, 105 views in > file:/D:/apache-ofbiz-16.11.04/applications/party/webapp/partymgr/WEB-INF/controller.xml > 2018-03-28 10:02:01,150 |http-nio-443-exec-2 |ConfigXMLReader > |I| controller loaded: 0.0s, 45 requests, 22 views in > file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/common-controller.xml > 2018-03-28 10:02:01,166 |http-nio-443-exec-2 |ConfigXMLReader > |I| controller loaded: 0.0s, 0 requests, 0 views in > file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/handlers-controller.xml > 2018-03-28 10:02:01,181 |http-nio-443-exec-2 |ConfigXMLReader > |I| controller loaded: 0.0s, 30 requests, 13 views in > file:/D:/apache-ofbiz-16.11.04/framework/common/webcommon/WEB-INF/security-controller.xml > 2018-03-28 10:02:01,195 |http-nio-443-exec-2 |ConfigXMLReader > |I| controller loaded: 0.0s, 4 requests, 0 views in > file:/D:/apache-ofbiz-16.11.04/applications/commonext/webapp/WEB-INF/controller.xml > 2018-03-28 10:02:01,235 |http-nio-443-exec-2 |ConfigXMLReader > |I| controller loaded: 0.021s, 379 requests, 154 views in > file:/D:/apache-ofbiz-16.11.04/applications/content/webapp/content/WEB-INF/controller.xml > {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |LoginWorker > |I| ---801---KeyValue---is---{color} > {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto > |I| ---803---KeyName and encryptMethod and > encryptedString---are--- - TRUE - aJ2+NE8uB+1IAeKogPERRw=={color} > {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto > |I| ---804---originalKeyName---is---{color} > {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto > |I| ---805---originalKeyName---is---{color} > {color:#f79232}2018-03-28 10:02:01,249 |http-nio-443-exec-2 |EntityCrypto > |I| > [---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@39915efb|mailto:---806---hashRequest---is---org.apache.shiro.crypto.hash.SimpleHashRequest@39915efb]{color} > 2018-03-28 10:02:01,249 |http-nio-443-exec-2 |JavaEventHandler > |E| Problems Processing Event > java.lang.NullPointerException > at > org.apache.ofbiz.entity.util.EntityCrypto$ShiroStorageHandler.getHashedKeyName(EntityCrypto.java:253) > ~[ofbiz.jar:?] > at > org.apache.ofbiz.entity.util.EntityCrypto.findKey(EntityCrypto.java:160) > ~[ofbiz.jar:?] > at > org.apache.ofbiz.entity.util.EntityCrypto.doDecrypt(EntityCrypto.java:144) > ~[ofbiz.jar:?] > at > org.apache.ofbiz.entity.util.EntityCrypto.decrypt(EntityCrypto.java:127) > ~[ofbiz.jar:?] > at > org.apache.ofbiz.webapp.control.LoginWorker.login(LoginWorker.java:391) > ~[ofbiz.jar:?] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > ~[?:1.8.0_131] > at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) > ~[?:1.8.0_131] > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > ~[?:1.8.0_131] > at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_131] > at > org.apache.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:100) > [ofbiz.jar:?] > at > org.apache.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:80) > [ofbiz.jar:?] > at > org.apache.ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:732) > [ofbiz.jar:?] > at > org.apache.ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:453) > [ofbiz.jar:?] > at > org.apache.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:210) > [ofbiz.jar:?] > at > org.apache.ofbiz.webapp.control.ControlServlet.doPost(ControlServlet.java:85) > [ofbiz.jar:?] > at javax.servlet.http.HttpServlet.service(HttpServlet.java:707) > [javax.servlet-api-3.1.0.jar:3.1.0] > at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) > [javax.servlet-api-3.1.0.jar:3.1.0] > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:292) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) > [tomcat-catalina-8.0.42.jar:8.0.42] > at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:52) > [tomcat-embed-websocket-8.0.39.jar:8.0.39] > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:240) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:196) > [ofbiz.jar:?] > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:240) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.ofbiz.webapp.control.ControlFilter.doFilter(ControlFilter.java:156) > [ofbiz.jar:?] > at > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:240) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:207) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:212) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:94) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:504) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:141) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:88) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:620) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:502) > [tomcat-catalina-8.0.42.jar:8.0.42] > at > org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1104) > [tomcat-coyote-8.0.42.jar:8.0.42] > at > org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:684) > [tomcat-coyote-8.0.42.jar:8.0.42] > at > org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1519) > [tomcat-coyote-8.0.42.jar:8.0.42] > at > org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:1475) > [tomcat-coyote-8.0.42.jar:8.0.42] > at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > [?:1.8.0_131] > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > [?:1.8.0_131] > at > org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) > [tomcat-util-8.0.42.jar:8.0.42] > at java.lang.Thread.run(Thread.java:748) [?:1.8.0_131] > {code} > > -- This message was sent by Atlassian JIRA (v7.6.3#76005)