Add w2_end_mt message #41
Comments
|
Receiving session should lookup transactionid and verify that transaction really has been sent |
|
Test 10. w2_check_w2 should be OK now including bitcoin transaction check. Handling fileGet in test 10. Tried stop-start ui-server + w2 page reload without any lock.
issue-41-test10-w2-sender-torando.log |
|
Test 11: Torando is sending and requesting money from montreal. Origin is not allowed by Access-Control-Allow-Origin error in montreal wallet. Also a JS error in montreal log. Could not see the problem with JS error. Added extra debug info. issue-41-test11-w2-receiver-montreal.log |
|
Test 12: Montreal is sending and requesting money from torando. issue-41-test12-w2-sender-montreal.log 09:47:59.692 all.js:138978 MoneyNetworkW2Service.process_incoming_message/807: Using group_debug_seq 807 for this receive w2_check_mt message operation
09:47:59.693 all.js:138986 MoneyNetworkW2Service.process_incoming_message/807: processing inner_path = merged-MoneyNetwork/1HXzvtSLuvxZfh6LgdaqTk4FSVf7x8w7NJ/data/users/18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ/bcc04fff7c-o.1511858724237 with encrypt3
09:47:59.694 all.js:138987 MoneyNetworkW2Service.process_incoming_message/807: now = 1511858879693, extra = {"optional_file":true,"timeout_count":3,"group_debug_seq":807,"file_info":{"inner_path":"data/users/18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ/bcc04fff7c-o.1511858724237","uploaded":0,"is_pinned":0,"time_accessed":0,"site_id":41,"is_downloaded":0,"file_id":21048,"peer":0,"time_added":1511858775,"hash_id":59755,"time_downloaded":0,"size":1808},"db_query_at":1511858776344,"fileget_at":1511858879629,"decrypt_at":1511858879692,"modified":1511858775,"fileget":true,"decrypt":true,"send_overhead":51000,"receive_overhead":103348,"total_overhead":154348}
09:47:59.694 all.js:138992 MoneyNetworkW2Service.process_incoming_message/807: filename = bcc04fff7c-o.1511858724237
09:47:59.695 all.js:138997 MoneyNetworkW2Service.process_incoming_message/807: file_timestamp = 1511858724237
09:47:59.695 all.js:139061 MoneyNetworkW2Service.process_incoming_message/807: request = {"msgtype":"w2_check_mt","money_transactions":[{"action":"Send","code":"tBTC","amount":0.0001,"json":{"address":"2NDtv9Fv34KfnaLzLpSQJ8MRiSYZQ3vDZxG","return_address":"2N7YmLzmDpaZvjUhWUzV8WYfWWcHmaYCsf2"}},{"action":"Request","code":"tBTC","amount":0.0001,"json":{"address":"2NATzh5Qf7hiBwoce6r1pRyEdnbPP4q29Uz","return_address":"2MtHadLKGKsZiiWswhQpewJ6882nsyZqCcg"}}]}
09:47:59.700 all.js:140430 MoneyNetworkW2Service.process_incoming_message.w2_check_mt read_w_session callback 1/807: session_info = {"money_transactionid":"nUAEbWjsfw1cE1mn3tp8i0hwML2Zb1he1scyc7G9yjrKh4H3Q879GqSezh7A","sender":true,"contact":{"alias":"jro","cert_user_id":"jro@zeroid.bit","auth_address":"18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ"},"money_transactions":[{"action":"Send","code":"tBTC","amount":0.0001,"json":{"return_address":"2N7YmLzmDpaZvjUhWUzV8WYfWWcHmaYCsf2"}},{"action":"Request","code":"tBTC","amount":0.0001,"json":{"address":"2NATzh5Qf7hiBwoce6r1pRyEdnbPP4q29Uz"}}],"ip_external":true,"prvkey":"-----BEGIN RSA PRIVATE KEY-----\nMIICXQIBAAKBgQCHr354RApibVrS/x+NwzAhc72FPsm2BTdVG5oz7fIfi3vBkTbv\ngxNh8bwPmW5HXD2BWHP6PCz60Ek+1MfEtncAORx5rLReF7ueFbFEgNIoZ0hfHFbF\nxR2NgsYq35bFAOnrF+jmkrDFCUNsxLXWAnv5/1cdhv7ABOcrf2d0LGWpowIDAQAB\nAoGACWD2CvYZCaipaIA9/ByLzSQk63rlknFV28ompIREAi4wBbc15uRWiKSe7S0b\n1E7p6XDlw1w5d7TB+1fV3yEFpKJ6a0ANqlfgeQoNw0dEosFgY07bcrLrnYR2OuBC\nRqU/wN8K3IRltm2ZRndQQsmDCh4KEq/54ZV6ueSKdEXAUuECQQDHp+y3llYsFzDt\nhF3vxeiyyl5jeZtt50LwjIcvcJFscgvy58UIIcZW1zu5LcMiQC5qAi9CklUUL3cp\nWS7Fj8BZAkEArfoMMjYyGrowNQPMLHYJyrGA0kBtARHfa3jbfpRZW15SRXUeax/1\nWth3FjpOrbi1Qa7bY7L4Qb0NU+OAgHVaWwJAR44niKSYvbSSFI4cHJMRkwJ0UbKD\nwh/gHdY/nQD7HaLCJYxrUT7JltYM0ZFVHjFicRvXVESduV5Jlsgfuy6KAQJBAIM1\nO5F60hraJJU3AfreItBdQjxPHmZaJleVVGSIWUPRRNbhV0mPVqlm65k17DSUQw8A\nYrE0PorFm5GS2E0JNFUCQQDGjBn0mIafZGzBh4C+aJc4QdgtuF1ITzZA2rj/8Tad\n2paTbegOfUKcvHqBUHB5Gj0W7zUUU870RA3AeEeD8cRy\n-----END RSA PRIVATE KEY-----","userid2":363}
09:47:59.700 all.js:140446 MoneyNetworkW2Service.process_incoming_message.w2_check_mt read_w_session callback 1/807:
warning. waiting for pubkeys message from other wallet session. cannot encrypt send w2_start_mt message without public keys
09:47:59.701 all.js:140460 MoneyNetworkW2Service.process_incoming_message.w2_check_mt read_w_session callback 1/807:
w2_query_6 = select filename from files_optional where filename like 'bcc04fff7c-%'
09:47:59.702 MoneyNetworkAPI.js:247 MoneyNetworkW2Service.process_incoming_message.w2_check_mt read_w_session callback 1/807: w2 query 6 dbQuery started (817/807). 2 pending ZeroNet API operations (814/788,817/807)
09:47:59.745 MoneyNetworkAPI.js:269 MoneyNetworkW2Service.process_incoming_message.w2_check_mt read_w_session callback 1/807: w2 query 6 dbQuery finished. res = "OK. Returned 2 rows". elapsed time 44 ms (817/807). 1 pending ZeroNet API operation (814/788)
09:47:59.755 all.js:140466 MoneyNetworkW2Service.process_incoming_message.w2_check_mt dbQuery callback 2/807: res = [{"filename":"bcc04fff7c-o.1511858718101"},{"filename":"bcc04fff7c-o.1511858724237"}]
09:47:59.759 all.js:140475 MoneyNetworkW2Service.process_incoming_message.w2_check_mt dbQuery callback 2/807: res = [{"filename":"bcc04fff7c-o.1511858718101"},{"filename":"bcc04fff7c-o.1511858724237"}]
09:47:59.761 all.js:140478 MoneyNetworkW2Service.process_incoming_message.w2_check_mt dbQuery callback 2/807: old res.length = 2
09:47:59.762 all.js:140497 MoneyNetworkW2Service.process_incoming_message.w2_check_mt dbQuery callback 2/807:
pubkeys_filename = bcc04fff7c-o.1511858718101
09:47:59.762 MoneyNetworkAPI.js:247 MoneyNetworkW2Service.process_incoming_message.w2_check_mt dbQuery callback 2/807:
merged-MoneyNetwork/1HXzvtSLuvxZfh6LgdaqTk4FSVf7x8w7NJ/data/users/18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ/bcc04fff7c-o.1511858718101 optionalFileInfo started (818/807). 2 pending ZeroNet API operations (814/788,818/807)
09:47:59.773 MoneyNetworkAPI.js:269 MoneyNetworkW2Service.process_incoming_message.w2_check_mt dbQuery callback 2/807: merged-MoneyNetwork/1HXzvtSLuvxZfh6LgdaqTk4FSVf7x8w7NJ/data/users/18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ/bcc04fff7c-o.1511858718101 optionalFileInfo finished. res = "OK". elapsed time 11 ms (818/807). 1 pending ZeroNet API operation (814/788)
09:47:59.774 all.js:140505 MoneyNetworkW2Service.process_incoming_message.w2_check_mt optionalFileInfo callback 3/807:
file_info = {
"inner_path":"data/users/18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ/bcc04fff7c-o.1511858718101","uploaded":0,"is_pinned":0,"time_accessed":0,"site_id":41,
"is_downloaded":0,
"file_id":21045,"peer":0,"time_added":1511858722,"hash_id":55830,"time_downloaded":0,"size":548
}
09:47:59.775 all.js:138763 MoneyNetworkW2Service.save_w_session/807: save new wallet to wallet session encrypted in ls. session_info = {"money_transactionid":"nUAEbWjsfw1cE1mn3tp8i0hwML2Zb1he1scyc7G9yjrKh4H3Q879GqSezh7A","sender":true,"contact":{"alias":"jro","cert_user_id":"jro@zeroid.bit","auth_address":"18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ"},"money_transactions":[{"action":"Send","code":"tBTC","amount":0.0001,"json":{"return_address":"2N7YmLzmDpaZvjUhWUzV8WYfWWcHmaYCsf2"}},{"action":"Request","code":"tBTC","amount":0.0001,"json":{"address":"2NATzh5Qf7hiBwoce6r1pRyEdnbPP4q29Uz"}}],"ip_external":true,"prvkey":"-----BEGIN RSA PRIVATE KEY-----\nMIICXQIBAAKBgQCHr354RApibVrS/x+NwzAhc72FPsm2BTdVG5oz7fIfi3vBkTbv\ngxNh8bwPmW5HXD2BWHP6PCz60Ek+1MfEtncAORx5rLReF7ueFbFEgNIoZ0hfHFbF\nxR2NgsYq35bFAOnrF+jmkrDFCUNsxLXWAnv5/1cdhv7ABOcrf2d0LGWpowIDAQAB\nAoGACWD2CvYZCaipaIA9/ByLzSQk63rlknFV28ompIREAi4wBbc15uRWiKSe7S0b\n1E7p6XDlw1w5d7TB+1fV3yEFpKJ6a0ANqlfgeQoNw0dEosFgY07bcrLrnYR2OuBC\nRqU/wN8K3IRltm2ZRndQQsmDCh4KEq/54ZV6ueSKdEXAUuECQQDHp+y3llYsFzDt\nhF3vxeiyyl5jeZtt50LwjIcvcJFscgvy58UIIcZW1zu5LcMiQC5qAi9CklUUL3cp\nWS7Fj8BZAkEArfoMMjYyGrowNQPMLHYJyrGA0kBtARHfa3jbfpRZW15SRXUeax/1\nWth3FjpOrbi1Qa7bY7L4Qb0NU+OAgHVaWwJAR44niKSYvbSSFI4cHJMRkwJ0UbKD\nwh/gHdY/nQD7HaLCJYxrUT7JltYM0ZFVHjFicRvXVESduV5Jlsgfuy6KAQJBAIM1\nO5F60hraJJU3AfreItBdQjxPHmZaJleVVGSIWUPRRNbhV0mPVqlm65k17DSUQw8A\nYrE0PorFm5GS2E0JNFUCQQDGjBn0mIafZGzBh4C+aJc4QdgtuF1ITzZA2rj/8Tad\n2paTbegOfUKcvHqBUHB5Gj0W7zUUU870RA3AeEeD8cRy\n-----END RSA PRIVATE KEY-----","userid2":363,"lost_pubkey_message_count":1}
09:47:59.777 all.js:138772 MoneyNetworkW2Service.save_w_session: session_info is already in z_cache
09:47:59.779 MoneyNetworkAPI.js:3020 MoneyNetworkAPI.encrypt_json: encrypt1/807: this.other_session_pubkey2 = AjNp+TH4ho3DEmyfa73v447KWgv/W8t3R94/mY+ib/2+
09:47:59.780 MoneyNetworkAPI.js:3020 MoneyNetworkAPI.encrypt_2: encrypt1/807: encrypted_text_1 = {"money_transactionid":"nUAEbWjsfw1cE1mn3tp8i0hwML2Zb1he1scyc7G9yjrKh4H3Q879GqSezh7A","sender":true,"contact":{"alias":"jro","cert_user_id":"jro@zeroid.bit","auth_address":"18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ"},"money_transactions":[{"action":"Send","code":"tBTC","amount":0.0001,"json":{"return_address":"2N7YmLzmDpaZvjUhWUzV8WYfWWcHmaYCsf2"}},{"action":"Request","code":"tBTC","amount":0.0001,"json":{"address":"2NATzh5Qf7hiBwoce6r1pRyEdnbPP4q29Uz"}}],"ip_external":true,"prvkey":"-----BEGIN RSA PRIVATE KEY-----\nMIICXQIBAAKBgQCHr354RApibVrS/x+NwzAhc72FPsm2BTdVG5oz7fIfi3vBkTbv\ngxNh8bwPmW5HXD2BWHP6PCz60Ek+1MfEtncAORx5rLReF7ueFbFEgNIoZ0hfHFbF\nxR2NgsYq35bFAOnrF+jmkrDFCUNsxLXWAnv5/1cdhv7ABOcrf2d0LGWpowIDAQAB\nAoGACWD2CvYZCaipaIA9/ByLzSQk63rlknFV28ompIREAi4wBbc15uRWiKSe7S0b\n1E7p6XDlw1w5d7TB+1fV3yEFpKJ6a0ANqlfgeQoNw0dEosFgY07bcrLrnYR2OuBC\nRqU/wN8K3IRltm2ZRndQQsmDCh4KEq/54ZV6ueSKdEXAUuECQQDHp+y3llYsFzDt\nhF3vxeiyyl5jeZtt50LwjIcvcJFscgvy58UIIcZW1zu5LcMiQC5qAi9CklUUL3cp\nWS7Fj8BZAkEArfoMMjYyGrowNQPMLHYJyrGA0kBtARHfa3jbfpRZW15SRXUeax/1\nWth3FjpOrbi1Qa7bY7L4Qb0NU+OAgHVaWwJAR44niKSYvbSSFI4cHJMRkwJ0UbKD\nwh/gHdY/nQD7HaLCJYxrUT7JltYM0ZFVHjFicRvXVESduV5Jlsgfuy6KAQJBAIM1\nO5F60hraJJU3AfreItBdQjxPHmZaJleVVGSIWUPRRNbhV0mPVqlm65k17DSUQw8A\nYrE0PorFm5GS2E0JNFUCQQDGjBn0mIafZGzBh4C+aJc4QdgtuF1ITzZA2rj/8Tad\n2paTbegOfUKcvHqBUHB5Gj0W7zUUU870RA3AeEeD8cRy\n-----END RSA PRIVATE KEY-----","userid2":363,"lost_pubkey_message_count":1}. calling aesEncrypt
09:47:59.782 MoneyNetworkAPI.js:247 MoneyNetworkAPI.encrypt_2: n/a aesEncrypt started (819/807). 2 pending ZeroNet API operations (814/788,819/807)
09:47:59.792 MoneyNetworkAPI.js:269 MoneyNetworkAPI.encrypt_2: n/a aesEncrypt finished. res = "OK". elapsed time 10 ms (819/807). 1 pending ZeroNet API operation (814/788)
09:47:59.792 MoneyNetworkAPI.js:247 MoneyNetworkAPI.encrypt_2 aesEncrypt callback 1: n/a eciesEncrypt started (820/807). 2 pending ZeroNet API operations (814/788,820/807)
09:47:59.811 MoneyNetworkAPI.js:269 MoneyNetworkAPI.encrypt_2 aesEncrypt callback 1: n/a eciesEncrypt finished. res = "OK". elapsed time 18 ms (820/807). 1 pending ZeroNet API operation (814/788)
09:47:59.811 MoneyNetworkAPI.js:3020 MoneyNetworkAPI.encrypt_2 eciesEncrypt callback 2: encrypt1/807: self.other_session_pubkey2 = AjNp+TH4ho3DEmyfa73v447KWgv/W8t3R94/mY+ib/2+, key = jjNAw2VDcYQgzKibc4t2AALKACD2GqjrPPJd/hIhCJEGdbeuTnbovLj48v5nmCyY4tB8CgAg2/U+nW/eEFGlgmDxlJI5OO3Nk4WdBlAzvtTHB+HwMI1DP9LDwKihQDe8BRMB25jSSyoqUqAMjrTGZjxuYl/jkLbUjzQaTvqm/yTEeOA5mHDipjTa18hi77NmEnsx1Ygnw7r7y+KyCEexQiXt0RcJkw==
09:47:59.816 MoneyNetworkAPI.js:247 MoneyNetworkAPI.encrypt_2 eciesEncrypt callback 2: n/a aesEncrypt started (821/807). 2 pending ZeroNet API operations (814/788,821/807)
09:47:59.829 MoneyNetworkAPI.js:269 MoneyNetworkAPI.encrypt_2 eciesEncrypt callback 2: n/a aesEncrypt finished. res = "OK". elapsed time 13 ms (821/807). 1 pending ZeroNet API operation (814/788)
09:47:59.829 MoneyNetworkAPI.js:3020 MoneyNetworkAPI.encrypt_2 aesEncrypt callback 3: encrypt1/807: aesEncrypt OK
09:47:59.830 MoneyNetworkAPI.js:3020 MoneyNetworkAPI.encrypt_2 aesEncrypt callback 3: encrypt1/807: encrypted_text_2 = ["jjNAw2VDcYQgzKibc4t2AALKACD2GqjrPPJd/hIhCJEGdbeuTnbovLj48v5nmCyY4tB8CgAg2/U+nW/eEFGlgmDxlJI5OO3Nk4WdBlAzvtTHB+HwMI1DP9LDwKihQDe8BRMB25jSSyoqUqAMjrTGZjxuYl/jkLbUjzQaTvqm/yTEeOA5mHDipjTa18hi77NmEnsx1Ygnw7r7y+KyCEexQiXt0RcJkw==","R5yr1OFxEGxg1OYp6kgR+A==","tAWL+4nOtzX1I8ZUJRU/U1IOFl4fMJIw6W4uS8K/nvsKs94nkoqPgMBjXSG72oWEieX2S2/TwEnkLIwl+fsaDzk8ToQR4CSQX2xYeQcv9pyAb8zOZBTO2nDQroEZkeUtTkIUBMqu1BwjY3Hc+LU2cQ3URd/T2dPmPiDv07g8Dex1S+TPxKBQV4NsC+kW9H/4OoiJr/U/PFgYnfDeCBRpv8ZHkeBsLQJkjdLxVq2rXKJz0DQTFzuX5iXj34U+pqqUVJC+22v6Q9Rn4uIZTkXlv7Z5vR33jF0pbYvwoaN55S/h9qsJvXNn0yJ1bl8wDiv+dxPaK+lQsu4tn8iBmAUnqqj3PivP6yymCqa4lQUQduSkVbntnvTqZU++5vB/RhBXjSE3aFdl7wZt4OAZiyuuLgmEnBPbo9XRcI6btNkcRkku3jFXEvpzXGtecI4gZeS3mlhRvJBupDlC8yDvsitFTokEGKHHq5/gnswSX5s/9j0O2iwS/sCGMpd1ewo07Rn9iMOx01c71DREI7mqCHpT3FRdljoYxrrm196D1OhwX13HCSNaT24jCMuIJYe9Yxhl5mVjNFDphhA5wtR2qcNt8Zbyt8CxxdKLOgMUifi6eRa9/9CNuQpoINJK+XDgyjeII6UC1oinvtmbfaeenteeH7r98uhsQ5FVfQrvZeJDFUymYWnxritShyOyIDMyzOgx3+QJMFt/paKKuak7tSf/4d4mHeTBSVmJBVjoW70faIr8haIcKdZFSGfnOZqV9kMFxnQ+aMI/bYRe7KhccJjPSwum7N6IclrVtKTIR7hhFAcC2e/rVmCcKrHrQWuLmSQfhhQfJGHFCqwtGOT7BijS/jVjNtHPYVcnRJQw63LU403UMvG1ntrws7vqucFBvFpACWfV7cfwCxjhC4zYD6lXcvVuSRZ0hEPs54vNhcgLf+pyqSXnm9rsEVdgyIkK8sdw7piAD0+d0bAlXOKB+llOkgmUWkhELolcZifirJcVCbUnUWZMn8BLaCPm9vKzf/esdYa37UiYofTIyRrFXCDS74wkZCpackgFBShn94SAKcRcF8JMUo0ZU4ZTPbUVG+FTXtKaaCmuvevXLLbHwRNLx/ucVwdpgzP9tlXqvdABfLCTR2OWPsDM6lY3+5oVgG0Y3bssUkiUJ7kOTTsaUGsCaOxiuT0Mz5zh5zdNJjjDQeKRIRoEDEnLUmCJ2bA9X0j6RC8krGF5p7RyyginKbTkNstz9UvYGKQNSlxdqPUS3yIxVsYdDXT1wKJvV4XM4v12NNMCJ96I6e+OSf63yH2VnJbc+1CSe4xF6CSFnluUfq1Zprx82S9rrYw6abA6DI43h5I3BDDv4uQ383GgEKvbhXJfpI4D8NsVh/Hh4l/1/KRd92aL2RQeXjI0YZkwR/k9ntsEJH+zNJxEYVJhddZzccSio7v0jWo/kZQUPR+nuJHsnGx/6c452jo1+v+zdKUxoMAJKWrrx0G9hqF5rPf9sEdEXXqpeFUrrUdo0jK/HxM4zydttQh/0to6sQr4vPdAogcQs5Y+a1MYGygFRwMlBMEh8+OMr3Cn9uGC5zQ92j3CLjSY8QHIWQ32BYrml0Ke0hy/kkhOjfJMaVrAT9El/C+md6OILzN3vLqXAHem7GQaUDXaz/zYHf0kr7qrl6E96ukRunxU6Ij0LDrM6zS2f3C9y02Qmspg52zDQo1GZVbMhen/PuhtM6fGU4Xh1RFKDItRBnvVwDssZEFeK9cWcGPUBfARq/9MWZnog1nJv4rKC71hlNu7IVIlQxv0PGVtkog33z9fosW8NDcHzXs54Z96oRTR6Zv4AWglIrHs0X8sRVOm4w9P+r3T3I02f9CH1vUmRBr9yeFcqvOzDueJXuQoPZvh69dwf3lDCwxEhwERdLFZ11IbbtDWiUw5Ubpf"]
09:47:59.833 all.js:138786 MoneyNetworkW2Service.save_w_session: OK. Saved wallet-wallet session information in localStorage
09:47:59.834 all.js:140519 MoneyNetworkW2Service.process_incoming_message.w2_check_mt save_w_session callback 4/807: retrying receive pubkeys and w2_check_mt messages. lost_pubkey_message_count = 1
09:47:59.835 MoneyNetworkAPI.js:344 MoneyNetworkAPILib.debug_group_operation_end/807: total elapsed time for group_debug_seq 807 was 103490 ms
09:47:59.836 MoneyNetworkAPI.js:345 MoneyNetworkAPILib.debug_group_operation_end/807: group_debug_operations = {"this_session_start_at":1511858776345,"this_session_filename":"0da5c03f06","direction":"in","filename":"bcc04fff7c-o.1511858724237","msgtype":"w2_check_mt","this_session_error":"OK. Submitted redo_pubkeys and redo_w2_start_mt jobs","this_session_finish_at":1511858879835,"this_session_elapsed_time":103490}
09:47:59.847 MoneyNetworkAPI.js:3020 MoneyNetworkAPI.get_session_filenames: encrypt3: wallet = false, this_session_filename = 0da5c03f06, other_session_filename = bcc04fff7c
09:47:59.967 MoneyNetworkAPI.js:269 MoneyNetworkAPILib.message_demon dbQuery callback: merged-MoneyNetwork/1HXzvtSLuvxZfh6LgdaqTk4FSVf7x8w7NJ/data/users/18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ/bcc04fff7c-o.1511858718101 fileGet finished. res = "OK". elapsed time 3658 ms (814/788). No pending ZeroNet API operations
09:47:59.969 MoneyNetworkAPI.js:975 MoneyNetworkAPILib.message_demon dbQuery callback/788: calling cb with merged-MoneyNetwork/1HXzvtSLuvxZfh6LgdaqTk4FSVf7x8w7NJ/data/users/18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ/bcc04fff7c-o.1511858718101 and encrypt3
09:47:59.970 all.js:138978 MoneyNetworkW2Service.process_incoming_message/788: Using group_debug_seq 788 for this receive pubkeys message operation
09:47:59.971 all.js:138986 MoneyNetworkW2Service.process_incoming_message/788: processing inner_path = merged-MoneyNetwork/1HXzvtSLuvxZfh6LgdaqTk4FSVf7x8w7NJ/data/users/18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ/bcc04fff7c-o.1511858718101 with encrypt3
09:47:59.971 all.js:138987 MoneyNetworkW2Service.process_incoming_message/788: now = 1511858879971, extra = {"optional_file":true,"timeout_count":1,"group_debug_seq":788,"file_info":{"inner_path":"data/users/18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ/bcc04fff7c-o.1511858718101","uploaded":0,"is_pinned":0,"time_accessed":0,"site_id":41,"is_downloaded":0,"file_id":21045,"peer":0,"time_added":1511858722,"hash_id":55830,"time_downloaded":0,"size":548},"db_query_at":1511858723340,"fileget_at":1511858879968,"decrypt_at":1511858879969,"modified":1511858721,"fileget":true,"decrypt":true,"send_overhead":3000,"receive_overhead":156629,"total_overhead":159629}
09:47:59.972 all.js:138992 MoneyNetworkW2Service.process_incoming_message/788: filename = bcc04fff7c-o.1511858718101
09:47:59.972 all.js:138997 MoneyNetworkW2Service.process_incoming_message/788: file_timestamp = 1511858718101
09:47:59.973 all.js:139061 MoneyNetworkW2Service.process_incoming_message/788: request = {"msgtype":"pubkeys","pubkey":"-----BEGIN PUBLIC KEY-----\nMIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQC2JTrHW4CjyN7g5YTx0Tw4hUoX\nprsl6icnjVLw9RstZlEojJGEttNz9SsmCoiW0GDkrqI7PExjCc0Z2dQgR5MJ/NhX\npoF5GdCeianBhw/+P+HlISw4H8i/EeFypjWktwLY+q9AEmr5XsjF81MTIon/u3V8\n4jcXBx2aesjrqQHmIwIDAQAB\n-----END PUBLIC KEY-----","pubkey2":"AimqAncBaOHJgJJ2h75liU6su3Xs6lU6c4ngXxy3YK0q"}
09:47:59.974 MoneyNetworkAPI.js:3020 MoneyNetworkAPI.setup_encryption: encrypt3: Encryption setup: waiting for other_session_pubkey2
09:47:59.978 MoneyNetworkAPI.js:3020 MoneyNetworkAPI.setup_encryption: encrypt3: Encryption setup done for wallet<->wallet communication
09:47:59.985 all.js:140278 MoneyNetworkW2Service.process_incoming_message.pubkeys read_w_session callback 1/788: session_info.money_transactions = [{"action":"Send","code":"tBTC","amount":0.0001,"json":{"return_address":"2N7YmLzmDpaZvjUhWUzV8WYfWWcHmaYCsf2"}},{"action":"Request","code":"tBTC","amount":0.0001,"json":{"address":"2NATzh5Qf7hiBwoce6r1pRyEdnbPP4q29Uz"}}]
09:47:59.995 all.js:140279 MoneyNetworkW2Service.process_incoming_message.pubkeys read_w_session callback 1/788: sender/receiver check. sender = true, receiver = false
09:47:59.999 all.js:140309 MoneyNetworkW2Service.process_incoming_message.pubkeys read_w_session callback 1/788: pubkeys message ok. wallet-wallet communication started. is sender of money transaction. waiting for w2_check_mt message from other wallet to crosscheck money transaction(s) before sending money transaction(s) to external API (btc.com)
09:48:00.004 MoneyNetworkAPI.js:344 MoneyNetworkAPILib.debug_group_operation_end/788: total elapsed time for group_debug_seq 788 was 156662 ms
09:48:00.007 MoneyNetworkAPI.js:345 MoneyNetworkAPILib.debug_group_operation_end/788: group_debug_operations = {"this_session_start_at":1511858723342,"this_session_filename":"0da5c03f06","direction":"in","filename":"bcc04fff7c-o.1511858718101","msgtype":"pubkeys","this_session_finish_at":1511858880004,"this_session_elapsed_time":156662}
09:48:09.836 MoneyNetworkAPI.js:3020 MoneyNetworkAPI.get_session_filenames: encrypt3: wallet = false, this_session_filename = 0da5c03f06, other_session_filename = bcc04fff7c
09:49:09.949 all.js?rev=3146&lang=en:593 hideProgress
Minor JS error in redo_w2_check_mt function + added extra debug into to log |
|
Test 15 failed. Money transaction is hanging. Problem with content.json distribution? issue-41-test15-w2-sender-montreal.log File merged-MoneyNetwork/1HXzvtSLuvxZfh6LgdaqTk4FSVf7x8w7NJ/data/users/18DbeZgtVCcLghmtzvg4Uv8uRQAwR8wnDQ/ba361b405a-o.1511864637298 was not distributed from torando to montreal (pubkeys message) Woke up montreal comp after sleep and distribution of missing file started. issue-41-test15-w2-sender-torando-2.log Problem. pubkeys message from montreal was sent as a normal file to torando and w2_check_mt was sent as a optional file. dbQuery is only checking optional files. Now checking both files and files_optional in message_demon and other queries. |
|
Test 16. wallet log ins with vpn off. Money transaction approval with vpn on in both sessions. Minor error in receive w2_start_mt. pay_results in w2_end_mt message was empty. No money was sent from montreal to torando. |
|
Test 17. wallet log ins with vpn off. Money transaction approval with vpn on in both sessions. Money was sent from torando to montreal and money was sent from montreal to torando. w2_end_mt message from montreal to torando is not ok. missing bitcoin transactionid for montreal payment operation (row 2). request = {"msgtype":"w2_end_mt","pay_results":[null,null]} issue41-test17-sender-montreal-w2.log |
backup check-in. send w2_end_mt not yet ok
backup check-in. send w2_end_mt not yet ok
|
Test 18. Added receive w2_end_mt code. Test failed. vpn was disabled at sender torando. But pubkeys message should have been send as a normal file instead. issue41-test18-sender-montreal-w2-2.log Error in z_file_get in MoneyNetworkAPI. List of normal files is hardcoded and pattern for 605599dbef.1511945421658 was not included.
|
|
Test 19. Changed optional file check in z_file_get. Testing with vpn on torando and vpn off for montreal. issue41-test19-sender-torando-w2-2.log |
…907735 test 19 OK
|
Test 20. Added better send/received notification in UI. Running with vpn off in both sessions. Money transaction should use normal files. Test failed. Torando is sending an optional file to montreal with ZeroNet port closed. issue41-test20-sender-torando-w2-1.log |
|
See jaros1/Money-Network#289 z_file_get: hanging optional fileGet / wallet sites |
|
Test 21. Sender torando (vpn=on) using optional files. Receiver montreal (vpn=off). Expects problems with handing fileGet operations. And maybe a waiting_for_file notification. Test failed: Error in post start_mt processing in montreal. Minor error in ZeroNet port check. issue41-test21-sender-torando-w2-1.log issue41-test21-content-json-torando.txt |
|
Test 22. Restarted test 21. Approved incoming money transaction in montreal. issue41-test22-sender-torando-w2-1.log test failed: montreal could not fileGet optional file with pubkeys message from montreal. ZeroNet port closed. Trying to send waiting_for_file message but cannot encrypt without pubkeys message from montreal torando: optional fileGet timeout. sending waiting_for_file notification to montreal /635
12:16:26.510 MoneyNetworkAPI.js:1034 MoneyNetworkAPILib.message_demon.step_1_fileget.waiting_for_file/635: Using group_debug_seq 635 for this waiting_for_file message
12:16:26.510 MoneyNetworkAPI.js:1039 MoneyNetworkAPILib.message_demon.step_1_fileget.waiting_for_file/635: sending waiting_for_file notification to other wallet. request = {"msgtype":"waiting_for_file","filename":"6d62619fa3-o.1512040517908"}
12:16:26.510 MoneyNetworkAPI.js:3155 MoneyNetworkAPI.send_message: encrypt3/635: Using group_debug_seq 635 for this send waiting_for_file message operation
12:16:26.511 MoneyNetworkAPI.js:3155 MoneyNetworkAPI.send_message: encrypt3/635: sessionid = eONOszQgM5lWbV4jvtSwvJoOKFxTqFZApBGifuRhkLLvGLIoISjtV8EYCnDM
12:16:26.511 MoneyNetworkAPI.js:3155 MoneyNetworkAPI.send_message: encrypt3/635: request = {"msgtype":"waiting_for_file","filename":"6d62619fa3-o.1512040517908"}
12:16:26.511 MoneyNetworkAPI.js:3155 MoneyNetworkAPI.send_message: encrypt3/635: msgtype = waiting_for_file, subsystem = api, optional =
12:16:26.512 MoneyNetworkAPI.js:349 MoneyNetworkAPILib.debug_group_operation_end/635: total elapsed time for group_debug_seq 635 was 2 ms
12:16:26.512 MoneyNetworkAPI.js:350 MoneyNetworkAPILib.debug_group_operation_end/635: group_debug_operations = {"this_session_start_at":1512040586510,"msgtype":"waiting_for_file","this_session_error":"Cannot JSEncrypt encrypt outgoing message. pubkey is missing in encryption setup","this_session_finish_at":1512040586512,"this_session_elapsed_time":2}
12:16:26.512 MoneyNetworkAPI.js:1043 MoneyNetworkAPILib.message_demon.step_1_fileget.waiting_for_file send_message callback 1/635: response = {"error":"Cannot JSEncrypt encrypt outgoing message. pubkey is missing in encryption setup"}
|
|
Test 23. Sender torando (vpn=on) using optional files. Receiver montreal (vpn=off). Expects problems with handing fileGet operations. And maybe a waiting_for_file notification. Test ok. Got a waiting_for_file notification in both wallet sessions. issue41-test23-sender-torando-w2-1.log problem with old file cleanup in w2 (initialize dbQuery check_files callback 6:). deleting just sent optional file (pubkeys message). cleanup should not delete offline messages. cleanup should not delete normal file. Added workaround for "failed" fileDelete from #1140 + now only cleanup for -e and -i files. todo: must add some extra cleanup for offline and normal files. Create a W2 issue |
|
Test 24. Expects pubkeys message from montreal to torando NOT to be deleted. issue41-test24-sender-torando-w2-2.log |
|
Test 25. Refactored fileDelete code (ZeroNet issue #1140) into z_file_delete. Added receive waiting_for_file code in W2. issue41-test25-sender-torando-w2-2.log Simple JS bug. Renamed encrypt to encrypt2 in w2 process_incoming_message |
|
Test 26. Expects receive waiting_for_file to work OK and publish a normal file instead of failed optional file. issue41-test26-sender-torando-w2-2.log Simple JS error. caught MoneyNetworkW2Service.process_incoming_message.waiting_for_file z_file_delete callback 2/959: Invalid call. parameter 5 cb is not a callback function |
|
Test 27. Expects receive waiting_for_file to work OK and publish a normal file instead of failed optional file. issue41-test27-sender-torando-w2-2.log Simple JS error in receive waiting_for_file message. Wrote with wrong filename |
|
Test 28. Expects receive waiting_for_file to work OK and publish a normal file instead of failed optional file. issue41-test28-sender-torando-w2-2.log Test failed. double JSON.stringify in renamed pubkeys message from montreal? 18:17:50.124 all.js:139012 MoneyNetworkW2Service.process_incoming_message/1018: Using group_debug_seq 1018 for this process_incoming_message operation
18:17:50.124 all.js:139020 MoneyNetworkW2Service.process_incoming_message/1018: processing inner_path = merged-MoneyNetwork/1HXzvtSLuvxZfh6LgdaqTk4FSVf7x8w7NJ/data/users/1CCiJ97XHgVeJrkbnzLgfXvYRr8QEWxnWF/8b52d35e4f.1512062135063 with encrypt3
18:17:50.125 all.js:139021 MoneyNetworkW2Service.process_incoming_message/1018: now = 1512062270125, extra = {"optional_file":false,"group_debug_seq":1018,"file_info":null,"db_query_at":1512062269694,"fileget_at":1512062270124,"decrypt_at":1512062270124,"modified":1512062268,"fileget":true,"decrypt":true,"send_overhead":133000,"receive_overhead":430,"total_overhead":133430}
18:17:50.125 all.js:139026 MoneyNetworkW2Service.process_incoming_message/1018: filename = 8b52d35e4f.1512062135063
18:17:50.126 all.js:139031 MoneyNetworkW2Service.process_incoming_message/1018: file_timestamp = 1512062135063
18:17:50.126 all.js:139095 MoneyNetworkW2Service.process_incoming_message/1018: request = "{\n\t\"encryption\": 3,\n\t\"message\": \"U2FsdGVkX1/7QFC5dXMLO6eW6FilQYCX6kW3zjM16u4j7ORqbdgNzEh8TsnkHPdm+2+w+xptt1AQwG9jp15eBGYFlcbbAa+dPJt4NlK7aGnxjmIh6Xg4yEZaEk6d8CJvC7A61bEqZcR/jrZh/IYWScR0tRJwOSv1qmZlctzguO4MswY7xUvLKGYqDNmrZQn3wceXlsCrtbtGzfvCfWmG54S/R97rGWITlP2iNcu87sLYSrdUSpof3yKxgOkFzGhmdlIpu23RPoUsgtOu/WABiz42ej8hHZ32VLNDdrSnMZ9roFz9EvbpvWEZingMS+CMaVT5r/B1tCEp3ZiGWu1qHCk3Tg/CNCjhTjDLWsksJrDyoq1OJKUMUFKWIqQHICvqTMWMBXoaimA+Q8LoyyuULs/aPnfaftgWD/pdrRw+Ar6QuC3VBdeTPV7LuW2viK6OF+33Wxi73qk8/pLc+pUqp4GqJjhkbRCdmm1SmB1UEkYVsNzYnknDFYfviHekIQT1\"\n}"
18:17:50.126 all.js:141543 MoneyNetworkW2Service.process_incoming_message/1018: response = {"msgtype":"response","error":"message is invalid. required msgtype is missing in json message (parameter 2)"}
18:17:50.126 MoneyNetworkAPI.js:349 MoneyNetworkAPILib.debug_group_operation_end/1018: total elapsed time for group_debug_seq 1018 was 432 ms
18:17:50.126 MoneyNetworkAPI.js:350 MoneyNetworkAPILib.debug_group_operation_end/1018: group_debug_operations = {"this_session_start_at":1512062269694,"this_session_filename":"35451b596d","direction":"in","filename":"8b52d35e4f.1512062135063","this_session_finish_at":1512062270126,"this_session_elapsed_time":432}
|
|
Test 29. Expects receive waiting_for_file to work OK and publish a normal file instead of failed optional file. issue41-test29-sender-torando-w2-2.log Torando: sender of money transaction: Montreal: receiver of money transaction: |
…267538 test 29 ok. money was sent and received.
…267538 test 29 ok. money was sent and received.
|
Closing. w2_end_mt message is working fine |
result = "b060af84c49f6305f68d5bb180ff983a41277f7aa3417c65116e20fc58ead18c"
other session must receive and validate result.
The text was updated successfully, but these errors were encountered: