ESI Transition Test Release

[edp]

Oh, that I still have on my template. I just refreshed the site to the home page and it is showing :

21 queries SQL time 0.0311s, Total time 0.1026s"

The fetch reported:

3670 queries SQL time 1.2736s, Total time 28.6495s

[Salvoxia]

It's possible that there's a pre-compiled cached version of the template before the changes were made. Try clearing your cache/templates_c folder.

[edp]

ok, will do.

btw, one pull resulted in an on web page message:

"ZKBApi: https://zkillboard.com/api/corporationID/98512537/
24 kills were posted and 23 were skipped (200 kills fetched). Timestamp of last kill: 2017-05-30 23:55:49
ESI Exception: API call to https://esi.tech.ccp.is/latest/killmail ... 0dc4b554f/ failed: Resolving timed out after 3007 milliseconds (API call to https://esi.tech.ccp.is/latest/killmail ... 0dc4b554f/ failed: Resolving timed out after 3007 milliseconds, Code: 0), KillID = 62617210
ESI Exception: API call to https://esi.tech.ccp.is/latest/killmail ... 3e2872585/ failed: Operation timed out after 3224 milliseconds with 0 bytes received (API call to https://esi.tech.ccp.is/latest/killmail ... 3e2872585/ failed: Operation timed out after 3224 milliseconds with 0 bytes received, Code: 0), KillID = 62617228"

[edp]

Post cache deletion, the fetch query result was:

2451 queries SQL time 0.8584s, Total time 28.3409s

[edp]

KB now concurrent with the stable platform. One more error thrown, the same one as before:

PHP 2: reset() expects parameter 1 to be array, null given in /var/www/tiod.wtf/kbtest/common/includes/esi/class.helpers.php on line 187, referer: http://kbtest.tiod.wtf/?a=home

[edp]

Ran through all the mods and other elements. Currently the only errors I am seeing are the ones I posted, including a perusal of the sql logs.

[Salvoxia]

Hi,

I observed the occasional error, especially when view pilot details for random pilots. It will return an error on the first try, but work when reloading the page.
I enhanced error logging in the ESI library to get to the bottom of this. Could you please update your common/includes/esi/class.esi.php file with this one, continue keeping the killboard up-to-date and send me the error log after a day or so? That would be very helpful!

Best Regards,
Salvoxia

[edp]

ok, pushing the update up now. I'll let you know what it generates.

[edp]

I did a quick comparative between logs and the timestamps to coordinate what was requested when the error was generated. I'm hoping this may help.

When this error occurred:

"[Wed Jun 07 15:27:57.250593 2017] [php7:notice] [pid 1338] PHP 2: reset() expects parameter 1 to be array, null given in /var/www/tiod.wtf/kbtest/common/includes/esi/class.helpers.php on line 187, referer: http://kbtest.tiod.wtf/?a=home"

This was what the access log reported at the same time for the transaction:

"[07/Jun/2017:15:27:57 -0400] "GET /mods/ship_display_tool/images/equipment/icon00_hig.png HTTP/1.1" 200 4013 "http://kbtest.tiod.wtf/?a=kill_detail&kll_id=1512" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"

[07/Jun/2017:15:27:57 -0400] "GET /mods/ship_display_tool/images/equipment/icon00_rig.png HTTP/1.1" 200 4380 "http://kbtest.tiod.wtf/?a=kill_detail&kll_id=1512" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"

[07/Jun/2017:15:27:57 -0400] "GET /thumb.php?type=pilot&id=2175&size=64&int=1 HTTP/1.1" 302 - "http://kbtest.tiod.wtf/?a=kill_detail&kll_id=1512" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"

[07/Jun/2017:15:27:57 -0400] "GET /thumb.php/map/30002468_145.png HTTP/1.1" 200 8173 "http://kbtest.tiod.wtf/?a=kill_detail&kll_id=1512" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"

[07/Jun/2017:15:27:57 -0400] "GET /thumb.php/region/30002468_145.png HTTP/1.1" 200 2433 "http://kbtest.tiod.wtf/?a=kill_detail&kll_id=1512" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"

[07/Jun/2017:15:27:57 -0400] "GET /thumb.php/cons/30002468_145.png HTTP/1.1" 200 1581 "http://kbtest.tiod.wtf/?a=kill_detail&kll_id=1512" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"

[07/Jun/2017:15:27:57 -0400] "GET /mods/ship_display_tool/images/flashImage/cap70.png HTTP/1.1" 200 4784 "http://kbtest.tiod.wtf/?a=kill_detail&kll_id=1512" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"

