devicemgrd null ptr error loop causing high cpu load.

mscott_mdm, those commands did the trick, although I'm still seeing some postgres sql serialization errors in the service helper log.

e.g.

CONTEXT: SQL statement "SELECT 1 FROM ONLY "public"."devices" x WHERE "id" OPERATOR(pg_catalog.=) $1 FOR SHARE OF x"

)">

1:: [41356] [2014/03/21 10:09:56.861] @@@ performInTransaction: -[PGConnection executeSQL:withParams:] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-864.18/Compiled/Fra mework-Models/Postgres/PGConnection.m:421): "Postgres error 40001 (ERROR: could not serialize access due to concurrent update

CONTEXT: SQL statement "SELECT 1 FROM ONLY "public"."devices" x WHERE "id" OPERATOR(pg_catalog.=) $1 FOR SHARE OF x"

)" @@@

1:: [41356] [2014/03/21 10:09:56.861] @@@ Retry 1 due to database transaction failure.... (in 3039us) @@@

1:: [41356] [2014/03/21 10:11:32.018] Incoming request: readSettings

1:: [41356] [2014/03/21 10:12:05.082] Incoming request: readSettings

0:: [41356] [2014/03/21 10:13:05.725] EXCEPTION: Postgres <-[PGConnection executeSQL:withParams:] (/SourceCache/RemoteDeviceManagement/RemoteDeviceManagement-864.18/Compiled/Fra mework-Models/Postgres/PGConnection.m:421): "Postgres error 40001 (ERROR: could not serialize access due to concurrent update

mscott_mdm wrote:

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.

jwzg2,

Serialization "errors" are not true errors, they are indications that two or more transactions to the database made conflicting changes and the transactions need to be retried. You can expect to see this periodically, but the logs also include a "@@@ Retry 1 due to database transaction failure...." line immediately afterwards to indicate that the retry is happening. The count will go up on successive failures of the same transaction, but unless the counts get really high (say, >10) this is pretty much normal behavior.

The heavier the load on the server, the more likely you are to see these, too, because there are more transactions being processed simultaneously.

Good evening mscott_mdm,

I followed your instructions:

This is how devicemgr.log looks like after killall -u _devicemgr

ProfileManager root# tail -f devicemgrd.log

2:: [21527] [2014/03/21 19:06:44.279] -[DMDevicemgrd setPushTimer]: Postponing sending push notifications for 5.000000 seconds

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] scoreString: W_............................................................................. ................................................................................ ................................................................................ ................................................................................ ....................................

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:06:47.343] -[ApacheMonitor _fetchApacheStatus]:idle_count: 354

2:: [21527] [2014/03/21 19:06:47.343] Parsed '[DMDevicemgrd sendPendingPushNotifications:4164670]' to selector 'sendPendingPushNotifications:' with arguments (

4164670

)

2:: [21527] [2014/03/21 19:06:47.344] -[DMDevicemgrd setPushTimer]: Already have pending push notification timer (fires in 1.934747 seconds)

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] scoreString: W_............................................................................. ................................................................................ ................................................................................ ................................................................................ ....................................

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:06:51.415] -[ApacheMonitor _fetchApacheStatus]:idle_count: 354

2:: [21527] [2014/03/21 19:06:51.416] Parsed '[DMDevicemgrd sendPendingPushNotifications:4164672]' to selector 'sendPendingPushNotifications:' with arguments (

4164672

)

2:: [21527] [2014/03/21 19:06:51.417] -[DMDevicemgrd setPushTimer]: Postponing sending push notifications for 5.000000 seconds

3:: [21527] scoreString: W_............................................................................. ................................................................................ ................................................................................ ................................................................................ ....................................

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:06:55.566] -[ApacheMonitor _fetchApacheStatus]:idle_count: 354

2:: [21527] [2014/03/21 19:06:55.566] Parsed '[DMDevicemgrd sendPendingPushNotifications:4164674]' to selector 'sendPendingPushNotifications:' with arguments (

4164674

)

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:06:56.558] -[DMDevicemgrd setPushTimer]: Postponing sending push notifications for 5.000000 seconds

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] scoreString: W__............................................................................ ................................................................................ ................................................................................ ................................................................................ ....................................

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:06:59.697] -[ApacheMonitor _fetchApacheStatus]:idle_count: 354

