Newly created user cannot change password

The realm, reseller2, has 2 users. One was created with the onboard script, and the other was created with the create-user script. I cannot change the password of both users.

User created with orboard script: jclarke@thesearchagency.com
User created with create-user script: jelliott@thesearchagency.com

Actual result: Users are seen in the db, and seem to be set up correctly, but when I go to change the password, the UI says: Your email address was not recognized.

Expected result: The user would be able to create a new password and log into the system.

Users in the db:
mysql> select * from users where realm_id= 'com.reseller2' limit 2 \G

• • • • • • • • • • • • • • • • • • • • • • • • • • • 1. row ***************************
                                                      id: 1b0e812f-3a8a-416f-9049-721acb8509bf
                                                      realm_id: com.reseller2
                                                      type: P
                                                      first_name:
                                                      last_name:
                                                      display: Reseller2
                                                      description: Reseller user for Reseller2
                                                      created_on: 2013-02-14 13:59:06
                                                      created_by: c7542d20-f6ba-4784-842b-9202a92190e2
                                                      modified_on: 2013-02-14 13:59:06
                                                      modified_by: c7542d20-f6ba-4784-842b-9202a92190e2
                                                      active: T
                                                      password: 75bb2f360e75cb8bd91160f45232d0e1e5efcf629121a0c035489102499214bf4251e21f6b920323cbcf9b1d98c3b3c0f46e5eb974402c9d9572ec5e45edf607
                                                      salt: 496620796f75206265636f6d6520746f6f20736572696f75732c20796f752077696c6c206c6f736520796f7572207761792e
                                                      organization:
                                                      language:
                                                      country:
                                                      timezone:
                                                      gender: M
                                                      birthday: NULL
                                                      login: 1970-01-01 00:00:00
                                                      email: jclarke@thesearchagency.com
                                                      homepage:
                                                      home_phone:
                                                      work_phone: 9787897899
                                                      mobile_phone:
                                                    • 2. row ***************************
                                                      id: 7bf6cf7d-7410-46c9-8238-be27f89ff21a
                                                      realm_id: com.reseller2
                                                      type: P
                                                      first_name:
                                                      last_name:
                                                      display: jen user2
                                                      description:
                                                      created_on: 2013-02-14 15:22:10
                                                      created_by: c7542d20-f6ba-4784-842b-9202a92190e2
                                                      modified_on: 2013-02-14 15:22:10
                                                      modified_by: c7542d20-f6ba-4784-842b-9202a92190e2
                                                      active: T
                                                      password: 756a0ee4f8ea07010a4734c73ab3ad0f3f30e7dcd54ba853b658b0d12c9277ea983f99983910bea1721c2539ec7191891cd3ae06c7da954a4245890ae31e0c03
                                                      salt: 496620796f75206265636f6d6520746f6f20736572696f75732c20796f752077696c6c206c6f736520796f7572207761792e
                                                      organization:
                                                      language: en
                                                      country: us
                                                      timezone: EST
                                                      gender: M
                                                      birthday: NULL
                                                      login: 1970-01-01 00:00:00
                                                      email: jelliott@thesearchagency.com
                                                      homepage:
                                                      home_phone:
                                                      work_phone:
                                                      mobile_phone:
                                                      2 rows in set (0.00 sec)

Tomcat log:

2013-02-14 16:44:19,723-28191237 [http-8080-15] INFO org.apache.cxf.interceptor.LoggingInInterceptor [] - Inbound Message
----------------------------
ID: 353
Address: http://reseller2.aq-qa-tomcat1:8080/sapi/rest/security/tokens/forgot-password
Http-Method: GET
Content-Type:
Headers:

