Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.11.2021 13:38
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 17105616 kB
MemAvailable 31661532 kB
Buffers 0 kB
Cached 14632872 kB
SwapCached 0 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.1
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 2021-11-15 13:37:06
DTS_END                         2021-11-15 13:37:06.6150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59252
TRN_ID                          224828
ATT_ID                          663
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: 8590.64 26602 1030877
customer order: creation 162.95 403 19554
customer order: refuse 14.03 394 1683
order to supplier: creation 43.82 1269 5258
order to supplier: removal 3.36 108594 403
invoice (draft): creation 44.21 3421 5305
invoice (draft): removal 48.13 952 5776
invoice accept: apply 39.44 21613 4733
invoice accept: cancel 43.48 961 5218
customer reserve: creation 3653.25 212 438390
customer reserve: removal 4259.34 67 511121
realization accept: apply 79.48 192 9538
realization accept: cancel 79.60 141 9552
payment from customer: creation 55.88 107 6706
payment from customer: removal 5.61 67 673
payment to supplier: creation 45.21 125 5425
payment to supplier: removal 3.09 84 371
service: total inventory turnovers 3.35 3885 402
service: total monetary turnovers 5.45 617 654
service: aggregate perf. data 0.92 14039 110
service: refresh index statistics 0.04 374906 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-11-2021 13:38:51 to 15-11-2021 13:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3625
TEST_TIME 2 4641
TEST_TIME 3 4065
TEST_TIME 4 4226
TEST_TIME 5 4068
TEST_TIME 6 4488
TEST_TIME 7 4550
TEST_TIME 8 4791
TEST_TIME 9 5565
TEST_TIME 10 5889
TEST_TIME 11 5964
TEST_TIME 12 5915
TEST_TIME 13 5949
TEST_TIME 14 6085
TEST_TIME 15 6330
TEST_TIME 16 6497
TEST_TIME 17 6753
TEST_TIME 18 6933
TEST_TIME 19 7325
TEST_TIME 20 7956
TEST_TIME 21 8115
TEST_TIME 22 8627
TEST_TIME 23 8876
TEST_TIME 24 9388
TEST_TIME 25 9276
TEST_TIME 26 9573
TEST_TIME 27 10598
TEST_TIME 28 10460
TEST_TIME 29 10208
TEST_TIME 30 10026
TEST_TIME 31 9860
TEST_TIME 32 9817
TEST_TIME 33 9643
TEST_TIME 34 9524
TEST_TIME 35 9454
TEST_TIME 36 9346
TEST_TIME 37 9212
TEST_TIME 38 8987
TEST_TIME 39 8908
TEST_TIME 40 8874
TEST_TIME 41 8969
TEST_TIME 42 8946
TEST_TIME 43 8875
TEST_TIME 44 8869
TEST_TIME 45 8793
TEST_TIME 46 8824
TEST_TIME 47 8871
TEST_TIME 48 9005
TEST_TIME 49 9000
TEST_TIME 50 9065
TEST_TIME 51 9003
TEST_TIME 52 8910
TEST_TIME 53 8914
TEST_TIME 54 8829
TEST_TIME 55 8788
TEST_TIME 56 8689
TEST_TIME 57 8624
TEST_TIME 58 8614
TEST_TIME 59 8600
TEST_TIME 60 8624
TEST_TIME 61 8634
TEST_TIME 62 8591
TEST_TIME 63 8712
TEST_TIME 64 8663
TEST_TIME 65 8709
TEST_TIME 66 8809
TEST_TIME 67 8746
TEST_TIME 68 8762
TEST_TIME 69 8772
TEST_TIME 70 8795
TEST_TIME 71 9050
TEST_TIME 72 9070
TEST_TIME 73 9039
TEST_TIME 74 8965
TEST_TIME 75 8879
TEST_TIME 76 8873
TEST_TIME 77 8828
TEST_TIME 78 8783
TEST_TIME 79 8823
TEST_TIME 80 8833
TEST_TIME 81 8794
TEST_TIME 82 8729
TEST_TIME 83 8663
TEST_TIME 84 8648
TEST_TIME 85 8723
TEST_TIME 86 8752
TEST_TIME 87 8736
TEST_TIME 88 8752
TEST_TIME 89 8761
TEST_TIME 90 8830
TEST_TIME 91 8826
TEST_TIME 92 8829
TEST_TIME 93 8887
TEST_TIME 94 8933
TEST_TIME 95 8912
TEST_TIME 96 8880
TEST_TIME 97 8798
TEST_TIME 98 8798
TEST_TIME 99 8753
TEST_TIME 100 8712
TEST_TIME 101 8739
TEST_TIME 102 8725
TEST_TIME 103 8703
TEST_TIME 104 8696
TEST_TIME 105 8694
TEST_TIME 106 8715
TEST_TIME 107 8731
TEST_TIME 108 8706
TEST_TIME 109 8740
TEST_TIME 110 8759
TEST_TIME 111 8760
TEST_TIME 112 8766
TEST_TIME 113 8810
TEST_TIME 114 8803
TEST_TIME 115 8731
TEST_TIME 116 8704
TEST_TIME 117 8695
TEST_TIME 118 8667
TEST_TIME 119 8657
TEST_TIME 120 8591

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-11-2021 13:38:55 to 15-11-2021 13:38:58.

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 1437102 1437102 0 0.00 0 21363 7 0 0
doc_list_biud 1437101 1437101 0 0.00 0 69625 31 0 0
sp_add_invoice_to_stock 4854 4733 121 2.49 37 248825 21613 0 121
sp_cancel_adding_invoice 5218 5218 0 0.00 5 120518 962 0 0
sp_cancel_client_order 1683 1683 0 0.00 4 16503 395 0 0
sp_cancel_customer_reserve 511121 511121 0 0.00 1 61554 68 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 1207 68 0 0
sp_cancel_pay_to_supplier 371 371 0 0.00 3 3697 85 0 0
sp_cancel_supplier_invoice 5787 5776 11 0.19 2 69634 953 0 11
sp_cancel_supplier_order 403 403 0 0.00 9 1283057 108595 0 0
sp_cancel_write_off 9552 9552 0 0.00 1 9133 141 0 0
sp_client_order 19554 19554 0 0.00 4 54639 404 0 0
sp_customer_reserve 438390 438390 0 0.00 0 90645 213 0 0
sp_fill_shopping_cart 30079 30079 0 0.00 1 17477 47 0 0
sp_fill_shopping_cart_clo_res 438388 438388 0 0.00 0 21004 22 0 0
sp_get_clo_for_invoice 4731 4731 0 0.00 0 21285 386 0 0
sp_kill_cost_storno 15814 15814 0 0.00 0 16783 66 0 0
sp_kill_qstorno_ret_qs2qd 1037973 1037973 0 0.00 0 69624 37 0 0
sp_kill_qty_storno 548021 548021 0 0.00 0 69625 79 0 0
sp_lock_selected_doc 19866 19866 0 0.00 0 8109 6 0 0
sp_make_cost_storno 26400 26400 0 0.00 0 14183 59 0 0
sp_multiply_rows_for_pdistr 26399 26399 0 0.00 0 8712 33 0 0
sp_multiply_rows_for_qdistr 19554 19554 0 0.00 0 14385 213 0 0
sp_pay_from_customer 6706 6706 0 0.00 4 9495 107 0 0
sp_pay_to_supplier 5425 5425 0 0.00 5 8149 126 0 0
sp_payment_common 12131 12131 0 0.00 1 7525 19 0 0
sp_qd_handle_on_cancel_clo 1683 1683 0 0.00 0 16498 362 0 0
sp_qd_handle_on_invoice_upd_sts 9948 9948 0 0.00 0 24375 361 0 0
sp_qd_handle_on_reserve_upd_sts 9538 9538 0 0.00 0 37720 60 0 0
sp_reserve_write_off 9538 9538 0 0.00 7 37762 193 0 0
sp_supplier_invoice 5305 5305 0 0.00 7 83791 3422 0 0
sp_supplier_order 5258 5258 0 0.00 6 32386 1270 0 0
srv_aggregate_perf_data 110 110 0 0.00 2524 127473 14040 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1622 197111 51504 0 0
srv_find_qd_qs_mism 985992 985992 0 0.00 0 21363 6 0 0
srv_make_invnt_saldo 402 402 0 0.00 54 114693 3886 0 0
srv_make_money_saldo 654 654 0 0.00 1 11545 617 0 0
srv_recalc_idx_stat 5 5 0 0.00 214186 696440 374907 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4733 4733 0 0.00 0 13384 202 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 1 3543 77 0 0
t$perf-norm:sp_cancel_client_order 1683 1683 0 0.00 0 9950 21 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 2121 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 710 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 371 371 0 0.00 0 1159 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 1419 13 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 21744 1480 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 1473 7 0 0
t$perf-norm:sp_client_order 19554 19554 0 0.00 0 29643 21 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 390 3 0 0
t$perf-norm:sp_pay_from_customer 6706 6706 0 0.00 0 3354 8 0 0
t$perf-norm:sp_pay_to_supplier 5425 5425 0 0.00 0 23930 16 0 0
t$perf-norm:sp_reserve_write_off 9538 9538 0 0.00 0 5221 8 0 0
t$perf-norm:sp_supplier_invoice 5305 5305 0 0.00 0 13510 29 0 0
t$perf-norm:sp_supplier_order 5258 5258 0 0.00 0 6739 15 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 811 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 121 5 0 0
t$perf-norm:srv_make_invnt_saldo 402 402 0 0.00 0 1314 11 0 0
t$perf-norm:srv_make_money_saldo 654 654 0 0.00 0 4329 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9700 9458 242 2.49 0 4788 16 0 242
v_all_customers 35840 35840 0 0.00 0 19 1 0 0
v_all_suppliers 28920 28920 0 0.00 0 19 1 0 0
v_all_wares 39102 39102 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 623 7 0 0
v_cancel_client_order 3366 3366 0 0.00 0 1709 3 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 1062 6 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 3616 8 0 0
v_cancel_payment_to_supplier 742 742 0 0.00 0 3691 8 0 0
v_cancel_supplier_invoice 804 782 22 2.73 0 5092 23 0 22
v_cancel_supplier_order 806 806 0 0.00 0 2341 11 0 0
v_cancel_write_off 1970 1970 0 0.00 0 2621 16 0 0
v_max_id_clo_ord 5249 5249 0 0.00 0 558 1 0 0
v_max_id_clo_res 941 941 0 0.00 0 6826 19 0 0
v_max_id_ord_sup 5278 5278 0 0.00 0 6882 7 0 0
v_max_non_paid_invoice 1492 1492 0 0.00 0 933 3 0 0
v_max_non_paid_realizn 3468 3468 0 0.00 0 7520 5 0 0
v_min_id_clo_ord 5249 5249 0 0.00 0 3171 7 0 0
v_min_id_clo_res 941 941 0 0.00 0 4947 16 0 0
v_min_id_ord_sup 5278 5278 0 0.00 0 6072 12 0 0
v_min_non_paid_invoice 5425 5425 0 0.00 0 5818 11 0 0
v_min_non_paid_realizn 6706 6706 0 0.00 0 5966 11 0 0
v_our_firm 9738 9738 0 0.00 0 15 1 0 0
v_reserve_write_off 19074 19074 0 0.00 0 5501 6 0 0
x_lock_depdocs_on_canc_invoice 10603 10603 0 0.00 0 11689 124 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 4854 302 0 0
x_make_qstorno_1000_1200 5258 5258 0 0.00 2 32381 1186 0 0
x_make_qstorno_1200_2000 5305 5305 0 0.00 4 83387 3242 0 0
x_make_qstorno_2100_3300 438390 438390 0 0.00 0 86445 188 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-11-2021 13:38:59 to 15-11-2021 13:39:03.

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
11:07:10 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 35058 8192000000 9720306096 10014781440 798763888 8.884 100 94 188 0 829780352 8199440 22817024
11:09:56 18180 8192000000 9748358704 10053840896 824711824 9.146 100 86 172 0 859014000 13826848 20475328
11:11:24 16298 8192000000 9800992784 10118754304 863704608 9.537 100 78 156 0 908048256 26607840 17735808
11:12:41 13325 8192000000 9875882128 10207555584 917617328 10.073 100 75 150 0 978666672 44935536 16113808
11:13:57 12140 8192000000 9912071472 10255847424 958500704 10.474 100 73 146 1 1021011760 46981440 15529616
11:15:13 6494 8192000000 9952855104 10303250432 990322000 10.785 100 82 164 1 1072683024 66059392 16301632
11:16:22 3646 8192000000 10015290496 10375606272 1022065872 11.092 100 82 161 1 1124723120 86319248 16338000
11:17:28 4711 8192000000 10021127808 10386186240 1037834608 11.244 100 82 158 1 1133759328 80653520 15271200
11:18:37 1920 8192000000 10051993408 10423169024 1064401024 11.499 100 92 181 1 1159061360 75902336 18758000
11:19:41 1687 8192000000 10078827216 10453450752 1072923648 11.580 100 89 179 1 1184649152 93614016 18111488
11:20:44 24503 8192000000 10021054864 10404655104 1078161264 11.630 100 83 163 2 1137815648 42617584 17036800
11:22:10 5089 8192000000 9998952256 10393268224 1084963568 11.695 100 55 106 1 1123894112 26530896 12399648
11:23:16 4910 8192000000 10020720736 10415140864 1095847984 11.798 100 60 118 1 1134484672 25105424 13531264
11:24:22 8237 8192000000 10004571504 10412068864 1098868304 11.827 100 36 72 2 1136433616 29353024 8212288
11:25:33 22439 8192000000 10015288784 10412974080 1103873312 11.874 100 51 102 1 1124061072 7641584 12546176
11:26:57 4672 8192000000 10034992064 10439876608 1111020848 11.942 100 54 107 3 1156935712 34479824 11435040
11:28:03 4073 8192000000 10003280752 10403463168 1078895952 11.637 98 39 75 2 1108025648 20610464 8519232
11:29:09 4126 8192000000 9970825952 10359365632 1034526608 11.212 96 38 73 1 1052966080 9530400 8909072
11:30:14 16990 8192000000 9980653696 10390917120 1038721936 11.252 97 25 50 0 1084606832 40698128 5186768
11:31:33 3220 8192000000 9960415456 10335363072 1029415120 11.163 99 47 92 1 1064517056 24115120 10986816
11:32:38 10206 8192000000 9949260896 10337550336 1007445328 10.951 98 34 68 0 1033015392 17497328 8072736
11:33:52 64598 8192000000 9947062368 10347216896 1016651312 11.040 98 24 48 0 1035289664 13651488 4986864
11:35:59 4964 8192000000 9981026048 10361348096 1061830336 11.474 100 43 84 4 1090154016 17583264 10740416
11:37:04 21320 8192000000 10003388176 10393493504 1080255088 11.650 100 53 106 0 1108533408 15357168 12921152
11:38:33 15058 8192000000 10042812048 10439270400 1102212656 11.859 100 44 86 0 1139029632 27884672 8932304
11:40:05 83017 8192000000 10109063776 10513637376 1117232128 12.001 100 30 60 2 1206933712 83031232 6670352
11:42:33 5691 8192000000 10145416544 10556489728 1131618320 12.137 100 51 102 0 1258945792 116189952 11137520
11:43:43 24364 8192000000 10162814272 10571632640 1141001792 12.225 100 34 68 0 1275354432 126826624 7526016
11:45:13 4445 8192000000 10233198336 10629574656 1147922064 12.290 100 72 146 2 1333510816 169112016 16476736
11:46:22 3950 8192000000 10242247696 10647187456 1152173184 12.330 100 74 148 1 1351257232 182797056 16286992
11:47:37 9965 8192000000 10256363648 10674896896 1161570768 12.418 100 61 122 0 1373264544 198059888 13633888
11:48:52 6746 8192000000 10271975632 10693459968 1172787552 12.523 100 47 94 0 1373392960 190327184 10278224
11:50:05 2281 8192000000 10328149520 10737958912 1182171856 12.610 100 82 159 2 1430913248 231471760 17269632
11:51:14 3723 8192000000 10363472256 10779832320 1192101760 12.703 100 66 132 0 1466355024 259160576 15092688
11:52:21 2578 8192000000 10413722672 10825195520 1191794304 12.700 100 87 172 0 1515006432 303497232 19714896
11:53:28 1619 8192000000 10440783216 10851926016 1190954032 12.692 100 82 162 1 1540514032 330165536 19394464
11:54:32 12178 8192000000 10480368176 10917998592 1183830736 12.626 100 75 147 0 1582938672 381906384 17201552
11:55:52 7833 8192000000 10456590544 10909401088 1189156336 12.676 100 65 129 1 1558456400 354478128 14821936
11:57:10 6508 8192000000 10450559536 10905300992 1184186368 12.629 100 49 97 0 1575250336 378946192 12117776
11:58:24 5105 8192000000 10424355152 10844700672 1159601776 12.400 100 60 120 0 1525009072 350026208 15381088
11:59:34 9558 8192000000 10366294816 10840027136 1135680576 12.175 99 36 72 0 1483182448 338485952 9015920
12:00:50 13163 8192000000 10318133136 10778578944 1121152640 12.038 99 39 78 1 1425059264 294347184 9559440
12:02:08 16358 8192000000 10280389776 10704035840 1102348432 11.860 98 26 52 0 1349246528 240254192 6643904
12:03:27 29675 8192000000 10262071808 10709221376 1102603088 11.862 99 15 30 1 1342092928 235371728 4118112
12:05:22 18985 8192000000 10036381792 10491301888 1096577392 11.805 99 55 107 3 1157144368 48698496 11868480
12:06:43 9327 8192000000 10055601248 10479579136 1109639024 11.929 100 56 111 2 1152856656 31633472 11584160
12:07:57 183994 8192000000 10032969888 10486792192 1032525216 11.193 93 20 41 2 1086130912 49129264 4476432
12:12:02 60538 8192000000 10027213200 10457497600 1080909840 11.656 98 9 18 0 1109377776 26862032 1605904
12:14:05 121902 8192000000 10052627456 10488913920 1115298112 11.983 100 22 45 1 1160130496 39831904 5000480
12:17:10 49118 8192000000 10088283328 10553384960 1112543680 11.956 99 13 26 0 1191256144 75576672 3135792
12:19:11 52878 8192000000 10048840208 10477694976 1103033664 11.866 98 34 68 1 1127404400 16843984 7526752
12:21:06 58745 8192000000 10128753504 10574028800 1125227696 12.076 99 40 79 2 1217494112 84423856 7842560
12:23:06 77347 8192000000 10128209120 10602590208 1137162560 12.189 100 23 46 2 1212219536 69994448 5062528
12:25:25 56983 8192000000 10080770336 10517299200 1136332560 12.181 100 25 49 1 1180957472 39941584 4683328
12:27:28 80915 8192000000 10090258912 10512117760 1082470624 11.671 95 16 32 2 1129205360 43624160 3110576
12:29:50 125452 8192000000 10069043440 10507812864 1127707936 12.100 100 31 61 0 1204673472 71041536 5924000
12:32:56 60405 8192000000 10071126560 10521071616 1130696944 12.128 99 13 26 1 1172673584 38882800 3093840
12:34:57 62811 8192000000 10080178976 10572886016 1128719840 12.109 99 3 6 0 1172186688 42828944 637904
12:37:00 51012 8192000000 10083406208 10557943808 1139448752 12.210 99 21 43 0 1200290608 55842528 4999328
12:39:00 91132 8192000000 10115870016 10613915648 1156474544 12.370 100 11 22 0 1215442304 57196016 1771744
12:41:37 53361 8192000000 10149733408 10598531072 1135390064 12.172 98 34 68 0 1211571216 69311408 6869744
12:43:31 43954 8192000000 10053363920 10486771712 1101389552 11.851 96 13 26 0 1155768576 51733088 2645936
12:45:15 46087 8192000000 10133432128 10587475968 1118805680 12.016 98 34 68 0 1209095808 82585904 7704224
12:47:02 123444 8192000000 10126619296 10704658432 1112254256 11.954 97 9 18 1 1269682944 155153360 2275328
12:50:05 68980 8192000000 10071735216 10491604992 1085711584 11.702 95 19 38 1 1101089872 11848688 3529600
12:52:15 118840 8192000000 10061695792 10508087296 1069833312 11.550 94 27 53 1 1137830784 62621840 5375632
12:55:15 42511 8192000000 10100721440 10531373056 1071555552 11.567 96 28 56 1 1131136240 53895440 5685248
12:57:05 39888 8192000000 10016334816 10494291968 1075752912 11.607 97 11 22 1 1136313744 57960448 2600384
12:59:35 84276 8192000000 10083380256 10527137792 1076058064 11.610 96 17 34 0 1149996464 69563424 4374976
13:02:06 102469 8192000000 10022922336 10459054080 1058807104 11.445 95 16 31 0 1115515376 53317776 3390496
13:05:00 39471 8192000000 10118669920 10562301952 1105669936 11.891 98 30 60 1 1200377664 88231472 6476256
13:06:44 94772 8192000000 10080939904 10539610112 1119095088 12.018 100 13 26 0 1196954640 75307952 2551600
13:09:19 59721 8192000000 10080561936 10552111104 1106349280 11.898 98 27 54 1 1183935952 72013088 5573584
13:11:20 81539 8192000000 10078743168 10556727296 1116568992 11.995 98 23 46 0 1189807664 69123376 4115296
13:13:42 97314 8192000000 10103110384 10561232896 1148221392 12.293 100 35 70 1 1211637808 56588432 6827984
13:16:40 52875 8192000000 10108646288 10595442688 1126450016 12.088 98 8 16 0 1196895424 69024752 1420656
13:18:36 56523 8192000000 10073734912 10521608192 1100109696 11.839 96 25 50 2 1138708672 33624736 4974240
13:20:42 41687 8192000000 10092488304 10526367744 1133939888 12.158 99 24 48 2 1180643088 41240784 5462416
13:22:28 65002 8192000000 10086549088 10556633088 1144317536 12.256 100 19 37 0 1198247888 50332896 3597456
13:24:35 50222 8192000000 10087179360 10528436224 1124155616 12.066 98 12 22 1 1159588128 32946688 2485824
13:26:26 37611 8192000000 10091316528 10529894400 1121215200 12.038 98 30 59 0 1160929408 34161232 5552976
13:28:05 60228 8192000000 10077881984 10517524480 1091988128 11.762 95 23 44 1 1143546960 46727392 4831440
13:30:11 197110 8192000000 10100884336 10577477632 1140465360 12.220 99 18 35 1 1204654784 60639504 3549920
13:34:28 52274 8192000000 10111061664 10597568512 1154076112 12.348 100 4 8 0 1222332480 67389744 866624
13:36:32 36366 8192000000 10045946880 10508587008 1084539696 11.691 95 14 28 0 1122527536 35107936 2879904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-11-2021 13:39:05 to 15-11-2021 13:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 15-11-2021 13:39:21 to 15-11-2021 13:39:22.

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          223802
MON$OLDEST_ACTIVE               225719
MON$OLDEST_SNAPSHOT             225719
MON$NEXT_TRANSACTION            225719
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                       2011328
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2660 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2660 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2660 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2660 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 ---
:120: 
:121: mscs-test-161.budzdorov.ru    Mon Nov 15 13:37:10 2021
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:123: 
--- end of diff output ---

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

15.11.21 13:39:24. 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
2021-11-15 13:39:26 No severe PSQL-related problems occured
15.11.21 13:39:27. End of report.