Q: devicemgrd null ptr error loop causing high cpu load.

Question

Hplogsdon

Level 1 (0 points)

Q: devicemgrd null ptr error loop causing high cpu load.

I've got a Mac Mini running Mavericks and Server 3, upgraded this morning. The install and migration appeared to run perfectly flawless (which it's never done before). After a couple reboots, I became worried about the fan which was still pegged on high. Activity Monitor reported that `devicemgrd` was consuming around 250% cpu. I got into Server.app and toggled Profile Manager off, which did nothing for devicemgrd. I killed the process in Activity Monitor, and the CPU usage died down and the fan calmed. After toggling Profile Manager back on, the CPU again shot up to fluctuate between 150% and 250%, devicemgrd being the culprit again.

Here are some log entries relevant to the issue:

/var/log/system.log:

Oct 23 08:44:51 stage devicemgrd[10507]: NSURLConnection/CFURLConnection HTTP load failed (kCFStreamErrorDomainSSL, -9843)
Oct 23 08:44:51 stage servermgrd[1364]: NSURLConnection/CFURLConnection HTTP load failed (kCFStreamErrorDomainSSL, -9843)
Oct 23 08:44:55 --- last message repeated 1 time ---

repeating, seemingly forever. "Security/SecureTransport.h", line 258 (MacOS10.9 SDK) claims that Code -9843 is: "errSSLHostNameMismatch = -9843, /* peer host name mismatch */"

/Library/Logs/ProfileManager/devicemgrd.log:

[7339] [2013/10/23 10:30:36.451] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [7339] [2013/10/23 10:30:36.453]
    ############################################################################
    devicemgrd-848.17 (PID:7339, OS:13A603, SERVER:13S440, ARCH:x86_64) starting
    LA: devicemgrd
    Log verbosity level = 1
    UID = 220, EUID = 220
    ############################################################################
0:: [7339] [2013/10/23 10:30:36.666] +[PGConnection reloadPreferences]: DBDebug = NO, DBLogNotices = NO, DBLogSQL = NO, DBMonitor = NO
0:: [7339] [2013/10/23 10:30:37.181] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
0:: [7339] [2013/10/23 10:30:37.183] Profile Manager service STOPPED
0:: [7339] [2013/10/23 10:30:37.183] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
    BACKTRACE: {
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
    ? | 140735497428728
    ? | 140735497441209
    }
1:: [7339] [2013/10/23 10:30:37.185] -[NSException(devicemgr_Additions) error]: No error set for exception: -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
    ? | 140735560719989
    ? | 4360259180
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
0:: [7339] [2013/10/23 10:30:37.185]  Caught exception sending objc_msgsend-style notification <PGDatabaseNotification:0x7fb70ba03dc0> [channel: 'objc_send', message: '[DMDevicemgrd updateAllProfilesForID:5]']. -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
0:: [7339] [2013/10/23 10:30:37.186] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
0:: [7339] [2013/10/23 10:30:37.188] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
    BACKTRACE: {
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
    ? | 140735497428728
    ? | 140735497441209
    }
1:: [7339] [2013/10/23 10:30:37.190] -[NSException(devicemgr_Additions) error]: No error set for exception: -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
    ? | 140735560719989
    ? | 4360259180
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
0:: [7339] [2013/10/23 10:30:37.190]  Caught exception sending objc_msgsend-style notification <PGDatabaseNotification:0x7fb70970c540> [channel: 'objc_send', message: '[DMDevicemgrd updateAllProfilesForID:5]']. -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
0:: [7339] [2013/10/23 10:30:37.191] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
1:: [7339] [2013/10/23 10:30:37.192] Incoming request: readSettings
0:: [7339] [2013/10/23 10:30:37.193] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
    BACKTRACE: {
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
    ? | 140735497428728
    ? | 140735497441209
    }
1:: [7339] [2013/10/23 10:30:37.194] -[NSException(devicemgr_Additions) error]: No error set for exception: -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
    ? | 140735560719989
    ? | 4360259180
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
0:: [7339] [2013/10/23 10:30:37.194]  Caught exception sending objc_msgsend-style notification <PGDatabaseNotification:0x7fb7094295b0> [channel: 'objc_send', message: '[DMDevicemgrd updateAllProfilesForID:5]']. -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
0:: [7339] [2013/10/23 10:30:37.195] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
0:: [7339] [2013/10/23 10:30:37.196] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
    BACKTRACE: {
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
    ? | 140735497428728
    ? | 140735497441209
    }
1:: [7339] [2013/10/23 10:30:37.198] -[NSException(devicemgr_Additions) error]: No error set for exception: -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
    ? | 140735560719989
    ? | 4360259180
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
0:: [7339] [2013/10/23 10:30:37.198]  Caught exception sending objc_msgsend-style notification <PGDatabaseNotification:0x7fb70970dff0> [channel: 'objc_send', message: '[DMDevicemgrd updateAllProfilesForID:5]']. -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
0:: [7339] [2013/10/23 10:30:37.199] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
0:: [7339] [2013/10/23 10:30:37.200] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
    BACKTRACE: {
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
    ? | 140735497428728
    ? | 140735497441209
    }
1:: [7339] [2013/10/23 10:30:37.201] Registering for network reachability notifications to "gateway.push.apple.com".
1:: [7339] [2013/10/23 10:30:37.202] -[NSException(devicemgr_Additions) error]: No error set for exception: -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
    ? | 140735560719989
    ? | 4360259180
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
0:: [7339] [2013/10/23 10:30:37.202]  Caught exception sending objc_msgsend-style notification <PGDatabaseNotification:0x7fb7097278e0> [channel: 'objc_send', message: '[DMDevicemgrd updateAllProfilesForID:5]']. -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
0:: [7339] [2013/10/23 10:30:37.204] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
0:: [7339] [2013/10/23 10:30:37.204] APNS topic = com.apple.mgmt.XServer.a26a4f58-983e-4371-9bfd-0ca50ed2c509
0:: [7339] [2013/10/23 10:30:37.205] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
    BACKTRACE: {
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
    ? | 140735497428728
    ? | 140735497441209
    }
1:: [7339] [2013/10/23 10:30:37.207] -[NSException(devicemgr_Additions) error]: No error set for exception: -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
    ? | 140735560719989
    ? | 4360259180
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
0:: [7339] [2013/10/23 10:30:37.207]  Caught exception sending objc_msgsend-style notification <PGDatabaseNotification:0x7fb70970dff0> [channel: 'objc_send', message: '[DMDevicemgrd updateAllProfilesForID:5]']. -[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL"
0:: [7339] [2013/10/23 10:30:37.208] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
0:: [7339] [2013/10/23 10:30:37.209] EXCEPTION:  NullPtr <-[PGDatabaseNotification sendToPayloadTargetInTransaction] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-848.17/Compiled/Framework-Models/Postgres/PGDatabaseNotification.m:150): "'((m = class_getInstanceMethod(t, s)))' is NULL">
    BACKTRACE: {
    ? | 4362365675
    ? | 4362406189
    ? | 4362403886
    ? | 4362522118
    ? | 4362403429
    ? | 140735534645333
    ? | 140735534511505
    ? | 140735534510651
    ? | 140735426040493
    ? | 140735426049679
    ? | 140735426054621
    ? | 140735426047907
    ? | 140735426052499
    ? | 140735497428728
    ? | 140735497441209
    }

again, repeating seemingly forever, and fast. This has the most noise, revealing this error: "((m = class_getInstanceMethod(t, s)))' is NULL". New exceptions get written to the file every few milliseconds or so. Running devicemgrd in this state for about 1 hour resulted in a 2.28 Gigabyte uncompressed logfile.