{Accept=[application/json, text/plain], accept-encoding=[gzip, deflate], accept-language=[en-gb], connection=[keep-alive], Content-Type=[null], host=[reseller2.aq-qa-tomcat1:8080], referer=[http://reseller2.aq-qa-tomcat1:8080/reseller/], user-agent=[Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0]}

--------------------------------------
2013-02-14 16:44:19,725-28191239 [http-8080-15] INFO com.thesearchagency.sapi.security.TokenParsingInterceptor [] - handleMessage
2013-02-14 16:44:19,725-28191239 [http-8080-15] DEBUG com.thesearchagency.sapi.security.TokenParsingInterceptor [] - Received query string: null
2013-02-14 16:44:19,725-28191239 [http-8080-15] DEBUG com.thesearchagency.sapi.security.TokenParsingInterceptor [] - No context found, using guest context
2013-02-14 16:44:19,726-28191240 [http-8080-15] INFO com.thesearchagency.sapi.security.TokenParsingInterceptor [] - Adding (token 2013-02-14T08:54:51.178Z:[e042e3b8-b281-4851-a76d-acfabb994dec]:{}) context to message
2013-02-14 16:44:19,727-28191241 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.JAXRSContextProvider [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Found security context for e042e3b8-b281-4851-a76d-acfabb994dec
2013-02-14 16:44:19,728-28191242 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.JAXRSContextProvider [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Found tokenized security context: (token 2013-02-14T08:54:51.178Z:[e042e3b8-b281-4851-a76d-acfabb994dec]:{})
2013-02-14 16:44:19,728-28191242 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.JAXRSContextProvider [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Getting request context for e042e3b8-b281-4851-a76d-acfabb994dec in realm null
2013-02-14 16:44:19,728-28191242 [http-8080-15] DEBUG com.thesearchagency.util.PropertiesPropertySetFactory [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Property set for settings/null.properties is {}
2013-02-14 16:44:19,728-28191242 [http-8080-15] INFO com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - handleMessage
2013-02-14 16:44:19,728-28191242 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - resource: public javax.ws.rs.core.Response com.thesearchagency.sapi.services.security.SecurityService.getForgotPasswordToken(com.thesearchagency.saas.core.services.IRequestContext)
2013-02-14 16:44:19,728-28191242 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Executing getForgotPasswordToken
2013-02-14 16:44:19,728-28191242 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Checking if the principal is active
2013-02-14 16:44:19,729-28191243 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Getting data source for com_thesearchagency_saas_core_services_user_managers_UserManager
2013-02-14 16:44:19,749-28191263 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Checking for execute permissions on SAPI:com.thesearchagency.sapi.services.security.SecurityService.getForgotPasswordToken
2013-02-14 16:44:19,749-28191263 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Getting data source for com_thesearchagency_saas_core_services_security_manager_TSASecurityManager
2013-02-14 16:44:19,752-28191266 [http-8080-15] DEBUG com.thesearchagency.saas.core.services.security.manager.TSASecurityManager [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Attempting to get permissions for principal e042e3b8-b281-4851-a76d-acfabb994dec
2013-02-14 16:44:19,763-28191277 [http-8080-15] INFO com.thesearchagency.saas.core.services.security.manager.TSASecurityManager [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - matching 'SAPI:execute:com.thesearchagency.sapi.services.security.SecurityService.getForgotPasswordToken'.matches('SAPI:execute:com.thesearchagency.sapi.services.security.SecurityService.getForgotPasswordToken') -> true
2013-02-14 16:44:19,763-28191277 [http-8080-15] DEBUG com.thesearchagency.sapi.services.security.SecurityService [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - SecurityService: Getting token for forgot password user
2013-02-14 16:44:19,763-28191277 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.JAXRSContextProvider [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Getting data source for com_thesearchagency_saas_core_services_user_managers_UserManager
2013-02-14 16:44:19,769-28191283 [http-8080-15] DEBUG com.thesearchagency.security.TokenizedSecurityContext [timezone=UTC, principalId=, requestId=5f74648c-5a84-4f65-999d-92caeeff551d, locale=en_GB, authToken=null, hostname=70.166.144.17, ipAddress=70.166.144.17] - Encoded Token:X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3
2013-02-14 16:44:19,770-28191284 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.CacheControlResponseHandler [] - Headers: null
2013-02-14 16:44:19,770-28191284 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.CacheControlResponseHandler [] - Adding Expires header
2013-02-14 16:44:19,770-28191284 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.CacheControlResponseHandler [] - Adding Cache-Control header
2013-02-14 16:44:19,771-28191285 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.CacheControlResponseHandler [] - Adding Pragma header
2013-02-14 16:44:19,774-28191288 [http-8080-15] INFO org.apache.cxf.interceptor.LoggingOutInterceptor [] - Outbound Message
---------------------------
ID: 353
Response-Code: 200
Content-Type: text/plain
Headers:

{Expires=[-1], Cache-Control=[no-cache], Pragma=[no-cache], X-AML-API-ID=[5f74648c-5a84-4f65-999d-92caeeff551d], Date=[Thu, 14 Feb 2013 16:44:19 GMT]}

Payload: X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3
--------------------------------------
2013-02-14 16:44:19,840-28191354 [http-8080-15] INFO org.apache.cxf.interceptor.LoggingInInterceptor [] - Inbound Message
----------------------------
ID: 354
Address: http://reseller2.aq-qa-tomcat1:8080/sapi/rest/notifications/send/email/changePasswordRequest
Encoding: UTF-8
Http-Method: POST
Content-Type: application/json; charset=UTF-8
Headers:

{Accept=[application/json, text/plain], accept-encoding=[gzip, deflate], accept-language=[en-gb], cache-control=[no-cache], connection=[keep-alive], Content-Length=[102], content-type=[application/json; charset=UTF-8], host=[reseller2.aq-qa-tomcat1:8080], pragma=[no-cache], referer=[http://reseller2.aq-qa-tomcat1:8080/reseller/], user-agent=[Mozilla/5.0 (Windows NT 6.1; WOW64; rv:18.0) Gecko/20100101 Firefox/18.0], x-tsa-authenticate=[X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3]}

Payload:

{"recipientEmail":"jclarke@thesearchagency.com", "url":"http://reseller2.aq-qa-tomcat1:8080/reseller"}

--------------------------------------
2013-02-14 16:44:19,841-28191355 [http-8080-15] INFO com.thesearchagency.sapi.security.TokenParsingInterceptor [] - handleMessage
2013-02-14 16:44:19,841-28191355 [http-8080-15] DEBUG com.thesearchagency.sapi.security.TokenParsingInterceptor [] - Received query string: null
2013-02-14 16:44:19,841-28191355 [http-8080-15] DEBUG com.thesearchagency.sapi.security.TokenParsingInterceptor [] - Using context from header: X-TSA-Authenticate
2013-02-14 16:44:19,841-28191355 [http-8080-15] DEBUG com.thesearchagency.security.TokenizedSecurityContext [] - Token:X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3
2013-02-14 16:44:19,844-28191358 [http-8080-15] INFO com.thesearchagency.sapi.security.TokenParsingInterceptor [] - Adding (token 2013-02-14T16:44:19.767Z:[d42f5a21-fd27-4381-8726-1aa5194e6443]:

{expirationDate=2013-02-14T16:49:19.767Z}

) context to message
2013-02-14 16:44:19,846-28191360 [http-8080-15] DEBUG com.thesearchagency.security.TokenizedSecurityContext [timezone=UTC, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Token:X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3
2013-02-14 16:44:19,849-28191363 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.JAXRSContextProvider [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Found security context for d42f5a21-fd27-4381-8726-1aa5194e6443
2013-02-14 16:44:19,849-28191363 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.JAXRSContextProvider [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Found tokenized security context: (token 2013-02-14T16:44:19.767Z:[d42f5a21-fd27-4381-8726-1aa5194e6443]:

{expirationDate=2013-02-14T16:49:19.767Z}

)
2013-02-14 16:44:19,849-28191363 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.JAXRSContextProvider [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Getting request context for d42f5a21-fd27-4381-8726-1aa5194e6443 in realm null
2013-02-14 16:44:19,850-28191364 [http-8080-15] DEBUG com.thesearchagency.util.PropertiesPropertySetFactory [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Property set for settings/null.properties is {}
2013-02-14 16:44:19,851-28191365 [http-8080-15] INFO com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - handleMessage
2013-02-14 16:44:19,852-28191366 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - resource: public javax.ws.rs.core.Response com.thesearchagency.sapi.services.notification.NotificationService.sendNotification(com.thesearchagency.saas.core.services.IRequestContext,java.lang.String,java.lang.String,java.util.HashMap)
2013-02-14 16:44:19,852-28191366 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Executing sendNotification
2013-02-14 16:44:19,852-28191366 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Checking if the principal is active
2013-02-14 16:44:19,852-28191366 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Getting data source for com_thesearchagency_saas_core_services_user_managers_UserManager
2013-02-14 16:44:19,855-28191369 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Checking for execute permissions on SAPI:com.thesearchagency.sapi.services.notification.NotificationService.sendNotification
2013-02-14 16:44:19,855-28191369 [http-8080-15] DEBUG com.thesearchagency.sapi.security.PermissionsCheckingInterceptor [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Getting data source for com_thesearchagency_saas_core_services_security_manager_TSASecurityManager
2013-02-14 16:44:19,857-28191371 [http-8080-15] DEBUG com.thesearchagency.saas.core.services.security.manager.TSASecurityManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Attempting to get permissions for principal d42f5a21-fd27-4381-8726-1aa5194e6443
2013-02-14 16:44:19,861-28191375 [http-8080-15] INFO com.thesearchagency.saas.core.services.security.manager.TSASecurityManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - matching 'SAPI:execute:com.thesearchagency.sapi.services.notification.NotificationService.sendNotification'.matches('SAPI:execute:com.thesearchagency.sapi.services.notification.NotificationService.sendNotification') -> true
2013-02-14 16:44:19,861-28191375 [http-8080-15] DEBUG com.thesearchagency.sapi.services.notification.NotificationService [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Retrieving recipient by email: 'jclarke@thesearchagency.com'
2013-02-14 16:44:19,861-28191375 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.JAXRSContextProvider [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Getting data source for com_thesearchagency_saas_core_services_user_managers_UserManager
2013-02-14 16:44:19,862-28191376 [http-8080-15] DEBUG com.thesearchagency.database.storage.jdbc.JDBCStorageManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Count objects using query sql:
SELECT COUNT AS TSA_COUNT_RESULT FROM users WHERE (users.email = 'jclarke@thesearchagency.com')
2013-02-14 16:44:19,864-28191378 [http-8080-15] DEBUG com.thesearchagency.database.storage.jdbc.JDBCStorageManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - query: SELECT *
WHERE Email = 'jclarke@thesearchagency.com'

2013-02-14 16:44:19,864-28191378 [http-8080-15] DEBUG com.thesearchagency.database.storage.jdbc.JDBCStorageManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - current 0 offset 0
2013-02-14 16:44:19,864-28191378 [http-8080-15] DEBUG com.thesearchagency.database.storage.jdbc.JDBCStorageManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - ret.size() == 0 desired 1024
2013-02-14 16:44:19,864-28191378 [http-8080-15] DEBUG com.thesearchagency.database.storage.jdbc.JDBCStorageManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - snarf data: ret.size() == 0 desired 1024
2013-02-14 16:44:19,864-28191378 [http-8080-15] DEBUG com.thesearchagency.database.storage.jdbc.JDBCStorageManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Fetch objects using query sql:
SELECT users.work_phone,users.type,users.modified_on,users.organization,users.password,users.modified_by,users.salt,users.last_name,users.home_phone,users.birthday,users.login,users.realm_id,users.timezone,users.first_name,users.country,users.mobile_phone,users.description,users.display,users.active,users.email,users.gender,users.homepage,users.created_by,users.created_on,users.id,users.language FROM users WHERE (users.email = 'jclarke@thesearchagency.com') LIMIT 0 , 150
2013-02-14 16:44:19,865-28191379 [http-8080-15] WARN com.thesearchagency.database.storage.jdbc.BaseJDBCStorageManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - No value found for property Birthday
2013-02-14 16:44:19,866-28191380 [http-8080-15] WARN com.thesearchagency.database.storage.jdbc.BaseJDBCStorageManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - No value found for property Birthday
2013-02-14 16:44:19,866-28191380 [http-8080-15] WARN com.thesearchagency.database.storage.jdbc.BaseJDBCStorageManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - No value found for property Birthday
2013-02-14 16:44:19,867-28191381 [http-8080-15] DEBUG com.thesearchagency.database.storage.jdbc.JDBCStorageManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - snarf data: ret.size() == 3 desired 1024
2013-02-14 16:44:19,867-28191381 [http-8080-15] DEBUG com.thesearchagency.database.storage.jdbc.JDBCStorageManager [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Fetch objects using query sql:
SELECT users.work_phone,users.type,users.modified_on,users.organization,users.password,users.modified_by,users.salt,users.last_name,users.home_phone,users.birthday,users.login,users.realm_id,users.timezone,users.first_name,users.country,users.mobile_phone,users.description,users.display,users.active,users.email,users.gender,users.homepage,users.created_by,users.created_on,users.id,users.language FROM users WHERE (users.email = 'jclarke@thesearchagency.com') LIMIT 150 , 150
2013-02-14 16:44:19,868-28191382 [http-8080-15] DEBUG com.thesearchagency.sapi.services.notification.NotificationService [timezone=UTC, principalId=d42f5a21-fd27-4381-8726-1aa5194e6443, requestId=e506cf30-b110-4b9f-94c6-fa60afd2d619, locale=en_GB, authToken=X2CU34D5NWDJVB3W3IROSLKYCVSNXGWGT4I6IR7GOLM4ZPGI5IB5IA5TCUUFE3ZOZRY2Q4PP6VQXCUFSMIVKATXW7557ZW4HIMWCVGMVJ3OYRTQTJALPT2GI2ZJQ3S4FO72URVDOBHVR7ZOCK36T7UP75EMHHYIYPS6CLCTGBKVIXLE7OANCWLGY65FYCWU3, hostname=70.166.144.17, ipAddress=70.166.144.17] - Multiple users found with email 'jclarke@thesearchagency.com'
2013-02-14 16:44:19,869-28191383 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.CacheControlResponseHandler [] - Headers: null
2013-02-14 16:44:19,869-28191383 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.CacheControlResponseHandler [] - Adding Expires header
2013-02-14 16:44:19,869-28191383 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.CacheControlResponseHandler [] - Adding Cache-Control header
2013-02-14 16:44:19,869-28191383 [http-8080-15] DEBUG com.thesearchagency.sapi.jaxrs.CacheControlResponseHandler [] - Adding Pragma header
2013-02-14 16:44:19,872-28191386 [http-8080-15] INFO org.apache.cxf.interceptor.LoggingOutInterceptor [] - Outbound Message
---------------------------
ID: 354
Response-Code: 404
Content-Type: text/plain
Headers:

{Expires=[-1], Cache-Control=[no-cache], Pragma=[no-cache], Content-Type=[text/plain], X-AML-API-ID=[e506cf30-b110-4b9f-94c6-fa60afd2d619], Date=[Thu, 14 Feb 2013 16:44:19 GMT]}

Payload: Subject not found
--------------------------------------
2013-02