2:: [21527] [2014/03/21 19:06:59.697] Parsed '[DMDevicemgrd sendPendingPushNotifications:4164676]' to selector 'sendPendingPushNotifications:' with arguments (

4164676

)

2:: [21527] [2014/03/21 19:06:59.698] -[DMDevicemgrd setPushTimer]: Already have pending push notification timer (fires in 1.860582 seconds)

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Readying connection [<PGconnObj:0x7fd950f00a40> connection #1 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f00a40> connection #1 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] scoreString: WWWWWWRRR_..................................................................... ................................................................................ ................................................................................ ................................................................................ ....................................

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:07:03.845] -[ApacheMonitor _fetchApacheStatus]:idle_count: 346

2:: [21527] [2014/03/21 19:07:03.846] Parsed '[DMDevicemgrd sendPendingPushNotifications:4164678]' to selector 'sendPendingPushNotifications:' with arguments (

4164678

)

2:: [21527] [2014/03/21 19:07:03.847] -[DMDevicemgrd setPushTimer]: Postponing sending push notifications for 5.000000 seconds

3:: [21527] scoreString: W.___.___C..................................................................... ................................................................................ ................................................................................ ................................................................................ ....................................

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:07:07.998] -[ApacheMonitor _fetchApacheStatus]:idle_count: 353

2:: [21527] [2014/03/21 19:07:07.998] Parsed '[DMDevicemgrd sendPendingPushNotifications:4164680]' to selector 'sendPendingPushNotifications:' with arguments (

4164680

)

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:07:08.996] -[DMDevicemgrd setPushTimer]: Postponing sending push notifications for 5.000000 seconds

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] scoreString: W.C...._R...................................................................... ................................................................................ ................................................................................ ................................................................................ ....................................

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:07:12.148] -[ApacheMonitor _fetchApacheStatus]:idle_count: 352

2:: [21527] [2014/03/21 19:07:12.148] Parsed '[DMDevicemgrd sendPendingPushNotifications:4164682]' to selector 'sendPendingPushNotifications:' with arguments (

4164682

)

2:: [21527] [2014/03/21 19:07:12.149] -[DMDevicemgrd setPushTimer]: Already have pending push notification timer (fires in 1.846956 seconds)

2:: [21527] [2014/03/21 19:07:12.385] Parsed '[DMDevicemgrd processODSearches]' to selector 'processODSearches' with arguments (

)

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

0:: [21527] [2014/03/21 19:07:12.387] NextSearch: 57973286-6E91-45F9-8537-E8A2A50CDA7C

3:: [21527] -[DMODSyncRunner startSyncWithQueryDictionary:completionHandler:]: Enqueuing query = {

attribute = "dsAttrTypeStandard:GeneratedUID";

"match_type" = 8193;

"query_values" = (

"57973286-6E91-45F9-8537-E8A2A50CDA7C"

);

"search_type" = 1;

}

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:07:12.388] -[DMODSyncRunner _beginNonStateMachineBlock]: Starting non-state machine block operation

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

1:: [21527] [2014/03/21 19:07:12.388] Starting sync of query <DMODQueries 0x7fd950ed9a80> search_type = 1, attribute = 'dsAttrTypeStandard:GeneratedUID', match_type = 8193, query_values = [57973286-6E91-45F9-8537-E8A2A50CDA7C]

2:: [21527] [2014/03/21 19:07:12.388] Search type: user single attribute search

2:: [21527] [2014/03/21 19:07:12.393] Parsed '[DMDevicemgrd processODSearches]' to selector 'processODSearches' with arguments (

)

2:: [21527] [2014/03/21 19:07:12.395] Parsed '[DMDevicemgrd processODSearches]' to selector 'processODSearches' with arguments (

)

0:: [21527] [2014/03/21 19:07:12.395] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164688-2_value_key" for table "__runtime_temp_devicemgrd-4164688-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.395] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164688-2')

2:: [21527] [2014/03/21 19:07:12.403] -[DMODSyncRunner _syncAbstractUsers:fromODAttributeMap:]: Syncing active user admin with GUID 57973286-6E91-45F9-8537-E8A2A50CDA7C

