Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.12.2022 04:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14713904 kB
MemAvailable 30018400 kB
Buffers 20 kB
Cached 16898016 kB
SwapCached 3156 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-14 04:37:21
DTS_END                         2022-12-14 04:37:21.5840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58592
TRN_ID                          223646
ATT_ID                          611
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8809.18 27306 1057102
customer order: creation 172.37 482 20684
customer order: refuse 14.93 420 1791
order to supplier: creation 46.34 1358 5561
order to supplier: removal 3.23 167029 387
invoice (draft): creation 45.08 3055 5410
invoice (draft): removal 49.81 1042 5977
invoice accept: apply 41.57 24757 4988
invoice accept: cancel 44.02 1186 5282
customer reserve: creation 3798.20 238 455784
customer reserve: removal 4300.83 97 516099
realization accept: apply 82.83 288 9939
realization accept: cancel 79.23 159 9508
payment from customer: creation 59.32 180 7118
payment from customer: removal 5.73 119 687
payment to supplier: creation 49.63 192 5955
payment to supplier: removal 3.17 116 380
service: total inventory turnovers 4.09 3863 491
service: total monetary turnovers 7.91 378 949
service: aggregate perf. data 0.90 16475 108
service: refresh index statistics 0.03 324687 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-12-2022 04:38:11 to 14-12-2022 04:38:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8762
TEST_TIME 2 6150
TEST_TIME 3 6923
TEST_TIME 4 7048
TEST_TIME 5 6769
TEST_TIME 6 6244
TEST_TIME 7 6352
TEST_TIME 8 6324
TEST_TIME 9 6404
TEST_TIME 10 6198
TEST_TIME 11 6560
TEST_TIME 12 6644
TEST_TIME 13 6987
TEST_TIME 14 7068
TEST_TIME 15 7302
TEST_TIME 16 7369
TEST_TIME 17 7693
TEST_TIME 18 7765
TEST_TIME 19 7888
TEST_TIME 20 8087
TEST_TIME 21 8257
TEST_TIME 22 8231
TEST_TIME 23 8349
TEST_TIME 24 8295
TEST_TIME 25 8207
TEST_TIME 26 8416
TEST_TIME 27 8749
TEST_TIME 28 9953
TEST_TIME 29 10433
TEST_TIME 30 10774
TEST_TIME 31 11146
TEST_TIME 32 11205
TEST_TIME 33 11046
TEST_TIME 34 10821
TEST_TIME 35 10638
TEST_TIME 36 10491
TEST_TIME 37 10424
TEST_TIME 38 10281
TEST_TIME 39 10239
TEST_TIME 40 10092
TEST_TIME 41 9967
TEST_TIME 42 9843
TEST_TIME 43 9770
TEST_TIME 44 9722
TEST_TIME 45 9638
TEST_TIME 46 9824
TEST_TIME 47 9919
TEST_TIME 48 9883
TEST_TIME 49 9958
TEST_TIME 50 9939
TEST_TIME 51 9865
TEST_TIME 52 9729
TEST_TIME 53 9602
TEST_TIME 54 9482
TEST_TIME 55 9355
TEST_TIME 56 9321
TEST_TIME 57 9310
TEST_TIME 58 9237
TEST_TIME 59 9210
TEST_TIME 60 9201
TEST_TIME 61 9120
TEST_TIME 62 9107
TEST_TIME 63 9116
TEST_TIME 64 9182
TEST_TIME 65 9246
TEST_TIME 66 9184
TEST_TIME 67 9217
TEST_TIME 68 9251
TEST_TIME 69 9185
TEST_TIME 70 9284
TEST_TIME 71 9318
TEST_TIME 72 9295
TEST_TIME 73 9283
TEST_TIME 74 9279
TEST_TIME 75 9287
TEST_TIME 76 9322
TEST_TIME 77 9425
TEST_TIME 78 9359
TEST_TIME 79 9290
TEST_TIME 80 9263
TEST_TIME 81 9211
TEST_TIME 82 9163
TEST_TIME 83 9131
TEST_TIME 84 9107
TEST_TIME 85 9051
TEST_TIME 86 9030
TEST_TIME 87 8997
TEST_TIME 88 8978
TEST_TIME 89 8903
TEST_TIME 90 8866
TEST_TIME 91 8947
TEST_TIME 92 8973
TEST_TIME 93 8936
TEST_TIME 94 8918
TEST_TIME 95 8907
TEST_TIME 96 8940
TEST_TIME 97 8981
TEST_TIME 98 8972
TEST_TIME 99 9040
TEST_TIME 100 9036
TEST_TIME 101 9152
TEST_TIME 102 9116
TEST_TIME 103 9117
TEST_TIME 104 9164
TEST_TIME 105 9090
TEST_TIME 106 9023
TEST_TIME 107 8989
TEST_TIME 108 8997
TEST_TIME 109 8943
TEST_TIME 110 8899
TEST_TIME 111 8900
TEST_TIME 112 8869
TEST_TIME 113 8843
TEST_TIME 114 8859
TEST_TIME 115 8855
TEST_TIME 116 8830
TEST_TIME 117 8828
TEST_TIME 118 8868
TEST_TIME 119 8863
TEST_TIME 120 8833

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-12-2022 04:38:15 to 14-12-2022 04:38:18.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1476202 1476202 0 0.00 0 29787 13 0 0
doc_list_biud 1476193 1476193 0 0.00 0 30376 40 0 0
sp_add_invoice_to_stock 5100 4988 112 2.19 34 175820 24757 0 112
sp_cancel_adding_invoice 5282 5282 0 0.00 7 90534 1186 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 9101 420 0 0
sp_cancel_customer_reserve 516099 516099 0 0.00 1 30378 98 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 4 3494 120 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 4 3781 117 0 0
sp_cancel_supplier_invoice 5990 5977 13 0.21 3 22126 1043 0 13
sp_cancel_supplier_order 387 387 0 0.00 17 1571601 167030 0 0
sp_cancel_write_off 9508 9508 0 0.00 2 25025 159 0 0
sp_client_order 20684 20684 0 0.00 5 14413 482 0 0
sp_customer_reserve 455784 455784 0 0.00 0 67084 239 0 0
sp_fill_shopping_cart 31642 31642 0 0.00 1 15913 91 0 0
sp_fill_shopping_cart_clo_res 455757 455757 0 0.00 0 16604 19 0 0
sp_get_clo_for_invoice 4947 4947 0 0.00 0 18279 276 0 0
sp_kill_cost_storno 15857 15857 0 0.00 0 29770 74 0 0
sp_kill_qstorno_ret_qs2qd 1048070 1048070 0 0.00 0 30371 43 0 0
sp_kill_qty_storno 553930 553930 0 0.00 0 30375 99 0 0
sp_lock_selected_doc 20562 20562 0 0.00 0 9985 8 0 0
sp_make_cost_storno 27958 27958 0 0.00 0 19911 80 0 0
sp_multiply_rows_for_pdistr 27957 27957 0 0.00 0 7943 39 0 0
sp_multiply_rows_for_qdistr 20683 20683 0 0.00 0 14233 183 0 0
sp_pay_from_customer 7118 7118 0 0.00 5 12810 181 0 0
sp_pay_to_supplier 5955 5955 0 0.00 6 19770 193 0 0
sp_payment_common 13070 13070 0 0.00 2 7478 47 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 1 9093 342 0 0
sp_qd_handle_on_invoice_upd_sts 10229 10229 0 0.00 0 17901 518 0 0
sp_qd_handle_on_reserve_upd_sts 9939 9939 0 0.00 0 15182 70 0 0
sp_reserve_write_off 9939 9939 0 0.00 8 15192 289 0 0
sp_supplier_invoice 5410 5410 0 0.00 9 78286 3055 0 0
sp_supplier_order 5561 5561 0 0.00 7 30320 1358 0 0
srv_aggregate_perf_data 108 108 0 0.00 2340 194485 16475 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 755 220266 35689 0 0
srv_find_qd_qs_mism 1008008 1008008 0 0.00 0 24985 9 0 0
srv_make_invnt_saldo 491 491 0 0.00 63 77384 3863 0 0
srv_make_money_saldo 949 949 0 0.00 1 14387 378 0 0
srv_recalc_idx_stat 4 4 0 0.00 83711 565267 324687 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4994 4994 0 0.00 0 25100 951 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 1 6595 473 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 1906 28 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 1302 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 1595 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 427 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 761 31 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 91464 6924 0 0
t$perf-norm:sp_cancel_write_off 939 939 0 0.00 0 2418 19 0 0
t$perf-norm:sp_client_order 20684 20684 0 0.00 0 5554 37 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 3430 24 0 0
t$perf-norm:sp_pay_from_customer 7118 7118 0 0.00 0 23578 24 0 0
t$perf-norm:sp_pay_to_supplier 5955 5955 0 0.00 0 4039 21 0 0
t$perf-norm:sp_reserve_write_off 9939 9939 0 0.00 0 7132 26 0 0
t$perf-norm:sp_supplier_invoice 5410 5410 0 0.00 0 10694 38 0 0
t$perf-norm:sp_supplier_order 5561 5561 0 0.00 0 8544 35 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 531 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 118 7 0 0
t$perf-norm:srv_make_invnt_saldo 491 491 0 0.00 0 5298 26 0 0
t$perf-norm:srv_make_money_saldo 949 949 0 0.00 0 1177 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 163 55 0 0
v_add_invoice_to_stock 10112 9888 224 2.21 0 7717 29 0 224
v_all_customers 37504 37504 0 0.00 0 234 4 0 0
v_all_suppliers 30676 30676 0 0.00 0 219 5 0 0
v_all_wares 41354 41354 0 0.00 0 89 4 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 392 16 0 0
v_cancel_client_order 3582 3582 0 0.00 0 3160 13 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 3420 15 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 1 2124 14 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 3675 15 0 0
v_cancel_supplier_invoice 872 846 26 2.98 0 918 23 0 26
v_cancel_supplier_order 774 774 0 0.00 0 1004 12 0 0
v_cancel_write_off 1878 1878 0 0.00 0 5355 28 0 0
v_max_id_clo_ord 5556 5556 0 0.00 0 5285 6 0 0
v_max_id_clo_res 997 997 0 0.00 0 10928 26 0 0
v_max_id_ord_sup 5405 5405 0 0.00 0 4984 8 0 0
v_max_non_paid_invoice 1575 1575 0 0.00 0 158 8 0 0
v_max_non_paid_realizn 3826 3826 0 0.00 0 1008 8 0 0
v_min_id_clo_ord 5556 5556 0 0.00 0 2167 9 0 0
v_min_id_clo_res 996 996 0 0.00 0 926 10 0 0
v_min_id_ord_sup 5405 5405 0 0.00 0 4917 10 0 0
v_min_non_paid_invoice 5953 5953 0 0.00 0 7472 18 0 0
v_min_non_paid_realizn 7117 7117 0 0.00 0 3420 17 0 0
v_our_firm 10430 10430 0 0.00 0 66 5 0 0
v_reserve_write_off 19878 19878 0 0.00 0 6899 13 0 0
x_lock_depdocs_on_canc_invoice 10836 10836 0 0.00 0 15780 132 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 2368 319 0 0
x_make_qstorno_1000_1200 5561 5561 0 0.00 2 30281 1176 0 0
x_make_qstorno_1200_2000 5410 5410 0 0.00 4 78247 2807 0 0
x_make_qstorno_2100_3300 455781 455781 0 0.00 0 67084 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-12-2022 04:38:19 to 14-12-2022 04:38:23.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:25 4 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:26 19282 8192000000 9720079616 10017533952 801641824 8.913 100 81 162 0 828373136 7131104 19600208
02:09:52 19816 8192000000 9755190384 10063990784 828863520 9.188 100 86 170 0 860291456 11071296 20356640
02:11:21 16185 8192000000 9797421360 10117316608 859242128 9.493 100 75 150 0 899310896 22771568 17297200
02:12:41 7810 8192000000 9875307712 10210177024 922983232 10.126 100 86 169 0 981558400 39759744 18815424
02:13:57 3654 8192000000 9936912784 10281566208 971824080 10.605 100 88 174 0 1050425088 60234704 18366304
02:15:04 2202 8192000000 9974201760 10327818240 1005789200 10.935 100 93 178 0 1089131584 64916912 18425472
02:16:09 1342 8192000000 10019456912 10383011840 1027523936 11.145 100 89 170 0 1138736672 94287952 16924784
02:17:13 1114 8192000000 10017181360 10382811136 1043601552 11.299 100 96 183 2 1135117920 73679920 17836448
02:18:16 978 8192000000 10039362720 10413256704 1065545984 11.510 100 99 184 1 1159402160 75158224 18697952
02:19:18 945 8192000000 10041371792 10418163712 1065492640 11.509 100 94 180 3 1160389920 76853872 18043408
02:20:20 1025 8192000000 10039025312 10420899840 1073929008 11.590 100 87 170 0 1156464576 66014560 16521008
02:21:21 12010 8192000000 10020035840 10411188224 1076212704 11.611 100 56 112 0 1152300368 64883744 11203920
02:22:34 1000 8192000000 10040879392 10434027520 1078439344 11.633 100 82 163 1 1161347264 65011728 17896192
02:23:38 1192 8192000000 10030855952 10418929664 1083472896 11.681 100 91 165 4 1149856704 49534096 16849712
02:24:40 924 8192000000 10056516064 10450231296 1090966224 11.752 100 86 169 3 1179907760 71138512 17803024
02:25:42 2090 8192000000 10041866336 10429620224 1094258592 11.783 100 85 155 2 1161633408 52026816 15348000
02:26:45 1458 8192000000 10063974592 10454818816 1095744688 11.797 100 85 164 2 1185316272 72983856 16587728
02:27:49 1043 8192000000 10055493808 10448453632 1095372304 11.794 100 94 168 7 1176805088 64368496 17064288
02:28:51 1088 8192000000 10025318512 10410643456 1074133024 11.592 100 86 164 0 1147206688 56193312 16880352
02:29:53 901 8192000000 10025818208 10414579712 1075865248 11.608 100 81 157 2 1149303584 56417200 17021136
02:30:55 6052 8192000000 10019423552 10415157248 1061633280 11.472 100 74 148 0 1141796384 65181136 14981968
02:32:03 20430 8192000000 9930297680 10316664832 1001979984 10.898 94 34 64 1 1048814672 38971504 7863184
02:33:27 4506 8192000000 9930808192 10300616704 1018312816 11.056 98 82 150 5 1055885888 21418032 16155040
02:34:34 12629 8192000000 9953657408 10335195136 1001505472 10.893 96 49 94 0 1061106336 50734160 8866704
02:35:47 1010 8192000000 9973421808 10344427520 1022533504 11.096 100 90 171 0 1096274064 54334096 19406464
02:36:49 1385 8192000000 9981560496 10353741824 1028453936 11.154 100 95 173 1 1098526960 50808848 19264176
02:37:57 19589 8192000000 9989970928 10375639040 1058593008 11.443 100 57 108 3 1114218800 44413568 11212224
02:39:27 12224 8192000000 10028468848 10420760576 1079520512 11.643 100 64 118 2 1152666704 61658912 11487280
02:40:45 2715 8192000000 10069693168 10457882624 1099176464 11.830 100 77 143 1 1190168608 76305456 14686688
02:41:56 1420 8192000000 10105043248 10497667072 1113767712 11.968 100 84 166 0 1226031904 93011872 19252320
02:43:01 13850 8192000000 10158315280 10558664704 1126354384 12.087 100 68 132 1 1270717408 130565424 13797600
02:44:27 5513 8192000000 10176452208 10587340800 1143395280 12.247 100 57 112 1 1300405040 144766608 12243152
02:45:41 3308 8192000000 10227629200 10641121280 1159536112 12.399 100 74 142 2 1347970768 172579648 15855008
02:46:49 1148 8192000000 10279492272 10680356864 1174165440 12.536 100 93 177 0 1390865648 196913264 19786944
02:47:52 3774 8192000000 10325659840 10743250944 1183247728 12.620 100 82 157 0 1432682000 231981168 17453104
02:48:59 1988 8192000000 10320446640 10736349184 1185828128 12.645 100 82 157 0 1436021856 231123520 19070208
02:50:03 737 8192000000 10369466688 10785648640 1180871376 12.598 100 93 176 1 1478124032 277447328 19805328
02:51:06 1227 8192000000 10376481264 10799714304 1188747856 12.672 100 90 172 1 1488677248 279358272 20571120
02:52:09 998 8192000000 10415169104 10833211392 1195076640 12.731 100 95 177 0 1520424720 305619072 19729008
02:53:14 2051 8192000000 10432413632 10855346176 1194032400 12.721 100 97 189 0 1541056048 324263200 22760448
02:54:18 1270 8192000000 10459441712 10897498112 1203005552 12.804 100 90 170 0 1570621344 347060096 20555696
02:55:23 1186 8192000000 10494678416 10907398144 1196420272 12.743 100 93 181 0 1604776000 384274320 24081408
02:56:27 801 8192000000 10516077040 10930790400 1169350160 12.491 99 94 182 1 1622559280 432167008 21042112
02:57:30 1139 8192000000 10520406880 10963623936 1192918048 12.711 100 89 177 1 1621970432 408290656 20761728
02:58:35 878 8192000000 10509095104 10924888064 1177169632 12.564 99 96 189 0 1613440176 414357472 21913072
02:59:38 2241 8192000000 10553841264 10987806720 1184901584 12.636 100 78 155 0 1657752560 453862288 18988688
03:00:48 899 8192000000 10575943104 10990227456 1174290736 12.537 100 83 164 1 1680671728 486038832 20342160
03:02:02 3391 8192000000 10601218528 11017031680 1154923504 12.356 99 74 138 1 1698027712 526217248 16886960
03:03:09 2950 8192000000 10619426544 11092983808 1163276224 12.434 100 55 110 0 1724941888 545987888 15677776
03:04:16 1165 8192000000 10534260640 11070136320 1165071504 12.451 100 81 150 4 1635182736 452409536 17701696
03:05:18 12331 8192000000 10392361024 10949443584 1142837088 12.242 100 49 98 0 1552186320 396764080 12585152
03:06:53 24262 8192000000 10242592096 10714312704 1132664304 12.146 99 19 38 0 1344327184 206505104 5157776
03:08:17 14442 8192000000 10076188320 10503069696 1142737840 12.241 100 36 63 0 1168405104 19831024 5836240
03:09:33 49838 8192000000 10093455152 10556047360 1138819648 12.204 99 17 34 0 1214922176 72355312 3747216
03:11:58 23236 8192000000 10075826880 10515705856 1133126160 12.151 99 29 53 1 1176259840 37041840 6091840
03:13:22 66869 8192000000 10068063312 10553643008 1128227744 12.105 99 6 12 0 1185182928 55815456 1139728
03:15:31 97750 8192000000 10092736368 10546511872 1119905520 12.026 97 22 43 1 1163608128 39239728 4462880
03:18:15 15894 8192000000 10115955280 10553864192 1150885392 12.318 100 50 94 1 1206631984 46175776 9570816
03:19:37 10070 8192000000 10147754000 10580430848 1154151456 12.348 100 56 109 3 1241778960 76391712 11235792
03:20:48 52840 8192000000 10182893104 10655469568 1148888384 12.299 99 18 36 0 1276525440 123379024 4258032
03:23:06 15793 8192000000 10181505616 10621018112 1136278224 12.181 100 37 72 0 1269404144 125009760 8116160
03:24:22 99760 8192000000 10096524208 10600308736 1144806672 12.261 100 7 14 0 1223270336 77131680 1331984
03:27:03 220266 8192000000 10072750400 10551230464 1084561920 11.691 94 16 32 0 1128581072 40367504 3651648
03:31:45 72406 8192000000 10101703616 10525966336 1145680208 12.269 100 65 120 6 1196021216 37795056 12545952
03:33:58 13041 8192000000 10123274656 10561880064 1149048688 12.301 100 57 103 0 1224935568 66599152 9287728
03:35:11 36689 8192000000 10164828192 10604830720 1163539712 12.436 100 48 90 1 1260408784 86620992 10248080
03:37:02 62403 8192000000 10184371856 10668224512 1147639552 12.287 98 21 42 0 1281040496 128269632 5131312
03:39:05 67335 8192000000 10150992016 10614087680 1107423536 11.908 95 14 28 0 1204028512 93355872 3249104
03:41:16 53630 8192000000 10102777328 10660057088 1111220976 11.944 98 11 22 0 1214318704 100701088 2396640
03:43:10 33541 8192000000 10091776400 10535538688 1130109408 12.122 99 23 44 0 1169613488 34509392 4994688
03:44:45 32475 8192000000 10113428736 10560745472 1100478896 11.842 97 36 72 1 1164140432 56261600 7399936
03:46:21 65847 8192000000 10137069440 10595217408 1094399744 11.784 97 27 54 1 1178498480 78694400 5404336
03:48:30 43481 8192000000 10074175728 10556145664 1108151280 11.915 98 26 51 0 1159138208 45395600 5591328
03:50:25 51645 8192000000 10107348240 10565099520 1131180496 12.132 100 20 41 0 1218362288 82917408 4264384
03:52:17 82472 8192000000 10143320144 10600869888 1117002640 11.999 98 16 32 0 1203003072 82390544 3609888
03:54:49 51517 8192000000 10091268608 10528849920 1074815280 11.598 95 27 54 0 1125406224 45294512 5296432
03:56:44 105382 8192000000 10042229248 10533363712 1095601232 11.796 98 25 46 0 1149780976 48883088 5296656
03:59:33 6443 8192000000 10042994224 10474967040 1108809904 11.921 100 67 119 0 1138945472 17472208 12663360
04:00:40 45152 8192000000 10044809520 10498342912 1111736464 11.949 100 16 32 0 1174917264 60040176 3140624
04:02:25 40418 8192000000 10059206432 10518454272 1115021440 11.980 100 27 54 0 1166830832 45317376 6492016
04:04:09 48316 8192000000 10080672240 10538934272 1117484496 12.003 100 19 38 0 1192175616 70768800 3922320
04:05:59 43770 8192000000 10094489904 10536517632 1128820160 12.110 100 26 53 1 1182466560 48076544 5569856
04:07:45 120358 8192000000 10071118496 10545754112 1111859440 11.950 98 6 12 0 1173017504 60200080 957984
04:10:46 42230 8192000000 10135406624 10576384000 1138615600 12.203 99 20 40 0 1221134640 78347904 4171136
04:12:32 44917 8192000000 10147665968 10602082304 1120678768 12.033 97 33 67 0 1240082288 111742272 7661248
04:14:17 34106 8192000000 10163247344 10622410752 1139515824 12.211 99 30 60 0 1233257696 87634160 6107712
04:15:52 52912 8192000000 10170866208 10657320960 1133693744 12.156 99 39 78 0 1274291760 131760688 8837328
04:18:32 43688 8192000000 10071727680 10512179200 1103392480 11.870 97 27 50 0 1139446944 30665216 5389248
04:20:19 38761 8192000000 10146182112 10597814272 1124692544 12.071 98 53 106 1 1222018576 86513104 10812928
04:21:58 102407 8192000000 10039955776 10482913280 1115131792 11.981 98 22 42 0 1152161184 32722848 4306544
04:24:42 24374 8192000000 10106364224 10550652928 1119941920 12.026 100 53 104 0 1193801680 63022608 10837152
04:27:09 65103 8192000000 10070113248 10533675008 1108359424 11.917 98 17 35 0 1166619264 54347584 3912256
04:29:21 86658 8192000000 10088577616 10569773056 1116198976 11.991 98 5 10 1 1177664048 60371296 1093776
04:32:28 34783 8192000000 10097827104 10546683904 1097732912 11.816 96 19 38 0 1146157680 44298080 4126688
04:34:09 27855 8192000000 10091526960 10527928320 1082007936 11.667 95 31 62 0 1159511504 71093696 6409872
04:35:49 34010 8192000000 10114587344 10548719616 1132106096 12.141 100 27 51 3 1196727408 59464608 5156704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-12-2022 04:38:25 to 14-12-2022 04:38:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-12-2022 04:38:42 to 14-12-2022 04:38:44.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          221208
MON$OLDEST_ACTIVE               223793
MON$OLDEST_SNAPSHOT             223793
MON$NEXT_TRANSACTION            223793
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2060128
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2884 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2884 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2884 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2884 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:245: 
:246: mscs-test-161.budzdorov.ru    Wed Dec 14 04:37:25 2022
:247:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:248: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

14.12.22 04:38:45. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-12-14 04:38:48 No severe PSQL-related problems occured
14.12.22 04:38:49. End of report.