12 Replies Latest reply: Mar 8, 2013 5:54 AM by Templeton Peck
fkick1 Level 1 Level 1 (60 points)

Hi All,

 

I've got profile manager running on a late 2012 mac mini server and I'm trying to admin both OS X (laptops and desktops) and ios devices with it. I've got trust profiles installed on all the devices, as well as the enrollment profiles. When I go to push settings to an IOS devices, everything works as expected, both in and outside of our office, however when I try to push new setttings profiles to an OS X machine (either lion or MT Lion), the task will just hang at "sending" until I cancel it. I've let them go on over night, I've logged out and rebooted client machines, and these settings just won't take.


Any suggestions?

 

Thanks in advance!


MAC MINI SERVER (LATE 2012), OS X Mountain Lion (10.8.2), ios 6.0.1
  • -{ jonohayes }- Level 1 Level 1 (0 points)

    Same problem.

     

    What i found is that the profile will apear on the cliernt computer and then will disapear. I have files a bug report hopfully it will be fixed in 10.8.3 and Server update soon.

  • fkick1 Level 1 Level 1 (60 points)

    I get this issue on 10.7.5 machines as well :/

  • -{ jonohayes }- Level 1 Level 1 (0 points)

    My clients are running 10.8.2 and have this problem. What version of Server are you running?

  • fkick1 Level 1 Level 1 (60 points)

    Server is running 10.8.2 with the newest version of the OS X Server software. I've got clients with 10.8.2 and 10.7.5

  • McSpooky Level 1 Level 1 (0 points)

    I have the same issue. I am new to MacOSX and  need to set this up to combine and better control our mac pool.

    Not using this for ipads but I am using is with osx clients (latest version since I just purchased licenses) sometimes at random something goes through , usually though it just gets stuck

    I guess I have to manage with workgroup manager for now but it is annoying that the official control app does not seem to work,

     

    Can anyone tell me if there has to be a connection to apple at all times to make it work in the first place? That would seem like a weird requierement to me .

  • Templeton Peck Level 9 Level 9 (60,085 points)

    See this article about which ports need to opened on your network in order for PM to work:

     

    http://support.apple.com/kb/HT5302

  • fkick1 Level 1 Level 1 (60 points)

    Thanks Templeton,

     

    These machines are all on the same internal subnet, though I had opened those ports on my sonicwall firewall. I let the last test of mine run for the past 48 hours, and it seems some of the push notifcations are getting through, they're just taking a very very long time to push, with some of the settings still hanging.

     

    Is there a limit to the number of machines one can have in a group? I've got two groups with around 20 members and a third with only 5, and the smaller group does appear to process better.

     

    Anyone know how often machines request updates from apples servers for push settings? I had thought it was supposed to be within a few minutes if all machines had an active network connection, similar to how iOS devices receive push notifcations from apps.

     

    Thanks!

  • Templeton Peck Level 9 Level 9 (60,085 points)

    even though they're all internal, you'll still need to open port 5223 on your firewall so the server can send the policies to the apple push notification servers and the client can then receive them.

     

    You can also enable logging on both the clients and server  using these commands:

     

    defaults write /Library/Preferences/com.apple.apsd APSWriteLogs -bool TRUE

    defaults write /Library/Preferences/com.apple.apsd APSLogLevel -int 7

    killall apsd

     

    The log can be found in /Library/Logs  and it's actually pretty easy to understand and find where any issue might be.

  • fkick1 Level 1 Level 1 (60 points)

    Thanks Templeton,

     

    I've enabled logging, and iniated a simple "update info" on one of our macbook pros.

     

    The log for apsd.log reads:

    Mar  7 12:23:35 tgroupmail.com ProfileManager[5530] <Info>: Pushed to <Device:"Macbook Pro 17" DIT 1265"> with token hlWgB9+SPpPg8u0LhCiAwWCqBw4SWi6ScW3uRSUkVsE=, {"mdm":"","time":"1362687815.80418"}

    Mar  7 12:23:35 tgroupmail.com ProfileManager[5530] <Info>: Completed in 571ms (View: 0, DB: 49) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:23:35 tgroupmail.com ProfileManager[5532] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:23:35) [POST]

    Mar  7 12:23:35 tgroupmail.com ProfileManager[5532] <Info>: Completed in 111ms (View: 0, DB: 18) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:23:37 tgroupmail.com ProfileManager[5534] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:23:37) [POST]

    Mar  7 12:23:37 tgroupmail.com ProfileManager[5534] <Info>: Completed in 161ms (View: 0, DB: 39) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:23:38 tgroupmail.com ProfileManager[5536] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:23:38) [POST]

    Mar  7 12:23:38 tgroupmail.com ProfileManager[5536] <Info>: Completed in 103ms (View: 0, DB: 38) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:23:43 tgroupmail.com ProfileManager[5531] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:23:43) [POST]

    Mar  7 12:23:43 tgroupmail.com ProfileManager[5531] <Info>: Completed in 138ms (View: 0, DB: 38) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:45 tgroupmail.com ProfileManager[5534] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:45) [POST]

    Mar  7 12:24:45 tgroupmail.com ProfileManager[5534] <Info>: Completed in 136ms (View: 0, DB: 24) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:46 tgroupmail.com ProfileManager[5536] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:46) [POST]

    Mar  7 12:24:46 tgroupmail.com ProfileManager[5537] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:46) [POST]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5537] <Info>: Completed in 743ms (View: 0, DB: 10) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5536] <Info>: Completed in 810ms (View: 0, DB: 92) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5538] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:47) [POST]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5538] <Info>: Completed in 64ms (View: 0, DB: 7) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5530] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:47) [POST]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5530] <Info>: Completed in 215ms (View: 0, DB: 20) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:49 tgroupmail.com ProfileManager[5533] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:49) [POST]

    Mar  7 12:24:49 tgroupmail.com ProfileManager[5533] <Info>: Completed in 138ms (View: 0, DB: 24) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5532] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5533] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5532] <Info>: Completed in 70ms (View: 0, DB: 3) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5535] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5533] <Info>: Completed in 229ms (View: 0, DB: 18) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5535] <Info>: Completed in 147ms (View: 0, DB: 26) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5536] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5536] <Info>: Completed in 72ms (View: 0, DB: 2) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5537] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5538] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5538] <Info>: Completed in 114ms (View: 0, DB: 3) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5537] <Info>: Completed in 161ms (View: 0, DB: 14) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:52 tgroupmail.com ProfileManager[6288] <Info>: ServermgrDevicemgr starting...

    Mar  7 12:25:52 tgroupmail.com ProfileManager[6288] <Info>: default_profile_created_at_least_once was already true

    Mar  7 12:25:52 tgroupmail.com ProfileManager[6288] <Warning>: ** has_many_polymorphs: Warning; not all usage scenarios for polymorphic scopes are supported yet.

    Mar  7 12:25:52 tgroupmail.com ProfileManager[6288] <Info>: ServermgrDevicemgr done!

    Mar  7 12:26:49 tgroupmail.com ProfileManager[5535] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:49) [POST]

    Mar  7 12:26:49 tgroupmail.com ProfileManager[5536] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:49) [POST]

    Mar  7 12:26:50 tgroupmail.com ProfileManager[5537] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:50) [POST]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5535] <Info>: Completed in 1989ms (View: 0, DB: 8) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5539] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:51) [POST]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5537] <Info>: Completed in 1100ms (View: 0, DB: 11) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5530] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:51) [POST]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5530] <Info>: Completed in 57ms (View: 0, DB: 2) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5531] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:51) [POST]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5539] <Info>: Completed in 157ms (View: 0, DB: 15) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5536] <Info>: Completed in 2195ms (View: 0, DB: 126) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5532] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:51) [POST]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5531] <Info>: Completed in 126ms (View: 0, DB: 14) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5532] <Info>: Completed in 124ms (View: 0, DB: 14) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:28:52 tgroupmail.com ProfileManager[6397] <Info>: ServermgrDevicemgr starting...

    Mar  7 12:28:52 tgroupmail.com ProfileManager[6397] <Info>: default_profile_created_at_least_once was already true

    Mar  7 12:28:52 tgroupmail.com ProfileManager[6397] <Warning>: ** has_many_polymorphs: Warning; not all usage scenarios for polymorphic scopes are supported yet.

    Mar  7 12:28:52 tgroupmail.com ProfileManager[6397] <Info>: ServermgrDevicemgr done!

     

    I'm not seeing any errors within the 5 minutes that I started the process. However, in the profilemanager.log I do notice a recurring warning regarding has_many_polymorphs and one regarding an error about an untrusted certificate. Any thoughts? Log below:

     

    Mar  7 12:23:35 tgroupmail.com ProfileManager[5530] <Info>: Pushed to <Device:"Macbook Pro 17" DIT 1265"> with token hlWgB9+SPpPg8u0LhCiAwWCqBw4SWi6ScW3uRSUkVsE=, {"mdm":"","time":"1362687815.80418"}

    Mar  7 12:23:35 tgroupmail.com ProfileManager[5530] <Info>: Completed in 571ms (View: 0, DB: 49) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:23:35 tgroupmail.com ProfileManager[5532] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:23:35) [POST]

    Mar  7 12:23:35 tgroupmail.com ProfileManager[5532] <Info>: Completed in 111ms (View: 0, DB: 18) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:23:37 tgroupmail.com ProfileManager[5534] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:23:37) [POST]

    Mar  7 12:23:37 tgroupmail.com ProfileManager[5534] <Info>: Completed in 161ms (View: 0, DB: 39) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:23:38 tgroupmail.com ProfileManager[5536] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:23:38) [POST]

    Mar  7 12:23:38 tgroupmail.com ProfileManager[5536] <Info>: Completed in 103ms (View: 0, DB: 38) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:23:43 tgroupmail.com ProfileManager[5531] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:23:43) [POST]

    Mar  7 12:23:43 tgroupmail.com ProfileManager[5531] <Info>: Completed in 138ms (View: 0, DB: 38) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:45 tgroupmail.com ProfileManager[5534] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:45) [POST]

    Mar  7 12:24:45 tgroupmail.com ProfileManager[5534] <Info>: Completed in 136ms (View: 0, DB: 24) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:46 tgroupmail.com ProfileManager[5536] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:46) [POST]

    Mar  7 12:24:46 tgroupmail.com ProfileManager[5537] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:46) [POST]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5537] <Info>: Completed in 743ms (View: 0, DB: 10) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5536] <Info>: Completed in 810ms (View: 0, DB: 92) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5538] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:47) [POST]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5538] <Info>: Completed in 64ms (View: 0, DB: 7) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5530] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:47) [POST]

    Mar  7 12:24:47 tgroupmail.com ProfileManager[5530] <Info>: Completed in 215ms (View: 0, DB: 20) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:24:49 tgroupmail.com ProfileManager[5533] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:24:49) [POST]

    Mar  7 12:24:49 tgroupmail.com ProfileManager[5533] <Info>: Completed in 138ms (View: 0, DB: 24) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5532] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5533] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5532] <Info>: Completed in 70ms (View: 0, DB: 3) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5535] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5533] <Info>: Completed in 229ms (View: 0, DB: 18) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5535] <Info>: Completed in 147ms (View: 0, DB: 26) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5536] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5536] <Info>: Completed in 72ms (View: 0, DB: 2) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5537] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5538] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:25:05) [POST]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5538] <Info>: Completed in 114ms (View: 0, DB: 3) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:05 tgroupmail.com ProfileManager[5537] <Info>: Completed in 161ms (View: 0, DB: 14) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:25:52 tgroupmail.com ProfileManager[6288] <Info>: ServermgrDevicemgr starting...

    Mar  7 12:25:52 tgroupmail.com ProfileManager[6288] <Info>: default_profile_created_at_least_once was already true

    Mar  7 12:25:52 tgroupmail.com ProfileManager[6288] <Warning>: ** has_many_polymorphs: Warning; not all usage scenarios for polymorphic scopes are supported yet.

    Mar  7 12:25:52 tgroupmail.com ProfileManager[6288] <Info>: ServermgrDevicemgr done!

    Mar  7 12:26:49 tgroupmail.com ProfileManager[5535] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:49) [POST]

    Mar  7 12:26:49 tgroupmail.com ProfileManager[5536] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:49) [POST]

    Mar  7 12:26:50 tgroupmail.com ProfileManager[5537] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:50) [POST]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5535] <Info>: Completed in 1989ms (View: 0, DB: 8) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5539] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:51) [POST]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5537] <Info>: Completed in 1100ms (View: 0, DB: 11) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5530] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:51) [POST]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5530] <Info>: Completed in 57ms (View: 0, DB: 2) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5531] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:51) [POST]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5539] <Info>: Completed in 157ms (View: 0, DB: 15) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5536] <Info>: Completed in 2195ms (View: 0, DB: 126) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5532] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:26:51) [POST]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5531] <Info>: Completed in 126ms (View: 0, DB: 14) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:26:51 tgroupmail.com ProfileManager[5532] <Info>: Completed in 124ms (View: 0, DB: 14) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:28:52 tgroupmail.com ProfileManager[6397] <Info>: ServermgrDevicemgr starting...

    Mar  7 12:28:52 tgroupmail.com ProfileManager[6397] <Info>: default_profile_created_at_least_once was already true

    Mar  7 12:28:52 tgroupmail.com ProfileManager[6397] <Warning>: ** has_many_polymorphs: Warning; not all usage scenarios for polymorphic scopes are supported yet.

    Mar  7 12:28:52 tgroupmail.com ProfileManager[6397] <Info>: ServermgrDevicemgr done!

    Mar  7 12:29:00 tgroupmail.com ProfileManager[6091] <Info>: DemandProcess: quitting after 300 seconds of inactivity

    Mar  7 12:31:52 tgroupmail.com ProfileManager[6490] <Info>: ServermgrDevicemgr starting...

    Mar  7 12:31:52 tgroupmail.com ProfileManager[6490] <Info>: default_profile_created_at_least_once was already true

    Mar  7 12:31:52 tgroupmail.com ProfileManager[6490] <Warning>: ** has_many_polymorphs: Warning; not all usage scenarios for polymorphic scopes are supported yet.

    Mar  7 12:31:52 tgroupmail.com ProfileManager[6490] <Info>: ServermgrDevicemgr done!

    Mar  7 12:32:48 tgroupmail.com ProfileManager[6559] <Info>: ServermgrDevicemgr starting...

    Mar  7 12:32:48 tgroupmail.com ProfileManager[6559] <Info>: default_profile_created_at_least_once was already true

    Mar  7 12:32:48 tgroupmail.com ProfileManager[6559] <Warning>: ** has_many_polymorphs: Warning; not all usage scenarios for polymorphic scopes are supported yet.

    Mar  7 12:32:48 tgroupmail.com ProfileManager[6559] <Info>: ServermgrDevicemgr done!

    Mar  7 12:32:59 tgroupmail.com ProfileManager[6739] <Info>: ServermgrDevicemgr starting...

    Mar  7 12:32:59 tgroupmail.com ProfileManager[6739] <Info>: default_profile_created_at_least_once was already true

    Mar  7 12:32:59 tgroupmail.com ProfileManager[6739] <Warning>: ** has_many_polymorphs: Warning; not all usage scenarios for polymorphic scopes are supported yet.

    Mar  7 12:32:59 tgroupmail.com ProfileManager[6739] <Info>: ServermgrDevicemgr done!

    Mar  7 12:33:34 tgroupmail.com ProfileManager[6922] <Info>: DemandProcess: running with arguments '-e production'

    Mar  7 12:33:34 tgroupmail.com ruby[6922] <Info>: Pruning certificate chain to 18446744073709551615

    Mar  7 12:33:34 tgroupmail.com ruby[6922] <Debug>: Trying to add a bogus certificate

    Mar  7 12:33:34 tgroupmail.com ruby[6922] <Debug>: An error occured while inserting an untrusted certificate into the chain

    Mar  7 12:33:34 tgroupmail.com ProfileManager[6922] <Info>: Pushed to <LabSession:'Derek Klein@Edit 12'> with token GKBSdt1j/uZ9LY8tzNXJ07WaMfwxrfPFrz20yjsSEHw=, {"time":"1362688414.406703","mdm":""}

    Mar  7 12:33:35 tgroupmail.com ProfileManager[5534] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:33:35) [POST]

    Mar  7 12:33:35 tgroupmail.com ProfileManager[5534] <Info>: Completed in 69ms (View: 0, DB: 9) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:33:35 tgroupmail.com ProfileManager[5535] <Info>: Processing MagicController#do_magic (for 206.214.40.205 at 2013-03-07 12:33:35) [POST]

    Mar  7 12:33:36 tgroupmail.com ProfileManager[5535] <Info>: Completed in 471ms (View: 0, DB: 32) | 200 OK [https://tgroupmail.com/magic/do_magic]

    Mar  7 12:34:52 tgroupmail.com ProfileManager[6974] <Info>: ServermgrDevicemgr starting...

    Mar  7 12:34:52 tgroupmail.com ProfileManager[6974] <Info>: default_profile_created_at_least_once was already true

    Mar  7 12:34:52 tgroupmail.com ProfileManager[6974] <Warning>: ** has_many_polymorphs: Warning; not all usage scenarios for polymorphic scopes are supported yet.

    Mar  7 12:34:52 tgroupmail.com ProfileManager[6974] <Info>: ServermgrDevicemgr done!

     

    Also, here's the php.log for the same time period:

     

    0::Mar 07 13:23:55.622 [5540] <172.16.1.193> Status='Idle'

    0::Mar 07 13:23:55.635 [5540] <172.16.1.193> Sending request 'ProvisioningProfileList' as CommandUUID=fe6f4d70-6971-0130-e7f7-282066350016

    0::Mar 07 13:23:55.636 [5540] <172.16.1.193> Completed in 16ms | 200 OK [https://tgroupmail.com/devicemanagement/mdm/connect.php]

    0::Mar 07 13:23:55.688 [5726] <172.16.1.193> Status='Acknowledged' CommandUUID=fe6f4d70-6971-0130-e7f7-282066350016

    0::Mar 07 13:23:55.701 [5726] <172.16.1.193> Sending request 'ProfileList' as CommandUUID=fe6e0150-6971-0130-e7f6-282066350016

    0::Mar 07 13:23:55.701 [5726] <172.16.1.193> Completed in 16ms | 200 OK [https://tgroupmail.com/devicemanagement/mdm/connect.php]

    0::Mar 07 13:23:55.752 [5541] <172.16.1.193> Status='Acknowledged' CommandUUID=fe6e0150-6971-0130-e7f6-282066350016

    0::Mar 07 13:23:55.765 [5541] <172.16.1.193> Completed in 16ms | 200 OK [https://tgroupmail.com/devicemanagement/mdm/connect.php]

    0::Mar 07 13:25:57.217 [5540] <206.214.40.203> Status='Idle'

    0::Mar 07 13:25:57.221 [5540] <206.214.40.203> EXCEPTION: 400 Bad Request - target not found at

    0::Mar 07 13:25:57.221 [5540] <206.214.40.203> #0 /Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/backend/php/db .php(372): _connect_transaction_1(Array)

    0::Mar 07 13:25:57.221 [5540] <206.214.40.203> #1 /Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/backend/php/co nnect.php(77): PerformInTransaction('_connect_transa...', Array)

    0::Mar 07 13:25:57.221 [5540] <206.214.40.203> #2 {main}

    0::Mar 07 13:25:57.221 [5540] <206.214.40.203> Completed in 8ms | 400 Bad Request  [https://tgroupmail.com/devicemanagement/mdm/connect.php]

    0::Mar 07 13:26:00.287 [5726] <206.214.40.203> checkin: 'TokenUpdate'

    0::Mar 07 13:26:00.291 [5726] <206.214.40.203> EXCEPTION: 403 Forbidden - target not found at

    0::Mar 07 13:26:00.291 [5726] <206.214.40.203> #0 /Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/backend/php/db .php(372): _checkin_transaction(Array)

    0::Mar 07 13:26:00.291 [5726] <206.214.40.203> #1 /Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/backend/php/ch eckin.php(124): PerformInTransaction('_checkin_transa...', Array)

    0::Mar 07 13:26:00.291 [5726] <206.214.40.203> #2 {main}

    0::Mar 07 13:26:00.291 [5726] <206.214.40.203> Completed in 7ms | 403 Forbidden  [https://tgroupmail.com/devicemanagement/mdm/checkin.php]

    0::Mar 07 13:26:08.045 [5541] <206.214.40.203> Status='Idle'

    0::Mar 07 13:26:08.048 [5541] <206.214.40.203> EXCEPTION: 400 Bad Request - target not found at

    0::Mar 07 13:26:08.048 [5541] <206.214.40.203> #0 /Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/backend/php/db .php(372): _connect_transaction_1(Array)

    0::Mar 07 13:26:08.048 [5541] <206.214.40.203> #1 /Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/backend/php/co nnect.php(77): PerformInTransaction('_connect_transa...', Array)

    0::Mar 07 13:26:08.048 [5541] <206.214.40.203> #2 {main}

    0::Mar 07 13:26:08.048 [5541] <206.214.40.203> Completed in 6ms | 400 Bad Request  [https://tgroupmail.com/devicemanagement/mdm/connect.php]

    0::Mar 07 13:28:02.146 [5540] <70.195.202.8> checkin: 'TokenUpdate'

    0::Mar 07 13:28:02.164 [5540] <70.195.202.8> EXCEPTION: 403 Forbidden - target not found at

    0::Mar 07 13:28:02.164 [5540] <70.195.202.8> #0 /Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/backend/php/db .php(372): _checkin_transaction(Array)

    0::Mar 07 13:28:02.164 [5540] <70.195.202.8> #1 /Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/backend/php/ch eckin.php(124): PerformInTransaction('_checkin_transa...', Array)

    0::Mar 07 13:28:02.164 [5540] <70.195.202.8> #2 {main}

    0::Mar 07 13:28:02.165 [5540] <70.195.202.8> Completed in 22ms | 403 Forbidden  [https://tgroupmail.com/devicemanagement/mdm/checkin.php]

    0::Mar 07 13:33:32.436 [5726] <172.16.1.247> Status='Idle'

    0::Mar 07 13:33:32.449 [5726] <172.16.1.247> Completed in 16ms | 200 OK [https://tgroupmail.com/devicemanagement/mdm/connect.php]

    0::Mar 07 13:33:32.477 [5541] <172.16.1.247> Status='Idle'

    0::Mar 07 13:33:32.490 [5541] <172.16.1.247> Completed in 16ms | 200 OK [https://tgroupmail.com/devicemanagement/mdm/connect.php]

    0::Mar 07 13:36:00.694 [5540] <206.214.40.203> checkin: 'TokenUpdate'

    0::Mar 07 13:36:00.698 [5540] <206.214.40.203> EXCEPTION: 403 Forbidden - target not found at

    0::Mar 07 13:36:00.698 [5540] <206.214.40.203> #0 /Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/backend/php/db .php(372): _checkin_transaction(Array)

    0::Mar 07 13:36:00.698 [5540] <206.214.40.203> #1 /Applications/Server.app/Contents/ServerRoot/usr/share/devicemgr/backend/php/ch eckin.php(124): PerformInTransaction('_checkin_transa...', Array)

    0::Mar 07 13:36:00.698 [5540] <206.214.40.203> #2 {main}

    0::Mar 07 13:36:00.698 [5540] <206.214.40.203> Completed in 7ms | 403 Forbidden  [https://tgroupmail.com/devicemanagement/mdm/checkin.php]

     

    Thanks for all your help!

  • Templeton Peck Level 9 Level 9 (60,085 points)

    I don't see anythingthat stands out.

     

    run the commands to enable logging on the server then push out a test profile to a single Mac.  then post the new contents of the apsd log from the server.

  • fkick1 Level 1 Level 1 (60 points)

    Here's the new contents. I've let the profile push run for about 5 minutes, and it's still "Sending". There are no other active tasks.

     

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Debug>: successful entitlement check 'com.apple.private.aps-connection-initiate'

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Debug>: Failed entitlement check 'com.apple.private.dark-wake-push' for com.apple.server[9442]

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Debug>: Configuring port owner connection port com.apple.server.aps

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Debug>: Persistent connections for user 501 already loaded, skipping check

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Info>: After rebuilding cache, all APSTopics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.contacts",

            "com.me.setupservice",

            "com.me.cal",

            "com.apple.maspushagent",

            "com.apple.assetcache"

        )

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Info>: For user 501, enabled topics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.setupservice",

            "com.apple.maspushagent"

        ) for token <efe7c228 7337e263 5bc2d88f b670516f 897da5bf e874fc77 c4584aff 4ff9c25c>

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Info>: For user 235, enabled topics = (

            "com.apple.assetcache"

        ) for token <2ea07878 98a635f2 f9c3bb71 b0b444ac e6cf9518 e96b5b36 b1a151a7 1f8749d8>

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Info>: For user 501 darkWakeEnabled, enabled topics = (

            "com.me.contacts",

            "com.me.cal"

        ) for token <a9d44c3e d8bcf27e 290c2836 3c615dca 1e5d5603 aea43ec5 5d9b870c c6b433b2>

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Adjusting connection with courier enabled

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Has connections with usable topics

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Info>: In _savePersistentConnectionTopics, _connectionPortName = com.apple.server.aps

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Info>: After rebuilding cache, all APSTopics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.contacts",

            "com.me.setupservice",

            "com.me.cal",

            "com.apple.maspushagent",

            "com.apple.assetcache"

        )

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Info>: For user 501, enabled topics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.setupservice",

            "com.apple.maspushagent"

        ) for token <efe7c228 7337e263 5bc2d88f b670516f 897da5bf e874fc77 c4584aff 4ff9c25c>

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Info>: For user 235, enabled topics = (

            "com.apple.assetcache"

        ) for token <2ea07878 98a635f2 f9c3bb71 b0b444ac e6cf9518 e96b5b36 b1a151a7 1f8749d8>

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Info>: For user 501 darkWakeEnabled, enabled topics = (

            "com.me.contacts",

            "com.me.cal"

        ) for token <a9d44c3e d8bcf27e 290c2836 3c615dca 1e5d5603 aea43ec5 5d9b870c c6b433b2>

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Adjusting connection with courier enabled

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Has connections with usable topics

    Mar  7 13:18:48 tgroupmail.com apsd[5646] <Info>: In _savePersistentConnectionTopics, _connectionPortName = com.apple.server.aps

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Debug>: successful entitlement check 'com.apple.private.aps-connection-initiate'

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Debug>: Failed entitlement check 'com.apple.private.dark-wake-push' for com.apple.server[9535]

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Debug>: Configuring port owner connection port com.apple.server.aps

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Debug>: Persistent connections for user 501 already loaded, skipping check

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Info>: After rebuilding cache, all APSTopics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.contacts",

            "com.me.setupservice",

            "com.me.cal",

            "com.apple.maspushagent",

            "com.apple.assetcache"

        )

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Info>: For user 501, enabled topics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.setupservice",

            "com.apple.maspushagent"

        ) for token <efe7c228 7337e263 5bc2d88f b670516f 897da5bf e874fc77 c4584aff 4ff9c25c>

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Info>: For user 235, enabled topics = (

            "com.apple.assetcache"

        ) for token <2ea07878 98a635f2 f9c3bb71 b0b444ac e6cf9518 e96b5b36 b1a151a7 1f8749d8>

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Info>: For user 501 darkWakeEnabled, enabled topics = (

            "com.me.contacts",

            "com.me.cal"

        ) for token <a9d44c3e d8bcf27e 290c2836 3c615dca 1e5d5603 aea43ec5 5d9b870c c6b433b2>

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Adjusting connection with courier enabled

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Has connections with usable topics

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Info>: In _savePersistentConnectionTopics, _connectionPortName = com.apple.server.aps

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Info>: After rebuilding cache, all APSTopics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.contacts",

            "com.me.setupservice",

            "com.me.cal",

            "com.apple.maspushagent",

            "com.apple.assetcache"

        )

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Info>: For user 501, enabled topics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.setupservice",

            "com.apple.maspushagent"

        ) for token <efe7c228 7337e263 5bc2d88f b670516f 897da5bf e874fc77 c4584aff 4ff9c25c>

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Info>: For user 235, enabled topics = (

            "com.apple.assetcache"

        ) for token <2ea07878 98a635f2 f9c3bb71 b0b444ac e6cf9518 e96b5b36 b1a151a7 1f8749d8>

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Info>: For user 501 darkWakeEnabled, enabled topics = (

            "com.me.contacts",

            "com.me.cal"

        ) for token <a9d44c3e d8bcf27e 290c2836 3c615dca 1e5d5603 aea43ec5 5d9b870c c6b433b2>

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Adjusting connection with courier enabled

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Has connections with usable topics

    Mar  7 13:20:48 tgroupmail.com apsd[5646] <Info>: In _savePersistentConnectionTopics, _connectionPortName = com.apple.server.aps

    Mar  7 13:22:02 tgroupmail.com apsd[5646] <Info>: <APSCourier: 0x7ff8c2000ee0>: Issuing keep alive now (critical reliability is disabled)

    Mar  7 13:22:02 tgroupmail.com apsd[5646] <Info>: <APSCourier: 0x7ff8c2000ee0>: Sending keep alive message

    Mar  7 13:22:02 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c2000ee0>: Outstanding data received: <0d000000 00> (length 5)

    Mar  7 13:22:02 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c2000ee0>: Stream processing: complete yes, invalid no, length parsed 5, parameters {

            APSProtocolCommand = 13;

        }

    Mar  7 13:22:02 tgroupmail.com apsd[5646] <Info>: <APSCourier: 0x7ff8c2000ee0>: Received successful keep-alive response

    Mar  7 13:22:02 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c2000ee0>: Stream processing: complete no, invalid no, length parsed 0, parameters (null)

    Mar  7 13:22:03 tgroupmail.com apsd[5646] <Info>: <APSCourier: 0x7ff8c22048b0>: Issuing keep alive now (critical reliability is disabled)

    Mar  7 13:22:03 tgroupmail.com apsd[5646] <Info>: <APSCourier: 0x7ff8c22048b0>: Sending keep alive message

    Mar  7 13:22:03 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Outstanding data received: <0d000000 00> (length 5)

    Mar  7 13:22:03 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Stream processing: complete yes, invalid no, length parsed 5, parameters {

            APSProtocolCommand = 13;

        }

    Mar  7 13:22:03 tgroupmail.com apsd[5646] <Info>: <APSCourier: 0x7ff8c22048b0>: Received successful keep-alive response

    Mar  7 13:22:03 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Stream processing: complete no, invalid no, length parsed 0, parameters (null)

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Debug>: successful entitlement check 'com.apple.private.aps-connection-initiate'

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Debug>: Failed entitlement check 'com.apple.private.dark-wake-push' for com.apple.server[9568]

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Debug>: Configuring port owner connection port com.apple.server.aps

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Debug>: Persistent connections for user 501 already loaded, skipping check

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Info>: After rebuilding cache, all APSTopics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.contacts",

            "com.me.setupservice",

            "com.me.cal",

            "com.apple.maspushagent",

            "com.apple.assetcache"

        )

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Info>: For user 501, enabled topics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.setupservice",

            "com.apple.maspushagent"

        ) for token <efe7c228 7337e263 5bc2d88f b670516f 897da5bf e874fc77 c4584aff 4ff9c25c>

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Info>: For user 235, enabled topics = (

            "com.apple.assetcache"

        ) for token <2ea07878 98a635f2 f9c3bb71 b0b444ac e6cf9518 e96b5b36 b1a151a7 1f8749d8>

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Info>: For user 501 darkWakeEnabled, enabled topics = (

            "com.me.contacts",

            "com.me.cal"

        ) for token <a9d44c3e d8bcf27e 290c2836 3c615dca 1e5d5603 aea43ec5 5d9b870c c6b433b2>

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Adjusting connection with courier enabled

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Has connections with usable topics

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Info>: In _savePersistentConnectionTopics, _connectionPortName = com.apple.server.aps

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Info>: After rebuilding cache, all APSTopics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.contacts",

            "com.me.setupservice",

            "com.me.cal",

            "com.apple.maspushagent",

            "com.apple.assetcache"

        )

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Info>: For user 501, enabled topics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.setupservice",

            "com.apple.maspushagent"

        ) for token <efe7c228 7337e263 5bc2d88f b670516f 897da5bf e874fc77 c4584aff 4ff9c25c>

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Info>: For user 235, enabled topics = (

            "com.apple.assetcache"

        ) for token <2ea07878 98a635f2 f9c3bb71 b0b444ac e6cf9518 e96b5b36 b1a151a7 1f8749d8>

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Info>: For user 501 darkWakeEnabled, enabled topics = (

            "com.me.contacts",

            "com.me.cal"

        ) for token <a9d44c3e d8bcf27e 290c2836 3c615dca 1e5d5603 aea43ec5 5d9b870c c6b433b2>

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Adjusting connection with courier enabled

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Has connections with usable topics

    Mar  7 13:22:48 tgroupmail.com apsd[5646] <Info>: In _savePersistentConnectionTopics, _connectionPortName = com.apple.server.aps

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Debug>: successful entitlement check 'com.apple.private.aps-connection-initiate'

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Debug>: Failed entitlement check 'com.apple.private.dark-wake-push' for com.apple.server[9650]

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Debug>: Configuring port owner connection port com.apple.server.aps

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Debug>: Persistent connections for user 501 already loaded, skipping check

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Info>: After rebuilding cache, all APSTopics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.contacts",

            "com.me.setupservice",

            "com.me.cal",

            "com.apple.maspushagent",

            "com.apple.assetcache"

        )

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Info>: For user 501, enabled topics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.setupservice",

            "com.apple.maspushagent"

        ) for token <efe7c228 7337e263 5bc2d88f b670516f 897da5bf e874fc77 c4584aff 4ff9c25c>

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Info>: For user 235, enabled topics = (

            "com.apple.assetcache"

        ) for token <2ea07878 98a635f2 f9c3bb71 b0b444ac e6cf9518 e96b5b36 b1a151a7 1f8749d8>

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Info>: For user 501 darkWakeEnabled, enabled topics = (

            "com.me.contacts",

            "com.me.cal"

        ) for token <a9d44c3e d8bcf27e 290c2836 3c615dca 1e5d5603 aea43ec5 5d9b870c c6b433b2>

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Adjusting connection with courier enabled

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Has connections with usable topics

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Info>: In _savePersistentConnectionTopics, _connectionPortName = com.apple.server.aps

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Info>: After rebuilding cache, all APSTopics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.contacts",

            "com.me.setupservice",

            "com.me.cal",

            "com.apple.maspushagent",

            "com.apple.assetcache"

        )

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Info>: For user 501, enabled topics = (

            "com.apple.alerts.XServer.d66128fc-d83e-4b7a-a4c1-a41ce8f4860b",

            "com.apple.alerts.XServer.a0e8a97f-32af-48f5-83cf-6d9a1f6fbbf8",

            "com.apple.alerts.XServer.d2bbab06-33fa-4787-b783-4c8babe04b4d",

            "com.apple.gamed",

            "com.apple.alerts.XServer.a7c1680f-30dc-448d-8caa-cfbdddc52f7e",

            "com.me.setupservice",

            "com.apple.maspushagent"

        ) for token <efe7c228 7337e263 5bc2d88f b670516f 897da5bf e874fc77 c4584aff 4ff9c25c>

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Info>: For user 235, enabled topics = (

            "com.apple.assetcache"

        ) for token <2ea07878 98a635f2 f9c3bb71 b0b444ac e6cf9518 e96b5b36 b1a151a7 1f8749d8>

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Info>: For user 501 darkWakeEnabled, enabled topics = (

            "com.me.contacts",

            "com.me.cal"

        ) for token <a9d44c3e d8bcf27e 290c2836 3c615dca 1e5d5603 aea43ec5 5d9b870c c6b433b2>

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Adjusting connection with courier enabled

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Debug>: <APSCourier: 0x7ff8c22048b0>: Has connections with usable topics

    Mar  7 13:24:48 tgroupmail.com apsd[5646] <Info>: In _savePersistentConnectionTopics, _connectionPortName = com.apple.server.aps

  • Templeton Peck Level 9 Level 9 (60,085 points)

    Unfortunately I'm not seeing anythnig that makes sense to me.  Are you sure all the necessary ports are open both inbound and outbound on your firewall?

     

    You may want to engage Apple support on this one.  Sorry man.