1:: [21527] [2014/03/21 19:07:12.411] -[DMODSyncRunner _syncUserODRecords:createActive:state:]: Processed 1 user records

3:: [21527] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: Done processing query.

————————————+——— Sync/Query State —————————————————

Work Queues | user | <unused>

| ————————————————+—————————

| sparse group | <unused>

| immediate group | <unused>

| recursive group | <unused>

| ————————————————+—————————

| deletions | <unused>

| membership | <unused>

————————————+——————————————————————————————————————

Visit Lists | user | 1

| ————————————————+—————————

| sparse group | <unused>

| immediate group | <unused>

| recursive group | <unused>

————————————+——————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.412] Sync of query <DMODQueries 0x7fd950ed9a80> search_type = 1, attribute = 'dsAttrTypeStandard:GeneratedUID', match_type = 8193, query_values = [57973286-6E91-45F9-8537-E8A2A50CDA7C] took 0.024 seconds

2:: [21527] [2014/03/21 19:07:12.413] -[DMODSyncRunner _endNonStateMachineBlock:]: Completed non-state machine block operation

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

0:: [21527] [2014/03/21 19:07:12.415] NextSearch: ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050

3:: [21527] -[DMODSyncRunner startSyncWithQueryDictionary:completionHandler:]: Enqueuing query = {

attribute = "dsAttrTypeStandard:GeneratedUID";

"match_type" = 8193;

"query_values" = (

"ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050"

);

"search_type" = 2;

}

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

0:: [21527] [2014/03/21 19:07:12.416] NextSearch: (null)

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

0:: [21527] [2014/03/21 19:07:12.417] NextSearch: (null)

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:07:12.417] -[DMODSyncRunner _beginNonStateMachineBlock]: Starting non-state machine block operation

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

1:: [21527] [2014/03/21 19:07:12.417] Starting sync of query <DMODQueries 0x7fd953c16690> search_type = 2, attribute = 'dsAttrTypeStandard:GeneratedUID', match_type = 8193, query_values = [ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050]

2:: [21527] [2014/03/21 19:07:12.417] Search type: group single attribute search (non-recursive)

0:: [21527] [2014/03/21 19:07:12.421] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164698-2_value_key" for table "__runtime_temp_devicemgrd-4164698-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.421] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164698-2')

0:: [21527] [2014/03/21 19:07:12.428] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164699-2_value_key" for table "__runtime_temp_devicemgrd-4164699-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.428] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164699-2')

0:: [21527] [2014/03/21 19:07:12.435] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164700-2_value_key" for table "__runtime_temp_devicemgrd-4164700-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.435] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164700-2')

2:: [21527] [2014/03/21 19:07:12.440] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Processing group admin (ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050) with requested sync mode: known children only, create active: NO

3:: [21527] Reloading invalidated object [<DMODNodeRef:0x7fd953c019e0> pk=2]

0:: [21527] [2014/03/21 19:07:12.452] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164702-2_value_key" for table "__runtime_temp_devicemgrd-4164702-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.452] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164702-2')

0:: [21527] [2014/03/21 19:07:12.459] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164703-2_value_key" for table "__runtime_temp_devicemgrd-4164703-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.459] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164703-2')

0:: [21527] [2014/03/21 19:07:12.466] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164704-2_value_key" for table "__runtime_temp_devicemgrd-4164704-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.466] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164704-2')

0:: [21527] [2014/03/21 19:07:12.477] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164705-4_value_key" for table "__runtime_temp_devicemgrd-4164705-4"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.477] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164705-4')

3:: [21527] Finished syncing group ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050 in mode 0 (known children only)

1:: [21527] [2014/03/21 19:07:12.486] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 1 group records

3:: [21527] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: After search:

————————————+——— Sync/Query State —————————————————

Work Queues | user | 0

| ————————————————+—————————

| sparse group | 0

| immediate group | 0

| recursive group | 0

| ————————————————+—————————

| deletions | <unused>

| membership | <unused>

————————————+——————————————————————————————————————

Visit Lists | user | <unused>

| ————————————————+—————————

| sparse group | 1

| immediate group | 0

| recursive group | 0

————————————+——————————————————————————————————————

