From 0c97e80295a529aa0b7aa285354f2fab4777b38e 2014-07-01 09:18:22 From: tripun Date: 2014-07-01 09:18:22 Subject: [PATCH] more debug logs --- diff --git a/rounding/bug_reports/debug_calc_post.txt b/rounding/bug_reports/debug_calc_post.txt new file mode 100644 index 0000000000000000000000000000000000000000..1c34f712b143d953bd81ed3ca1d9e85ce30883d7 --- /dev/null +++ b/rounding/bug_reports/debug_calc_post.txt @@ -0,0 +1,134 @@ +D 1000.00 EUR +commodity EUR + precision 2 + default + +2012-01-01 * + A 2 AAA @ 10.122 EUR + A 1 BBB @ 20.123 EUR + C + + +-V bal --debug amount.parse + + + 0ms [INFO] Ledger starting + 3ms [DEBUG] Rational parsed = 1 + 3ms [DEBUG] amount.cc: parse + 3ms [DEBUG] amount.cc: parse + 4ms [DEBUG] Rational parsed = 1 + 4ms [DEBUG] amount.cc: parse + 4ms [DEBUG] amount.cc: parse + 5ms [INFO] Parsing file "test.txt" + 5ms [DEBUG] Rational parsed = 1000 + 6ms [DEBUG] amount.cc: parse + 6ms [DEBUG] precision directive called 2 + 7ms [DEBUG] amount.cc: parse + 7ms [DEBUG] Rational parsed = 5061/500 + 7ms [DEBUG] amount.cc: parse + 7ms [DEBUG] amount.cc: parse + 7ms [DEBUG] Rational parsed = 20123/1000 + 8ms [DEBUG] amount.cc: parse + 8ms [DEBUG] amount.cc:print + 8ms [DEBUG] amount.cc:in place round commodity EUR value 20.24 + 9ms [DEBUG] amount.cc:print + 9ms [DEBUG] amount.cc:in place round commodity EUR value 20.12 + 9ms [DEBUG] amount.cc:print + 9ms [DEBUG] amount.cc:+= value before 20.24 + 9ms [DEBUG] amount.cc:print + 10ms [DEBUG] amount.cc:print + 10ms [DEBUG] amount.cc:+= value after 40.36 2nd amt 20.12 + 10ms [DEBUG] amount.cc: is_zero + 11ms [DEBUG] amount.cc: is_zero + 12ms [INFO] Read journal file (6ms) + 12ms [INFO] Found 1 transactions + 12ms [DEBUG] amount.cc: parse + 13ms [DEBUG] amount.cc: parse + 14ms [DEBUG] post.cc:get_amount wrapper in lookup + 15ms [DEBUG] post.cc:get_amount + 15ms [DEBUG] filters.cc:calc_posts + 15ms [DEBUG] post.cc:get_amount + 15ms [DEBUG] filters.cc:calc_posts + 15ms [DEBUG] post.cc:get_amount + 18ms [DEBUG] account.cc: total + 19ms [DEBUG] amount.cc:print + 20ms [DEBUG] amount.cc:in place round commodity EUR value 20.24 + 20ms [DEBUG] amount.cc:print + 20ms [DEBUG] amount.cc:in place round commodity EUR value 20.12 + 20ms [DEBUG] amount.cc:print + 20ms [DEBUG] balance.cc: amount added, value before 20.24 + 21ms [DEBUG] amount.cc:print + 21ms [DEBUG] amount.cc:+= value before 20.24 + 21ms [DEBUG] amount.cc:print + 21ms [DEBUG] amount.cc:print + 21ms [DEBUG] amount.cc:+= value after 40.36 2nd amt 20.12 + 21ms [DEBUG] amount.cc:print + 21ms [DEBUG] balance.cc: amount added, value after 40.36 + 22ms [DEBUG] amount.cc: is_zero + 22ms [DEBUG] account.cc: total + 23ms [DEBUG] amount.cc: is_zero + 23ms [DEBUG] format.cc:real_calc formatting + 24ms [DEBUG] amount.cc:print + 24ms [DEBUG] amount.cc:+= value before 20 + 24ms [DEBUG] amount.cc:print + 25ms [DEBUG] amount.cc:print + 25ms [DEBUG] amount.cc:+= value after 20 2nd amt 0 + 25ms [DEBUG] amount.cc:print + 26ms [DEBUG] amount.cc:in place round commodity EUR value 20.24 + 26ms [DEBUG] amount.cc:print + 26ms [DEBUG] amount.cc:in place round commodity EUR value 20.12 + 27ms [DEBUG] amount.cc:print + 27ms [DEBUG] balance.cc: amount added, value before 20.24 + 27ms [DEBUG] amount.cc:print + 27ms [DEBUG] amount.cc:+= value before 20.24 + 27ms [DEBUG] amount.cc:print + 27ms [DEBUG] amount.cc:print + 27ms [DEBUG] amount.cc:+= value after 40.36 2nd amt 20.12 + 28ms [DEBUG] amount.cc:print + 28ms [DEBUG] balance.cc: amount added, value after 40.36 + 28ms [DEBUG] amount.cc: is_zero + 28ms [DEBUG] amount.cc:print + 40.36 EUR A + 30ms [DEBUG] format.cc:real_calc formatting + 31ms [DEBUG] amount.cc:print + 31ms [DEBUG] amount.cc:+= value before 20 + 31ms [DEBUG] amount.cc:print + 31ms [DEBUG] amount.cc:print + 31ms [DEBUG] amount.cc:+= value after 20 2nd amt 0 + 32ms [DEBUG] amount.cc: is_zero + 32ms [DEBUG] amount.cc: is_zero + 33ms [DEBUG] amount.cc:print + -40.36 EUR C + 33ms [DEBUG] format.cc:real_calc formatting +-------------------- + 34ms [DEBUG] format.cc:real_calc formatting + 35ms [DEBUG] amount.cc:print + 35ms [DEBUG] amount.cc:+= value before 20 + 35ms [DEBUG] amount.cc:print + 35ms [DEBUG] amount.cc:print + 36ms [DEBUG] amount.cc:+= value after 20 2nd amt 0 + 36ms [DEBUG] amount.cc:print + 37ms [DEBUG] amount.cc:in place round commodity EUR value 20.24 + 37ms [DEBUG] amount.cc:print + 37ms [DEBUG] balance.cc: amount added, value before -40.36 + 37ms [DEBUG] amount.cc:print + 37ms [DEBUG] amount.cc:+= value before -40.36 + 37ms [DEBUG] amount.cc:print + 37ms [DEBUG] amount.cc:print + 38ms [DEBUG] amount.cc:+= value after -20.12 2nd amt 20.24 + 38ms [DEBUG] amount.cc:print + 38ms [DEBUG] balance.cc: amount added, value after -20.12 + 38ms [DEBUG] amount.cc:print + 39ms [DEBUG] amount.cc:in place round commodity EUR value 20.12 + 39ms [DEBUG] amount.cc:print + 39ms [DEBUG] balance.cc: amount added, value before -20.12 + 39ms [DEBUG] amount.cc:print + 39ms [DEBUG] amount.cc:+= value before -20.12 + 39ms [DEBUG] amount.cc:print + 39ms [DEBUG] amount.cc:print + 40ms [DEBUG] amount.cc:+= value after 0 2nd amt 20.12 + 40ms [DEBUG] amount.cc:print + 40ms [DEBUG] balance.cc: amount added, value after 0 + 0 + 40ms [INFO] Finished executing command (26ms) + 41ms [INFO] Ledger ended diff --git a/rounding/bug_reports/initialresults.txt b/rounding/bug_reports/initialresults.txt index bb8608818fa83fb242210c1d886193c5164c07d2..06555f6425db468190a2515d31ac93fff49270d2 100644 --- a/rounding/bug_reports/initialresults.txt +++ b/rounding/bug_reports/initialresults.txt @@ -1,100 +1,100 @@ 0ms [INFO] Ledger starting - 4ms [DEBUG] Rational parsed = 1 - 5ms [DEBUG] amount.cc: parse - 5ms [DEBUG] amount.cc: parse - 6ms [DEBUG] Rational parsed = 1 + 5ms [DEBUG] Rational parsed = 1 6ms [DEBUG] amount.cc: parse 6ms [DEBUG] amount.cc: parse - 9ms [INFO] Parsing file "test.txt" - 10ms [DEBUG] Rational parsed = 1000 - 10ms [DEBUG] amount.cc: parse - 10ms [DEBUG] precision directive called 2 - 12ms [DEBUG] amount.cc: parse - 13ms [DEBUG] Rational parsed = 10 - 13ms [DEBUG] amount.cc: parse + 7ms [DEBUG] Rational parsed = 1 + 7ms [DEBUG] amount.cc: parse + 8ms [DEBUG] amount.cc: parse + 12ms [INFO] Parsing file "test.txt" + 13ms [DEBUG] Rational parsed = 1000 14ms [DEBUG] amount.cc: parse - 14ms [DEBUG] Rational parsed = 20 - 14ms [DEBUG] amount.cc: parse - 15ms [DEBUG] amount.cc:print - 16ms [DEBUG] amount.cc:in place round commodity EUR value 10 + 14ms [DEBUG] precision directive called 2 + 15ms [DEBUG] amount.cc: parse + 15ms [DEBUG] Rational parsed = 10 + 15ms [DEBUG] amount.cc: parse + 16ms [DEBUG] amount.cc: parse + 16ms [DEBUG] Rational parsed = 20 + 16ms [DEBUG] amount.cc: parse 16ms [DEBUG] amount.cc:print - 17ms [DEBUG] amount.cc:in place round commodity EUR value 20 - 17ms [DEBUG] amount.cc:+= value before 10 - 17ms [DEBUG] amount.cc:+= value after 30 + 17ms [DEBUG] amount.cc:in place round commodity EUR value 10 17ms [DEBUG] amount.cc:print - 17ms [DEBUG] amount.cc: is_zero prec 0 quant string 1 + 17ms [DEBUG] amount.cc:in place round commodity EUR value 20 + 18ms [DEBUG] amount.cc:+= value before 10 + 18ms [DEBUG] amount.cc:+= value after 30 18ms [DEBUG] amount.cc:print 18ms [DEBUG] amount.cc: is_zero prec 0 quant string 1 - 19ms [DEBUG] Rational parsed = 10123/1000 - 19ms [DEBUG] amount.cc: parse - 19ms [DEBUG] Rational parsed = 20123/1000 - 19ms [DEBUG] amount.cc: parse - 20ms [INFO] Read journal file (11ms) - 20ms [INFO] Found 1 transactions + 19ms [DEBUG] amount.cc:print + 19ms [DEBUG] amount.cc: is_zero prec 0 quant string 1 + 20ms [DEBUG] Rational parsed = 10123/1000 20ms [DEBUG] amount.cc: parse + 20ms [DEBUG] Rational parsed = 20123/1000 + 20ms [DEBUG] amount.cc: parse + 21ms [INFO] Read journal file (9ms) + 21ms [INFO] Found 1 transactions 21ms [DEBUG] amount.cc: parse - 22ms [DEBUG] post.cc:get_amount wrapper in lookup - 22ms [DEBUG] post.cc:get_amount - 23ms [DEBUG] post.cc:get_amount - 23ms [DEBUG] post.cc:get_amount - 26ms [DEBUG] account.cc: total - 27ms [DEBUG] amount.cc:print - 27ms [DEBUG] amount.cc:in place round commodity EUR value 20.12 - 27ms [DEBUG] amount.cc:print - 27ms [DEBUG] amount.cc:in place round commodity EUR value 10.12 - 27ms [DEBUG] balance.cc: amount added, value before 20.120000000000001 - 27ms [DEBUG] amount.cc:+= value before 20.120000000000001 - 28ms [DEBUG] amount.cc:+= value after 30.240000000000002 - 28ms [DEBUG] balance.cc: amount added, value after 30.240000000000002 - 28ms [DEBUG] amount.cc:print - 28ms [DEBUG] amount.cc: is_zero prec 3 quant string 30.24 - 29ms [DEBUG] account.cc: total - 29ms [DEBUG] amount.cc:print - 29ms [DEBUG] amount.cc: is_zero prec 2 quant string -30 - 29ms [DEBUG] format.cc:real_calc formatting - 30ms [DEBUG] amount.cc:+= value before 20 - 31ms [DEBUG] amount.cc:+= value after 20 - 31ms [DEBUG] amount.cc:print - 31ms [DEBUG] amount.cc:in place round commodity EUR value 20.12 - 31ms [DEBUG] amount.cc:print - 32ms [DEBUG] amount.cc:in place round commodity EUR value 10.12 - 32ms [DEBUG] balance.cc: amount added, value before 20.120000000000001 - 32ms [DEBUG] amount.cc:+= value before 20.120000000000001 - 32ms [DEBUG] amount.cc:+= value after 30.240000000000002 + 22ms [DEBUG] amount.cc: parse + 23ms [DEBUG] post.cc:get_amount wrapper in lookup + 24ms [DEBUG] post.cc:get_amount + 24ms [DEBUG] post.cc:get_amount + 24ms [DEBUG] post.cc:get_amount + 28ms [DEBUG] account.cc: total + 30ms [DEBUG] amount.cc:print + 30ms [DEBUG] amount.cc:in place round commodity EUR value 20.12 + 30ms [DEBUG] amount.cc:print + 30ms [DEBUG] amount.cc:in place round commodity EUR value 10.12 + 31ms [DEBUG] balance.cc: amount added, value before 20.120000000000001 + 31ms [DEBUG] amount.cc:+= value before 20.120000000000001 + 31ms [DEBUG] amount.cc:+= value after 30.240000000000002 32ms [DEBUG] balance.cc: amount added, value after 30.240000000000002 32ms [DEBUG] amount.cc:print 33ms [DEBUG] amount.cc: is_zero prec 3 quant string 30.24 - 33ms [DEBUG] amount.cc:print - 30.24 EUR A + 33ms [DEBUG] account.cc: total + 34ms [DEBUG] amount.cc:print + 34ms [DEBUG] amount.cc: is_zero prec 2 quant string -30 34ms [DEBUG] format.cc:real_calc formatting - 35ms [DEBUG] amount.cc:+= value before 20 + 36ms [DEBUG] amount.cc:+= value before 20 36ms [DEBUG] amount.cc:+= value after 20 - 36ms [DEBUG] amount.cc:print - 36ms [DEBUG] amount.cc: is_zero prec 2 quant string -30 37ms [DEBUG] amount.cc:print - 37ms [DEBUG] amount.cc: is_zero prec 2 quant string -30 + 37ms [DEBUG] amount.cc:in place round commodity EUR value 20.12 37ms [DEBUG] amount.cc:print - -30.00 EUR C - 38ms [DEBUG] format.cc:real_calc formatting --------------------- - 38ms [DEBUG] format.cc:real_calc formatting - 39ms [DEBUG] amount.cc:+= value before 20 - 40ms [DEBUG] amount.cc:+= value after 20 - 40ms [DEBUG] amount.cc:print - 40ms [DEBUG] amount.cc:in place round commodity EUR value 20.12 - 41ms [DEBUG] balance.cc: amount added, value before -30 - 41ms [DEBUG] amount.cc:+= value before -30 - 41ms [DEBUG] amount.cc:+= value after -9.879999999999999 - 41ms [DEBUG] balance.cc: amount added, value after -9.879999999999999 + 37ms [DEBUG] amount.cc:in place round commodity EUR value 10.12 + 37ms [DEBUG] balance.cc: amount added, value before 20.120000000000001 + 38ms [DEBUG] amount.cc:+= value before 20.120000000000001 + 38ms [DEBUG] amount.cc:+= value after 30.240000000000002 + 38ms [DEBUG] balance.cc: amount added, value after 30.240000000000002 + 38ms [DEBUG] amount.cc:print + 38ms [DEBUG] amount.cc: is_zero prec 3 quant string 30.24 + 38ms [DEBUG] amount.cc:print + 30.24 EUR A + 40ms [DEBUG] format.cc:real_calc formatting + 40ms [DEBUG] amount.cc:+= value before 20 + 41ms [DEBUG] amount.cc:+= value after 20 41ms [DEBUG] amount.cc:print - 41ms [DEBUG] amount.cc:in place round commodity EUR value 10.12 - 41ms [DEBUG] balance.cc: amount added, value before -9.879999999999999 - 41ms [DEBUG] amount.cc:+= value before -9.879999999999999 - 41ms [DEBUG] amount.cc:+= value after 0.24000000000000021 - 42ms [DEBUG] balance.cc: amount added, value after 0.24000000000000021 + 41ms [DEBUG] amount.cc: is_zero prec 2 quant string -30 42ms [DEBUG] amount.cc:print - 42ms [DEBUG] amount.cc: is_zero prec 3 quant string 0.24 + 42ms [DEBUG] amount.cc: is_zero prec 2 quant string -30 42ms [DEBUG] amount.cc:print + -30.00 EUR C + 44ms [DEBUG] format.cc:real_calc formatting +-------------------- + 44ms [DEBUG] format.cc:real_calc formatting + 45ms [DEBUG] amount.cc:+= value before 20 + 46ms [DEBUG] amount.cc:+= value after 20 + 46ms [DEBUG] amount.cc:print + 46ms [DEBUG] amount.cc:in place round commodity EUR value 20.12 + 47ms [DEBUG] balance.cc: amount added, value before -30 + 47ms [DEBUG] amount.cc:+= value before -30 + 47ms [DEBUG] amount.cc:+= value after -9.879999999999999 + 47ms [DEBUG] balance.cc: amount added, value after -9.879999999999999 + 47ms [DEBUG] amount.cc:print + 47ms [DEBUG] amount.cc:in place round commodity EUR value 10.12 + 47ms [DEBUG] balance.cc: amount added, value before -9.879999999999999 + 47ms [DEBUG] amount.cc:+= value before -9.879999999999999 + 48ms [DEBUG] amount.cc:+= value after 0.24000000000000021 + 48ms [DEBUG] balance.cc: amount added, value after 0.24000000000000021 + 48ms [DEBUG] amount.cc:print + 48ms [DEBUG] amount.cc: is_zero prec 3 quant string 0.24 + 48ms [DEBUG] amount.cc:print 0.24 EUR - 43ms [INFO] Finished executing command (20ms) - 43ms [INFO] Ledger ended + 49ms [INFO] Finished executing command (25ms) + 49ms [INFO] Ledger ended diff --git a/rounding/bug_reports/test.txt b/rounding/bug_reports/test.txt index a176cbdc6d1500a9a6e8fe3c47b4bf111d636eac..c6fe6d97fd16ec0c62b79bf3c01004dc2a37ae73 100644 --- a/rounding/bug_reports/test.txt +++ b/rounding/bug_reports/test.txt @@ -1,4 +1,7 @@ D 1000.00 EUR +commodity EUR + precision 2 + default 2012-01-01 * A 1 AAA @@ 10.00 EUR