Question about DEBUG ON

[Stephen Woodbridge]

Hi all,

I have used DEBUG ON in my mapfiles in the past, but I'm getting 
something very strange happening that I do not remember happening in the 
past. It looks like the map is getting fully rendered twice and a single 
map request. This is happening on both 4.6 and 4.9 and from multiple 
different mapfiles.

Does anyone have an explanation for this?

Thanks,
   -Steve

http://localhost/cgi-bin/mapserv?mode=map&layers=all&scale=1000000&map=/home/direct/mapserver/default_mapfile.map&mapxy=-71.38918+42.61935&map_size=300+200

Output to /var/log/apache/error.log from a SINGLE draw request:

> [Wed Nov 15 14:03:33 2006].668138 msDrawMap(): Layer 0 (color_symbol), 0.000s
> [Wed Nov 15 14:03:33 2006].668505 msDrawMap(): Layer 1 (AK_Can_Border_Fill), 0.000s
> [Wed Nov 15 14:03:33 2006].668690 msDrawMap(): Layer 2 (Mexican_States), 0.000s
> [Wed Nov 15 14:03:33 2006].671238 msDrawMap(): Layer 3 (States/Provinces), 0.003s
> [Wed Nov 15 14:03:33 2006].677381 msDrawMap(): Layer 5 (Counties), 0.006s
> [Wed Nov 15 14:03:33 2006].682194 msDrawMap(): Layer 6 (Places), 0.005s
> [Wed Nov 15 14:03:33 2006].699806 msDrawMap(): Layer 9 (Water), 0.018s
> [Wed Nov 15 14:03:33 2006].700657 msDrawMap(): Layer 10 (States_Outline), 0.001s
> [Wed Nov 15 14:03:33 2006].703170 msDrawMap(): Layer 19 (Major_Roads_Z2), 0.002s
> [Wed Nov 15 14:03:33 2006].703601 msDrawMap(): Layer 30 (Administrative_Area_Boundaries_3_County), 0.000s
> [Wed Nov 15 14:03:33 2006].704170 msDrawMap(): Layer 31 (Islands), 0.001s
> [Wed Nov 15 14:03:33 2006].704737 msDrawMap(): Layer 32 (Administrative_Area_Boundaries_5_Other), 0.001s
> [Wed Nov 15 14:03:33 2006].705809 msDrawMap(): Layer 33 (Land_Use_Features_A_Z1), 0.001s
> [Wed Nov 15 14:03:33 2006].706328 msDrawMap(): Layer 35 (Land_Use_Features_B_Z1), 0.000s
> [Wed Nov 15 14:03:33 2006].706696 msDrawMap(): Layer 38 (Administrative_Area_Boundaries_3_County), 0.000s
> [Wed Nov 15 14:03:33 2006].707041 msDrawMap(): Layer 40 (Oceans), 0.000s
> [Wed Nov 15 14:03:33 2006].708529 msDrawMap(): Layer 42 (Waterway_Polygons), 0.001s
> [Wed Nov 15 14:03:33 2006].708658 msDrawMap(): Layer 43 (Provinces_Outline), 0.000s
> [Wed Nov 15 14:03:33 2006].709133 msDrawMap(): Layer 47 (Major_Roads_Z2), 0.000s
> [Wed Nov 15 14:03:33 2006].709612 msDrawMap(): Layer 59 (Canada_Cities_z3), 0.000s
> [Wed Nov 15 14:03:33 2006].710052 msDrawMap(): Layer 63 (Highway_Shields_Z2a), 0.000s
> [Wed Nov 15 14:03:33 2006].711423 msDrawMap(): Layer 68 (US_Highway_Shields_Z1), 0.001s
> [Wed Nov 15 14:03:33 2006].712573 msDrawMap(): Layer 70 (Interstate_Highway_Shields_Z1), 0.001s
> [Wed Nov 15 14:03:33 2006].728407 msDrawMap(): Layer 74 (US_Places), 0.016s
> [Wed Nov 15 14:03:33 2006].728635 msDrawMap(): Layer 75 (US_State_Capitals), 0.000s
> [Wed Nov 15 14:03:33 2006].728666 msDrawMap(): Layer 77 (copyright), 0.000s
> [Wed Nov 15 14:03:33 2006].805605 msDrawMap(): Drawing Label Cache, 0.077s
> [Wed Nov 15 14:03:33 2006].805641 msDrawMap() total time: 0.138s
> [Wed Nov 15 14:03:34 2006].320742 msDrawMap(): Layer 0 (color_symbol), 0.000s
> [Wed Nov 15 14:03:34 2006].321095 msDrawMap(): Layer 1 (AK_Can_Border_Fill), 0.000s
> [Wed Nov 15 14:03:34 2006].321229 msDrawMap(): Layer 2 (Mexican_States), 0.000s
> [Wed Nov 15 14:03:34 2006].323781 msDrawMap(): Layer 3 (States/Provinces), 0.003s
> [Wed Nov 15 14:03:34 2006].329886 msDrawMap(): Layer 5 (Counties), 0.006s
> [Wed Nov 15 14:03:34 2006].334698 msDrawMap(): Layer 6 (Places), 0.005s
> [Wed Nov 15 14:03:34 2006].352820 msDrawMap(): Layer 9 (Water), 0.018s
> [Wed Nov 15 14:03:34 2006].353720 msDrawMap(): Layer 10 (States_Outline), 0.001s
> [Wed Nov 15 14:03:34 2006].356263 msDrawMap(): Layer 19 (Major_Roads_Z2), 0.003s
> [Wed Nov 15 14:03:34 2006].356693 msDrawMap(): Layer 30 (Administrative_Area_Boundaries_3_County), 0.000s
> [Wed Nov 15 14:03:34 2006].357263 msDrawMap(): Layer 31 (Islands), 0.001s
> [Wed Nov 15 14:03:34 2006].357828 msDrawMap(): Layer 32 (Administrative_Area_Boundaries_5_Other), 0.001s
> [Wed Nov 15 14:03:34 2006].358888 msDrawMap(): Layer 33 (Land_Use_Features_A_Z1), 0.001s
> [Wed Nov 15 14:03:34 2006].359413 msDrawMap(): Layer 35 (Land_Use_Features_B_Z1), 0.001s
> [Wed Nov 15 14:03:34 2006].359784 msDrawMap(): Layer 38 (Administrative_Area_Boundaries_3_County), 0.000s
> [Wed Nov 15 14:03:34 2006].360128 msDrawMap(): Layer 40 (Oceans), 0.000s
> [Wed Nov 15 14:03:34 2006].361598 msDrawMap(): Layer 42 (Waterway_Polygons), 0.001s
> [Wed Nov 15 14:03:34 2006].361718 msDrawMap(): Layer 43 (Provinces_Outline), 0.000s
> [Wed Nov 15 14:03:34 2006].362200 msDrawMap(): Layer 47 (Major_Roads_Z2), 0.000s
> [Wed Nov 15 14:03:34 2006].362686 msDrawMap(): Layer 59 (Canada_Cities_z3), 0.000s
> [Wed Nov 15 14:03:34 2006].363117 msDrawMap(): Layer 63 (Highway_Shields_Z2a), 0.000s
> [Wed Nov 15 14:03:34 2006].364521 msDrawMap(): Layer 68 (US_Highway_Shields_Z1), 0.001s
> [Wed Nov 15 14:03:34 2006].365698 msDrawMap(): Layer 70 (Interstate_Highway_Shields_Z1), 0.001s
> [Wed Nov 15 14:03:34 2006].381811 msDrawMap(): Layer 74 (US_Places), 0.016s
> [Wed Nov 15 14:03:34 2006].382029 msDrawMap(): Layer 75 (US_State_Capitals), 0.000s
> [Wed Nov 15 14:03:34 2006].382059 msDrawMap(): Layer 77 (copyright), 0.000s
> [Wed Nov 15 14:03:34 2006].458967 msDrawMap(): Drawing Label Cache, 0.077s
> [Wed Nov 15 14:03:34 2006].459002 msDrawMap() total time: 0.139s