3:: [21527] -[DMODSyncRunner _processGroupWorkQueuesWithState:trackDeletion:]: processing group work queue for mode: all children and their descendents

3:: [21527] -[DMODSyncRunner _processGroupWorkQueuesWithState:trackDeletion:]: processing group work queue for mode: all children

3:: [21527] -[DMODSyncRunner _processGroupWorkQueuesWithState:trackDeletion:]: processing group work queue for mode: known children only

3:: [21527] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: Done processing work queue generated by search results

3:: [21527] Notified admin of 0 of 0 updated recursively sync groups

0:: [21527] [2014/03/21 19:07:12.493] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164707-2_value_key" for table "__runtime_temp_devicemgrd-4164707-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.493] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164707-2')

3:: [21527] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: Done processing query.

————————————+——— Sync/Query State —————————————————

Work Queues | user | 0

| ————————————————+—————————

| sparse group | 0

| immediate group | 0

| recursive group | 0

| ————————————————+—————————

| deletions | <unused>

| membership | 0

————————————+——————————————————————————————————————

Visit Lists | user | <unused>

| ————————————————+—————————

| sparse group | 1

| immediate group | 0

| recursive group | 0

————————————+——————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.500] Sync of query <DMODQueries 0x7fd953c16690> search_type = 2, attribute = 'dsAttrTypeStandard:GeneratedUID', match_type = 8193, query_values = [ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050] took 0.083 seconds

2:: [21527] [2014/03/21 19:07:12.501] -[DMODSyncRunner _endNonStateMachineBlock:]: Completed non-state machine block operation

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

0:: [21527] [2014/03/21 19:07:12.508] NextSearch: 57973286-6e91-45f9-8537-e8a2a50cda7c

3:: [21527] -[DMODSyncRunner startSyncWithQueryDictionary:completionHandler:]: Enqueuing query = {

attribute = "dsAttrTypeStandard:GeneratedUID";

"match_type" = 8193;

"query_values" = (

"57973286-6e91-45f9-8537-e8a2a50cda7c"

);

"search_type" = 1;

}

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:07:12.509] -[DMODSyncRunner _beginNonStateMachineBlock]: Starting non-state machine block operation

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

1:: [21527] [2014/03/21 19:07:12.509] Starting sync of query <DMODQueries 0x7fd954001410> search_type = 1, attribute = 'dsAttrTypeStandard:GeneratedUID', match_type = 8193, query_values = [57973286-6E91-45F9-8537-E8A2A50CDA7C]

2:: [21527] [2014/03/21 19:07:12.509] Search type: user single attribute search

0:: [21527] [2014/03/21 19:07:12.514] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164718-2_value_key" for table "__runtime_temp_devicemgrd-4164718-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.514] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164718-2')

2:: [21527] [2014/03/21 19:07:12.521] -[DMODSyncRunner _syncAbstractUsers:fromODAttributeMap:]: Syncing active user admin with GUID 57973286-6E91-45F9-8537-E8A2A50CDA7C

1:: [21527] [2014/03/21 19:07:12.528] -[DMODSyncRunner _syncUserODRecords:createActive:state:]: Processed 1 user records

3:: [21527] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: Done processing query.

————————————+——— Sync/Query State —————————————————

Work Queues | user | <unused>

| ————————————————+—————————

| sparse group | <unused>

| immediate group | <unused>

| recursive group | <unused>

| ————————————————+—————————

| deletions | <unused>

| membership | <unused>

————————————+——————————————————————————————————————

Visit Lists | user | 1

| ————————————————+—————————

| sparse group | <unused>

| immediate group | <unused>

| recursive group | <unused>

————————————+——————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.529] Sync of query <DMODQueries 0x7fd954001410> search_type = 1, attribute = 'dsAttrTypeStandard:GeneratedUID', match_type = 8193, query_values = [57973286-6E91-45F9-8537-E8A2A50CDA7C] took 0.019 seconds

2:: [21527] [2014/03/21 19:07:12.530] -[DMODSyncRunner _endNonStateMachineBlock:]: Completed non-state machine block operation

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

0:: [21527] [2014/03/21 19:07:12.531] NextSearch: abcdefab-cdef-abcd-efab-cdef00000050

3:: [21527] -[DMODSyncRunner startSyncWithQueryDictionary:completionHandler:]: Enqueuing query = {

attribute = "dsAttrTypeStandard:GeneratedUID";

"match_type" = 8193;

"query_values" = (

"abcdefab-cdef-abcd-efab-cdef00000050"

);

"search_type" = 3;

}

3:: [21527] Returned connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

2:: [21527] [2014/03/21 19:07:12.532] -[DMODSyncRunner _beginNonStateMachineBlock]: Starting non-state machine block operation

3:: [21527] Readying connection [<PGconnObj:0x7fd950f0dd60> connection #4 in pool devicemgr_v2m0@_devicemgr]

1:: [21527] [2014/03/21 19:07:12.532] Starting sync of query <DMODQueries 0x7fd950eef310> search_type = 3, attribute = 'dsAttrTypeStandard:GeneratedUID', match_type = 8193, query_values = [ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050]

2:: [21527] [2014/03/21 19:07:12.532] Search type: group single attribute search recursing on parents

0:: [21527] [2014/03/21 19:07:12.537] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164723-2_value_key" for table "__runtime_temp_devicemgrd-4164723-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.537] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164723-2')

0:: [21527] [2014/03/21 19:07:12.543] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164724-2_value_key" for table "__runtime_temp_devicemgrd-4164724-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.543] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164724-2')

2:: [21527] [2014/03/21 19:07:12.549] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Processing group admin (ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050) with requested sync mode: all children, create active: NO

1:: [21527] [2014/03/21 19:07:12.551] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Querying OD for 2 new users and groups

3:: [21527] Reloading invalidated object [<DMODNodeRef:0x7fd953c035b0> pk=2]

0:: [21527] [2014/03/21 19:07:12.562] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164726-2_value_key" for table "__runtime_temp_devicemgrd-4164726-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.562] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164726-2')

2:: [21527] [2014/03/21 19:07:12.568] -[DMODSyncRunner _syncAbstractUsers:fromODAttributeMap:]: Syncing active user admin with GUID 57973286-6E91-45F9-8537-E8A2A50CDA7C

2:: [21527] [2014/03/21 19:07:12.574] -[DMODSyncRunner _createUserWithAttributes:asActive:odNodeCache:]: Ignoring user root (FFFFEEEE-DDDD-CCCC-BBBB-AAAA00000000) with uid 0

2:: [21527] [2014/03/21 19:07:12.574] -[DMODSyncRunner _createUserWithAttributes:asActive:odNodeCache:]: Ignoring user diradmin (AF65D4DE-11F3-4996-BCE4-936ECB3CF922) with uid 1000

0:: [21527] [2014/03/21 19:07:12.577] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164728-2_value_key" for table "__runtime_temp_devicemgrd-4164728-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.577] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164728-2')

3:: [21527] Considering 1 sub-groups for pushing onto work queue: known children only

0:: [21527] [2014/03/21 19:07:12.583] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164729-2_value_key" for table "__runtime_temp_devicemgrd-4164729-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.583] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164729-2')

0:: [21527] [2014/03/21 19:07:12.590] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164730-2_value_key" for table "__runtime_temp_devicemgrd-4164730-2"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.590] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164730-2')

3:: [21527] Pushed 1 sub-groups onto work queue: known children only

0:: [21527] [2014/03/21 19:07:12.600] PostgreSQL NOTICES:

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

0 | NOTICE: CREATE TABLE / UNIQUE will create implicit index "__runtime_temp_devicemgrd-4164731-4_value_key" for table "__runtime_temp_devicemgrd-4164731-4"

——+————————————————————————————————————————————————————————————————————————————— —————————————————————————————————————————————————————————————————————————————

2:: [21527] [2014/03/21 19:07:12.600] Updating PGModel 'TemporaryUUIDTable' (table '__runtime_temp_devicemgrd-4164731-4')

3:: [21527] Finished syncing group ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050 in mode 1 (all children)

After the I tried to login to the ProfileManagers Webinterface the output of devicemgr.log switched to:

1:: [21527] [2014/03/21 19:07:12.608] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 1 group records

2:: [21527] [2014/03/21 19:07:12.608] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 1 groups

2:: [21527] [2014/03/21 19:07:12.613] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.613] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.614] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.614] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.614] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.615] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.615] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.616] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

1:: [21527] [2014/03/21 19:07:12.616] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.616] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

2:: [21527] [2014/03/21 19:07:12.619] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

1:: [21527] [2014/03/21 19:07:12.619] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.619] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.621] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.622] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.622] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.623] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.623] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

2:: [21527] [2014/03/21 19:07:12.624] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.624] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.624] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

1:: [21527] [2014/03/21 19:07:12.624] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.624] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

2:: [21527] [2014/03/21 19:07:12.627] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

1:: [21527] [2014/03/21 19:07:12.628] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.628] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.631] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.631] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.631] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.632] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.632] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.633] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

2:: [21527] [2014/03/21 19:07:12.633] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.634] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

1:: [21527] [2014/03/21 19:07:12.634] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.634] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

2:: [21527] [2014/03/21 19:07:12.636] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

1:: [21527] [2014/03/21 19:07:12.636] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.636] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.639] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.640] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.640] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.641] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.641] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.642] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.642] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.642] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

1:: [21527] [2014/03/21 19:07:12.642] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.642] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

2:: [21527] [2014/03/21 19:07:12.645] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

1:: [21527] [2014/03/21 19:07:12.645] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.645] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.648] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.649] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.649] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

2:: [21527] [2014/03/21 19:07:12.650] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.650] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.651] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.651] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.651] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

1:: [21527] [2014/03/21 19:07:12.651] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.651] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

2:: [21527] [2014/03/21 19:07:12.654] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

1:: [21527] [2014/03/21 19:07:12.654] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.654] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.657] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.658] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.658] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.659] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

2:: [21527] [2014/03/21 19:07:12.659] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.659] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.660] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.660] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

1:: [21527] [2014/03/21 19:07:12.660] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.660] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

2:: [21527] [2014/03/21 19:07:12.663] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

1:: [21527] [2014/03/21 19:07:12.663] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.663] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.666] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.666] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.667] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.667] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.668] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.668] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.669] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.669] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

1:: [21527] [2014/03/21 19:07:12.669] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.669] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

2:: [21527] [2014/03/21 19:07:12.672] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

1:: [21527] [2014/03/21 19:07:12.672] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.672] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.675] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.675] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.676] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.677] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

2:: [21527] [2014/03/21 19:07:12.677] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.677] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.678] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.678] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

1:: [21527] [2014/03/21 19:07:12.678] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.678] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

2:: [21527] [2014/03/21 19:07:12.681] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

1:: [21527] [2014/03/21 19:07:12.681] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.681] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.684] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.685] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.685] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.686] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.686] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.686] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.687] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.687] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

1:: [21527] [2014/03/21 19:07:12.687] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.687] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

2:: [21527] [2014/03/21 19:07:12.690] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

1:: [21527] [2014/03/21 19:07:12.690] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.690] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.693] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.694] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.694] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

2:: [21527] [2014/03/21 19:07:12.695] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.695] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.695] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.696] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.696] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

1:: [21527] [2014/03/21 19:07:12.696] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.696] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

2:: [21527] [2014/03/21 19:07:12.699] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

1:: [21527] [2014/03/21 19:07:12.699] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.699] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.702] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.703] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

2:: [21527] [2014/03/21 19:07:12.703] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.703] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.704] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.704] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.705] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.706] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

1:: [21527] [2014/03/21 19:07:12.706] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.706] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

2:: [21527] [2014/03/21 19:07:12.710] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

1:: [21527] [2014/03/21 19:07:12.710] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.710] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.714] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.714] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.715] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.716] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.716] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.717] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.718] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

2:: [21527] [2014/03/21 19:07:12.718] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

1:: [21527] [2014/03/21 19:07:12.718] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.718] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

2:: [21527] [2014/03/21 19:07:12.721] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

1:: [21527] [2014/03/21 19:07:12.721] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.721] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.725] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.725] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.725] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.726] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.726] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

2:: [21527] [2014/03/21 19:07:12.727] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.727] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.728] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

1:: [21527] [2014/03/21 19:07:12.728] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.728] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

2:: [21527] [2014/03/21 19:07:12.732] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