/Library/Logs/ProfileManager/PostgreSQL-2013-10-23.log: (today's date)

2013-10-23 09:17:58 MDTLOG:  database system was shut down at 2013-10-23 09:16:40 MDT
2013-10-23 09:17:58 MDTLOG:  autovacuum launcher started
2013-10-23 09:17:58 MDTLOG:  database system is ready to accept connections
2013-10-23 09:17:58 MDTLOG:  connection received: host=[local]
2013-10-23 09:17:58 MDTLOG:  connection authorized: user=_devicemgr database=template1
2013-10-23 09:17:58 MDTLOG:  connection received: host=[local]
2013-10-23 09:17:58 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:17:59 MDTLOG:  connection received: host=[local]
2013-10-23 09:17:59 MDTLOG:  replication connection authorized: user=_devicemgr
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "widgets" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "preference_panes" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "printers" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "installed_applications" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "users" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "profiles" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "dm_schema_information" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "provisioning_profiles" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "od_nodes" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "user_groups" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "network_lab_sessions" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "enterprise_apps" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "library_items" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "data_files" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "knob_sets" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "od_library_items" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "work_tasks" ENABLE TRIGGER USER
2013-10-23 09:18:02 MDTLOG:  statement: ALTER TABLE "user_tasks" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "mdm_targets" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "asset_metadata" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "auto_join_profiles" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "db_notifications" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "internal_tasks" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "library_item_tasks" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "vpp_licenses" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "settings" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "device_groups" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "media" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "lab_sessions" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "dynamic_attributes_defaults" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "library_item_metadata" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "completed_tasks" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "mdm_tasks" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "system_applications" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "owner_lab_sessions" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "assets" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "devices" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "vpp_products" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "library_items_assets" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "its_accounts" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  statement: ALTER TABLE "profile_substitution_caches" ENABLE TRIGGER USER
2013-10-23 09:18:03 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:03 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:03 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:03 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:03 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:03 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:03 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3857-2" ("value" varchar UNIQUE)
2013-10-23 09:18:03 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:03 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:03 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3861-2" ("value" varchar UNIQUE)
2013-10-23 09:18:04 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3869-2" ("value" varchar UNIQUE)
2013-10-23 09:18:04 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3869-5" ("value" varchar UNIQUE)
2013-10-23 09:18:04 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3876-2" ("value" varchar UNIQUE)
2013-10-23 09:18:04 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3876-5" ("value" varchar UNIQUE)
2013-10-23 09:18:04 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3876-2"
2013-10-23 09:18:04 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3876-5"
2013-10-23 09:18:04 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3861-2"
2013-10-23 09:18:04 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3869-2"
2013-10-23 09:18:04 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3857-2"
2013-10-23 09:18:04 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3869-5"
2013-10-23 09:18:19 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:19 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:19 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:19 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:19 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:19 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:19 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:19 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:19 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:19 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:19 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:19 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:19 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:19 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:19 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:19 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:19 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:19 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:19 MDTLOG:  connection received: host=[local]
2013-10-23 09:18:19 MDTLOG:  connection authorized: user=_devicemgr database=devicemgr_v2m0
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "server_organization" = NULL, "updated_at" = '2013-10-23 15:18:19.636912', "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: | 
            [***REMOVED***]
 

          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "server_organization" = NULL, "updated_at" = '2013-10-23 15:18:19.637496', "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "server_organization" = NULL, "updated_at" = '2013-10-23 15:18:19.761528', "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "server_organization" = NULL, "updated_at" = '2013-10-23 15:18:19.762069', "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]

 
          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "server_organization" = NULL, "updated_at" = '2013-10-23 15:18:19.762103', "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "server_organization" = NULL, "updated_at" = '2013-10-23 15:18:19.775118', "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "server_organization" = NULL, "updated_at" = '2013-10-23 15:18:19.803989', "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "server_organization" = NULL, "updated_at" = '2013-10-23 15:18:19.818087', "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "server_organization" = NULL, "updated_at" = '2013-10-23 15:18:19.886453', "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:19 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:19 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:20 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:20 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:21 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:21 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:21 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:21 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:21 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:21 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:21 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:21 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:21 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:21 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:21 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:21 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:21 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:21 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:21 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:21 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:21 MDTERROR:  could not serialize access due to concurrent update
2013-10-23 09:18:21 MDTSTATEMENT:  UPDATE "settings" SET "knob_sets_enabled" = '---
        com.apple.carddav.account: 0
        ._com.apple.carddav.account: BOOLEAN
        com.apple.caldav.account: 0
        ._com.apple.caldav.account: BOOLEAN
        ', "trust_payloads" = '---
        ssl_trust_payload:
          PayloadVersion: 1
          PayloadDisplayName: "Root certificate for "
          PayloadIdentifier: com.apple.ssl.certificate
          PayloadType: com.apple.security.root
          PayloadContent: |
            [***REMOVED***]


          PayloadDescription: Installs the Root certificate for .
          PayloadUUID: C772D490-BEA3-4A99-9D09-05A14FD89452
        ' WHERE "id" = 1
2013-10-23 09:18:35 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3943-2" ("value" varchar UNIQUE)
2013-10-23 09:18:35 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3949-2" ("value" varchar UNIQUE)
2013-10-23 09:18:35 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3960-2" ("value" varchar UNIQUE)
2013-10-23 09:18:35 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3960-5" ("value" varchar UNIQUE)
2013-10-23 09:18:35 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3962-2" ("value" varchar UNIQUE)
2013-10-23 09:18:35 MDTLOG:  statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-3962-5" ("value" varchar UNIQUE)
2013-10-23 09:18:35 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3960-5"
2013-10-23 09:18:35 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3962-2"
2013-10-23 09:18:35 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3962-5"
2013-10-23 09:18:35 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3949-2"
2013-10-23 09:18:35 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3960-2"
2013-10-23 09:18:35 MDTLOG:  statement: DROP TABLE "__runtime_temp_devicemgrd-3943-2"
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "widgets" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "preference_panes" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "printers" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "installed_applications" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "users" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "profiles" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "dm_schema_information" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "provisioning_profiles" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "od_nodes" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "user_groups" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "network_lab_sessions" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "enterprise_apps" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  connection received: host=[local]
2013-10-23 09:25:04 MDTLOG:  connection authorized: user=_devicemgr database=postgres
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "library_items" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "data_files" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "knob_sets" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "od_library_items" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "work_tasks" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "user_tasks" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "mdm_targets" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "asset_metadata" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "auto_join_profiles" ENABLE TRIGGER USER
2013-10-23 09:25:04 MDTLOG:  statement: ALTER TABLE "db_notifications" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "internal_tasks" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "library_item_tasks" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "vpp_licenses" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "settings" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "device_groups" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "media" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "lab_sessions" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "dynamic_attributes_defaults" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "library_item_metadata" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "completed_tasks" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "mdm_tasks" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "system_applications" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "owner_lab_sessions" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "assets" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "devices" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "vpp_products" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "library_items_assets" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "its_accounts" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  statement: ALTER TABLE "profile_substitution_caches" ENABLE TRIGGER USER
2013-10-23 09:25:05 MDTLOG:  unexpected EOF on client connection with an open transaction
2013-10-23 09:25:05 MDTLOG:  received smart shutdown request
2013-10-23 09:25:05 MDTLOG:  autovacuum launcher shutting down
2013-10-23 09:25:05 MDTLOG:  shutting down
2013-10-23 09:25:05 MDTLOG:  database system is shut down
'/Library/Server/ProfileManager/Data/PostgreSQL/pg_xlog/000000010000000000000005' ... '/Library/Server/ProfileManager/Data/backup/000000010000000000000005'
'/Library/Server/ProfileManager/Data/PostgreSQL/pg_xlog/000000010000000000000005' --> '/Library/Server/ProfileManager/Data/backup/000000010000000000000005'

This log file is from between one reboot to the other lasting about 7 minutes. The Payload data is the base64 encoded x509 public key of the server, repeated over and over again.

Regarding the hostname mismatch, I don't think that's an issue, since running `hostname` reports the CommonName of the server's public key. It's a StartCom Class 1 Server certificate issued by "StartCom Class 1 Primary Intermediate Server CA".

I've chmodded the executable "/Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/devicemgrd" to 400 for the time being to prevent the server from melting (it was reporting up to 90ºC at the exhaust vent). Seeing as I use Profile Manager, I'd like to resolve this issue (I say this because a couple other threads suggest disabling profile manager as a solution).

Does anyone have any suggestions as to the cause, or any possible solutions?

Thank you.

Mac mini, OS X Server

Posted on Oct 23, 2013 11:34 AM

I have this question too

Solved

mscott_mdm

Level 2 (225 points)

A:

OK. This command (same deal, one line) should fix this issue:

sudo -u _devicemgr /Applications/Server.app/Contents/ServerRoot/usr/bin/psql -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "DELETE FROM db_notifications WHERE message LIKE '[DMDevicemgrd updateAllProfilesForID:%';"

Posted on Oct 24, 2013 10:23 AM

Q: devicemgrd null ptr error loop causing high cpu load.

All replies

Helpful answers

first Page 5 of 7 last

by mscott_mdm,

mscott_mdm Mar 18, 2014 7:31 PM in response to fkick1
Level 2 (225 points)

Mar 18, 2014 7:31 PM in response to fkick1

fkick1,

Can you look in /Library/Logs/ProfileManager and see which of the log files there seems to be growing the fastest. Then attach the last 100 or so lines from that log. That might give me a lead.

Thanks.
Reply options

Link to this post

by kjm_bkk,

kjm_bkk Mar 19, 2014 3:01 AM in response to mscott_mdm
Level 1 (0 points)

Mar 19, 2014 3:01 AM in response to mscott_mdm

Me too. Upgraded yesterday and getting up to 100% cpu, large logs for PostgreSQL and php:

-rw-r--r--   1 _devicemgr admin              1759 18 Mar 11:59 PostgreSQL-2014-03-17.log.bz2
-rw-r--r--   1 _devicemgr admin           8408275 19 Mar 13:22 PostgreSQL-2014-03-18.log.bz2
-rw-------   1 _devicemgr admin        2013869893 19 Mar 16:00 PostgreSQL-2014-03-19.log
-rw-r--r--   1 _devicemgr admin            271536 18 Mar 12:52 devicemgrd-2014-03-18-125224.log.bz2
-rw-r--r--   1 _devicemgr admin            166297 18 Mar 14:10 devicemgrd-2014-03-18-141041.log.bz2
-rw-r--r--   1 _devicemgr admin            163821 18 Mar 15:24 devicemgrd-2014-03-18-152425.log.bz2
-rw-r--r--   1 _devicemgr admin            161182 18 Mar 16:42 devicemgrd-2014-03-18-164221.log.bz2
-rw-r--r--   1 _devicemgr admin            163284 19 Mar 09:21 devicemgrd-2014-03-19-092126.log.bz2
-rw-r--r--   1 _devicemgr admin            161162 19 Mar 11:05 devicemgrd-2014-03-19-110553.log.bz2
-rw-r--r--   1 _devicemgr admin            161338 19 Mar 12:40 devicemgrd-2014-03-19-124011.log.bz2
-rw-r--r--   1 _devicemgr admin            160411 19 Mar 14:19 devicemgrd-2014-03-19-141917.log.bz2
-rw-r--r--   1 _devicemgr admin            160230 19 Mar 15:56 devicemgrd-2014-03-19-155649.log.bz2
-rw-r--r--   1 _devicemgr _devicemgr       351615 19 Mar 16:00 devicemgrd.log
-rw-r--r--   1 _devicemgr _devicemgr        31224 19 Mar 12:58 dmrunnerd.log
-rw-r--r--   1 _devicemgr _devicemgr         2514 18 Mar 11:59 migration_tool.log
-rw-r--r--   1 _devicemgr admin                 0 18 Mar 11:58 php-fpm-slow-devicemgr.log
-rw-r--r--   1 _devicemgr admin             56349 19 Mar 15:58 php-fpm.devicemgr.log
-rw-r--r--   1 _devicemgr admin            465367 19 Mar 16:00 php-fpm.log
-rw-r-----   1 _devicemgr admin       10452887391 19 Mar 16:00 php.log
-rw-r-----   1 _devicemgr admin       23118993042 19 Mar 14:52 php.log.0
-rw-------   1 root        admin          50819072 19 Mar 16:00 php.log.0.bz2
-rw-r-----   1 _devicemgr admin         124718009 19 Mar 11:50 php.log.1.bz2
-rw-r-----   1 _devicemgr admin          66863169 19 Mar 10:30 php.log.2.bz2
-rw-r-----   1 _devicemgr admin          19777248 19 Mar 09:30 php.log.3.bz2
-rw-r-----   1 _devicemgr admin         670570956 18 Mar 18:19 php.log.4.bz2
-rw-r-----   1 _devicemgr admin         236948473 18 Mar 13:30 php.log.5.bz2
-rw-r-----   1 _devicemgr admin        7045436181 18 Mar 12:30 php.log.6

$ sudo tail -n 100 PostgreSQL-2014-03-19.log

2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: could not serialize access due to read/write dependencies among transactions
2014-03-19 16:45:00 ICTDETAIL: Reason code: Canceled on identification as a pivot, during conflict in checking.
2014-03-19 16:45:00 ICTHINT: The transaction might succeed if retried.
2014-03-19 16:45:00 ICTSTATEMENT: UPDATE "devices" SET ("updated_at","last_checkin_time") = (dm_current_timestamp(),dm_current_timestamp()) WHERE id = $1
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: could not serialize access due to read/write dependencies among transactions
2014-03-19 16:45:00 ICTDETAIL: Reason code: Canceled on identification as a pivot, during conflict in checking.
2014-03-19 16:45:00 ICTHINT: The transaction might succeed if retried.
2014-03-19 16:45:00 ICTSTATEMENT: UPDATE "devices" SET ("updated_at","last_checkin_time","DeviceName","ProductName","OSVersion","Seria lNumber","BluetoothMAC","EthernetMAC","WiFiMAC") = (dm_current_timestamp(),dm_current_timestamp(),$1,$2,$3,$4,$5,$6,$7) WHERE id = $8
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: DEALLOCATE pdo_stmt_00000005
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_device_application_lists_updated($1)
2014-03-19 16:45:00 ICTERROR: current transaction is aborted, commands ignored until end of transaction block
2014-03-19 16:45:00 ICTSTATEMENT: SELECT dm_complete_mdm_tasks_for_mdm_target($1,$2,$3,$4,$5)

$ sudo tail -n 100 php.log
Password:
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>     },{
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>       'CommonName'=>'Spectrum Asia',
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>       'Data'=>'<BinaryString: 996 bytes>',
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>       'IsIdentity'=>''
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>     },{
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>       'CommonName'=>'MDM Identity Certificate:59f46288-aedb-4716-aa8e-53eb9c4937d7',
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>       'Data'=>'<BinaryString: 806 bytes>',
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>       'IsIdentity'=>'1'
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>     },{
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>       'CommonName'=>'IntermediateCA_MACSERVER.SPEC-OSO.LAN_1',
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>       'Data'=>'<BinaryString: 1057 bytes>',
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>       'IsIdentity'=>''
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>     } ]',
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>   'CommandUUID'=>'6E5D787E-EFAB-4B0D-A0E3-F139F1629F27',
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>   'RequestType'=>'CertificateList',
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>   'Status'=>'Acknowledged',
1::Mar 19 16:50:03.159 [50480] <10.0.1.127>   'UDID'=>'54cce79415b8510595a19af4de5a53df'
1::Mar 19 16:50:03.159 [50480] <10.0.1.127> }
0::Mar 19 16:50:03.163 [50905] <10.0.1.176> {LogPDOException (db.php:656)} PDOException: 'SQLSTATE[40001]: Serialization failure: 7 ERROR: could not serialize access due to read/write dependencies among transactions
0::Mar 19 16:50:03.163 [50905] <10.0.1.176> DETAIL: Reason code: Canceled on conflict out to pivot 1762381, during read.
0::Mar 19 16:50:03.163 [50905] <10.0.1.176> HINT: The transaction might succeed if retried.' (info: '[ <BinaryString: 5 bytes>,7,ERROR: could not serialize access due to read/write dependencies among transactions
0::Mar 19 16:50:03.163 [50905] <10.0.1.176> DETAIL: Reason code: Canceled on conflict out to pivot 1762381, during read.
0::Mar 19 16:50:03.163 [50905] <10.0.1.176> HINT: The transaction might succeed if retried. ]', code:40001)
0::Mar 19 16:50:03.216 [50480] <10.0.1.127> {_connect_transaction_1 (db.php:393)} Status='Acknowledged' CommandUUID=6E5D787E-EFAB-4B0D-A0E3-F139F1629F27
1::Mar 19 16:50:03.217 [43732] <10.0.1.184> {SendFinalOutput (mdm_connect.php:133)} Sent Final Output (389 bytes)
1::Mar 19 16:50:03.217 [43732] <10.0.1.184> ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ - /devicemanagement/mdm/mdm_connect
0::Mar 19 16:50:03.217 [43732] <10.0.1.184> {SendFinalOutput (mdm_connect.php:133)} Completed in 538ms | 200 OK [https://macserver.spec-oso.lan/devicemanagement/api/device/mdm_connect]
0::Mar 19 16:50:03.217 [50905] <10.0.1.176> {PerformInTransaction (mdm_connect.php:126)} @@@ Retry #6 of '_connect_transaction_1' due to database transaction failure 'SQLSTATE[40001]: Serialization failure: 7 ERROR: could not serialize access due to read/write dependencies among transactions
0::Mar 19 16:50:03.217 [50905] <10.0.1.176> DETAIL: Reason code: Canceled on conflict out to pivot 1762381, during read.
0::Mar 19 16:50:03.217 [50905] <10.0.1.176> HINT: The transaction might succeed if retried.'.... (in 15.783ms) @@@
1::Mar 19 16:50:03.229 [59324] <10.0.1.184> {LogElapsedTime (common.php:74)} Time since script start: 144us [https://macserver.spec-oso.lan/devicemanagement/api/device/mdm_connect]
1::Mar 19 16:50:03.230 [59324] <10.0.1.184> {require_once (mdm_connect.php:11)} vvvvvvvvvvvvvvvvvvvvvvvvvvvvvvv - PUT mdm_connect
2::Mar 19 16:50:03.233 [50905] <10.0.1.176> {Target_for_client_certificate (target.php:334)} Target_for_client_certificate: checkin_token = 'e69a23b2-cd56-4ab9-86f9-78487716ea3f'
1::Mar 19 16:50:03.236 [50905] <10.0.1.176> {Target_for_incoming_request (mdm_connect.php:20)} Found target Mac: <'Sai’s iMac'[57](SN:C02LD05MDNML, CN:e69a23b2-cd56-4ab9-86f9-78487716ea3f) OWNER:admin[5](S-GUID:641d2716-a5c2-4364-ba20-e79bc1c3d2a2)>
1::Mar 19 16:50:03.236 [50905] <10.0.1.176> For request:
1::Mar 19 16:50:03.236 [50905] <10.0.1.176> {
1::Mar 19 16:50:03.236 [50905] <10.0.1.176>   'CommandUUID'=>'BB88E418-692A-4D09-A69D-CE77ABB56930',
1::Mar 19 16:50:03.236 [50905] <10.0.1.176>   'ProvisioningProfileList'=>'[ ]',
1::Mar 19 16:50:03.236 [50905] <10.0.1.176>   'RequestType'=>'ProvisioningProfileList',
1::Mar 19 16:50:03.236 [50905] <10.0.1.176>   'Status'=>'Acknowledged',
1::Mar 19 16:50:03.236 [50905] <10.0.1.176>   'UDID'=>'1e09fe49e46f5c4e86e26becf58c9193'
1::Mar 19 16:50:03.236 [50905] <10.0.1.176> }
0::Mar 19 16:50:03.236 [50905] <10.0.1.176> {_connect_transaction_1 (db.php:393)} Status='Acknowledged' CommandUUID=BB88E418-692A-4D09-A69D-CE77ABB56930
1::Mar 19 16:50:03.237 [59324] <10.0.1.184> {Target_for_incoming_request (mdm_connect.php:20)} Found target Mac: <'Julian’s iMac'[46](OWNER:User[5])>
0::Mar 19 16:50:03.237 [59324] <10.0.1.184> {_connect_transaction_1 (db.php:393)} Status='Acknowledged' CommandUUID=7B4138F5-EAF0-4698-94E9-64D5FCE1BAB2
0::Mar 19 16:50:03.246 [50480] <10.0.1.127> {LogPDOException (db.php:656)} PDOException: 'SQLSTATE[40001]: Serialization failure: 7 ERROR: could not serialize access due to read/write dependencies among transactions
0::Mar 19 16:50:03.246 [50480] <10.0.1.127> DETAIL: Reason code: Canceled on conflict out to pivot 1762381, during read.
0::Mar 19 16:50:03.246 [50480] <10.0.1.127> HINT: The transaction might succeed if retried.' (info: '[ <BinaryString: 5 bytes>,7,ERROR: could not serialize access due to read/write dependencies among transactions
0::Mar 19 16:50:03.246 [50480] <10.0.1.127> DETAIL: Reason code: Canceled on conflict out to pivot 1762381, during read.
0::Mar 19 16:50:03.246 [50480] <10.0.1.127> HINT: The transaction might succeed if retried. ]', code:40001)
0::Mar 19 16:50:03.247 [50480] <10.0.1.127> {PerformInTransaction (mdm_connect.php:126)} @@@ Retry #3 of '_connect_transaction_1' due to database transaction failure 'SQLSTATE[40001]: Serialization failure: 7 ERROR: could not serialize access due to read/write dependencies among transactions
0::Mar 19 16:50:03.247 [50480] <10.0.1.127> DETAIL: Reason code: Canceled on conflict out to pivot 1762381, during read.
0::Mar 19 16:50:03.247 [50480] <10.0.1.127> HINT: The transaction might succeed if retried.'.... (in 1.588ms) @@@
2::Mar 19 16:50:03.249 [50480] <10.0.1.127> {Target_for_client_certificate (target.php:334)} Target_for_client_certificate: checkin_token = '59f46288-aedb-4716-aa8e-53eb9c4937d7'
1::Mar 19 16:50:03.252 [50480] <10.0.1.127> {Target_for_incoming_request (mdm_connect.php:20)} Found target Mac: <'Pim’s iMac'[65](SN:C02JCADNDHJF, CN:59f46288-aedb-4716-aa8e-53eb9c4937d7) OWNER:admin[5](S-GUID:641d2716-a5c2-4364-ba20-e79bc1c3d2a2)>
1::Mar 19 16:50:03.252 [50480] <10.0.1.127> For request:
1::Mar 19 16:50:03.252 [50480] <10.0.1.127> {
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>   'CertificateList'=>'[ {
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'CommonName'=>'com.apple.systemdefault',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'Data'=>'<BinaryString: 536 bytes>',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'IsIdentity'=>'1'
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>     },{
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'CommonName'=>'com.apple.kerberos.kdc',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'Data'=>'<BinaryString: 553 bytes>',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'IsIdentity'=>'1'
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>     },{
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'CommonName'=>'Apple Code Signing Certification Authority',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'Data'=>'<BinaryString: 1045 bytes>',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'IsIdentity'=>''
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>     },{
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'CommonName'=>'Software Signing',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'Data'=>'<BinaryString: 1314 bytes>',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'IsIdentity'=>''
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>     },{
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'CommonName'=>'Spectrum Asia',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'Data'=>'<BinaryString: 996 bytes>',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'IsIdentity'=>''
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>     },{
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'CommonName'=>'MDM Identity Certificate:59f46288-aedb-4716-aa8e-53eb9c4937d7',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'Data'=>'<BinaryString: 806 bytes>',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'IsIdentity'=>'1'
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>     },{
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'CommonName'=>'IntermediateCA_MACSERVER.SPEC-OSO.LAN_1',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'Data'=>'<BinaryString: 1057 bytes>',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>       'IsIdentity'=>''
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>     } ]',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>   'CommandUUID'=>'6E5D787E-EFAB-4B0D-A0E3-F139F1629F27',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>   'RequestType'=>'CertificateList',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>   'Status'=>'Acknowledged',
1::Mar 19 16:50:03.252 [50480] <10.0.1.127>   'UDID'=>'54cce79415b8510595a19af4de5a53df'
1::Mar 19 16:50:03.252 [50480] <10.0.1.127> }
0::Mar 19 16:50:03.252 [50480] <10.0.1.127> {_connect_transaction_1 (db.php:393)} Status='Acknowledged' CommandUUID=6E5D787E-EFAB-4B0D-A0E3-F139F1629F27
0::Mar 19 16:50:03.261 [59324] <10.0.1.184> {LogPDOException (db.php:656)} PDOException: 'SQLSTATE[40001]: Serialization failure: 7 ERROR: could not serialize access due to read/write dependencies among transactions
0::Mar 19 16:50:03.261 [59324] <10.0.1.184> DETAIL: Reason code: Canceled on identification as a pivot, during write.
0::Mar 19 16:50:03.261 [59324] <10.0.1.184> HINT: The transaction might succeed if retried.' (info: '[ <BinaryString: 5 bytes>,7,ERROR: could not serialize access due to read/write dependencies among transactions
0::Mar 19 16:50:03.261 [59324] <10.0.1.184> DETAIL: Reason code: Canceled on identification as a pivot, during write.
0::Mar 19 16:50:03.261 [59324] <10.0.1.184> HINT: The transaction might succeed if retried. ]', code:40001)
0::Mar 19 16:50:03.262 [59324] <10.0.1.184> {PerformInTransaction (mdm_connect.php:126)} @@@ Retry #1 of '_connect_transaction_1' due to database transaction failure 'SQLSTATE[40001]: Serialization failure: 7 ERROR: could not serialize access due to read/write dependencies among transactions
0::Mar 19 16:50:03.262 [59324] <10.0.1.184> DETAIL: Reason code: Canceled on identification as a pivot, during write.
0::Mar 19 16:50:03.262 [59324] <10.0.1.184> HINT: The transaction might succeed if retried.'.... (in 3.926ms) @@@

