[horde] Bad log level META

Simon B simon.buongiorno at gmail.com
Tue Feb 21 17:58:02 UTC 2017


On 21 February 2017 at 17:07, Michael J Rubinsky <mrubinsk at horde.org> wrote:
>
> Quoting Simon B <simon.buongiorno at gmail.com>:
>
>> On 21 February 2017 at 14:38, Michael J Rubinsky <mrubinsk at horde.org>
>> wrote:
>>>
>>>
>>> Quoting Simon B <simon.buongiorno at gmail.com>:
>>>
>>>> On 20 February 2017 at 19:53, Michael J Rubinsky <mrubinsk at horde.org>
>>>> wrote:
>>>>>
>>>>>
>>>>>
>>>>> Quoting Simon B <simon.buongiorno at gmail.com>:
>>>>>
>>>>>> On 20 February 2017 at 17:50, Michael J Rubinsky <mrubinsk at horde.org>
>>>>>> wrote:
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> Quoting Simon B <simon.buongiorno at gmail.com>:
>>>>>>>
>>>>>>>> On 20 February 2017 at 16:58, Michael J Rubinsky
>>>>>>>> <mrubinsk at horde.org>
>>>>>>>> wrote:
>>>>>>>>>
>>>>>>>>>
>>>>>>>>>
>>>>>>>>>
>>>>>>>>>
>>>>>>>>> Quoting Simon B <simon.buongiorno at gmail.com>:
>>>>>>>>>
>>>>>>>>>> Hi
>>>>>>>>>>
>>>>>>>>>> Since updating via git yesterday AM, ActiveSync has stopped
>>>>>>>>>> working
>>>>>>>>>> (i.e. IMP is working fine).
>>>>>>>>>>
>>>>>>>>>> Attempting to to go to .../admin/activesync.php in the browser (to
>>>>>>>>>> clear the devices as my first troubleshooting step), give this
>>>>>>>>>> error:
>>>>>>>>>>
>>>>>>>>>>
>>>>>>>>>>
>>>>>>>>>> A fatal error has occurred
>>>>>>>>>>
>>>>>>>>>> Bad log level META
>>>>>>>>>>
>>>>>>>>>
>>>>>>>>> Are you 100% sure you are up to date with git master? There were a
>>>>>>>>> few
>>>>>>>>> BC
>>>>>>>>> related fixes I committed after the main push that introduced these
>>>>>>>>> changes.
>>>>>>>>
>>>>>>>>
>>>>>>>>
>>>>>>>>
>>>>>>>>
>>>>>>>> Hi Mike
>>>>>>>>
>>>>>>>> I ran it again this morning before sending the mail.  I even
>>>>>>>> restarted
>>>>>>>> Apache after changing the logging requirement to false..
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> I found and fixed an issue that would cause this when logging is
>>>>>>> turned
>>>>>>> off,
>>>>>>> or when the path is not writeable. Still not sure what would have
>>>>>>> caused
>>>>>>> this when logging was configured properly, or when viewing the admin
>>>>>>> page.
>>>>>>>
>>>>>>> Anyway, let me know if this gets you closer...
>>>>>>
>>>>>>
>>>>>>
>>>>>>
>>>>>> Git says it is up to date, but no change in behaviour.
>>>>>
>>>>>
>>>>>
>>>>>
>>>>> Is this just a "normal" git master checkout? I can't reproduce any of
>>>>> this.
>>>>>
>>>>> Try putting:
>>>>>
>>>>> Horde::debug($this->_logger);
>>>>>
>>>>> on line 121 of Horde_ActiveSync_SyncCache (before the // @todo line)
>>>>> and
>>>>> navigate to the activesync admin page again. This will output the
>>>>> contents
>>>>> of _logger in your {tmp directory}/horde_debug.txt.
>>>>
>>>>
>>>>
>>>> Done and sent off-list.
>>>
>>>
>>>
>>> Well, this is not correct:
>>>
>>> 2017-02-21T12:17:33+00:00 DEBUG: Variable information:
>>> object(Horde_Core_Log_Logger)#76 (3) {
>>>   ["_levels":"Horde_Log_Logger":private]=>  <----------------- Not
>>> correct.
>>>   array(8) {
>>>     [0]=>
>>>     string(5) "EMERG"
>>>     [1]=>
>>>     string(5) "ALERT"
>>>     [2]=>
>>>     string(4) "CRIT"
>>>     [3]=>
>>>     string(3) "ERR"
>>>     [4]=>
>>>     string(4) "WARN"
>>>     [5]=>
>>>     string(6) "NOTICE"
>>>     [6]=>
>>>     string(4) "INFO"
>>>     [7]=>
>>>     string(5) "DEBUG"
>>>   }
>>>
>>> The visibility of that member should be "protected", and not "private".
>>> This
>>> was changed in Horde_Log along with the commits that modified the
>>> logging.
>>> Are you SURE you have a pristine git *master* checkout and your system
>>> isn't
>>> including any FRAMEWORK_5_2 libraries?
>>>
>>> See
>>>
>>> https://github.com/horde/horde/commit/f78145d405576ce30fabcbbc87e28952225a8ba5
>>
>>
>> Hi Mike,
>>
>> Unfortunately, I didn't document my git install :(  but looking at the
>> current script, I can't see how it isn't a pristine check out - I can
>> send you the log, but it does a good job of deleting all the files and
>> recreating the symlinks.  In fact, if I look into the file from the
>> link you sent, I see the file is as the link looks like it should be.
>>
>>    33 class Horde_Log_Logger implements Serializable
>>    34 {
>>    35     /* Serialize version. */
>>    36     const VERSION = 1;
>>    37
>>    38     /**
>>    39      * Log levels where the keys are the level priorities and
>> the values are
>>    40      * the level names.
>>    41      *
>>    42      * @var array
>>    43      */
>>    44     protected $_levels = array();
>>    45
>>    46     /**
>>    47      * Horde_Log_Handler_Base objects.
>>    48      *
>>    49      * @var array
>>    50      */
>>    51     protected $_handlers = array();
>>   "/usr/share/git-horde5/framework/Log/lib/Horde/Log/Logger.php" 252L,
>> 7156C
>>
>>
>> How would I determine if the system has any orphan FRAMEWORK_5_2
>> libraries?
>
>
>
> Look at phpinfo() from within Horde's admin phpshell. Make sure that the
> include_path doesn't include any  path that may have any older libraries.
> Also make sure you clear any local server caches and/or restart the
> webserver. Perhaps you have some stale opcode cache? Other than that, I'm
> really out of ideas.

Thanks Mike.

Can't reboot right now, so I tried to clear the cache with the script..

root at mail:~# date
Tue Feb 21 17:46:00 UTC 2017
root at mail:~# /usr/share/git-horde5/framework/Autoloader_Cache/bin/horde-autoloader-cache-prune
PHP Warning:  require_once(Horde/Autoloader/Cache.php): failed to open
stream: No such file or directory in
/usr/share/git-horde5/framework/Autoloader_Cache/bin/horde-autoloader-cache-prune
on line 18
PHP Fatal error:  require_once(): Failed opening required
'Horde/Autoloader/Cache.php' (include_path='.:/usr/share/php') in
/usr/share/git-horde5/framework/Autoloader_Cache/bin/horde-autoloader-cache-prune
on line 18
root at mail:~#

That didn't go well..

phpinfo() gives..
include_path/usr/share/horde-beta/libs:/usr/share/git-horde5/horde/lib:.:/usr/share/php:/usr/share/pear

/usr/share/pear doesn't exist, so I have no idea why it's in the path
(probably left over from when I had two pear installs, before I moved
pre-prod to the git clone...

anyway, rm- rf /tmp/* followed by an apache stop/start seems to have
enabled both the activesync.php and the phones are actually
synchronising again.

I might at the cache clearance to my git-pull script, but I'd prefer
to be a bit more elegant than rm.  The clue to use
bin/horde-autoloader-cache-prune came from
https://www.horde.org/apps/horde/docs/PERFORMANCE

Thanks for your help and patience.

Simon


More information about the horde mailing list