Profile Manager no longer working since upgrading to version 5

similar quirks here - are you using VPP?

Yes i am using VPP apps converted to managed distribution. Apple support is saying the error is coming up in PM due to the site taking longer than 60 secs to load, gotta wait for a server.app update. grrrrr

Same issue with approx. 2000 devices and integrated with Active Directory. No errors other then a fair number of transactions retrying. I think it may be a scalability issue in the current release.

If it helps anyone, it appears the issue is related to VPP apps after looking at the Postgres SQL activity.

Open psql:

sudo -u _devicemgr psql -h /Library/Server/ProfileManager/Config/var/PostgreSQL devicemgr_v2m0

Then view the current activity:

select waiting,state,query from pg_stat_activity;

It is running this SQL stored procedure multiple times that never completes then the browser gives up and returns the refresh error:

SELECT dm_app_info_for_user_group(<user_group_number>);

Yeah I think it might be the scalability issue. I know for sure I need to rename my server to our school domain, right now I am running as a .local hostname. Waiting for spring break to update the name and do over with PM and OD. I am set up similar to you @4nNtt I'm binded to AD. I currently have 7 Active Tasks pending with one device In Progress. It seems like it's always 1 device holding up a transaction.

Some more information:

devicemgr_v2m0=# select waiting,state,query from pg_stat_activity;

waiting | state | query

---------+--------+---------------------------------------------------

f | idle | COMMIT

f | active | SELECT dm_app_info_for_user_group(4439)

f | idle | COMMIT

f | active | SELECT dm_app_info_for_user_group(4439)

f | active | SELECT dm_app_info_for_user_group(3954)

f | active | SELECT dm_app_info_for_user_group(4439)

f | idle | COMMIT

f | active | SELECT dm_app_info_for_user_group(4439)

f | idle | COMMIT

f | idle | COMMIT

f | idle | DROP TABLE __rt_temp_6718241_2

f | idle | COMMIT

f | idle | LISTEN objc_send

f | idle | COMMIT

f | idle | COMMIT

f | idle | COMMIT

f | idle | COMMIT

Eventually those dm_app_info_for_user_group(...) functions complete, but it seems to take 15-20 minutes.

These appear to be the problem functions that are causing the timeouts:

SELECT dm_app_info_for_user_group(3830);

SELECT dm_app_info_for_device_group(2264);

SELECT dm_app_info_for_user(2774);

Going to the "Apps" section works fine as long as one of these functions is not being called. Otherwise there are timeout issues in the Apps section too.

The output of those functions (15 minutes later) looks something like this:

[{"app_id":2, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":216, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":2

02, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":270, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":281, "applei

d_needed":40, "device_needed":40, "assigned":[]},{"app_id":152, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":73, "appleid_needed":40

, "device_needed":0, "assigned":[]},{"app_id":215, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":226, "appleid_needed":40, "device_ne

eded":40, "assigned":[]},{"app_id":209, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":4, "appleid_needed":0, "device_needed":0, "assi

gned":[]},{"app_id":221, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":103, "appleid_needed":40, "device_needed":40, "assigned":[]},{

"app_id":276, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":64, "appleid_needed":0, "device_needed":0, "assigned":[]},{"app_id":212,

"appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":105, "appleid_needed":40, "device_needed":0, "assigned":[]},{"app_id":223, "appleid_nee

ded":40, "device_needed":40, "assigned":[]},{"app_id":264, "appleid_needed":0, "device_needed":0, "assigned":[]},{"app_id":259, "appleid_needed":0, "devi

ce_needed":0, "assigned":[]},{"app_id":111, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":230, "appleid_needed":40, "device_needed":4

0, "assigned":[]},{"app_id":225, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":71, "appleid_needed":0, "device_needed":0, "assigned":

[]},{"app_id":75, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":266, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id

":66, "appleid_needed":0, "device_needed":0, "assigned":[]},{"app_id":263, "appleid_needed":0, "device_needed":0, "assigned":[]},{"app_id":67, "appleid_n

eeded":0, "device_needed":0, "assigned":[]},{"app_id":78, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":113, "appleid_needed":40, "de

vice_needed":40, "assigned":[]},{"app_id":280, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":124, "appleid_needed":0, "device_needed"

:0, "assigned":[]},{"app_id":219, "appleid_needed":40, "device_needed":0, "assigned":[]},{"app_id":74, "appleid_needed":0, "device_needed":0, "assigned":

[]},{"app_id":207, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":104, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_i

d":141, "appleid_needed":40, "device_needed":0, "assigned":[]},{"app_id":267, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":279, "app

leid_needed":40, "device_needed":40, "assigned":[]},{"app_id":265, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":151, "appleid_needed

":40, "device_needed":40, "assigned":[]},{"app_id":99, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":70, "appleid_needed":0, "device_

needed":0, "assigned":[]},{"app_id":63, "appleid_needed":0, "device_needed":0, "assigned":[]},{"app_id":261, "appleid_needed":40, "device_needed":40, "as

signed":[]},{"app_id":107, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":220, "appleid_needed":40, "device_needed":40, "assigned":[]}

,{"app_id":229, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":3, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":22

8, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":277, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":109, "appleid

_needed":40, "device_needed":40, "assigned":[]},{"app_id":211, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":112, "appleid_needed":40

, "device_needed":40, "assigned":[]},{"app_id":275, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":1, "appleid_needed":40, "device_nee

ded":40, "assigned":[]},{"app_id":106, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":62, "appleid_needed":0, "device_needed":0, "assi

gned":[]},{"app_id":116, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":125, "appleid_needed":0, "device_needed":0, "assigned":[]},{"a

pp_id":224, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":102, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":258,

"appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":144, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":218, "appleid_n

eeded":40, "device_needed":40, "assigned":[]},{"app_id":268, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":153, "appleid_needed":0, "

device_needed":0, "assigned":[]},{"app_id":108, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":114, "appleid_needed":40, "device_neede

d":40, "assigned":[]},{"app_id":274, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":143, "appleid_needed":40, "device_needed":40, "***

igned":[]},{"app_id":271, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":72, "appleid_needed":0, "device_needed":0, "assigned":[]},{"a

pp_id":260, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":110, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":210,

"appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":272, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":222, "appleid_n

eeded":40, "device_needed":40, "assigned":[]},{"app_id":65, "appleid_needed":0, "device_needed":0, "assigned":[]},{"app_id":214, "appleid_needed":40, "de

vice_needed":40, "assigned":[]},{"app_id":217, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":123, "appleid_needed":0, "device_needed"

:0, "assigned":[]},{"app_id":208, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":227, "appleid_needed":40, "device_needed":40, "assign

ed":[]},{"app_id":100, "appleid_needed":0, "device_needed":0, "assigned":[]},{"app_id":101, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app

_id":262, "appleid_needed":0, "device_needed":0, "assigned":[]},{"app_id":61, "appleid_needed":0, "device_needed":0, "assigned":[]},{"app_id":273, "apple

id_needed":40, "device_needed":40, "assigned":[]},{"app_id":142, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":278, "appleid_needed":

40, "device_needed":40, "assigned":[]},{"app_id":115, "appleid_needed":40, "device_needed":40, "assigned":[]},{"app_id":241, "appleid_needed":40, "device

_needed":40, "assigned":[]}]

This will also reproduce the problem: (where 462 is a device id)

SELECT dm_vpp_license_info_for_device_and_product(462, id, 'app_id') FROM vpp_products;

This is basically filtering all of the results of a view with 60,000 rows 200 times and it takes about a minute. If multiple instances are running in parallel it runs even slower. It runs something like this every time you click on something in the browser (except in the "apps" section) which is why it times out. This data appears to be used to display vpp license counts (which may not even be visible when browsing).

Calling these higher level stored procedures all do something similar to that call as part of their implementation:

SELECT dm_app_info_for_user_group(3830);

SELECT dm_app_info_for_device_group(2264);

SELECT dm_app_info_for_user(2774);

SELECT dm_app_info_for_device(462);

I couldn't find anything abnormal in the data. It think this may be a scalability issue in OS X Server 5.0.0 - 5.0.15.

BTW, Queries over the "view_truth_assets_by_devices" view seem to be the cause of poor performance in dm_vpp_license_info_for_device_and_product(). This view has about 60,000 rows.

Same errors here, many thanks for your info 4nNtt - glad its not just me!

changing the proxy timeout in the Apache config helps matters, but doesn't fully fix just yet.

Thread here with info

Re: server 5 proxy timeout settings

Thank you for all your input 4nNtt I've been tinkering with PM and a classroom cart that didn't finish installing a few apps. I had to restore and re-enroll then instead of adding the apps through the device groups I added the device group through the Apps section. Selected the app and device group and added that way and it seems to work a lot better that route for me. Not sure what the hold up was the other way around.