Re: 6.5.5 much slower than 6.3.6
Arkadiy Kulev <[email protected]> Wed, 23 Sep 2009 23:58:04 +0400
| Newsgroups | gmane.comp.video.image-magick.bugs |
|---|---|
| Message-ID | <[email protected]> |
Hello duc, attached. Wednesday, September 23, 2009, 11:48:11 PM, you wrote: >> I've noticed, that the 6.5.5 trace contains alot of "getpid" calls, > As previously mentioned that should not be a problem. The getpid() method > has nearly zero overhead. We might be able to discern something from the > debugging log. Add -debug all to your command line and forward the logs > (6.3.6 and 6.5.5) to this e-mail address. Arkadiy Kulev mailto:[email protected] +7 495 5070602 Moscow, Russia _______________________________________________ Magick-bugs mailing list [email protected] http://studio.imagemagick.org/mailman/listinfo/magick-bugs
magick-debug-636.txt
(text/plain, 5.8 KB)
convert -debug all t1.jpg -quality 75 t2.jpg
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Configure convert[92846]: utility.c/unknown/808/Configure
Command line: convert {-debug} {all} {t1.jpg} {-quality} {75} {t2.jpg}
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Blob convert[92846]: blob.c/unknown/2320/Blob
read 4096 magic header bytes
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Cache convert[92846]: cache.c/unknown/2161/Cache
destroy
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "/usr/local/lib/ImageMagick/config/magic.xml"
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "/usr/local/share/ImageMagick/config/magic.xml"
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "/usr/local/share/doc/ImageMagick-6.3.6/magic.xml"
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "/usr/local/share/ImageMagick-6.3.6/magic.xml"
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "/root/.magick/magic.xml"
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "magic.xml"
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Configure convert[92846]: magic.c/unknown/678/Configure
Loading magic map "/usr/local/share/ImageMagick/config/magic.xml" ...
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Blob convert[92846]: blob.c/unknown/2320/Blob
read 4096 magic header bytes
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Coder convert[92846]: jpeg.c/unknown/819/Coder
Interlace: nonprogressive
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Coder convert[92846]: jpeg.c/unknown/821/Coder
Data precision: 8
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Coder convert[92846]: jpeg.c/unknown/823/Coder
Geometry: 1978x2498
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Coder convert[92846]: jpeg.c/unknown/903/Coder
Quality: 97
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Coder convert[92846]: jpeg.c/unknown/996/Coder
Colorspace: RGB
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Coder convert[92846]: jpeg.c/unknown/1023/Coder
Sampling Factors: 2x2,1x1,1x1
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Resource convert[92846]: resource.c/unknown/216/Resource
Area: 37.6972mb/37.6972mb/6.96875gb
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Resource convert[92846]: resource.c/unknown/216/Resource
Memory: 37.6972mb/37.6972mb/5.22656gb
2009-09-23T23:55:56+04:00 0:01 0.000u 6.3.6 Cache convert[92846]: cache.c/unknown/3524/Cache
open t1.jpg[0] (anonymous memory, 1978x2498 37.6972mb)
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "/usr/local/lib/ImageMagick/config/delegates.xml"
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "/usr/local/share/ImageMagick/config/delegates.xml"
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "/usr/local/share/doc/ImageMagick-6.3.6/delegates.xml"
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "/usr/local/share/ImageMagick-6.3.6/delegates.xml"
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "/root/.magick/delegates.xml"
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Configure convert[92846]: configure.c/unknown/482/Configure
Searching for configure file: "delegates.xml"
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Configure convert[92846]: delegate.c/unknown/1153/Configure
Loading delegate map "/usr/local/lib/ImageMagick/config/delegates.xml" ...
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Coder convert[92846]: jpeg.c/unknown/1661/Coder
Image resolution: 72,72
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Resource convert[92846]: resource.c/unknown/216/Resource
Memory: 37.6972mb/75.3943mb/5.22656gb
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Resource convert[92846]: resource.c/unknown/824/Resource
Memory: 37.6972mb/37.6972mb/5.22656gb
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Coder convert[92846]: jpeg.c/unknown/1707/Coder
Interlace: non-progressive
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Coder convert[92846]: jpeg.c/unknown/1722/Coder
Quality: 75
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Coder convert[92846]: jpeg.c/unknown/1762/Coder
Input sampling-factors=2x2,1x1,1x1
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Coder convert[92846]: jpeg.c/unknown/1826/Coder
Storage class: DirectClass
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Coder convert[92846]: jpeg.c/unknown/1828/Coder
Depth: 8
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Coder convert[92846]: jpeg.c/unknown/1834/Coder
Number of colors: unspecified
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Coder convert[92846]: jpeg.c/unknown/1836/Coder
JPEG data precision: 8
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Coder convert[92846]: jpeg.c/unknown/1890/Coder
Image colorspace is RGB
2009-09-23T23:55:57+04:00 0:02 0.328u 6.3.6 Coder convert[92846]: jpeg.c/unknown/1892/Coder
Sampling factors: 2x2,1x1,1x1
2009-09-23T23:55:57+04:00 0:02 0.703u 6.3.6 Resource convert[92846]: resource.c/unknown/824/Resource
Memory: 37.6972mb/0b/5.22656gb
2009-09-23T23:55:57+04:00 0:02 0.703u 6.3.6 Cache convert[92846]: cache.c/unknown/2161/Cache
destroy t1.jpg[0]
magick-debug-655.txt
(text/plain, 7 KB)
convert -debug all t1.jpg -quality 75 t2.jpg
2009-09-23T23:55:18+04:00 0:01 0.000u 6.5.5 Configure convert[77272]: utility.c/ExpandFilenames/866/Configure
Command line: convert {-debug} {all} {t1.jpg} {-quality} {75} {t2.jpg}
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Policy convert[77272]: policy.c/IsRightsAuthorized/520/Policy
Domain: Path; rights=Read; pattern="t1.jpg" ...
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Blob convert[77272]: blob.c/OpenBlob/2407/Blob
read 3 magic header bytes
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Cache convert[77272]: cache.c/DestroyPixelCache/1504/Cache
destroy
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "/usr/local/share/ImageMagick-6.5.5/config/magic.xml"
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "/usr/local/lib/ImageMagick-6.5.5/config/magic.xml"
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "/usr/local/share/doc/ImageMagick-6.5.5/magic.xml"
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "/usr/local/share/ImageMagick-6.5.5/magic.xml"
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "/root/.magick/magic.xml"
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "magic.xml"
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Configure convert[77272]: magic.c/LoadMagicList/682/Configure
Loading magic configure file "/usr/local/share/ImageMagick-6.5.5/config/magic.xml" ...
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Policy convert[77272]: policy.c/IsRightsAuthorized/520/Policy
Domain: Coder; rights=Read; pattern="JPEG" ...
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Policy convert[77272]: policy.c/IsRightsAuthorized/520/Policy
Domain: Path; rights=Read; pattern="t1.jpg" ...
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Blob convert[77272]: blob.c/OpenBlob/2407/Blob
read 3 magic header bytes
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Coder convert[77272]: jpeg.c/ReadJPEGImage/1037/Coder
Interlace: nonprogressive
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Coder convert[77272]: jpeg.c/ReadJPEGImage/1039/Coder
Data precision: 8
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Coder convert[77272]: jpeg.c/ReadJPEGImage/1041/Coder
Geometry: 1978x2498
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Coder convert[77272]: jpeg.c/JPEGSetImageQuality/717/Coder
Quality: 97
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Coder convert[77272]: jpeg.c/JPEGSetImageSamplingFactor/814/Coder
Colorspace: RGB
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Coder convert[77272]: jpeg.c/JPEGSetImageSamplingFactor/841/Coder
Sampling Factors: 2x2,1x1,1x1
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Resource convert[77272]: resource.c/AcquireMagickResource/257/Resource
Area: 37.7mb/37.7mb/6.9703gb
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Resource convert[77272]: resource.c/AcquireMagickResource/257/Resource
Memory: 37.7mb/37.7mb/5.2278gb
2009-09-23T23:55:18+04:00 0:01 0.016u 6.5.5 Cache convert[77272]: cache.c/OpenPixelCache/3950/Cache
open t1.jpg[0] (heap memory, 1978x2498 37.7mb)
2009-09-23T23:55:18+04:00 0:02 0.492u 6.5.5 Policy convert[77272]: policy.c/IsRightsAuthorized/520/Policy
Domain: Coder; rights=Write; pattern="JPG" ...
2009-09-23T23:55:18+04:00 0:02 0.492u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "/usr/local/share/ImageMagick-6.5.5/config/delegates.xml"
2009-09-23T23:55:18+04:00 0:02 0.492u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "/usr/local/lib/ImageMagick-6.5.5/config/delegates.xml"
2009-09-23T23:55:18+04:00 0:02 0.492u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "/usr/local/share/doc/ImageMagick-6.5.5/delegates.xml"
2009-09-23T23:55:18+04:00 0:02 0.492u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "/usr/local/share/ImageMagick-6.5.5/delegates.xml"
2009-09-23T23:55:18+04:00 0:02 0.492u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "/root/.magick/delegates.xml"
2009-09-23T23:55:18+04:00 0:02 0.492u 6.5.5 Configure convert[77272]: configure.c/GetConfigureOptions/528/Configure
Searching for configure file: "delegates.xml"
2009-09-23T23:55:18+04:00 0:02 0.492u 6.5.5 Configure convert[77272]: delegate.c/LoadDelegateList/1194/Configure
Loading delegate configuration file "/usr/local/lib/ImageMagick-6.5.5/config/delegates.xml" ...
2009-09-23T23:55:18+04:00 0:02 0.500u 6.5.5 Policy convert[77272]: policy.c/IsRightsAuthorized/520/Policy
Domain: Path; rights=Write; pattern="t2.jpg" ...
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Coder convert[77272]: jpeg.c/WriteJPEGImage/1694/Coder
Image resolution: 72,72
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Resource convert[77272]: resource.c/AcquireMagickResource/257/Resource
Memory: 14.14mb/51.83mb/5.2278gb
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Resource convert[77272]: resource.c/RelinquishMagickResource/957/Resource
Memory: 14.14mb/37.7mb/5.2278gb
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Coder convert[77272]: jpeg.c/WriteJPEGImage/1776/Coder
Interlace: non-progressive
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Coder convert[77272]: jpeg.c/WriteJPEGImage/1791/Coder
Quality: 75
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Coder convert[77272]: jpeg.c/WriteJPEGImage/1831/Coder
Input sampling-factors=2x2,1x1,1x1
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Coder convert[77272]: jpeg.c/WriteJPEGImage/1895/Coder
Storage class: DirectClass
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Coder convert[77272]: jpeg.c/WriteJPEGImage/1897/Coder
Depth: 8
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Coder convert[77272]: jpeg.c/WriteJPEGImage/1903/Coder
Number of colors: unspecified
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Coder convert[77272]: jpeg.c/WriteJPEGImage/1905/Coder
JPEG data precision: 8
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Coder convert[77272]: jpeg.c/WriteJPEGImage/1959/Coder
Image colorspace is RGB
2009-09-23T23:55:19+04:00 0:02 0.500u 6.5.5 Coder convert[77272]: jpeg.c/WriteJPEGImage/1961/Coder
Sampling factors: 2x2,1x1,1x1
2009-09-23T23:55:20+04:00 0:04 1.031u 6.5.5 Resource convert[77272]: resource.c/RelinquishMagickResource/957/Resource
Memory: 37.7mb/0b/5.2278gb
2009-09-23T23:55:20+04:00 0:04 1.031u 6.5.5 Cache convert[77272]: cache.c/DestroyPixelCache/1504/Cache
destroy t1.jpg[0]