REST API giving 500 Internal Errors when setting property
I am setting the temperature property on a Thing, and intermittently getting 500 Internal Errors.
curl -v -X PUT -H 'appKey: 119710c2-d13f-4986-9bde-dfe359327a26' -d '{ "temperature": 55 }' -H 'Accept: application/json' -H "Content-Type: application/json" "http://localhost:8080/Thingworx/Things/MyThing/Properties/temperature
About 10% of the time I get 200 OK result and the property gets set properly, but mostly I get 500 Internal Error.
I think this must be a TW bug? Running ThingWorx 8.1.2-b51 at the moment.
The log complains about [context: com.thingworx.webservices.context.HttpExecutionContext@722b9f2d] in the http-nio-8080-exec-5 thread. I don't see any stack traces, even in catalina.out in Tomcat.
Aside from this, the Thingworx installation is working just fine in terms of Composer and so on.
However this problem is keeping us from actually using our IoT assets to set the property values. So we urgently need help with this.
ApplicationLog below. It's odd that the more use of the mashup(s) using this Thing (just normal GetPropertyValues stuff), the higher the chance to get a 500.
2018-03-29 13:39:06.017+0200 [L: DEBUG] [O: c.t.p.PersistenceProviderTransactionManager] [I: ] [U: sams] [S: ] [T: http-nio-8080-exec-11] ***ending transaction level 0***
2018-03-29 13:39:06.018+0200 [L: DEBUG] [O: c.t.p.ThingworxDefaultTransaction] [I: ] [U: sams] [S: ] [T: http-nio-8080-exec-11] ***committing transaction***
2018-03-29 13:39:09.761+0200 [L: DEBUG] [O: c.t.s.a.ApplicationKey] [I: ] [U: ] [S: ] [T: http-nio-8080-exec-18] Application key sams-app-key is valid.
2018-03-29 13:39:09.761+0200 [L: DEBUG] [O: c.t.s.s.i.SubscriptionStoreInMemoryThreadSafe] [I: ] [U: ] [S: ] [T: http-nio-8080-exec-18] There are no listeners for publisher GenericThing
2018-03-29 13:39:09.761+0200 [L: DEBUG] [O: c.t.s.s.i.SubscriptionStoreInMemoryThreadSafe] [I: ] [U: ] [S: ] [T: http-nio-8080-exec-18] There are no listeners for publisher SecurityMonitor
2018-03-29 13:39:09.762+0200 [L: DEBUG] [O: c.t.s.s.i.SubscriptionStoreInMemoryThreadSafe] [I: ] [U: ] [S: ] [T: http-nio-8080-exec-18] There are no listeners for publisher SecurityMonitor
2018-03-29 13:39:09.762+0200 [L: DEBUG] [O: c.t.s.s.i.SubscriptionStoreInMemoryThreadSafe] [I: ] [U: ] [S: ] [T: http-nio-8080-exec-18] There are no subscribers for event Type.Thing:Entity.SecurityMonitor:Event.ApplicationKeySucceeded
2018-03-29 13:39:09.762+0200 [L: DEBUG] [O: c.t.w.BaseService] [I: ] [U: sams] [S: ] [T: http-nio-8080-exec-18] Executing request [URI: /Thingworx/Things/MyThing/Properties/temperature]
2018-03-29 13:39:09.762+0200 [L: DEBUG] [O: c.t.w.c.HttpExecutionContext] [I: ] [U: sams] [S: ] [T: http-nio-8080-exec-18] { "temperature": 13.1 }
2018-03-29 13:39:09.763+0200 [L: DEBUG] [O: c.t.p.PersistenceProviderTransactionManager] [I: ] [U: sams] [S: ] [T: http-nio-8080-exec-18] ***Creating top level scope for transaction***
2018-03-29 13:39:09.765+0200 [L: ERROR] [O: c.t.w.BaseService] [I: ] [U: sams] [S: ] [T: http-nio-8080-exec-18] [context: com.thingworx.webservices.context.HttpExecutionContext@3593905c]
2018-03-29 13:39:09.766+0200 [L: DEBUG] [O: c.t.p.p.StreamEntryProcessor] [I: ] [U: SuperUser] [S: ] [T: pool-13-thread-12] Stream Entry Max Wait Time Reached...
2018-03-29 13:39:09.767+0200 [L: DEBUG] [O: c.t.p.p.StreamEntryProcessor] [I: ] [U: SuperUser] [S: ] [T: pool-13-thread-12] Processing 1 Stream Entries on Thread 142
2018-03-29 13:39:09.767+0200 [L: DEBUG] [O: c.t.p.PersistenceProviderTransactionManager] [I: ] [U: SuperUser] [S: ] [T: pool-13-thread-12] ***Creating top level scope for transaction***
2018-03-29 13:39:09.767+0200 [L: DEBUG] [O: c.t.p.p.PersistentPropertyDataProcessor] [I: ] [U: SuperUser] [S: ] [T: pool-13-thread-12] Persisted properties dedupped by 0%
2018-03-29 13:39:09.767+0200 [L: DEBUG] [O: c.t.p.PersistenceProviderTransactionManager] [I: ] [U: SuperUser] [S: ] [T: pool-13-thread-12] ***Created transaction from ThingworxPersistenceProvider ***
2018-03-29 13:39:09.770+0200 [L: DEBUG] [O: c.t.p.p.DefaultStreamEntryProcessor] [I: ] [U: SuperUser] [S: ] [T: pool-13-thread-12] Local Processing Of 1 Stream Entries on Thread 142 took 3053332 nanoseconds
2018-03-29 13:39:09.770+0200 [L: DEBUG] [O: c.t.p.ThingworxDefaultTransaction] [I: ] [U: SuperUser] [S: ] [T: pool-13-thread-12] ***committing transaction***
2018-03-29 13:39:10.952+0200 [L: DEBUG] [O: c.t.s.a.Authenticator] [I: ] [U: ] [S: ] [T: http-nio-8080-exec-9] This request is a Confirm Credentials action: false
2018-03-29 13:39:10.953+0200 [L: DEBUG] [O: c.t.s.a.Authenticator] [I: ] [U: ] [S: ] [T: http-nio-8080-exec-9] This request is a Change Password action: false
2018-03-29 13:39:10.954+0200 [L: DEBUG] [O: c.t.s.a.Authenticator] [I: ] [U: ] [S: ] [T: http-nio-8080-exec-9] This request is a password action related: false
2018-03-29 13:39:10.954+0200 [L: DEBUG] [O: c.t.s.a.Authenticator] [I: ] [U: ] [S: ] [T: http-nio-8080-exec-9] This request is a login action: false
