Looks like no one’s replied in a while. To start the conversation again, simply ask a new question.

User profile for user: Hplogsdon
Hplogsdon Author
User level: Level 1
0 points

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

Reply
94 replies
User profile for user: kjm_bkk
kjm_bkk
User level: 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
User profile for user: mscott_mdm
mscott_mdm
User level: Level 2
459 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
User profile for user: Patrick Fist
Patrick Fist
User level: Level 1
51 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
User profile for user: Patrick Fist
Patrick Fist
User level: Level 1
51 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
User profile for user: mscott_mdm
mscott_mdm
User level: Level 2
459 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
User profile for user: kjm_bkk
kjm_bkk
User level: 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
User profile for user: Patrick Fist
Patrick Fist
User level: Level 1
51 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
User profile for user: mscott_mdm
mscott_mdm
User level: Level 2
459 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
User profile for user: Patrick Fist
Patrick Fist
User level: Level 1
51 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
User profile for user: mscott_mdm
mscott_mdm
User level: Level 2
459 points

Mar 21, 2014 8:00 AM in response to Patrick Fist

Hi Patrick,


Well, that's disappointing. 😐


First, we're going to need to increase the logging verbosity for Profile Manager. Let's start with level 3:


sudo defaults write /Library/Preferences/com.apple.ProfileManager debugOutput 3

sudo killall -u _devicemgr


Give it a couple of minutes to get through any startup activities and then let's see the last 100 lines of the devicemgrd.log file. Then to save your disk, turn off the verbose logging:


sudo defaults delete /Library/Preferences/com.apple.ProfileManager debugOutput

sudo killall -u _devicemgr


Thanks.

Reply

devicemgrd null ptr error loop causing high cpu load.

Welcome to Apple Support Community
A forum where Apple customers help each other with their products. Get started with your Apple ID.
Learn more Sign up