[07/Jun/2017:15:27:57 -0400] "GET /mods/ship_display_tool/images/flashImage/Icon63_14.png HTTP/1.1" 200 1649 "http://kbtest.tiod.wtf/?a=kill_detail&kll_id=1512" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"

[07/Jun/2017:15:27:57 -0400] "POST /index.php/?a=kill_detail&kll_id=1512&xajax=1 HTTP/1.1" 200 - "http://kbtest.tiod.wtf/?a=kill_detail&kll_id=1512" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"

[07/Jun/2017:15:27:57 -0400] "GET /?a=thumb&id=2175&int=1&size=64&type=pilot HTTP/1.1" 200 1395 "http://kbtest.tiod.wtf/?a=kill_detail&kll_id=1512" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/51.0.2704.79 Safari/537.36 Edge/14.14393"

I redacted the bits that aren't relevant to debugging, btw....

[edp]

AHHHHHHH, in messages.log, I see this one moment BEFORE the other error(s), and the next error is far outside the chance it's coincidental:

Jun 7 15:27:56 dbd-fedora-23 audit: AVC avc: denied { execmem } for pid=33073 comm="httpd" scontext=system_u:system_r:httpd_t:s0 tcontext=system_u:system_r:httpd_t:s0 tclass=process permissive=1
Jun 7 15:27:56 dbd-fedora-23 audit: AVC avc: denied { write } for pid=33073 comm="httpd" name="CharacterID" dev="dm-0" ino=100832405 scontext=system_u:system_r:httpd_t:s0 tcontext=system_u:object_r:httpd_sys_content_t:s0 tclass=dir permissive=1
Jun 7 15:27:56 dbd-fedora-23 audit: AVC avc: denied { write } for pid=33073 comm="httpd" name="Files" dev="dm-0" ino=100665599 scontext=system_u:system_r:httpd_t:s0 tcontext=unconfined_u:object_r:httpd_sys_content_t:s0 tclass=dir permissive=1
Jun 7 15:27:56 dbd-fedora-23 audit: AVC avc: denied { remove_name } for pid=33073 comm="httpd" name="9b5c10ebbf6fffc524f20153cb11360e.txt" dev="dm-0" ino=180599 scontext=system_u:system_r:httpd_t:s0 tcontext=system_u:object_r:httpd_sys_content_t:s0 tclass=dir permissive=1
Jun 7 15:27:56 dbd-fedora-23 audit: AVC avc: denied { unlink } for pid=33073 comm="httpd" name="9b5c10ebbf6fffc524f20153cb11360e.txt" dev="dm-0" ino=180599 scontext=system_u:system_r:httpd_t:s0 tcontext=system_u:object_r:httpd_sys_content_t:s0 tclass=file permissive=1
Jun 7 15:27:56 dbd-fedora-23 audit: AVC avc: denied { rmdir } for pid=33073 comm="httpd" name="5c" dev="dm-0" ino=180598 scontext=system_u:system_r:httpd_t:s0 tcontext=system_u:object_r:httpd_sys_content_t:s0 tclass=dir permissive=1
Jun 7 15:27:56 dbd-fedora-23 audit: AVC avc: denied { add_name } for pid=33073 comm="httpd" name="5c" scontext=system_u:system_r:httpd_t:s0 tcontext=system_u:object_r:httpd_sys_content_t:s0 tclass=dir permissive=1
Jun 7 15:27:56 dbd-fedora-23 audit: AVC avc: denied { create } for pid=33073 comm="httpd" name="5c" scontext=system_u:system_r:httpd_t:s0 tcontext=system_u:object_r:httpd_sys_content_t:s0 tclass=dir permissive=1
Jun 7 15:27:56 dbd-fedora-23 audit: AVC avc: denied { create } for pid=33073 comm="httpd" name="9b5c10ebbf6fffc524f20153cb11360e.txt" scontext=system_u:system_r:httpd_t:s0 tcontext=system_u:object_r:httpd_sys_content_t:s0 tclass=file permissive=1
Jun 7 15:27:56 dbd-fedora-23 audit: AVC avc: denied { write } for pid=33073 comm="httpd" path="/var/www/tiod.wtf/kbtest/cache/esi/kbtest.tiod.wtf/Files/9b/5c/9b5c10ebbf6fffc524f20153cb11360e.txt" dev="dm-0" ino=67506859scontext=system_u:system_r:httpd_t:s0 tcontext=system_u:object_r:httpd_sys_content_t:s0 tclass=file permissive=1
Jun 7 15:27:56 dbd-fedora-23 audit: AVC avc: denied { append } for pid=33073 comm="httpd" name="error.log" dev="dm-0" ino=33732225 scontext=system_u:system_r:httpd_t:s0 tcontext=system_u:object_r:httpd_sys_content_t:s0 tclass=file permissive=1