[Bug 885912] New: zypper exec generates huge log (1.8G) even if 'no action' performed
https://bugzilla.novell.com/show_bug.cgi?id=885912 https://bugzilla.novell.com/show_bug.cgi?id=885912#c0 Summary: zypper exec generates huge log (1.8G) even if 'no action' performed Classification: openSUSE Product: openSUSE 13.1 Version: Final Platform: All OS/Version: openSUSE 13.1 Status: NEW Severity: Major Priority: P5 - None Component: libzypp AssignedTo: zypp-maintainers@forge.provo.novell.com ReportedBy: grantksupport@operamail.com QAContact: qa-bugs@suse.de Found By: --- Blocker: --- User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:31.0) Gecko/20100101 Firefox/31.0 on opensuse 13.1, i've installed rpm -qa | grep -i zypp libzypp-devel-13.10.2-19.6.x86_64 perl-zypp-0.5.17.1-8.1.x86_64 PackageKit-backend-zypp-0.8.11-2.3.1.x86_64 zypper-log-1.9.16-22.2.noarch zypper-1.9.16-22.2.x86_64 libzypp-13.10.2-19.6.x86_64 zypp-plugin-python-0.3-10.1.2.x86_64 python-zypp-0.5.17.1-8.1.x86_64 per http://doc.opensuse.org/projects/libzypp/13.1/zypp-envars.html I've set zypp env vars as 'quiet' as possible env | egrep "ZYPP|PLUGIN_DEBUG" ZYPP_ARIA2C=1 ZYPP_FULLLOG=0 PLUGIN_DEBUG=0 ZYPP_PROFILING=0 ZYPP_MEDIA_CURL_DEBUG=0 ZYPP_LIBSOLV_FULLLOG=0 clearing zypper log echo "" > /var/log/zypper.log ls -al /var/log/zypper.log -rw-r--r-- 1 root root 1 Jul 4 10:45 /var/log/zypper.log then exec'ing a single, simple `zypper up`, with NO resultant action zypper up ... Nothing to do. the generated log is 1.8 G!! ls -al /var/log/zypper.log -rw-r--r-- 1 root root 1.8G Jul 4 10:51 /var/log/zypper.log an example of its content head -n 100 /var/log/zypper.log && tail -n 100 /var/log/zypper.log 1404496161.744163 [1] <25839> main.cc(main):75 ===== Hi, me zypper 1.9.16 1404496161.744205 [1] <25839> main.cc(main):76 ===== 'zypper' 'up' ===== 1404496161.744288 [1] <25839> ZConfig.cc(_autodetectSystemArchitecture):71 Uname architecture is 'x86_64' 1404496161.744320 [1] <25839> ZConfig.cc(_autodetectTextLocale):200 Found LANG=en_US.UTF-8 1404496161.744329 [1] <25839> ZConfig.cc(_autodetectTextLocale):207 Default text locale is 'en_US' 1404496161.744346 [1] <25839> ZConfig.cc(Impl):316 libzypp: 13.10.2 built May 20 2014 12:52:07 1404496161.744387 [1] <25839> IniParser.cc(parse):69 Start parsing /etc/zypp/zypp.conf[g___] 1404496161.744407 [999] <25839> ProgressData.cc(report):80 {#1|/etc/zypp/zypp.conf}(0%) 1404496161.744462 [999] <25839> ProgressData.cc(report):80 {#1|/etc/zypp/zypp.conf}(23%) 1404496161.744484 [999] <25839> ProgressData.cc(report):80 {#1|/etc/zypp/zypp.conf}(49%) 1404496161.744507 [999] <25839> ProgressData.cc(report):80 {#1|/etc/zypp/zypp.conf}(73%) 1404496161.744525 [999] <25839> ProgressData.cc(report):80 {#1|/etc/zypp/zypp.conf}(95%) 1404496161.744535 [999] <25839> ProgressData.cc(report):80 {#1|/etc/zypp/zypp.conf}(100%) 1404496161.744543 [999] <25839> ProgressData.cc(report):80 {#1|/etc/zypp/zypp.conf}(100%) 1404496161.744549 [1] <25839> IniParser.cc(parse):119 Done parsing /etc/zypp/zypp.conf[_eF_] 1404496161.744557 [1] <25839> ProgressData.cc(report):86 {#1|/etc/zypp/zypp.conf}END 1404496161.744642 [1] <25839> ZConfig.cc(Impl):540 ZConfig singleton created. 1404496161.744652 [1] <25839> ZConfig.cc(ZConfig):669 libzypp: 13.10.2 built May 20 2014 12:52:07 1404496161.744658 [1] <25839> ZConfig.cc(ZConfig):669 libsolv: 0.4.2 1404496161.744663 [1] <25839> ZConfig.cc(ZConfig):669 zypp.conf: '/etc/zypp/zypp.conf' 1404496161.744671 [1] <25839> ZConfig.cc(ZConfig):669 TextLocale: 'en_US' (en_US) 1404496161.744677 [1] <25839> ZConfig.cc(ZConfig):669 SystemArchitecture: 'x86_64' (x86_64) 1404496161.745967 [1] <25839> Zypper.cc(Zypper):80 Zypper instance created. 1404496161.745978 [1] <25839> media.h(MediaCallbacks):181 Set media callbacks.. 1404496161.745987 [999] <25839> Zypper.cc(instance):98 Got an existing instance. 1404496161.745993 [999] <25839> Zypper.cc(instance):98 Got an existing instance. 1404496161.745999 [999] <25839> Zypper.cc(instance):98 Got an existing instance. 1404496161.746005 [999] <25839> Zypper.cc(instance):98 Got an existing instance. 1404496161.746010 [1] <25839> Zypper.cc(processGlobalOptions):364 START 1404496161.746034 [5] <25839> Measure.cc(Impl):147 START MEASURE(ReadConfig) 1404496161.746044 [1] <25839> Augeas.cc(Augeas):21 Going to read zypper config using Augeas... 1404496161.749964 [1] <25839> Augeas.cc(Augeas):102 Done reading conf files: 1404496161.749983 [1] <25839> Augeas.cc(Augeas):107 user conf read: no 1404496161.749990 [1] <25839> Augeas.cc(Augeas):108 global conf read: yes 1404496161.750010 [5] <25839> Measure.cc(elapsed):170 ELAPSED(ReadConfig) 0 (u 0.01 s 0.00 c 0.00) 1404496161.750055 [1] <25839> Augeas.cc(get):148 No match for /files/etc/zypp/zypper.conf/main/*/showAlias 1404496161.750077 [1] <25839> Augeas.cc(get):148 No match for /files/etc/zypp/zypper.conf/main/*/repoListColumns 1404496161.750097 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/solver/*/installRecommends = no 1404496161.750120 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/solver/*/forceResolutionCommands = remove 1404496161.750220 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/color/*/useColors = autodetect 1404496161.750244 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/color/*/background = light 1404496161.750264 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/color/*/result = black 1404496161.750284 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/color/*/msgStatus = grey 1404496161.750303 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/color/*/msgError = red 1404496161.750323 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/color/*/msgWarning = yellow 1404496161.750343 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/color/*/positive = green 1404496161.750375 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/color/*/negative = red 1404496161.750394 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/color/*/highlight = lightcyan 1404496161.750414 [1] <25839> Augeas.cc(get):144 Got /files/etc/zypp/zypper.conf/color/*/promptOption = grey 1404496161.750432 [1] <25839> Augeas.cc(get):148 No match for /files/etc/zypp/zypper.conf/obs/*/baseUrl 1404496161.750451 [1] <25839> Augeas.cc(get):148 No match for /files/etc/zypp/zypper.conf/obs/*/platform 1404496161.750466 [5] <25839> Measure.cc(~Impl):155 MEASURE(ReadConfig) 0 (u 0.01 s 0.00 c 0.00) [0 (u 0.00 s 0.00 c 0.00)] 1404496161.750589 [1] <25839> Zypper.cc(processGlobalOptions):472 Verbosity 1 1404496161.750596 [1] <25839> Zypper.cc(processGlobalOptions):473 Output type 1 1404496161.750619 [1] <25839> Zypper.cc(processGlobalOptions):592 repos.d dir = /etc/zypp/repos.d 1404496161.750626 [1] <25839> Zypper.cc(processGlobalOptions):593 cache dir = /var/cache/zypp 1404496161.750631 [1] <25839> Zypper.cc(processGlobalOptions):594 raw cache dir = /var/cache/zypp/raw 1404496161.750637 [1] <25839> Zypper.cc(processGlobalOptions):595 solv cache dir = /var/cache/zypp/solv 1404496161.750642 [1] <25839> Zypper.cc(processGlobalOptions):596 package cache dir = /var/cache/zypp/packages 1404496161.750648 [1] <25839> Zypper.cc(processGlobalOptions):608 Repositories enabled 1404496161.750655 [1] <25839> Zypper.cc(processGlobalOptions):790 DONE 1404496161.750661 [1] <25839> Zypper.cc(processCommandOptions):971 START 1404496161.750817 [1] <25839> Zypper.cc(processCommandOptions):2678 Done parsing options. 1404496161.750825 [1] <25839> Zypper.cc(processCommandOptions):2697 Done 1404496161.750857 [1] <25839> ZYppFactory.cc(_openLockFile):166 Open lockfile /var/run/zypp.pid 1404496161.750883 [1] <25839> ZYppFactory.cc(readLockFile):221 read: Lockfile /var/run/zypp.pid has pid 0 (our pid: 25839) 1404496161.750908 [1] <25839> ZYppFactory.cc(writeLockFile):233 write: Lockfile /var/run/zypp.pid got pid 25839 1404496161.750924 [1] <25839> ZYppFactory.cc(_closeLockFile):184 Close lockfile /var/run/zypp.pid 1404496161.750955 [1] <25839> ZYppImpl.cc(ZYppImpl):45 libzypp: 13.10.2 built May 20 2014 12:52:07 1404496161.750961 [1] <25839> ZYppImpl.cc(ZYppImpl):45 libsolv: 0.4.2 1404496161.750966 [1] <25839> ZYppImpl.cc(ZYppImpl):45 zypp.conf: '/etc/zypp/zypp.conf' 1404496161.750974 [1] <25839> ZYppImpl.cc(ZYppImpl):45 TextLocale: 'en_US' (en_US) 1404496161.750979 [1] <25839> ZYppImpl.cc(ZYppImpl):45 SystemArchitecture: 'x86_64' (x86_64) 1404496161.750985 [1] <25839> ZYppImpl.cc(ZYppImpl):46 Initializing keyring... 1404496161.751069 [1] <25839> KeyRing.cc(Impl):173 Current KeyRing::DefaultAccept: 0000000000 1404496161.751079 [1] <25839> Zypper.cc(doCommand):2799 Going to process command update 1404496161.751087 [1] <25839> Zypper.cc(doCommand):4345 Skipping interactive patches: no 1404496161.751097 [1] <25839> misc.cc(get_download_option):529 Download mode: as-needed (zconfig value) 1404496161.751106 [1] <25839> repos.cc(init_target):797 Initializing target 1404496161.751114 [1] <25839> ZYppImpl.cc(initializeTarget):96 initTarget( /) 1404496161.755462 [1] <25839> librpmDb.cc(globalInit):145 librpm init done: (_target:x86_64-linux) (_dbpath:/var/lib/rpm) 1404496161.755497 [1] <25839> RpmDb.cc(initDatabase):348 Calling initDatabase: '(/)/var/lib/rpm' 1404496161.755505 [1] <25839> librpmDb.cc(unblockAccess):341 Unblock access 1404496161.755527 [1] <25839> librpmDb.cc(DbDirInfo):519 '(/)/var/lib/rpm': 1404496161.755540 [1] <25839> librpmDb.cc(DbDirInfo):519 Dir: /var/lib/rpm{d 0755 0/0} 1404496161.755547 [1] <25839> librpmDb.cc(DbDirInfo):519 V4: /var/lib/rpm/Packages{- 0644 0/0 size 314871808} 1404496161.755590 [1] <25839> librpmDb.cc(DbDirInfo):519 V3: /var/lib/rpm/packages.rpm{[2-No such file or directory]} 1404496161.755735 [1] <25839> librpmDb.cc(DbDirInfo):519 V3ToV4: /var/lib/rpm/packages.rpm3{[2-No such file or directory]} 1404496161.755752 [1] <25839> RpmDb.cc(internal_initDatabase):471 Found rpm4 database in /var/lib/rpm{d 0755 0/0} 1404496161.755764 [1] <25839> RpmDb.cc(internal_initDatabase):488 Initial state: V4(X--)V3(---): '(/)/var/lib/rpm'[librpmDb CLOSED '(/)/var/lib/rpm'] 1404496161.755772 [1] <25839> librpmDb.cc(dbAccess):237 Set new database location: '(/)/var/lib/rpm' 1404496161.756417 [1] <25839> librpmDb.cc(D):97 DBACCESS {NULL(/)/var/lib/rpm} 1404496161.756440 [1] <25839> RpmDb.cc(internal_initDatabase):507 Access state: V4(X--)V3(---): '(/)/var/lib/rpm'[ReferenceCounted(@0x22e1240<=1){NULL(/)/var/lib/rpm}] 1404496161.762921 [1] <25839> librpmDb.cc(dbRelease):308 dbRelease: release, outstanding 0 1404496161.763257 [1] <25839> RpmDb.cc(initDatabase):429 Syncronizing keys with zypp keyring 1404496161.763273 [1] <25839> RpmDb.cc(syncTrustedKeys):965 Going to sync trusted keys... 1404496161.763570 [1] <25839> librpmDb.cc(D):97 DBACCESS {NULL(/)/var/lib/rpm} 1404496161.770837 [1] <25839> librpmDb.cc(dbRelease):308 dbRelease: release, outstanding 0 1404496161.771191 [1] <25839> RpmDb.cc(computeKeyRingSync):945 gpg-pubkey-00e006f2-52707bfd R_ 1404496291.813208 [1] <25839> PoolImpl.cc(logSat):101 watch triggered Rule #23585: 1404496291.813215 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil2-2.0~rc1-36.1.x86_64 [111915]I (w1) Install.level3748 1404496291.813222 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil2-1.9.4_bluez5-1.1.i586 [31485] (w2) Conflict.level1 1404496291.813227 [1] <25839> PoolImpl.cc(logSat):101 next rules: 23586 158889 1404496291.813233 [1] <25839> PoolImpl.cc(logSat):101 watch triggered Rule #23586: 1404496291.813240 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil2-2.0~rc1-36.1.x86_64 [111915]I (w1) Install.level3748 1404496291.813246 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil2-2.0~rc1-36.1.x86_64 [17077] (w2) 1404496291.813251 [1] <25839> PoolImpl.cc(logSat):101 next rules: 23587 158890 1404496291.813257 [1] <25839> PoolImpl.cc(logSat):101 unit Rule #23586: 1404496291.813264 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil2-2.0~rc1-36.1.x86_64 [111915]I (w1) Install.level3748 1404496291.813270 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil2-2.0~rc1-36.1.x86_64 [17077] (w2) 1404496291.813276 [1] <25839> PoolImpl.cc(logSat):101 next rules: 23587 158890 1404496291.813281 [1] <25839> PoolImpl.cc(logSat):101 -> decided to conflict libbluedevil2-2.0~rc1-36.1.x86_64 1404496291.813287 [1] <25839> PoolImpl.cc(logSat):101 watch triggered Rule #23587: 1404496291.813294 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil2-2.0~rc1-36.1.x86_64 [111915]I (w1) Install.level3748 1404496291.813301 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil2-2.0~rc1-36.1.i586 [17076] (w2) Conflict.level1 1404496291.813306 [1] <25839> PoolImpl.cc(logSat):101 next rules: 23592 158891 1404496291.813312 [1] <25839> PoolImpl.cc(logSat):101 watch triggered Rule #23592: 1404496291.813319 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil2-2.0~rc1-36.1.x86_64 [111915]I (w1) Install.level3748 1404496291.813326 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.4.1.i586 [29775] Conflict.level1 1404496291.813332 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.4.1.i686 [38580] Conflict.level1 1404496291.813339 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.4.1.x86_64 [50034] Conflict.level2 1404496291.813345 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.11.1.i586 [71998] Conflict.level1 1404496291.813351 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.15.1.i586 [71999] Conflict.level1 1404496291.813358 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.7.1.i586 [72000] Conflict.level1 1404496291.813364 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.15.2.i686 [72001] Conflict.level1 1404496291.813374 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.7.1.i686 [72002] Conflict.level1 1404496291.813380 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.11.1.x86_64 [72008] Conflict.level2 1404496291.813387 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.15.1.x86_64 [72009] Conflict.level2 1404496291.813393 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.7.1.x86_64 [72010] Conflict.level2 1404496291.813400 [1] <25839> PoolImpl.cc(logSat):101 glibc-2.18-4.15.1.x86_64 [111207]I (w2) Install.level2 1404496291.813406 [1] <25839> PoolImpl.cc(logSat):101 next rules: 0 23611 1404496291.813411 [1] <25839> PoolImpl.cc(logSat):101 propagate for decision -52034 level 3748 1404496291.813417 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil2-1.9.4_bluez5-1.1.x86_64 [52034] Conflict.level3748 1404496291.813423 [1] <25839> PoolImpl.cc(logSat):101 watch triggered Rule #158900: 1404496291.813430 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil-devel-1.9.4_bluez5-1.1.x86_64 [52033] (w1) Conflict.level3747 1404496291.813437 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-1.9.4_bluez5-1.1.i586 [31485] Conflict.level1 1404496291.813444 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-1.9.4_bluez5-1.1.x86_64 [52034] (w2) Conflict.level3748 1404496291.813449 [1] <25839> PoolImpl.cc(logSat):101 next rules: 158901 0 1404496291.813454 [1] <25839> PoolImpl.cc(logSat):101 propagate for decision -17077 level 3748 1404496291.813460 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil2-2.0~rc1-36.1.x86_64 [17077] Conflict.level3748 1404496291.813466 [1] <25839> PoolImpl.cc(logSat):101 watch triggered Rule #35002: 1404496291.813473 [1] <25839> PoolImpl.cc(logSat):101 !bluedevil-2.0~rc1-16.6.x86_64 [110865]I Install.level2727 1404496291.813480 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-2.0~rc1-36.1.x86_64 [17077] (w1) Conflict.level3748 1404496291.813487 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-1.9.4_bluez5-1.1.x86_64 [52034] Conflict.level3748 1404496291.813494 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-2.0~rc1-36.1.x86_64 [111915]I (w2) Install.level3748 1404496291.813499 [1] <25839> PoolImpl.cc(logSat):101 next rules: 220526 23602 1404496291.813505 [1] <25839> PoolImpl.cc(logSat):101 watch triggered Rule #220526: 1404496291.813511 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil-devel-2.0~rc1-36.1.x86_64 [17075] (w1) Conflict.level3747 1404496291.813518 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-2.0~rc1-36.1.i586 [17076] Conflict.level1 1404496291.813525 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-2.0~rc1-36.1.x86_64 [17077] (w2) Conflict.level3748 1404496291.813532 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-2.0~rc1-36.1.x86_64 [111915]I Install.level3748 1404496291.813537 [1] <25839> PoolImpl.cc(logSat):101 next rules: 220527 23602 1404496291.813543 [1] <25839> PoolImpl.cc(logSat):101 watch triggered Rule #23602: 1404496291.813549 [1] <25839> PoolImpl.cc(logSat):101 !libbluedevil-devel-2.0~rc1-36.1.x86_64 [111913]I Install.level3747 1404496291.813556 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-2.0~rc1-36.1.i586 [17076] Conflict.level1 1404496291.813563 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-2.0~rc1-36.1.x86_64 [17077] (w2) Conflict.level3748 1404496291.813570 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-2.0~rc1-36.1.x86_64 [111915]I (w1) Install.level3748 1404496291.813575 [1] <25839> PoolImpl.cc(logSat):101 next rules: 235958 239759 1404496291.813581 [1] <25839> PoolImpl.cc(logSat):101 watch triggered Rule #239759: 1404496291.813588 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-2.0~rc1-36.1.x86_64 [111915]I (w1) Install.level3748 1404496291.813595 [1] <25839> PoolImpl.cc(logSat):101 libbluedevil2-2.0~rc1-36.1.x86_64 [17077] (w2) Conflict.level3748 1404496291.813608 [1] <25839> PoolImpl.cc(logSat):101 next rules: 0 0 1404496291.813614 [1] <25839> PoolImpl.cc(logSat):101 ----- propagate end----- 1404496291.813624 [1] <25839> PoolImpl.cc(logSat):101 deciding unresolved rules 1404496291.816265 [1] <25839> PoolImpl.cc(logSat):99 solver statistics: 4 learned rules, 0 unsolvable, 0 minimization steps 1404496291.816283 [1] <25839> PoolImpl.cc(logSat):99 done solving. 1404496291.816288 [1] <25839> PoolImpl.cc(logSat):99 1404496291.816296 [1] <25839> PoolImpl.cc(logSat):99 solver took 26206 ms 1404496291.816302 [1] <25839> PoolImpl.cc(logSat):99 final solver statistics: 0 problems, 4 learned rules, 0 unsolvable 1404496291.816309 [1] <25839> PoolImpl.cc(logSat):99 solver_solve took 58487 ms 1404496291.816315 [1] <25839> SATResolver.cc(doUpdate):880 ....Solver end 1404496291.816484 [1] <25839> SATResolver.cc(doUpdate):931 SATResolver::doUpdate() done 1404496291.816497 [1] <25839> solve-commit.cc(solve_and_commit):556 got solution, showing summary 1404496291.816596 [1] <25839> Summary.cc(readPool):102 Pool contains 111198 items. 1404496291.816616 [1] <25839> Summary.cc(readPool):103 Install summary: 1404496291.836228 [1] <25839> Summary.cc(readPool):298 package update candidates: 259 1404496291.836261 [1] <25839> Summary.cc(readPool):299 to be actually updated: 0 1404496291.836280 [1] <25839> Summary.cc(readPool):298 product update candidates: 0 1404496291.836286 [1] <25839> Summary.cc(readPool):299 to be actually updated: 0 1404496291.850094 [999] <25839> Zypper.cc(instance):98 Got an existing instance. 1404496291.851723 [999] <25839> Zypper.cc(instance):98 Got an existing instance. 1404496291.851741 [999] <25839> Zypper.cc(instance):98 Got an existing instance. 1404496291.851748 [999] <25839> Zypper.cc(instance):98 Got an existing instance. 1404496291.851773 [1] <25839> Zypper.cc(cleanup):5030 START 1404496291.851812 [1] <25839> main.cc(~Bye):60 ===== Exiting main() ===== 1404496291.851854 [1] <25839> PathInfo.cc(recursive_rmdir):430 recursive_rmdir /var/tmp/zypp.buzCWl 1404496291.852135 [1] <25839> TmpPath.cc(~Impl):78 TmpPath cleaned up /var/tmp/zypp.buzCWl{d 0700 0/0} 1404496291.852152 [1] <25839> ZYppFactory.cc(_openLockFile):166 Open lockfile /var/run/zypp.pid 1404496291.852170 [1] <25839> ZYppFactory.cc(~ZYppGlobalLock):115 Cleanned lock file. (25839) 1404496291.852178 [1] <25839> ZYppFactory.cc(_closeLockFile):184 Close lockfile /var/run/zypp.pid 1404496291.852294 [1] <25839> Zypper.cc(~Zypper):87 Zypper instance destroyed. Bye! 1404496291.852334 [1] <25839> PathInfo.cc(recursive_rmdir):430 recursive_rmdir /var/tmp/TmpDir.hM7Uhb 1404496291.852392 [1] <25839> TmpPath.cc(~Impl):78 TmpPath cleaned up /var/tmp/TmpDir.hM7Uhb{d 0700 0/0} 1404496291.853528 [1] <25839> RpmDb.cc(closeDatabase):734 Calling closeDatabase: RpmDb[V4(X--)V3(---): '(/)/var/lib/rpm'] 1404496291.853546 [1] <25839> librpmDb.cc(blockAccess):328 Block access 1404496291.853553 [1] <25839> RpmDb.cc(closeDatabase):765 closeDatabase: RpmDb[NO_INIT] 1404496291.853558 [1] <25839> TargetImpl.cc(~TargetImpl):760 Targets closed 1404496291.853570 [1] <25839> RpmDb.cc(~RpmDb):268 ~RpmDb() 1404496291.853576 [1] <25839> RpmDb.cc(~RpmDb):271 ~RpmDb() end this level of logging as default if completely excessive, and on small resource systems, can completely use up available disk space. Reproducible: Always Steps to Reproduce: 1. 2. 3. -- Configure bugmail: https://bugzilla.novell.com/userprefs.cgi?tab=email ------- You are receiving this mail because: ------- You are on the CC list for the bug.
https://bugzilla.novell.com/show_bug.cgi?id=885912 https://bugzilla.novell.com/show_bug.cgi?id=885912#c1 --- Comment #1 from grant k <grantksupport@operamail.com> 2014-07-04 18:18:35 UTC --- for reference, zypper se -s -v | grep -i zypp | grep ^i i | PackageKit-backend-zypp | package | 0.8.11-2.3.1 | x86_64 | OS13-oss i | libzypp | package | 13.10.2-19.6 | x86_64 | OS13-update i | libzypp-devel | package | 13.10.2-19.6 | x86_64 | OS13-update i | perl-zypp | package | 0.5.17.1-8.1 | x86_64 | OS13-update i | python-zypp | package | 0.5.17.1-8.1 | x86_64 | OS13-update i | zypp-plugin-python | package | 0.3-10.1.2 | x86_64 | OS13-oss i | zypper | package | 1.9.16-22.2 | x86_64 | OS13-update i | zypper-log | package | 1.9.16-22.2 | noarch | OS13-update zypper lr OS13-oss OS13-update | grep ^URI URI : http://download.opensuse.org/distribution/13.1/repo/oss/ URI : http://download.opensuse.org/update/13.1 -- Configure bugmail: https://bugzilla.novell.com/userprefs.cgi?tab=email ------- You are receiving this mail because: ------- You are on the CC list for the bug.
https://bugzilla.novell.com/show_bug.cgi?id=885912 https://bugzilla.novell.com/show_bug.cgi?id=885912#c2 --- Comment #2 from grant k <grantksupport@operamail.com> 2014-07-04 22:20:45 UTC --- /bin/cp -af /var/log/zypper.log /var/log/zypper.log.GOOD && echo "" > /var/log/zypper.log && ls -al /var/log/zypper.log && zypper clean --all && zypper ref && ls -al /var/log/zypper.log && /bin/cp -af /var/log/zypper.log.GOOD /var/log/zypper.log testing this, /bin/cp -af /var/log/zypper.log /var/log/zypper.log.GOOD \ && echo "" > /var/log/zypper.log \ && ls -al /var/log/zypper.log \ && zypper up --dry-run \ && ls -al /var/log/zypper.log \ && /bin/mv -f /var/log/zypper.log.GOOD /var/log/zypper.log on 6 different machines returns, for the last `ls`, -rw-r--r-- 1 root root 1.8G Jul 4 15:15 /var/log/zypper.log -rw-r----- 1 root root 1.9G Jul 4 15:18 /var/log/zypper.log -rw-r--r-- 1 root root 815M Jul 4 15:15 /var/log/zypper.log -rw-r--r-- 1 root root 1.3G Jul 4 15:16 /var/log/zypper.log -rw-r--r-- 1 root root 540M Jul 4 15:15 /var/log/zypper.log -rw-r--r-- 1 root root 563M Jul 4 15:15 /var/log/zypper.log -- Configure bugmail: https://bugzilla.novell.com/userprefs.cgi?tab=email ------- You are receiving this mail because: ------- You are on the CC list for the bug.
https://bugzilla.novell.com/show_bug.cgi?id=885912 https://bugzilla.novell.com/show_bug.cgi?id=885912#c3 --- Comment #3 from grant k <grantksupport@operamail.com> 2014-07-04 23:01:27 UTC --- testing this, setting the libzypp ENV vars = 0 export \ ZYPP_FULLLOG=0 \ ZYPP_LIBSOLV_FULLLOG=0 \ ZYPP_LIBSAT_FULLLOG=0 \ ZYPP_MEDIA_CURL_DEBUG=0 \ PLUGIN_DEBUG=0 \ ZYPP_PROFILING=0 echo "" > /var/log/zypper.log /bin/cp -af /var/log/zypper.log /var/log/zypper.log.GOOD \ && echo "" > /var/log/zypper.log \ && zypper up --dry-run \ && ls -al /var/log/zypper.log \ && /bin/mv -f /var/log/zypper.log.GOOD /var/log/zypper.log results in 'huge' logs, -rw-r--r-- 1 root root 1.8G Jul 4 16:00 /var/log/zypper.log otoh, UNsetting the vars, unset \ ZYPP_FULLLOG \ ZYPP_LIBSOLV_FULLLOG \ ZYPP_LIBSAT_FULLLOG \ ZYPP_MEDIA_CURL_DEBUG \ PLUGIN_DEBUG ZYPP_PROFILING echo "" > /var/log/zypper.log /bin/cp -af /var/log/zypper.log /var/log/zypper.log.GOOD \ && echo "" > /var/log/zypper.log \ && zypper up --dry-run \ && ls -al /var/log/zypper.log \ && /bin/mv -f /var/log/zypper.log.GOOD /var/log/zypper.log results in much more reasonably sized logs, -rw-r--r-- 1 root root 1.3M Jul 4 16:00 /var/log/zypper.log -- Configure bugmail: https://bugzilla.novell.com/userprefs.cgi?tab=email ------- You are receiving this mail because: ------- You are on the CC list for the bug.
https://bugzilla.novell.com/show_bug.cgi?id=885912 https://bugzilla.novell.com/show_bug.cgi?id=885912#c4 --- Comment #4 from grant k <grantksupport@operamail.com> 2014-07-04 23:02:23 UTC --- those echo "" > /var/log/zypper.log are not necessary, of course -- Configure bugmail: https://bugzilla.novell.com/userprefs.cgi?tab=email ------- You are receiving this mail because: ------- You are on the CC list for the bug.
https://bugzilla.novell.com/show_bug.cgi?id=885912 https://bugzilla.novell.com/show_bug.cgi?id=885912#c5 grant k <grantksupport@operamail.com> changed: What |Removed |Added ---------------------------------------------------------------------------- Status|NEW |CLOSED Resolution| |INVALID --- Comment #5 from grant k <grantksupport@operamail.com> 2014-07-04 23:04:39 UTC --- pebkac. @ http://doc.opensuse.org/projects/libzypp/13.1/zypp-envars.html "Note that for Boolean variables we usually test whether the variable exists and not for the assigned value." -- Configure bugmail: https://bugzilla.novell.com/userprefs.cgi?tab=email ------- You are receiving this mail because: ------- You are on the CC list for the bug.
https://bugzilla.novell.com/show_bug.cgi?id=885912 https://bugzilla.novell.com/show_bug.cgi?id=885912#c6 --- Comment #6 from Michael Andres <ma@suse.com> 2014-07-05 10:31:39 CEST --- JFYI: I've set zypp env vars as 'quiet' as possible env | egrep "ZYPP|PLUGIN_DEBUG" ZYPP_ARIA2C=1 Just in case you intentionally wanted to switch on the ARIA2C backend. It's dropped in opensuse-13.1 (since libzypp-13.2). This variable has no effect. -- Configure bugmail: https://bugzilla.novell.com/userprefs.cgi?tab=email ------- You are receiving this mail because: ------- You are on the CC list for the bug.
participants (1)
-
bugzilla_noreply＠novell.com