Closed Bug 1062510 Opened 10 years ago Closed 6 years ago

With firefox 32 an image loading problem was introduced where a page seems to stop rendering and some images show up as only a dot. Some refreshes fully load, and some don't. Almost random seeming.

Categories

(Core :: Graphics: ImageLib, defect)

32 Branch
x86
Windows 7
defect
Not set
normal

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: jonathan, Unassigned)

References

()

Details

Attachments

(12 files)

User Agent: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/36.0.1985.143 Safari/537.36 Steps to reproduce: 1) I upgraded to version 32.0 today 2) I went to: www.magazineagent.com/The-Week/Magazine Actual results: Some images are missing and show up as a dog. If I load the image in a new tab it shows. If I refresh the page, sometimes they show, sometimes they do not. Different images seem to be missing different times. Expected results: All images should have loaded well. We test this page and many on the site regularly. The issue is directly related to upgrade to FireFox 32 We can repeat this on every workstation we tested upgrading to FirefFox 32 in the office today.
Hardware: x86_64 → x86
If I open firefox to try to debug the issue, the page loads every time perfect, I assume because Firebug is intercepting changing the order of the page rendering requests. I believe (could be way off, but I suspect) this may have something to do with the new caching logic possibly, and firefox believes it has completed the page for some reason. There are no errors in console, and I can't use a net watcher add on, or the problem never comes up
Could you attach a screenshot, please. And try with a clean profile: https://support.mozilla.org/en-US/kb/profile-manager-create-and-remove-firefox-profiles
We have reinstalled firefox on several PC's. I tried running firefox with addon's disabled (safe mode)... The problem persists.
Attached image Capture.PNG
HEre is a screen shot, with the dot highlighted, and an example of a missing image. If i refresh the page, half the time it renders complete. half the time it misses different random images
Here is a pdf, with screen shots of the full page and broken page
Note, that in FireFox 31.0 The problem never existed. In Chrome and IE on the Same machines, the problem does not exist
Following these instructions https://support.mozilla.org/en-US/questions/1013493 Appears to resolve the issue So it seems related to the new caching features almost certain
Did you try to clean your browser cache? (restart Firefox) The cache folder is displayed in the page about:cache (line "disk"), so you can delete it manually too.
Component: Untriaged → Networking: Cache
Product: Firefox → Core
Version: unspecified → 32 Branch
Jonathan, I was trying to reproduce - no luck. I'd like to ask you for the following: - run firefox with NSPR logging on by setting environment variables: NSPR_LOG_MODULES=timestamp,cache2:5,nsHttp:4 and NSPR_LOG_FILE=/path/to/log/file - when you reproduce, send the log file (attach here as a zip or send it to me via email, since some cookies may be visible) - please include in a comment or that email URL of the image that failed to load, this is very important Thanks! PS: more on how to turn NSPR logging on can be found at: https://developer.mozilla.org/en-US/docs/Mozilla/Debugging/HTTP_logging
Flags: needinfo?(jonathan)
Email sent to Honza Bambas as well: (The images won't paste here, so I will attach to bug report now as well) This is with regards to the bug we are seeing on our end, that we are discussing in the bug report: https://bugzilla.mozilla.org/show_bug.cgi?id=1062510 The last URL requested has several examples of the missing image: http://www.magazine-agent.com/Ranger-Rick/Magazine In this picture, the two dots, represent missing images: The markup for those 2 images is as follows: (I captured the markup, after the failure, by opening developer tool in firefox, selecting the two elements, and “Copy HTML”) <a href="/Cover/Ranger-Rick/69748" class="coverbox cboxElement" id="MainContent_covSmall_repCovers_hlCover_3"><img alt="Ranger Rick Magazine | 4/2014 Cover" src="http://hosted-images.magazine-services.net/images/image.aspx?i=cover0069748.jpg&amp;w=89" class="border mar5" title="Ranger Rick Magazine | 4/2014 Cover" id="MainContent_covSmall_repCovers_imgCover_3"></a> <a href="/Cover/Ranger-Rick/69295" class="coverbox cboxElement" id="MainContent_covSmall_repCovers_hlCover_4"><img alt="Ranger Rick Magazine | 3/2014 Cover" src="http://hosted-images.magazine-services.net/images/image.aspx?i=cover0069295.jpg&amp;w=89" class="border mar5" title="Ranger Rick Magazine | 3/2014 Cover" id="MainContent_covSmall_repCovers_imgCover_4"></a> If I copy those 2 image url’s, into a new tab, I can request the image directly as many times as I wish, and it will display every time. http://hosted-images.magazine-services.net/images/image.aspx?i=cover0069748.jpg&amp;w=89 http://hosted-images.magazine-services.net/images/image.aspx?i=cover0069295.jpg&amp;w=89 As a reminder, if I have developer tools open, the problem does not occur, this leads me to believe there is a difference in behavior, maybe some of the cache optimizations get turned off? We tested this on machines, that did not have any AddOns installed (our developer machines have Firebug, which was disabled for testing), but we installed this version on some servers, with no addons and the problem still occurred, with no addons installed. Here is the about, to make sure you are clear on the version we have tried on several machines in our office.
Flags: needinfo?(jonathan)
Attached file log.zip
Here is the log file requested.
Snippet of missing images, with 2 dots representing missing images, related to log.zip attached in previous file
Thanks for such a quick reply! Please first confirm you are running Firefox 32.0, you have correctly copy/pasted the list of NSPR log modules and are on a clean profile. Since in the log I cannot see cache v2 logs. I've locally checked that the logging works for the new cache when the modules are set (copy/pasted from my comment).
Flags: needinfo?(jonathan)
Attached image FireFoxVersion.PNG
All machines with reported problem have Firefox 32.0 as per this screen shot. Some where updates, and some where clean installs on machine without Firefox previously.
Flags: needinfo?(jonathan)
This is the NSPR environment setting i used to generate log: timestamp,nsHttp:5,nsSocketTransport:5,nsStreamPump:5,nsHostResolver:5
I can regenerate with these settings added: ,cache2:5,nsHttp:4 I will upload new log file shortly.
Please change the log modules as I've suggested in comment 9: timestamp,cache2:5,nsHttp:4 and please use a new profile to produce the log: https://support.mozilla.org/en-US/kb/profile-manager-create-and-remove-firefox-profiles Maybe the simplest way is to run Firefox from the command line as: firefox.exe -profile /path/to/profile The directory doesn't need to exists, will be automatically created by Firefox.
Even best is to run Firefox as following: firefox.exe -safe-mode -profile /path/to/profile
Attached image GoodHOuseKeeping.PNG
OK, I have uploaded new log file, with this: C:\>set NSPR_LOG_MODULES=timestamp,cache2:5,nsHttp:4,nsSocketTransport:5,nsStreamPump:5,nsHostResolver:5 C:\>set NSPR_LOG_FILE=%TEMP%\log.txt C:\>cd "program files <x86>\Mozilla FireFox" C:\Program Files (x86)\Mozilla Firefox>firefox.exe I also attached another screen with highlighted missing image. The test URL was: http://www.magazine-agent.com/Good-Housekeeping/Magazine
I will create a new profile now, and relog / upload as well.
Attached file After new profile log
OK, the latest attachment are: After deleted and created new profile. The orginal profile was "Default", I deleted that one, created new, deleted again (using delete all files both times), then created the latest one used to run the last log file and snippet attachments
This is the command prompt setup: Microsoft Windows [Version 6.1.7601] Copyright (c) 2009 Microsoft Corporation. All rights reserved. C:\>set NSPR_LOG_MODULES=timestamp,cache2:5,nsHttp:4,nsSocketTransport:5,nsStrea mPump:5,nsHostResolver:5 C:\>set NSPR_LOG_FILE=%TEMP%\log.txt C:\>cd "program files <x86>\Mozilla FireFox" C:\Program Files (x86)\Mozilla Firefox>firefox.exe C:\Program Files (x86)\Mozilla Firefox>
Jonathan, it's nice you add more log modules, but I asked for only 3 of them. When you add 6 it makes the log too large and almost not possible to analyze. Please, use only the 3 modules I wanted, thanks.
C:\Program Files (x86)\Mozilla Firefox>set NSPR_LOG_MODULES=timestamp,cache2:5,nsHttp:4 C:\Program Files (x86)\Mozilla Firefox>set NSPR_LOG_FILE=%TEMP%\log.txt C:\Program Files (x86)\Mozilla Firefox>firefox.exe
Here is the snippet for the last log file, with only 3 modules you asked for timestamp,cache2:5,nsHttp:4
(In reply to Jonathan from comment #28) > Created attachment 8485161 [details] > NSPR_LOG_MODULES=timestamp,cache2:5,nsHttp:4 > > C:\Program Files (x86)\Mozilla Firefox>set > NSPR_LOG_MODULES=timestamp,cache2:5,nsHttp:4 > > C:\Program Files (x86)\Mozilla Firefox>set NSPR_LOG_FILE=%TEMP%\log.txt > > C:\Program Files (x86)\Mozilla Firefox>firefox.exe And which URL did fail when you produced this log?
Flags: needinfo?(jonathan)
(In reply to Honza Bambas (:mayhemer) from comment #30) > And which URL did fail when you produced this log? Found out: http://hosted-images.magazine-services.net/images/image.aspx?i=cover0068116.jpg&w=89 Jonathan, I still see there is something wrong/non-standard with your Firefox, an extension involved or some non-standard setting. When I run 32.0 in a clean profile, I can see only a single request for the image (actually all images). In your log I can see 3 requests, all with VALIDATE_ALWAYS flag set which is non-standard. I also don't see any problem with the loading channels, content always loads correctly (6153 bytes). Please send me about:support page content from the same Firefox instance/profile you are testing with (there is a button to copy the content to the clipboard on that page). Ehsan, Joe, I can see this flag is set in img lib [1][2], but I don't know that code. Can you please elaborate under what conditions Gecko sets VALIDATE_ALWAYS flag on image loads? [1] http://hg.mozilla.org/mozilla-central/annotate/dddbe46f3ceb/content/base/src/nsImageLoadingContent.cpp#l644 [2] http://hg.mozilla.org/mozilla-central/annotate/2255d7d187b2/image/src/imgLoader.cpp#l1825
Flags: needinfo?(jonathan)
Flags: needinfo?(joe)
Flags: needinfo?(ehsan.akhgari)
Just now, I was able to see error here: http://www.magazine-agent.com/Readers-Digest/Magazine After only one refrehs: Here is the about:support. I don't see any extensions in the list, but maybe there is something hidden. My problem with that argument, is we can reproduce this on machines which never had FireFox... which leads me to believe, it is not Extension based. Also, the fact that if we revert to old cache: https://support.mozilla.org/en-US/questions/1013493 The problem disappears, seems to reinforce this idea. { "application": { "name": "Firefox", "version": "32.0", "userAgent": "Mozilla/5.0 (Windows NT 6.1; WOW64; rv:32.0) Gecko/20100101 Firefox/32.0", "supportURL": "https://support.mozilla.org/1/firefox/32.0/WINNT/en-US/" }, "crashes": { "submitted": [], "pending": 0 }, "modifiedPreferences": { "browser.cache.disk.capacity": 1048576, "browser.cache.disk.smart_size.first_run": false, "browser.cache.frecency_experiment": 3, "browser.places.smartBookmarksVersion": 7, "browser.sessionstore.upgradeBackup.latestBuildID": "20140825202822", "browser.startup.homepage_override.mstone": "32.0", "browser.startup.homepage_override.buildID": "20140825202822", "extensions.lastAppVersion": "32.0", "gfx.direct3d.last_used_feature_level_idx": 0, "network.cookie.prefsMigrated": true, "places.history.expiration.transient_current_max_pages": 104858, "plugin.disable_full_page_plugin_for_types": "application/pdf", "privacy.sanitize.migrateFx3Prefs": true }, "graphics": { "numTotalWindows": 1, "numAcceleratedWindows": 1, "windowLayerManagerType": "Direct3D 10", "windowLayerManagerRemote": false, "adapterDescription": "AMD Radeon HD 7570", "adapterVendorID": "0x1002", "adapterDeviceID": "0x675d", "adapterRAM": "1024", "adapterDrivers": "aticfx64 aticfx64 aticfx64 aticfx32 aticfx32 aticfx32 atiumd64 atidxx64 atidxx64 atiumdag atidxx32 atidxx32 atiumdva atiumd6a atitmm64", "driverVersion": "9.12.0.0", "driverDate": "12-19-2012", "adapterDescription2": "", "adapterVendorID2": "", "adapterDeviceID2": "", "adapterRAM2": "", "adapterDrivers2": "", "driverVersion2": "", "driverDate2": "", "isGPU2Active": false, "direct2DEnabled": true, "directWriteEnabled": true, "directWriteVersion": "6.2.9200.16571", "webglRenderer": "Google Inc. -- ANGLE (AMD Radeon HD 7570 Direct3D9Ex vs_3_0 ps_3_0)", "info": { "AzureCanvasBackend": "direct2d", "AzureSkiaAccelerated": 0, "AzureFallbackCanvasBackend": "cairo", "AzureContentBackend": "direct2d" } }, "javaScript": { "incrementalGCEnabled": true }, "accessibility": { "isActive": false, "forceDisabled": 0 }, "libraryVersions": { "NSPR": { "minVersion": "4.10.6", "version": "4.10.6" }, "NSS": { "minVersion": "3.16.4 Basic ECC", "version": "3.16.4 Basic ECC" }, "NSSUTIL": { "minVersion": "3.16.4", "version": "3.16.4" }, "NSSSSL": { "minVersion": "3.16.4 Basic ECC", "version": "3.16.4 Basic ECC" }, "NSSSMIME": { "minVersion": "3.16.4 Basic ECC", "version": "3.16.4 Basic ECC" } }, "userJS": { "exists": false }, "extensions": [], "experiments": [] }
I know nothing about imagelib. I believe Jeff might be able to help.
Flags: needinfo?(ehsan.akhgari) → needinfo?(jmuizelaar)
Flags: needinfo?(joe)
So, I double checked the log from 31 and load of the image (if that is the one, since reporter didn't correctly inform on the URL, so it can well be a guess). There is nothing wrong on the level of HTTP and caching. Moving to img lib.
Component: Networking: Cache → ImageLib
Honza Bambas, Do you want me to rerun with log, and give you url's of specific missing images, screen shot, location in markup, or what ever you need to make it easier for you??
(In reply to Jonathan from comment #35) > Honza Bambas, > Do you want me to rerun with log, and give you url's of specific missing > images, screen shot, location in markup, or what ever you need to make it > easier for you?? It would be the best, to make 100% sure it's not in the HTTP layer. Thanks!
The final request to: http://www.magazine-agent.com/Natural-History/Magazine Has this missing image: src: http://hosted-images.magazine-services.net/images/image.aspx?i=cover0068429.jpg&amp;w=89 Corresponding to the markup spot: <a id="MainContent_covSmall_repCovers_hlCover_4" class="coverbox cboxElement" href="/Cover/Natural-History/68429"> <img id="MainContent_covSmall_repCovers_imgCover_4" title="Natural History Magazine | 12/2013 Cover" class="border mar5" src="http://hosted-images.magazine-services.net/images/image.aspx?i=cover0068429.jpg&amp;w=89" alt="Natural History Magazine | 12/2013 Cover"></a>
(In reply to Jonathan from comment #37) > Created attachment 8487379 [details] > Nat_Hist_Log_Image_Screen.zip > > The final request to: > http://www.magazine-agent.com/Natural-History/Magazine > > Has this missing image: > src: > http://hosted-images.magazine-services.net/images/image.aspx?i=cover0068429. > jpg&amp;w=89 > > Corresponding to the markup spot: > <a id="MainContent_covSmall_repCovers_hlCover_4" class="coverbox > cboxElement" href="/Cover/Natural-History/68429"> > <img id="MainContent_covSmall_repCovers_imgCover_4" title="Natural History > Magazine | 12/2013 Cover" class="border mar5" > src="http://hosted-images.magazine-services.net/images/image. > aspx?i=cover0068429.jpg&amp;w=89" alt="Natural History Magazine | 12/2013 > Cover"></a> Thanks. I can see 10 requests for the missing image (?i=cover0068429.jpg&w=89) made in the log within 3 minutes. There is no error on the HTTP level with this image. I still don't follow why I can see 4 request for the same image during the page load all equipped with VALIDATE_ALWAYS flag. 1: goes from the net, loads correctly (gzipped CL=4639) 2: 3.13 sec later, VALIDATE_ALWAYS set, validates and gets 200, rewrites the entry, delivers complete content from the net 3: 1.86 sec later, same situation 4: 1.30 sec later, same situation 5: 1.44 sec later, same situation 6: ~3 minutes later, loads from cache (no VALIDATE_ALWAYS set), and is canceled from OnStartRequest call at [1] / is result of a redirect from escaped URL 7 + 8: 2.5 sec later, almost parallel, load from cache and deliver complete content 9: 3. sec later, cache entry not even attempted to open, loads from net and delivers complete content 10: 45 sec later, comes from HTTP cache (no validation) and delivers complete content There is also one request for ?i=cover0068429.jpg&amp;w=89 (escaped) that redirects to unescaped URL and results to request #6 from the list above (probably the manual load of the image to save it?) Jonathan what exactly you do to produce the log? You should just start the browser, load the page, and when it's failed, save the URL, close firefox and save the log. This one has 23MB, really huge and seems like you were not just loading the page. [1] http://hg.mozilla.org/mozilla-central/annotate/843332cc69af/image/src/imgLoader.cpp#l2212
Are people still seeing this?

reporter seems to be gone, so => incomplete

Status: UNCONFIRMED → RESOLVED
Closed: 6 years ago
Flags: needinfo?(jmuizelaar)
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: