vkext RPC printf debug

Author: hrissan

vkext/vkext-rpc-tl-serialization.cpp

@@ -930,10 +930,16 @@ bool store_function2(VK_ZVAL_API_P arr, zval *fetcher) {
   START_TIMER(store_function2)
   assert(arr);
   if (Z_TYPE_P(arr) != IS_OBJECT) {
+    fprintf(stderr, "store_function2 Z_TYPE not object, %d\n", int(Z_TYPE_P(arr)));
     END_TIMER(store_function2)
     return false;
   }
+  fprintf(stderr, "store_function2 before typedStore call\n");
+  if (EG(exception)) {
+    fprintf(stderr, "typedStore exception before\n");
+  }
   vk_zend_call_known_instance_method(arr, "typedStore", strlen("typedStore"), fetcher, 0, NULL);
+  fprintf(stderr, "store_function2 after typedStore call\n");
   if (EG(exception)) {
     // This behavior is consistent with old code, in this case, query_one will return qid 0
     fprintf(stderr, "typedStore exception\n");
@@ -953,6 +959,7 @@ bool store_function2(VK_ZVAL_API_P arr, zval *fetcher) {
   }
   // when using fetcher, tl_current_function_name will not be accessed. But we set it anyway in case we forgot something.
   tl_current_function_name = "typedStore";
+  fprintf(stderr, "store_function2 before finish\n");
   END_TIMER(store_function2)
   return true;
 }
@@ -1423,13 +1430,15 @@ static zval *convert_rpc_extra_header_to_php_repr(const vkext_rpc::tl::RpcReqRes
 zval *fetch_function2(zval *fetcher) {
   ADD_CNT(fetch_function2)
   START_TIMER(fetch_function2)
+  fprintf(stderr, "fetch_function2 start\n");
 
   assert(fetcher);
   assert(Z_TYPE_P(fetcher) == IS_OBJECT);
 
   vkext_rpc::RpcError rpc_error;
   rpc_error.try_fetch();
   if (rpc_error.error.has_value()) {
+    fprintf(stderr, "fetch_function2 rpc_error.error\n");
     zval *ret = make_query_result_or_error(NULL, rpc_error.error.value(), rpc_error.header.has_value() ? &rpc_error.header.value() : nullptr, rpc_error.flags);
     END_TIMER(fetch_function2)
     return ret;
@@ -1438,7 +1447,9 @@ zval *fetch_function2(zval *fetcher) {
   zval* return_value;
   VK_ALLOC_INIT_ZVAL(return_value);
   ZVAL_UNDEF(return_value);
+  fprintf(stderr, "fetch_function2 before typedFetch call\n");
   vk_zend_call_known_instance_method(fetcher, "typedFetch", strlen("typedFetch"), return_value, 0, NULL);
+  fprintf(stderr, "fetch_function2 after typedFetch call %d\n", Z_TYPE_P(return_value));
   if (EG(exception)) {
     efree(return_value); // it is UNDEF
 
@@ -1451,8 +1462,9 @@ zval *fetch_function2(zval *fetcher) {
     zval *message;
     VK_ALLOC_INIT_ZVAL(message);
     ZVAL_UNDEF(message);
+    fprintf(stderr, "fetch_function2 exception before call getMessage\n");
     vk_zend_call_known_instance_method(&exception_zval, "getMessage", strlen("getMessage"), message, 0, NULL);
-    // fprintf(stderr, "getMessage after call %d\n", Z_TYPE(message));
+    fprintf(stderr, "fetch_function2 exception after call getMessage %d\n", Z_TYPE_P(message));
     assert(Z_TYPE_P(message) == IS_STRING);
 
     OBJ_RELEASE(old_exception);
@@ -1464,18 +1476,21 @@ zval *fetch_function2(zval *fetcher) {
     // vk_zend_update_public_property_string(_err, "error", "hren");
     vk_zend_update_public_property_long(_err, "error_code", -1000);
     END_TIMER(fetch_function2)
+    fprintf(stderr, "fetch_function2 exception return %d\n", Z_TYPE_P(_err));
     return _err;
   }
   // TODO - will remove later when everything works
   // fprintf(stderr, "typedFetch after call %d\n", Z_TYPE_P(return_value));
   if (Z_TYPE_P(return_value) != IS_OBJECT) { // should be never, but that is user code
+    fprintf(stderr, "fetch_function2 typedFetch return_value not object\n");
     zval *_err = create_php_instance(reqResult_error_class_name);
     vk_zend_update_public_property_string(_err, "error", "fetcher->typedFetch() did not return object, as expected");
     vk_zend_update_public_property_long(_err, "error_code", -1000);
     END_TIMER(fetch_function2)
     return _err;
   }
   if (rpc_error.header.has_value()) {
+    fprintf(stderr, "fetch_function2 typedFetch rpc_error.header\n");
     zval *wrapped_err = create_php_instance(reqResult_header_class_name);
     zval *header_php_repr = convert_rpc_extra_header_to_php_repr(rpc_error.header.value());
 
@@ -1485,6 +1500,7 @@ zval *fetch_function2(zval *fetcher) {
     END_TIMER(fetch_function2)
     return wrapped_err;
   }
+  fprintf(stderr, "fetch_function2 typedFetch underscore class\n");
   zval *wrapped_err = create_php_instance(reqResult_underscore_class_name);
   set_field(&wrapped_err, return_value, "result", -1);
   END_TIMER(fetch_function2)

vkext/vkext.h

@@ -7,7 +7,7 @@
 
 #include "vkext/vk_zend.h"
 
-#define VKEXT_VERSION "1.03"
+#define VKEXT_VERSION "1.03-rpc-debug1"
 
 #define VKEXT_NAME "vk_extension"