Any assistance would be very much appreciated, not least by my little mac mini, which sounds as though it's about to take off!
Reply options

Link to this post

by mscott_mdm,

mscott_mdm Mar 19, 2014 8:40 AM in response to kjm_bkk
Level 2 (225 points)

Mar 19, 2014 8:40 AM in response to kjm_bkk

kjm_bkk & fkick1, I think this should stop this problem for one month:

sudo psql -U _devicemgr -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "UPDATE devices SET last_update_info_time = dm_current_timestamp() + '1 month'"

Copy the above and paste it into Terminal (or remote ssh shell) on the server as an admin user. You'll almost certainly be prompted to enter your admin password.

Note that until a proper fix is released by Apple, it would appear that enrolling a number of new devices could lead to this problem recurring in less than a month, but you can always run the above command again.

Please let me know if this seems to bring things "under control" for you. (It won't be immediate, but it should show signs of working within a few minutes.)
Reply options

Link to this post

by kjm_bkk,

kjm_bkk Mar 19, 2014 9:15 AM in response to mscott_mdm
Level 1 (0 points)

Mar 19, 2014 9:15 AM in response to mscott_mdm

Thanks very much. I had turned off the Profile Manager service and also the Web Server to try to prevent a melt down. I have run the SQL and started both services again. Nothing untoward so far, but I'll let you know progress tomorrow.

Thanks again.
Reply options

Link to this post

by fkick1,

fkick1 Mar 19, 2014 9:27 AM in response to mscott_mdm
Level 1 (73 points)

Servers Enterprise

Mar 19, 2014 9:27 AM in response to mscott_mdm

Thanks mscott_mdm. That definitely seems to have stemmed the immediate issue with the log files growing at a rapid rate.
Reply options

Link to this post

by mscott_mdm,

mscott_mdm Mar 19, 2014 10:38 AM in response to mscott_mdm
Level 2 (225 points)

Mar 19, 2014 10:38 AM in response to mscott_mdm

I would also recommend that you run this command, to keep newly-enrolled devices from causing the same problem after a couple of days:

sudo psql -U _devicemgr -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "ALTER TABLE devices ALTER COLUMN last_update_info_time SET DEFAULT dm_current_timestamp() + '1 month'"
Reply options

Link to this post

by Patrick Fist,

Patrick Fist Mar 19, 2014 10:44 AM in response to Hplogsdon
Level 1 (50 points)

Mar 19, 2014 10:44 AM in response to Hplogsdon
Hello everybody,

I have similar symptoms but not the same Logfile content. So I like to show you my content, maybe somebody got a idea.
My Symptoms are:
High CPU load
devicemgrd
dmrunnerd
postgres_real
php-fpm
Big Logfiles particularly:
php.log
devicemgrd-$date-$time.log

FYI, I stopped the service 'serveradmin stop devicemgr', restartet system at 17:42, start service at 17:48.

tail -n 100 /Library/Logs/ProfileManager/devicemgrd.log

1:: [363] [2014/03/19 17:48:24.398] Incoming request: noOp
1:: [363] [2014/03/19 17:48:29.917] Incoming request: setWebAppState
0:: [363] [2014/03/19 17:48:29.928] Profile Manager service started.
1:: [363] [2014/03/19 17:48:30.000] Preparing to sync required users and groups
1:: [363] [2014/03/19 17:48:30.355] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 1 group records
1:: [363] [2014/03/19 17:48:30.512] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Querying OD for 2 new users and groups
1:: [363] [2014/03/19 17:48:30.884] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [363] [2014/03/19 17:48:30.891] Preparing to sync OD subset
1:: [363] [2014/03/19 17:48:33.730] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Querying OD for 2 new users and groups
1:: [363] [2014/03/19 17:48:34.913] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Querying OD for 1 new users and groups
1:: [363] [2014/03/19 17:48:36.639] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Querying OD for 3 new users and groups
1:: [363] [2014/03/19 17:48:37.323] Incoming request: getWebAppState
0:: [363] [2014/03/19 17:48:37.328] Profile Manager service started.
1:: [363] [2014/03/19 17:48:39.099] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Querying OD for 5 new users and groups
1:: [363] [2014/03/19 17:48:40.705] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Querying OD for 4 new users and groups
1:: [363] [2014/03/19 17:48:42.083] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Querying OD for 1 new users and groups
1:: [363] [2014/03/19 17:48:42.200] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Querying OD for 1 new users and groups
1:: [363] [2014/03/19 17:48:42.319] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 182 group records
1:: [363] [2014/03/19 17:48:42.417] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 115 group records
1:: [363] [2014/03/19 17:48:42.417] -[DMODSyncRunner _syncODGroupSubset]: Processed 185 already active groups from OD subset
1:: [363] [2014/03/19 17:48:42.417] -[DMODSyncRunner _syncODGroupSubset]: Processed 115 non-active groups from OD subset
1:: [363] [2014/03/19 17:48:42.417] Preparing to sync active users and groups

/Library/Logs/ProfileManager/dmrunnerd.log

[713] [2014/03/19 17:48:24.389] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [713] [2014/03/19 17:48:24.391]
    ##########################################################################
    dmrunnerd-864.18 (PID:713, OS:13C64, SERVER:13S4138, ARCH:x86_64) starting
    LA: dmrunnerd
    Log verbosity level = 1
    UID = 220, EUID = 220
    ##########################################################################
0:: [713] [2014/03/19 17:48:24.400] Started Rails process for port 3320, pid=716
0:: [713] [2014/03/19 17:48:24.400] Started Rails process for port 3321, pid=717
0:: [713] [2014/03/19 17:48:24.403] Started Rails process for port 3322, pid=718
0:: [713] [2014/03/19 17:48:24.407] Started Rails process for port 3323, pid=719
0:: [713] [2014/03/19 17:48:24.409] Started Rails process for port 3324, pid=720
0:: [713] [2014/03/19 17:48:24.421] Started Rails process for port 3325, pid=721
0:: [713] [2014/03/19 17:48:24.432] Started Rails process for port 3326, pid=722
0:: [713] [2014/03/19 17:48:24.443] Started Rails process for port 3327, pid=723
0:: [713] [2014/03/19 17:48:24.450] Started Rails process for port 3328, pid=724
0:: [713] [2014/03/19 17:48:24.455] Started Rails process for port 3329, pid=726
0:: [713] [2014/03/19 17:52:57.463] ShutdownDMRunner: 2014-03-19 16:52:57 +0000
0:: [713] [2014/03/19 17:52:57.464] BYE

/Library/Logs/ProfileManager/php.log

1::Mar 19 17:49:35.903 [740] <62.96.187.1> {LogElapsedTime (common.php:74)} Time since script start: 4462us [https://hydra.s-f.com/devicemanagement/api/device/mdm_connect]
1::Mar 19 17:49:35.903 [740] <62.96.187.1> {require_once (mdm_connect.php:11)} vvvvvvvvvvvvvvvvvvvvvvvvvvvvvvv - PUT mdm_connect
1::Mar 19 17:49:35.920 [740] <62.96.187.1> {Target_for_incoming_request (mdm_connect.php:20)} Found target Mac: <'c1723-iMac27'[2257](OWNER:User[258])>
0::Mar 19 17:49:35.921 [740] <62.96.187.1> {_connect_transaction_1 (db.php:393)} Status='Idle'
1::Mar 19 17:49:35.941 [740] <62.96.187.1> {SendFinalOutput (mdm_connect.php:133)} Sent Final Output (0 bytes)
1::Mar 19 17:49:35.941 [740] <62.96.187.1> ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ - /devicemanagement/mdm/mdm_connect
0::Mar 19 17:49:35.941 [740] <62.96.187.1> {SendFinalOutput (mdm_connect.php:133)} Completed in 44ms | 200 OK [https://hydra.s-f.com/devicemanagement/api/device/mdm_connect]
1::Mar 19 17:51:07.682 [741] <62.96.187.1> {LogElapsedTime (common.php:74)} Time since script start: 542us [https://hydra.s-f.com/devicemanagement/api/device/mdm_connect]
1::Mar 19 17:51:07.682 [741] <62.96.187.1> {require_once (mdm_connect.php:11)} vvvvvvvvvvvvvvvvvvvvvvvvvvvvvvv - PUT mdm_connect
1::Mar 19 17:51:07.690 [741] <62.96.187.1> {Target_for_incoming_request (mdm_connect.php:20)} Found target Mac: <'c1885-macbookpro'[2300]>
0::Mar 19 17:51:07.690 [741] <62.96.187.1> {_connect_transaction_1 (db.php:393)} Status='Idle'
1::Mar 19 17:51:07.711 [741] <62.96.187.1> {SendFinalOutput (mdm_connect.php:133)} Sent Final Output (0 bytes)
1::Mar 19 17:51:07.711 [741] <62.96.187.1> ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ - /devicemanagement/mdm/mdm_connect
0::Mar 19 17:51:07.711 [741] <62.96.187.1> {SendFinalOutput (mdm_connect.php:133)} Completed in 29ms | 200 OK [https://hydra.s-f.com/devicemanagement/api/device/mdm_connect]
1::Mar 19 17:51:15.281 [740] <62.96.187.1> {LogElapsedTime (common.php:74)} Time since script start: 864us [https://hydra.s-f.com/devicemanagement/api/device/mdm_connect]
1::Mar 19 17:51:15.281 [740] <62.96.187.1> {require_once (mdm_connect.php:11)} vvvvvvvvvvvvvvvvvvvvvvvvvvvvvvv - PUT mdm_connect
1::Mar 19 17:51:15.287 [740] <62.96.187.1> {Target_for_incoming_request (mdm_connect.php:20)} Found target Mac: <'c1735-macpro'[2417]>
0::Mar 19 17:51:15.288 [740] <62.96.187.1> {_connect_transaction_1 (db.php:393)} Status='Idle'
1::Mar 19 17:51:15.306 [740] <62.96.187.1> {SendFinalOutput (mdm_connect.php:133)} Sent Final Output (0 bytes)
1::Mar 19 17:51:15.306 [740] <62.96.187.1> ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ - /devicemanagement/mdm/mdm_connect
0::Mar 19 17:51:15.306 [740] <62.96.187.1> {SendFinalOutput (mdm_connect.php:133)} Completed in 26ms | 200 OK [https://hydra.s-f.com/devicemanagement/api/device/mdm_connect]

/Library/Logs/ProfileManager/PostgreSQL-2014-03-19.log

2014-03-19 17:48:24 CETLOG: connection received: host=[local]
2014-03-19 17:48:24 CETLOG: connection authorized: user=_devicemgr database=template1
2014-03-19 17:48:29 CETLOG: connection received: host=[local]
2014-03-19 17:48:29 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:30 CETLOG: statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-4081214-3" ("value" uuid UNIQUE)
2014-03-19 17:48:30 CETLOG: statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-4081215-2" ("value" uuid UNIQUE)
2014-03-19 17:48:30 CETLOG: statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-4081219-2" ("value" uuid UNIQUE)
2014-03-19 17:48:30 CETLOG: statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-4081223-4" ("value" uuid UNIQUE)
2014-03-19 17:48:34 CETLOG: connection received: host=[local]
2014-03-19 17:48:34 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:34 CETLOG: connection received: host=[local]
2014-03-19 17:48:34 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:34 CETLOG: connection received: host=[local]
2014-03-19 17:48:34 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:34 CETLOG: connection received: host=[local]
2014-03-19 17:48:34 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:34 CETLOG: connection received: host=[local]
2014-03-19 17:48:34 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:34 CETLOG: connection received: host=[local]
2014-03-19 17:48:34 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:34 CETLOG: connection received: host=[local]
2014-03-19 17:48:34 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:34 CETLOG: connection received: host=[local]
2014-03-19 17:48:34 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:34 CETLOG: connection received: host=[local]
2014-03-19 17:48:34 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:34 CETLOG: connection received: host=[local]
2014-03-19 17:48:34 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:37 CETLOG: connection received: host=[local]
2014-03-19 17:48:37 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:48:42 CETLOG: statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-4081709-2" ("value" uuid UNIQUE)
2014-03-19 17:48:42 CETLOG: statement: CREATE TEMPORARY TABLE "__runtime_temp_devicemgrd-4081710-2" ("value" uuid UNIQUE)
2014-03-19 17:49:35 CETLOG: connection received: host=[local]
2014-03-19 17:49:35 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:51:07 CETLOG: connection received: host=[local]
2014-03-19 17:51:07 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0
2014-03-19 17:51:15 CETLOG: connection received: host=[local]
2014-03-19 17:51:15 CETLOG: connection authorized: user=_devicemgr database=devicemgr_v2m0

/Library/Logs/ProfileManager/profilemanager.log
[720] [2014/03/19 17:48:26.975] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [720] [2014/03/19 17:48:26.982]
    ###############################################################################
    profilemanager-864.18 (PID:720, OS:13C64, SERVER:13S4138, ARCH:x86_64) starting
    Log verbosity level = 1
    UID = 220, EUID = 220
    ###############################################################################
0:: [720] [2014/03/19 17:48:26.983] -[SULogFileCollection setGlobalLogLevelPrefix:]: NO
[726] [2014/03/19 17:48:26.976] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [726] [2014/03/19 17:48:26.982]
    ###############################################################################
    profilemanager-864.18 (PID:726, OS:13C64, SERVER:13S4138, ARCH:x86_64) starting
    Log verbosity level = 1
    UID = 220, EUID = 220
    ###############################################################################
0:: [726] [2014/03/19 17:48:26.983] -[SULogFileCollection setGlobalLogLevelPrefix:]: NO
[717] [2014/03/19 17:48:26.985] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [717] [2014/03/19 17:48:26.995]
    ###############################################################################
    profilemanager-864.18 (PID:717, OS:13C64, SERVER:13S4138, ARCH:x86_64) starting
    Log verbosity level = 1
    UID = 220, EUID = 220
    ###############################################################################
0:: [717] [2014/03/19 17:48:26.995] -[SULogFileCollection setGlobalLogLevelPrefix:]: NO
[723] [2014/03/19 17:48:26.982] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [723] [2014/03/19 17:48:27.002]
    ###############################################################################
    profilemanager-864.18 (PID:723, OS:13C64, SERVER:13S4138, ARCH:x86_64) starting
    Log verbosity level = 1
    UID = 220, EUID = 220
    ###############################################################################
0:: [723] [2014/03/19 17:48:27.002] -[SULogFileCollection setGlobalLogLevelPrefix:]: NO
[722] [2014/03/19 17:48:26.999] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [722] [2014/03/19 17:48:27.008]
    ###############################################################################
    profilemanager-864.18 (PID:722, OS:13C64, SERVER:13S4138, ARCH:x86_64) starting
    Log verbosity level = 1
    UID = 220, EUID = 220
    ###############################################################################
0:: [722] [2014/03/19 17:48:27.008] -[SULogFileCollection setGlobalLogLevelPrefix:]: NO
[724] [2014/03/19 17:48:26.995] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [724] [2014/03/19 17:48:27.009]
    ###############################################################################
    profilemanager-864.18 (PID:724, OS:13C64, SERVER:13S4138, ARCH:x86_64) starting
    Log verbosity level = 1
    UID = 220, EUID = 220
    ###############################################################################
0:: [724] [2014/03/19 17:48:27.009] -[SULogFileCollection setGlobalLogLevelPrefix:]: NO
[718] [2014/03/19 17:48:27.003] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [718] [2014/03/19 17:48:27.011]
    ###############################################################################
    profilemanager-864.18 (PID:718, OS:13C64, SERVER:13S4138, ARCH:x86_64) starting
    Log verbosity level = 1
    UID = 220, EUID = 220
    ###############################################################################
0:: [718] [2014/03/19 17:48:27.012] -[SULogFileCollection setGlobalLogLevelPrefix:]: NO
[721] [2014/03/19 17:48:27.030] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [721] [2014/03/19 17:48:27.036]
    ###############################################################################
    profilemanager-864.18 (PID:721, OS:13C64, SERVER:13S4138, ARCH:x86_64) starting
    Log verbosity level = 1
    UID = 220, EUID = 220
    ###############################################################################
0:: [721] [2014/03/19 17:48:27.036] -[SULogFileCollection setGlobalLogLevelPrefix:]: NO
[716] [2014/03/19 17:48:27.034] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [716] [2014/03/19 17:48:27.042]
    ###############################################################################
    profilemanager-864.18 (PID:716, OS:13C64, SERVER:13S4138, ARCH:x86_64) starting
    Log verbosity level = 1
    UID = 220, EUID = 220
    ###############################################################################
0:: [716] [2014/03/19 17:48:27.042] -[SULogFileCollection setGlobalLogLevelPrefix:]: NO
[719] [2014/03/19 17:48:27.063] -[SULogFileCollection setGlobalLogLevelPrefix:]: YES
0:: [719] [2014/03/19 17:48:27.079]
    ###############################################################################
    profilemanager-864.18 (PID:719, OS:13C64, SERVER:13S4138, ARCH:x86_64) starting
    Log verbosity level = 1
    UID = 220, EUID = 220
    ###############################################################################
0:: [719] [2014/03/19 17:48:27.079] -[SULogFileCollection setGlobalLogLevelPrefix:]: NO
[716] [2014/03/19 17:48:28.787] I: Logging preferences updated: level = 1, debugOutput = 0
[726] [2014/03/19 17:48:28.800] I: Logging preferences updated: level = 1, debugOutput = 0
[718] [2014/03/19 17:48:28.826] I: Logging preferences updated: level = 1, debugOutput = 0
[723] [2014/03/19 17:48:28.849] I: Logging preferences updated: level = 1, debugOutput = 0
[721] [2014/03/19 17:48:28.852] I: Logging preferences updated: level = 1, debugOutput = 0
[722] [2014/03/19 17:48:28.855] I: Logging preferences updated: level = 1, debugOutput = 0
[719] [2014/03/19 17:48:28.856] I: Logging preferences updated: level = 1, debugOutput = 0
[717] [2014/03/19 17:48:28.864] I: Logging preferences updated: level = 1, debugOutput = 0
[724] [2014/03/19 17:48:28.864] I: Logging preferences updated: level = 1, debugOutput = 0
[720] [2014/03/19 17:48:28.886] I: Logging preferences updated: level = 1, debugOutput = 0
[716] [2014/03/19 17:48:36.175] I: Processing DeviceController#start_ota (for ::1 at 2014-03-19 17:48:36) [GET]
[716] [2014/03/19 17:48:36.177] I: auth_token doesn't exist
[716] [2014/03/19 17:48:36.177] I: Redirected to https://localhost/auth?redirect=https://localhost/devicemanagement/api/authentic ation/device_callback
[716] [2014/03/19 17:48:36.177] I: Filter chain halted as [:verify_device_auth_token] rendered_or_redirected.
[716] [2014/03/19 17:48:36.177] I: Completed in 2ms (DB: 11) | 302 Found [http://localhost/device/start_ota]

Before the restart I runned the command:

sudo -u _devicemgr /Applications/Server.app/Contents/ServerRoot/usr/bin/psql -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "DELETE FROM db_notifications WHERE message LIKE '[DMDevicemgrd updateAllProfilesForID:%';"
DELETE 0

hydra:Logs root# sudo -u _devicemgr /Applications/Server.app/Contents/ServerRoot/usr/bin/psql -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "SELECT p.id, p.name, p.library_item_id, p.library_item_type, l.library_item_type FROM profiles AS p JOIN library_items AS l ON p.library_item_id = l.id WHERE p.library_item_type IS NULL;"
id | name | library_item_id | library_item_type | library_item_type
----+------+-----------------+-------------------+-------------------
(0 rows)

hydra:Logs root# sudo -u _devicemgr /Applications/Server.app/Contents/ServerRoot/usr/bin/psql -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "DELETE FROM db_notifications WHERE message LIKE '[DMDevicemgrd updateAllProfilesForID:%';"
DELETE 0
hydra:Logs root# sudo -u _devicemgr /Applications/Server.app/Contents/ServerRoot/usr/bin/psql -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "SELECT id, name, uuid, version, library_item_id, library_item_type FROM profiles WHERE library_item_id = 13;"
id | name | uuid | version | library_item_id | library_item_type
----+------+------+---------+-----------------+-------------------
(0 rows)

hydra:Logs root# sudo -u _devicemgr /Applications/Server.app/Contents/ServerRoot/usr/bin/psql -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "SELECT * FROM library_items WHERE id = 13;"
id | admin_temp_id |         created_at         |         updated_at         | library_item_type
----+---------------+----------------------------+----------------------------+- ------------------
13 |               | 2013-09-06 13:31:28.207353 | 2014-03-18 17:05:23.421446 | UserGroup
(1 row)

hydra:Logs root# sudo psql -U _devicemgr -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "UPDATE devices SET last_update_info_time = dm_current_timestamp() + '1 month'"
UPDATE 29

After I run this commands it looks like the log files are not more increasing that much, but I have still high CPU load.
dmrunnerd is almost on 100%

Thank you for any assistance.

Patrick.
Reply options

Link to this post

by mscott_mdm,

mscott_mdm Mar 19, 2014 10:47 AM in response to Patrick Fist
Level 2 (225 points)

Mar 19, 2014 10:47 AM in response to Patrick Fist

Patrick,

Please try the commands I posted today. Unfortunately, this thread has been recycled and the original fix I posted was for a different problem (albeit with similar symptoms). The problem you're experiencing now is almost certainly fixed by the two SQL commands I posted earlier.
Reply options

Link to this post

by Patrick Fist,

Patrick Fist Mar 19, 2014 11:06 AM in response to mscott_mdm
Level 1 (50 points)

Mar 19, 2014 11:06 AM in response to mscott_mdm

Hey mscott,

Im sorry but my english is not good enough to seperate the different problems from the same symptomes.
I run already following commands, but the process dmrunnerd is still on 100% since my last post.
devicemgrd is on 35$ cpu load, postgres_real on 21.5 and for any reason opendirectoryd on 70.7.

hydra:ProfileManager root# sudo psql -U _devicemgr -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "UPDATE devices SET last_update_info_time = dm_current_timestamp() + '1 month'"
UPDATE 29
hydra:ProfileManager root# sudo psql -U _devicemgr -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "ALTER TABLE devices ALTER COLUMN last_update_info_time SET DEFAULT dm_current_timestamp() + '1 month'"
ALTER TABLE

Which commands did you exactly mean?

Thank you for assistance.
Reply options

Link to this post

by mscott_mdm,

mscott_mdm Mar 19, 2014 11:30 AM in response to Patrick Fist
Level 2 (225 points)

Mar 19, 2014 11:30 AM in response to Patrick Fist

Patrick,

Yes, those are the commands to run. It might take a little while for your CPU usage to drop, but I would have expected it to be back to normal by now. But dmrunnerd running at 100% might be different issue. Can you paste the last 20 or so lines from /Library/Logs/ProfileManager/dmrunnerd.log?

Also, you very likely have a lot of log data in /Library/Logs/ProfileManager. Feel free to run either of these commands:

sudo rm -f /Library/Logs/ProfileManager/*.bz2

and/or

sudo rm -f /Library/Logs/ProfileManager/*.log

(The latter command will make debuging any prior problems difficult, but it may be necessary to get dmrunnerd settled down. There's a good chance it's busy compressing the numerous large log files you probably have in /Library/Logs/ProfileManager/.)
Reply options

Link to this post

by 4nNtt,

4nNtt Mar 19, 2014 3:25 PM in response to mscott_mdm
Level 1 (0 points)

Mar 19, 2014 3:25 PM in response to mscott_mdm

I'm running in to this too. I just changed permissions on the php.log file (sudo chmod 000 /Library/Logs/ProfileManager/php.log) to prevent it from growing for now. Looks like there are some bugs in the new DEP/VPP features that are implemented in PHP instead of Ruby.
Reply options

Link to this post

by kjm_bkk,

kjm_bkk Mar 20, 2014 12:40 AM in response to mscott_mdm
Level 1 (0 points)

Mar 20, 2014 12:40 AM in response to mscott_mdm

Done that too.

-rw-r----- 1 _devicemgr admin 96K 20 Mar 14:12 php.log
-rw-r----- 1 _devicemgr admin 19G 19 Mar 17:56 php.log.0

All seems to be back to normal logs and cpu load wise. I'm just mopping the perspiration off my mac minis brow!

Does any of this need to be reported to Apple, or do we assume they know and will provide a proper fix?

Thanks again for your help.
Reply options

Link to this post

by Patrick Fist,

Patrick Fist Mar 20, 2014 3:15 AM in response to mscott_mdm
Level 1 (50 points)

Mar 20, 2014 3:15 AM in response to mscott_mdm

Hello mscott_mdm,

thank you.
But now for any reason the opendirectoryd is on 80% load, devicemgrd down on 35% and postgres_real on 20%.

PostgreSQL log files stopped increasing. But I am getting much more devicemgrd-$Date+$Time.log files now.
One new log files every 10 Minutes as I can see.

The System freezed last night about half past six a.m.
Restartet the machine at ~10:30.
hydra:Logs root# tail -n 200 /var/log/opendirectoryd.log
2014-03-20 06:53:37.114916 CET - opendirectoryd (build 339.90.1) launched...
2014-03-20 06:53:37.117476 CET - Logging level limit changed to 'error'
2014-03-20 06:53:37.130607 CET - Initialize trigger support
2014-03-20 06:53:37.137539 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/SystemCache.bundle'
2014-03-20 06:53:37.144485 CET - Registered node with name '/Active Directory' as hidden
2014-03-20 06:53:37.144780 CET - Registered node with name '/Configure' as hidden
2014-03-20 06:53:37.145186 CET - Discovered configuration for node name '/Contacts' at path '/Library/Preferences/OpenDirectory/Configurations//Contacts.plist'
2014-03-20 06:53:37.145200 CET - Registered node with name '/Contacts'
2014-03-20 06:53:37.145482 CET - Registered node with name '/LDAPv3' as hidden
2014-03-20 06:53:37.147642 CET - Registered node with name '/Local' as hidden
2014-03-20 06:53:37.148840 CET - Registered node with name '/NIS' as hidden
2014-03-20 06:53:37.149276 CET - Discovered configuration for node name '/Search' at path '/Library/Preferences/OpenDirectory/Configurations//Search.plist'
2014-03-20 06:53:37.149291 CET - Registered node with name '/Search'
2014-03-20 06:53:37.150026 CET - Discovered configuration for node name '/LDAPv3/127.0.0.1' at path '/Library/Preferences/OpenDirectory/Configurations/LDAPv3/127.0.0.1.plist'
2014-03-20 06:53:37.150143 CET - Registered subnode with name '/LDAPv3/127.0.0.1'
2014-03-20 06:53:37.150823 CET - Discovered configuration for node name '/LDAPv3/172.30.64.4' at path '/Library/Preferences/OpenDirectory/Configurations/LDAPv3/172.30.64.4.plist'
2014-03-20 06:53:37.150842 CET - Registered subnode with name '/LDAPv3/172.30.64.4'
2014-03-20 06:53:37.152488 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/legacy.bundle'
2014-03-20 06:53:37.154860 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/search.bundle'
2014-03-20 06:53:37.158014 CET - '/Search' has registered, loading additional services
2014-03-20 06:53:37.158025 CET - Initialize augmentation support
2014-03-20 06:53:37.162139 CET - Successfully registered for Kernel identity service requests
2014-03-20 06:53:37.162152 CET - Adjusting kernel ID cache (100 -> 250) and membership cache (100 -> 500)
2014-03-20 06:53:37.429682 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/PlistFile.bundle'
2014-03-20 06:53:37.432091 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/FDESupport.bundle'
2014-03-20 06:53:37.436327 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/AppleID.bundle'
2014-03-20 06:53:37.454763 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/ConfigurationProfiles.bundle'
2014-03-20 06:53:37.457095 CET - Registered subnode with name '/Local/Default'
2014-03-20 06:53:37.462530 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/ldap.bundle'
2014-03-20 06:53:37.479377 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/AppleODClientLDAP.bundle'
2014-03-20 06:53:37.484055 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/AppleODClientPWS.bundle'
2014-03-20 06:53:42.449835 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/configure.bundle'
2014-03-20 06:53:42.451448 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/keychain.bundle'
2014-03-20 10:28:50.788041 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/ActiveDirectory.bundle'

hydra:Logs root# tail -n 200 /var/log/opendirectoryd.log.0
2014-03-20 06:45:58.732271 CET - opendirectoryd (build 339.90.1) launched...
2014-03-20 06:45:58.888849 CET - Logging level limit changed to 'error'
2014-03-20 06:45:59.336414 CET - Initialize trigger support
2014-03-20 06:45:59.473728 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/SystemCache.bundle'
2014-03-20 06:45:59.909198 CET - Registered node with name '/Active Directory' as hidden
2014-03-20 06:45:59.909646 CET - Registered node with name '/Configure' as hidden
2014-03-20 06:45:59.911124 CET - Discovered configuration for node name '/Contacts' at path '/Library/Preferences/OpenDirectory/Configurations//Contacts.plist'
2014-03-20 06:45:59.911136 CET - Registered node with name '/Contacts'
2014-03-20 06:45:59.911800 CET - Registered node with name '/LDAPv3' as hidden
2014-03-20 06:45:59.917412 CET - Registered node with name '/Local' as hidden
2014-03-20 06:45:59.919239 CET - Registered node with name '/NIS' as hidden
2014-03-20 06:45:59.920112 CET - Discovered configuration for node name '/Search' at path '/Library/Preferences/OpenDirectory/Configurations//Search.plist'
2014-03-20 06:45:59.920173 CET - Registered node with name '/Search'
2014-03-20 06:45:59.922775 CET - Discovered configuration for node name '/LDAPv3/127.0.0.1' at path '/Library/Preferences/OpenDirectory/Configurations/LDAPv3/127.0.0.1.plist'
2014-03-20 06:45:59.922958 CET - Registered subnode with name '/LDAPv3/127.0.0.1'
2014-03-20 06:45:59.924325 CET - Discovered configuration for node name '/LDAPv3/172.30.64.4' at path '/Library/Preferences/OpenDirectory/Configurations/LDAPv3/172.30.64.4.plist'
2014-03-20 06:45:59.924407 CET - Registered subnode with name '/LDAPv3/172.30.64.4'
2014-03-20 06:45:59.939244 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/legacy.bundle'
2014-03-20 06:45:59.960160 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/search.bundle'
2014-03-20 06:46:00.149087 CET - '/Search' has registered, loading additional services
2014-03-20 06:46:00.149115 CET - Initialize augmentation support
2014-03-20 06:46:00.200094 CET - Successfully registered for Kernel identity service requests
2014-03-20 06:46:20.375494 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/PlistFile.bundle'
2014-03-20 06:46:20.740221 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/FDESupport.bundle'
2014-03-20 06:46:21.141838 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/AppleID.bundle'
2014-03-20 06:46:23.070287 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/ConfigurationProfiles.bundle'
2014-03-20 06:46:23.118848 CET - Registered subnode with name '/Local/Default'
2014-03-20 06:46:23.208386 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/ldap.bundle'
2014-03-20 06:46:23.806707 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/AppleODClientLDAP.bundle'
2014-03-20 06:46:23.938565 CET - Loaded bundle at path '/System/Library/OpenDirectory/Modules/AppleODClientPWS.bundle'
2014-03-20 06:51:50.604312 CET - Starting shutdown process...

devicemgrd.log is repeating this message all time:

1:: [164] [2014/03/20 11:13:50.633] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.637] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.643] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.648] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.655] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.659] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.666] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.670] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.676] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.681] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.687] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.691] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.698] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.702] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.709] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.713] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.720] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.724] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.731] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.735] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.742] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.746] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.752] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.757] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.763] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.768] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.774] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.778] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.785] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.789] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.796] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.800] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.807] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.811] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.818] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.821] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.828] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.832] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.839] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.843] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.850] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records
1:: [164] [2014/03/20 11:13:50.854] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records
1:: [164] [2014/03/20 11:13:50.861] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

Any Idea? :-).
Reply options

Link to this post

by mscott_mdm,

mscott_mdm Mar 20, 2014 7:47 AM in response to Patrick Fist
Level 2 (225 points)

Mar 20, 2014 7:47 AM in response to Patrick Fist

Patrick,

This may be leftover accumulated work from the original problem. If this still hasn't stopped, you should try these commands:

sudo psql -U _devicemgr -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "DELETE FROM db_notifications"

sudo killall -u _devicemgr

If this does not stop the repeated logging in devicemgrd.log, then something else is going on and I'd need to see some different log output.
Reply options

Link to this post

by Patrick Fist,

Patrick Fist Mar 21, 2014 5:21 AM in response to mscott_mdm
Level 1 (50 points)

Mar 21, 2014 5:21 AM in response to mscott_mdm

Hello mscott_mdm,

which Logs do you like to see? :-).
mscott_mdm wrote:

Patrick,

This may be leftover accumulated work from the original problem. If this still hasn't stopped, you should try these commands:

sudo psql -U _devicemgr -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "DELETE FROM db_notifications"

sudo killall -u _devicemgr

If this does not stop the repeated logging in devicemgrd.log, then something else is going on and I'd need to see some different log output.
Reply options

Link to this post

first Page 5 of 7 last

Q: devicemgrd null ptr error loop causing high cpu load.

All replies Helpful answers

by mscott_mdm,

by kjm_bkk,

by mscott_mdm,

by kjm_bkk,

by fkick1,

by mscott_mdm,

by Patrick Fist,

by mscott_mdm,

by Patrick Fist,

by mscott_mdm,

by 4nNtt,

by kjm_bkk,

by Patrick Fist,

by mscott_mdm,

by Patrick Fist,

All replies

Helpful answers