> That the error_log date itself is changing seems
> to me that Apache also thinks its the wrong time.
> If this were consistent, I would think that your box
> just has the wrong timezone set, but that this is
> inconsistent is bizarre.
Yes, this is the problem. If it was just a matter of being one time or the
other, we could deal. But since its inconsistent --> its a serious
problem.
>
> It may be possible that the time calculation is based
> on some TZ* environment variable that is not consistent,
> perhaps being set by some scripts & something? Still
> bizarre but seems plausible to me. I would log the
> data from %ENV in Script_OnStart , and see how it differs
> between these time changes.
Thats what we thought. However, if we print out the TZ variable in the
Script_OnStart it consistently comes out PDT regardless of the time that
the server reports. Argh.
>
> Anyone else have any ideas here ?
>
> Looking at your Apache::ASP settings I would recommend
> that you set StateDir to some /tmp or /var area, instead
> of the default in Global/.state for better housekeeping.
> Global is really good for includes, global.asa, and
> perl modules, and I would keep the state files out of
> there which are dynamic bits.
>
Good point. We need to clean that up.
> --Joshua
>
> Carl Lipo wrote:
> >
> > We are having a problem with our Apache::ASP scripts and the clock time
> > they are reporting. It seems that when an ASP script is run, the internal
> > clock is sometimes set to GMT. An example from our error log is shown
> > below. The problem is that our scripts expect localtime (PDT). The other
> > odd (and particularly frustrating) thing is that this problem is *not*
> > consistent. Sometimes we get GMT while other times we get localtime (PDT).
> > Its very odd. We have narrowed the problem down to mod_perl and/or
> > Apache::ASP. Has anyone seen this behavior before? Is there a
> > configuration problem?
> >
> > The problem seems to start with the ScriptOnStart (not before that...) --
> > and lasts until the end of the ASP processing.
> >
> > >>>> This is PDT, the correct local time <<<<<<<<<<<<<<<<<<<<<<<<<<<<
> > [Wed Jul 5 16:10:56 2000] [error] [asp] [29715] [debug] ASP object
> > created -
> > Application: Apache::ASP::Application=HASH(0x83532a8); GlobalASA:
>Apache::ASP::GlobalASA=HASH(0x81df93c); Internal: Apache::ASP::State=HASH(0x81eab5c);
>Request: Apache::ASP::Request=HASH(0x87ed180); Response:
>Apache::ASP::Response=HASH(0x87ed198); Server: Apache::ASP::Server=HASH(0x848891c);
>Session: Apache::ASP::Session=HASH(0x81e97a8); app_state: 1; basename: index.htm;
>buffering_on: 1; cgi_headers: 0; clean: 0; compile_includes: 0; cookie_path: /; dbg:
>2; debugs_output: ARRAY(0x81a6990); errs: 0; filehandle: GLOB(0x8353284); filename:
>/office/common/infosource-wally/index.htm; filter: 1; global: /etc/apache/state-asp;
>global_package: ; group_refresh: 120; id:
>_office_common_infosource_wally_index_htmxINL; includes_dir: .; init_packages:
>ARRAY(0x8488964); mail_alert_to: ; mail_errors_to: [EMAIL PROTECTED]; mtime:
>962475992; no_cache: ; no_session: ; no_state: ; package:
>Apache::ASP::Compiles::_etc_apache_state_asp_global_asa; paranoid_session: 0;
>pod_comments: 1; r!
:
> > Apache=SCALAR(0x81e9610); remote_ip: 216.223.5.149; secure_session: ;
>session_serialize: ; session_timeout: 1200; stat_inc: ; stat_inc_match: ;
>state_cache: ; state_db: DB_File; state_dir: /etc/apache/state-asp/.state;
>state_manager: 10; ua: Mozilla/4.72
> > [en] (X11; U; Linux 2.2.13 i686); unique_packages: 0;
> > [Wed Jul 5 16:10:56 2000] [error] [asp] [29715] [debug] inlining include
>home-go.include
> > [Wed Jul 5 16:10:56 2000] [error] [asp] [29715] [debug] inlining include
>links/internap.links
> > [Wed Jul 5 16:10:56 2000] [error] [asp] [29715] [debug] active undefing
> > sub
>Apache::ASP::Compiles::_etc_apache_state_asp_global_asa::_office_common_infosource_wally_index_htmxINL
> > code CODE(0x87ef78c) before compiling
> > [Wed Jul 5 16:10:56 2000] [error] [asp] [29715] [debug] compiling into
> > package Apache::ASP::Compiles::_etc_apache_state_asp_global_asa subid
> >
>Apache::ASP::Compiles::_etc_apache_state_asp_global_asa::_office_common_infosource_wally_index_htmxINL
> >
> > >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
> > -->>> note here is the Script_OnStart. Notice how the time changes!
> > >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
> > [Wed Jul 5 23:10:56 2000] [error] [asp] [29715] [debug] Script_OnStart
> > [Wed Jul 5 23:10:56 2000] [error] [asp] [29715] [debug] executing
> > Script_OnStart
> > [Wed Jul 5 23:10:56 2000] [error] [asp] [29715] Time is now (localtime(time()):
> > [Wed Jul 5 23:10:56 2000] [error] [asp] [29715] 56 10 23 5 6 100 3 186
> > >>>> printing localtime(time()) [above] shows that perl really thinks it
> > >>>> is this later [GMT time] <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
> >
> > [Wed Jul 5 23:10:56 2000] [error] [asp] [29715] [debug] executing
> > _office_common_infosource_wally_index_htmxINL
> > [Wed Jul 5 23:10:58 2000] [error] [asp] [29715] ************
>INDEX.HTM*************
> > [Wed Jul 5 23:10:58 2000] [error] [asp] [29715] ***** INDEX USER: carl*****
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] active undefing sub
> >
>Apache::ASP::Compiles::_etc_apache_state_asp_global_asa::_etc_apache_state_asp_footer
> > code CODE(0x88cce9c) before compiling
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] executing
> >
>Apache::ASP::Compiles::_etc_apache_state_asp_global_asa::_etc_apache_state_asp_footer
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] Script_OnEnd
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] executing Script_OnEnd
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] building cgi headers
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] status 200
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] destroying - asp:
>Apache::ASP=HASH(0x856293c);
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] testing internal time for
>cleanup groups
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] state application locks:
>1, unlocks: 1
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] state application locks:
>0, unlocks: 0
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] state internal locks: 4,
>unlocks: 4
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] state internal locks: 0,
>unlocks: 0
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] state
>08a41d19d762fbf748c15fe02b8c76ce locks: 35, unlocks: 35
> > [Wed Jul 5 23:10:59 2000] [error] [asp] [29715] [debug] END ASP DESTROY
> >
> >
> > ----------------------------------------------------------------------------
> > Carl Lipo EMAIL: [EMAIL PROTECTED]
> > IT Systems Architect PAGER: 888.393.5620
> > InterNAP Network Services OFFICE: 206.504.5442
> > 601 Union Street, Suite 1000 FAX: 206.264.1833
> > Seattle, WA 98101 CELL: 206.579.0163
> >
> > Unauthorized use, disclosure or distribution of information within
> > this message is prohibited.
>
>