1:: [21527] [2014/03/21 19:07:12.732] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.732] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.735] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.736] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.736] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.737] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.737] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

2:: [21527] [2014/03/21 19:07:12.738] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

2:: [21527] [2014/03/21 19:07:12.738] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.738] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

1:: [21527] [2014/03/21 19:07:12.738] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.738] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

2:: [21527] [2014/03/21 19:07:12.741] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpoperator with GID 100 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000064

3:: [21527] WARNING: Ignoring request to sync group with GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000050. Requested sync mode: all children; Already visited in mode: all children

1:: [21527] [2014/03/21 19:07:12.741] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 2 group records

2:: [21527] [2014/03/21 19:07:12.741] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 2 groups

2:: [21527] [2014/03/21 19:07:12.744] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_ssh with GID 399 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018F

2:: [21527] [2014/03/21 19:07:12.745] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.3 with GID 404 and GUID 296CC6A1-90BD-4096-ABA6-C4A7EC6CA609

2:: [21527] [2014/03/21 19:07:12.745] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.access_screensharing with GID 398 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF0000018E

2:: [21527] [2014/03/21 19:07:12.746] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _appstore with GID 33 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000021

2:: [21527] [2014/03/21 19:07:12.746] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _lpadmin with GID 98 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF00000062

2:: [21527] [2014/03/21 19:07:12.747] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.1 with GID 402 and GUID 5D24713F-D84D-4B0E-A5AC-A0F501405789

2:: [21527] [2014/03/21 19:07:12.747] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group com.apple.sharepoint.group.2 with GID 403 and GUID 1EC73A3F-EBF6-4BD4-8DB5-B4062BB572BF

2:: [21527] [2014/03/21 19:07:12.748] -[DMODSyncRunner _syncGroupODRecord:createActive:syncMode:syncState:]: Ignoring special group _developer with GID 204 and GUID ABCDEFAB-CDEF-ABCD-EFAB-CDEF000000CC

1:: [21527] [2014/03/21 19:07:12.748] -[DMODSyncRunner _syncGroupODRecords:syncMode:createActive:state:]: Processed 8 group records

2:: [21527] [2014/03/21 19:07:12.748] -[DMODSyncRunner _blockSyncWithQuery:syncState:]: finding parent groups for 8 groups

Thanks for your support so far.

With 3.1.1 out now, has anyone seen any improvements?

All good so far. We'll see.

Didn't work out for me.

Profile Manager works fine as long as it doesn't do anything. However, as soon as it has to execute the first push operation to an iOS device (not a Mac), I am seeing the same issues as before: High CPU load, continuous log messages, failure of subsequent push operations, crashes of devicemgrd.

fkick1 wrote:

With 3.1.1 out now, has anyone seen any improvements?

Just installed 3.1.1, no improvements. Looks like it doesnt sends non-stop Push messages now.

But I have still high cpu load after I log in to the Webinterface.

Log's are still running full.

FYI.

mbresink wrote:

Didn't work out for me.

Profile Manager works fine as long as it doesn't do anything. However, as soon as it has to execute the first push operation to an iOS device (not a Mac), I am seeing the same issues as before: High CPU load, continuous log messages, failure of subsequent push operations, crashes of devicemgrd.

Hi mbresink,

which processes are on high load?

top -o cpu

I have similar symptomes, on my machine opendirectoryd and devicemgrd are on high load.

Sometimes postgreas_real.

best,

For me, it's devicemgrd, postgres, securityd, and ocspd.

The last two might indicate a certificate-related problem, but the certificates now work fine (while they were broken with 3.0.x).

Hi mbresink,

if the Server 3.1.2 update did not solved this issue ... the following instructions from a Apple Eng. helped for me:

Hi Patrick,

Thanks for sending us the 2 files. In order to isolate the problem, could you please remove this line:

Hi Patrick,

thanks a lot for your efforts! I can confirm that OS X Server 3.1.2 successfully resolved the problem already.

So we have now the first version of Profile Manager 3 which is indeed working.

Hello,

Im having these problems using server 5 and profile manager 4. PM 4 doesn't recognise the "dm_current_timestamp" function - has this been renamed in PM4, and if so does anyone know what it is?

thanks

OS X: 10.11.3

Server: 5

I have been having issues with Profile Manager on Server 5 as well. Specifically with tasks being randomly generated with no user interaction. This wouldn't be a huge problem if it was just a few devices. But it is randomly placing tasks of entire groups, (some groups upwards of 700 devices) in the queue. I have searched high and low across the googlesphere, applesphere, and everywhere I could, with nothing specifically related to this. But after reading post after post about similar issues, like this thread, I have learned a lot about profile manager over the past two days.

I started by running a few commands to clear out the active tasks to see if that would fix my problem:

Clear Active Tasks:

sudo psql -U _devicemgr -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "DELETE FROM library_item_tasks WHERE completed_at IS NOT NULL"

Clear Active AND Completed Tasks:

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

And that usually got the queue to clear for a bit. However, after a few minutes/hours/reboot, the queue started to fill up again.

This normally wouldn't be an issue however, it was breaking apps on student iPads, causing them to crash upon launch. The only fix was to delete the app and re-push just the app(s) that had been broken. I get about 30 kids everyday with this issue. Mostly, one app in particular, but there has been about 10 different apps. Some are free apps, some are paid app apps.

I have tried everything including these commands:

Backup Profile Manager Database:

#!/bin/bash

sudo pg_dump -h /Library/Server/ProfileManager/Config/var/PostgreSQL -U _devicemgr devicemgr_v2m0 -c -f ~/Desktop/profileManager.sql

Erase Profile Manager Database:

sudo /Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/backend/wipeDB .sh

Restore Profile Manager Database:

sudo -s

cat ~/Desktop/profileManager.sql | psql -h /Library/Server/ProfileManager/Config/var/PostgreSQL -U _devicemgr devicemgr_v2m0

The same issues were happening.

So I tried to reindex the profile manager database, thinking maybe that would do something:

Reindex Profile Manager Database Index:

sudo reindexdb devicemgr_v2m0 -U _devicemgr -h /Library/Server/ProfileManager/Config/var/PostgreSQL -e

It didn't fix this problem, but it did seem to speed things up a lot on web portal load times.

Then after some more research, I decided to download the app pgAdmin3 (A postgreSQL GUI) and dig around the database to see if maybe there was a table or column or something to give me a clue.

In case you want to look around the profile manager postgresql database, you have to modify a file to allow you to access it via pgAdmin3:

You need to modify /Library/Server/ProfileManager/Config/PostgreSQL_config.plist to enable access over TCP/IP (by default, postgres only listens on a UNIX socket).

Find and edit the existing "listen_addresses=" entry, and then add the last two lines:

<string>listen_addresses=127.0.0.1</string>

<string>-c</string>

<string>port=5432</string>

You can do this in your favorite plist editor or in terminal with this command:

sudo nano /Library/Server/ProfileManager/Config/PostgreSQL_config.plist

After digging around the db for a while, I noticed a function (_dm_mdm_target_needs_repush) that seems to be checking the "last_update_info_time", "last_auto_sync_profiles", and "first_push_time" and then push settings to the device if it fell below a threshold. Then I noticed that in the device table, a good majority of the devices didn't have a "first_push_time". It seemed odd they didn't have a first push time, considering they have all been pushed to. So my thinking was that somehow in the upgrade migration, those pieces of data never came through and it kept retrying to push every so often.

So I ran this command:

Set device "first_push_time" to past date:

sudo psql -U _devicemgr -d devicemgr_v2m0 -h /Library/Server/ProfileManager/Config/var/PostgreSQL -c "UPDATE devices SET first_push_time = ‘2015-12-25 00:00:00’”

I haven't had anything show up in the queue without me pushing manually since. I hope I didn't screw anything up, but it does seem to be working!

Hi Justin,

thank you for posting such a substantial reply. Ive been having the same issues on my domain, running the same server and OS version.

I decided to be brave and fun the "first_push_time" update but got an error message when doing so, have I copied it wrong?

Error message as follows. Thanks.

ERROR: syntax error at or near "00"

LINE 1: UPDATE devices SET first_push_time = ‘2015-12-25 00:00:00’

looking at my DB, an entry for a "first push time" device is

I assume those last numbers are miliseconds? Could this be why that command didnt work for me ?