Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.12.2022 10: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 14768944 kB
MemAvailable 30108116 kB
Buffers 0 kB
Cached 16955480 kB
SwapCached 3212 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-13 10:37:22
DTS_END                         2022-12-13 10:37:22.2810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46688
TRN_ID                          211081
ATT_ID                          580
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: 8537.04 26270 1024445
customer order: creation 164.77 497 19772
customer order: refuse 13.93 497 1671
order to supplier: creation 42.91 1521 5149
order to supplier: removal 3.07 206768 368
invoice (draft): creation 43.15 3436 5178
invoice (draft): removal 47.57 1208 5708
invoice accept: apply 40.23 27792 4828
invoice accept: cancel 42.92 1548 5150
customer reserve: creation 3715.35 262 445842
customer reserve: removal 4144.53 119 497344
realization accept: apply 81.63 299 9796
realization accept: cancel 73.58 186 8830
payment from customer: creation 56.73 201 6807
payment from customer: removal 5.76 147 691
payment to supplier: creation 45.51 216 5461
payment to supplier: removal 3.43 186 412
service: total inventory turnovers 3.61 4794 433
service: total monetary turnovers 7.44 460 893
service: aggregate perf. data 0.90 16540 108
service: refresh index statistics 0.03 258716 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-12-2022 10:38:53 to 13-12-2022 10:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10340
TEST_TIME 2 7852
TEST_TIME 3 7032
TEST_TIME 4 6786
TEST_TIME 5 7011
TEST_TIME 6 6865
TEST_TIME 7 6719
TEST_TIME 8 6894
TEST_TIME 9 6957
TEST_TIME 10 6915
TEST_TIME 11 7082
TEST_TIME 12 7059
TEST_TIME 13 6934
TEST_TIME 14 6792
TEST_TIME 15 6775
TEST_TIME 16 6919
TEST_TIME 17 7032
TEST_TIME 18 6910
TEST_TIME 19 6959
TEST_TIME 20 7024
TEST_TIME 21 7354
TEST_TIME 22 7296
TEST_TIME 23 7223
TEST_TIME 24 7247
TEST_TIME 25 7412
TEST_TIME 26 7477
TEST_TIME 27 7431
TEST_TIME 28 7502
TEST_TIME 29 7506
TEST_TIME 30 7815
TEST_TIME 31 7839
TEST_TIME 32 7870
TEST_TIME 33 7853
TEST_TIME 34 8169
TEST_TIME 35 8836
TEST_TIME 36 9356
TEST_TIME 37 9827
TEST_TIME 38 10231
TEST_TIME 39 10464
TEST_TIME 40 10296
TEST_TIME 41 10237
TEST_TIME 42 10000
TEST_TIME 43 10009
TEST_TIME 44 9943
TEST_TIME 45 9824
TEST_TIME 46 9700
TEST_TIME 47 9586
TEST_TIME 48 9454
TEST_TIME 49 9453
TEST_TIME 50 9309
TEST_TIME 51 9240
TEST_TIME 52 9325
TEST_TIME 53 9262
TEST_TIME 54 9098
TEST_TIME 55 9005
TEST_TIME 56 9045
TEST_TIME 57 8988
TEST_TIME 58 9003
TEST_TIME 59 8908
TEST_TIME 60 8898
TEST_TIME 61 8831
TEST_TIME 62 8723
TEST_TIME 63 8731
TEST_TIME 64 8700
TEST_TIME 65 8640
TEST_TIME 66 8646
TEST_TIME 67 8594
TEST_TIME 68 8604
TEST_TIME 69 8587
TEST_TIME 70 8636
TEST_TIME 71 8602
TEST_TIME 72 8697
TEST_TIME 73 8729
TEST_TIME 74 8762
TEST_TIME 75 8849
TEST_TIME 76 8887
TEST_TIME 77 8888
TEST_TIME 78 8981
TEST_TIME 79 8950
TEST_TIME 80 8879
TEST_TIME 81 8858
TEST_TIME 82 8775
TEST_TIME 83 8813
TEST_TIME 84 8758
TEST_TIME 85 8743
TEST_TIME 86 8735
TEST_TIME 87 8664
TEST_TIME 88 8677
TEST_TIME 89 8677
TEST_TIME 90 8636
TEST_TIME 91 8636
TEST_TIME 92 8676
TEST_TIME 93 8670
TEST_TIME 94 8660
TEST_TIME 95 8655
TEST_TIME 96 8685
TEST_TIME 97 8703
TEST_TIME 98 8736
TEST_TIME 99 8704
TEST_TIME 100 8703
TEST_TIME 101 8692
TEST_TIME 102 8747
TEST_TIME 103 8782
TEST_TIME 104 8831
TEST_TIME 105 8821
TEST_TIME 106 8758
TEST_TIME 107 8730
TEST_TIME 108 8746
TEST_TIME 109 8721
TEST_TIME 110 8686
TEST_TIME 111 8684
TEST_TIME 112 8637
TEST_TIME 113 8595
TEST_TIME 114 8549
TEST_TIME 115 8536
TEST_TIME 116 8583
TEST_TIME 117 8651
TEST_TIME 118 8624
TEST_TIME 119 8609
TEST_TIME 120 8556

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-12-2022 10:38:57 to 13-12-2022 10:39:00.

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 1432817 1432817 0 0.00 0 22683 15 0 0
doc_list_biud 1432815 1432815 0 0.00 0 36722 48 0 0
sp_add_invoice_to_stock 4908 4828 80 1.62 41 242439 27793 0 80
sp_cancel_adding_invoice 5150 5150 0 0.00 6 76174 1548 0 0
sp_cancel_client_order 1671 1671 0 0.00 6 10566 497 0 0
sp_cancel_customer_reserve 497344 497344 0 0.00 1 36754 119 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 2003 148 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 8699 187 0 0
sp_cancel_supplier_invoice 5710 5708 2 0.03 2 24361 1209 0 2
sp_cancel_supplier_order 368 368 0 0.00 14 1545463 206768 0 0
sp_cancel_write_off 8830 8830 0 0.00 1 19702 187 0 0
sp_client_order 19772 19772 0 0.00 5 23616 497 0 0
sp_customer_reserve 445842 445842 0 0.00 0 136875 262 0 0
sp_fill_shopping_cart 30097 30097 0 0.00 1 19122 92 0 0
sp_fill_shopping_cart_clo_res 445811 445811 0 0.00 0 22414 21 0 0
sp_get_clo_for_invoice 4780 4780 0 0.00 0 28414 374 0 0
sp_kill_cost_storno 15083 15083 0 0.00 0 13041 87 0 0
sp_kill_qstorno_ret_qs2qd 1009594 1009594 0 0.00 0 36631 52 0 0
sp_kill_qty_storno 533645 533645 0 0.00 0 36714 120 0 0
sp_lock_selected_doc 20161 20161 0 0.00 0 7303 7 0 0
sp_make_cost_storno 26843 26843 0 0.00 0 16273 87 0 0
sp_multiply_rows_for_pdistr 26841 26841 0 0.00 0 7662 42 0 0
sp_multiply_rows_for_qdistr 19772 19772 0 0.00 0 9854 177 0 0
sp_pay_from_customer 6807 6807 0 0.00 5 11092 201 0 0
sp_pay_to_supplier 5461 5461 0 0.00 7 11812 217 0 0
sp_payment_common 12267 12267 0 0.00 2 8811 52 0 0
sp_qd_handle_on_cancel_clo 1671 1671 0 0.00 1 8894 412 0 0
sp_qd_handle_on_invoice_upd_sts 9928 9928 0 0.00 0 34584 635 0 0
sp_qd_handle_on_reserve_upd_sts 9796 9796 0 0.00 0 11748 67 0 0
sp_reserve_write_off 9796 9796 0 0.00 8 17969 299 0 0
sp_supplier_invoice 5178 5178 0 0.00 7 74581 3437 0 0
sp_supplier_order 5149 5149 0 0.00 7 80925 1521 0 0
srv_aggregate_perf_data 108 108 0 0.00 2541 157872 16541 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 684 183735 34263 0 0
srv_find_qd_qs_mism 976553 976553 0 0.00 0 22682 11 0 0
srv_make_invnt_saldo 433 433 0 0.00 60 59201 4794 0 0
srv_make_money_saldo 893 893 0 0.00 1 21855 460 0 0
srv_recalc_idx_stat 4 4 0 0.00 37715 419992 258717 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4828 4828 0 0.00 1 11849 1063 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 1 6762 494 0 0
t$perf-norm:sp_cancel_client_order 1671 1671 0 0.00 0 11052 35 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 5375 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 452 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1279 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 703 39 0 0
t$perf-norm:sp_cancel_supplier_order 368 368 0 0.00 0 91046 8641 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 854 22 0 0
t$perf-norm:sp_client_order 19772 19772 0 0.00 0 6989 40 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 935 14 0 0
t$perf-norm:sp_pay_from_customer 6807 6807 0 0.00 0 13943 25 0 0
t$perf-norm:sp_pay_to_supplier 5461 5461 0 0.00 0 6675 25 0 0
t$perf-norm:sp_reserve_write_off 9796 9796 0 0.00 0 8833 25 0 0
t$perf-norm:sp_supplier_invoice 5178 5178 0 0.00 0 10593 37 0 0
t$perf-norm:sp_supplier_order 5149 5149 0 0.00 0 2334 35 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 2521 44 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 53 5 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 1569 23 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 960 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 198 94 0 0
v_add_invoice_to_stock 9714 9554 160 1.64 0 8410 24 0 160
v_all_customers 35928 35928 0 0.00 0 76 4 0 0
v_all_suppliers 28498 28498 0 0.00 0 71 5 0 0
v_all_wares 39544 39544 0 0.00 0 60 4 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 271 15 0 0
v_cancel_client_order 3342 3342 0 0.00 0 10247 14 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 626 12 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 381 11 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 7714 29 0 0
v_cancel_supplier_invoice 800 796 4 0.50 0 6394 30 0 4
v_cancel_supplier_order 736 736 0 0.00 0 1116 14 0 0
v_cancel_write_off 1922 1922 0 0.00 0 4702 28 0 0
v_max_id_clo_ord 5149 5149 0 0.00 0 7958 7 0 0
v_max_id_clo_res 949 949 0 0.00 0 2478 14 0 0
v_max_id_ord_sup 5176 5176 0 0.00 0 7614 9 0 0
v_max_non_paid_invoice 1537 1537 0 0.00 0 1261 9 0 0
v_max_non_paid_realizn 3679 3679 0 0.00 0 8190 12 0 0
v_min_id_clo_ord 5149 5149 0 0.00 0 2901 10 0 0
v_min_id_clo_res 949 949 0 0.00 0 581 9 0 0
v_min_id_ord_sup 5176 5176 0 0.00 0 5107 10 0 0
v_min_non_paid_invoice 5461 5461 0 0.00 0 5759 17 0 0
v_min_non_paid_realizn 6806 6806 0 0.00 0 8798 21 0 0
v_our_firm 9870 9870 0 0.00 0 55 5 0 0
v_reserve_write_off 19592 19592 0 0.00 0 6504 12 0 0
x_lock_depdocs_on_canc_invoice 10460 10460 0 0.00 0 39667 164 0 0
x_lock_depdocs_on_canc_sup_ord 368 368 0 0.00 0 4625 414 0 0
x_make_qstorno_1000_1200 5149 5149 0 0.00 2 80894 1334 0 0
x_make_qstorno_1200_2000 5178 5178 0 0.00 2 74558 3177 0 0
x_make_qstorno_2100_3300 445842 445842 0 0.00 0 136840 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-12-2022 10:39:01 to 13-12-2022 10:39:05.

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
08:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 36409 8192000000 9726247376 10025824256 803150768 8.928 100 91 182 0 832530448 7637232 21742448
08:10:11 17995 8192000000 9754038224 10064039936 832721552 9.227 100 89 178 0 865855808 12245040 20889216
08:11:39 15065 8192000000 9793544160 10113077248 862278144 9.523 100 76 152 0 902890032 23120704 17491184
08:13:00 12727 8192000000 9853514736 10188337152 910554384 10.003 100 78 154 0 963478912 36329504 16595024
08:14:18 3743 8192000000 9909912560 10253299712 957721616 10.467 100 81 160 0 1023927840 49421136 16785088
08:15:23 3988 8192000000 9966308160 10318487552 997125824 10.851 100 89 172 0 1085673440 71441248 17106368
08:16:31 1230 8192000000 9993913792 10356543488 1016416176 11.037 100 95 184 2 1109927648 75162432 18349040
08:17:35 1262 8192000000 10010575360 10378739712 1040321424 11.268 100 89 171 0 1130664464 72913856 17429184
08:18:38 801 8192000000 10036736368 10410438656 1051163552 11.372 100 98 188 2 1160056176 90592512 18300112
08:19:41 1879 8192000000 10039185840 10417901568 1060082224 11.457 100 92 179 1 1163983808 85652800 18248784
08:20:45 1346 8192000000 10054977968 10431160320 1064137248 11.496 100 96 189 4 1177608208 94308240 19162720
08:21:49 1166 8192000000 10061019728 10440982528 1069716640 11.549 100 96 182 1 1183926560 96015280 18194640
08:22:51 847 8192000000 10055584336 10442051584 1072936208 11.580 100 97 189 0 1177661600 85680720 19044672
08:23:54 1319 8192000000 10061749312 10448850944 1083097840 11.677 100 97 194 0 1182495424 79160336 20237248
08:24:58 959 8192000000 10054879920 10441924608 1088812736 11.731 100 85 161 0 1177606240 73434816 15358688
08:26:00 1457 8192000000 10031078784 10416467968 1095906704 11.799 100 87 150 4 1158436496 48019472 14510320
08:27:03 946 8192000000 10071452832 10462728192 1100679840 11.844 100 98 182 1 1193816496 74498816 18637840
08:28:06 1082 8192000000 10077421632 10469883904 1094472432 11.785 100 98 187 0 1202005040 89182032 18350576
08:29:09 1303 8192000000 10059608416 10448179200 1093652192 11.777 100 96 187 4 1183947632 70134576 20160864
08:30:12 1206 8192000000 10064632880 10446868480 1077343120 11.622 100 99 188 0 1186228336 91136768 17748448
08:31:14 1161 8192000000 10019049840 10400923648 1063377936 11.489 99 93 171 3 1145573088 64514400 17680752
08:32:16 1034 8192000000 10004949056 10384805888 1042667616 11.290 99 93 169 5 1127240256 66927072 17645568
08:33:19 1097 8192000000 10013511472 10390425600 1061284912 11.469 100 95 177 5 1133091312 53274960 18531440
08:34:23 1364 8192000000 9982277120 10365816832 1052933600 11.389 100 77 137 2 1108945728 42269552 13742576
08:35:26 1064 8192000000 9963229632 10336022528 1028213152 11.151 99 88 169 1 1088157360 42502576 17441632
08:36:31 2003 8192000000 9972297232 10351439872 1039199520 11.257 100 78 150 3 1102075248 46542048 16333680
08:37:36 885 8192000000 9995566560 10367700992 1050971904 11.370 100 87 164 1 1114866192 49004928 14889360
08:38:45 9757 8192000000 10030940064 10415685632 1063195152 11.487 100 75 140 2 1160058272 83130640 13732480
08:40:01 10496 8192000000 10030069040 10405965824 1076543520 11.615 100 89 165 2 1147995920 57052016 14400384
08:41:18 2337 8192000000 10037174032 10438225920 1093135984 11.772 100 71 133 2 1168421168 61077792 14207392
08:42:23 8372 8192000000 10071103280 10466324480 1096515088 11.805 100 62 121 0 1192172928 83489456 12168384
08:43:36 1690 8192000000 10112216752 10500878336 1115819264 11.987 100 89 166 1 1232206976 99480032 16907680
08:44:43 4070 8192000000 10146160080 10541264896 1118250224 12.010 100 73 136 3 1265823088 134148672 13424192
08:45:51 1103 8192000000 10195097040 10588626944 1130669552 12.128 100 90 168 3 1308633152 160022288 17941312
08:46:58 9646 8192000000 10202785856 10606661632 1141913408 12.234 100 70 136 2 1313526384 156385952 15227024
08:48:15 1460 8192000000 10204282128 10622697472 1153267856 12.340 100 92 175 0 1325075376 151720880 20086640
08:49:23 1560 8192000000 10211066448 10613907456 1163729440 12.438 100 81 154 0 1320924784 140291504 16903840
08:50:30 759 8192000000 10263923904 10660814848 1164649776 12.447 100 100 197 1 1379774000 192353072 22771152
08:51:35 1550 8192000000 10292439904 10691567616 1180437536 12.594 100 82 162 1 1406975248 208459792 18077920
08:52:40 2424 8192000000 10339585584 10740846592 1173713184 12.532 99 87 163 0 1454016208 261797904 18505120
08:53:48 842 8192000000 10354907296 10758537216 1183585888 12.624 100 94 182 1 1465818112 259807504 22424720
08:54:52 2548 8192000000 10368316752 10782765056 1170229936 12.499 99 77 141 3 1477808176 290610064 16968176
08:56:02 1471 8192000000 10412768016 10835574784 1188898944 12.673 100 88 172 0 1522365424 311573616 21892864
08:57:08 998 8192000000 10424552688 10851733504 1188994112 12.674 100 96 187 0 1534807328 321930640 23882576
08:58:13 674 8192000000 10455654096 10898128896 1192370832 12.705 100 99 192 1 1561649072 346479648 22798592
08:59:20 2121 8192000000 10480537664 10901704704 1199845648 12.775 100 83 162 0 1587406304 367487120 20073536
09:00:25 3127 8192000000 10509770048 10933837824 1210891904 12.877 100 87 173 0 1617790032 386606048 20292080
09:01:38 1881 8192000000 10522380992 10956201984 1192992816 12.711 100 85 160 1 1630002528 417967616 19042096
09:02:46 1212 8192000000 10524813824 10953310208 1195046224 12.730 100 96 187 0 1633005008 414003552 23955232
09:03:52 1047 8192000000 10567234176 10976661504 1192153024 12.703 100 95 185 1 1669074720 455834672 21087024
09:04:57 946 8192000000 10638285536 11050749952 1199698768 12.774 100 97 189 1 1740943024 518991872 22252384
09:06:00 1939 8192000000 10624464944 11069702144 1186087568 12.647 99 69 138 0 1732213680 528591104 17535008
09:07:26 2670 8192000000 10664160800 11139891200 1194706560 12.727 100 80 157 0 1769257456 554353632 20197264
09:08:37 7508 8192000000 10657815008 11105226752 1197642736 12.754 100 54 108 0 1759182928 546471488 15068704
09:09:47 5853 8192000000 10683810352 11136716800 1190802272 12.691 100 62 123 0 1788801952 582314800 15684880
09:10:57 7782 8192000000 10637351360 11127816192 1176965632 12.562 100 55 110 0 1757887920 566501984 14420304
09:12:07 5324 8192000000 10511575360 11032326144 1152668496 12.335 100 57 100 2 1634506240 468651952 13185792
09:13:18 12304 8192000000 10373798096 10933104640 1152205920 12.330 100 36 68 1 1484641888 323082336 9353632
09:14:42 51646 8192000000 10189249440 10724188160 1138865552 12.205 99 18 36 0 1337684176 194483456 4335168
09:17:09 32646 8192000000 10090818048 10517737472 1127680144 12.099 99 30 53 2 1186192288 52521424 5990720
09:18:46 86383 8192000000 10132549328 10584281088 1129884864 12.120 100 43 76 1 1236433536 98761152 7787520
09:21:15 2955 8192000000 10085267248 10541031424 1132476752 12.145 100 61 109 2 1182394192 38195248 11722192
09:22:24 71606 8192000000 10092757856 10553040896 1131975440 12.140 99 17 32 0 1184556880 49279136 3302304
09:24:41 84134 8192000000 10130136400 10576936960 1124728912 12.072 99 18 36 0 1206826944 77924912 4173120
09:27:22 183733 8192000000 10088860192 10542321664 1068629952 11.539 94 15 30 1 1120599264 49117264 2852048
09:31:26 95544 8192000000 10093692416 10555990016 1110827552 11.940 99 17 34 0 1183218784 69014000 3377232
09:34:20 40774 8192000000 10068473360 10531352576 1085050720 11.696 97 21 42 0 1142727136 53134096 4542320
09:36:01 94771 8192000000 10061187328 10512134144 1097692432 11.816 98 12 24 0 1156151536 55914512 2544592
09:38:50 57454 8192000000 10070588176 10530529280 1103334624 11.869 98 15 30 0 1159155952 53047488 2773840
09:41:01 27703 8192000000 10055328064 10519711744 1121288752 12.039 100 26 48 0 1161396928 35561456 4546720
09:42:29 55169 8192000000 10099070544 10576977920 1111806144 11.950 98 6 12 0 1179865216 66840064 1219008
09:44:24 53122 8192000000 10129549312 10593120256 1134004208 12.159 99 28 56 0 1207666224 67314736 6347280
09:46:28 18629 8192000000 10168151632 10608410624 1145582816 12.268 100 39 78 0 1275278480 120885248 8810416
09:47:47 24273 8192000000 10152852368 10603098112 1133274368 12.152 99 20 38 0 1246983712 109310464 4398880
09:49:12 25338 8192000000 10151235584 10602500096 1139920864 12.215 100 30 58 0 1256078528 110186304 5971360
09:50:40 22391 8192000000 10185513312 10639060992 1143665312 12.250 100 45 90 1 1273431264 120529104 9236848
09:52:25 35529 8192000000 10102140000 10571673600 1112063536 11.952 98 38 76 0 1234716656 114707184 7945936
09:54:04 79746 8192000000 10169816000 10658824192 1131801824 12.138 99 29 58 0 1280118256 141940416 6376016
09:56:26 86384 8192000000 10099528192 10544377856 1134108624 12.160 99 36 70 1 1168114544 26225840 7780080
09:58:54 25830 8192000000 10099939616 10548027392 1143043056 12.244 99 36 70 1 1198936400 48828080 7065264
10:00:21 61024 8192000000 10114594592 10612363264 1131786048 12.138 99 22 42 0 1241754752 105173664 4795040
10:02:22 50306 8192000000 10090141792 10590076928 1123822944 12.063 99 15 30 0 1174356336 47469616 3063776
10:04:14 90043 8192000000 10082989456 10574999552 1141481648 12.229 100 20 40 1 1222089792 76030144 4578000
10:06:52 57177 8192000000 10060638992 10504474624 1116968672 11.998 98 15 29 0 1165016400 45163280 2884448
10:09:42 70483 8192000000 10056919360 10504396800 1069162880 11.544 93 13 26 0 1098865280 27332640 2369760
10:11:58 29528 8192000000 10102178960 10540716032 1112902464 11.960 97 35 68 0 1175867712 55938816 7026432
10:13:28 43806 8192000000 10084365936 10544541696 1103458960 11.870 97 25 48 0 1161911472 53244832 5207680
10:15:26 43944 8192000000 10074399216 10501521408 1105787616 11.893 97 40 76 0 1152205280 38856592 7561072
10:17:10 16142 8192000000 10130859712 10556227584 1141815696 12.233 100 61 118 1 1216920384 63581008 11523680
10:18:27 28388 8192000000 10145299680 10583543808 1137716176 12.194 99 47 92 0 1232531696 85676656 9138864
10:20:20 106523 8192000000 10107514608 10562211840 1124736336 12.072 98 26 48 0 1230725344 100850896 5138112
10:23:07 143972 8192000000 10117886112 10575429632 1097642224 11.815 95 23 46 0 1179120192 76425824 5052144
10:26:33 37425 8192000000 10065013472 10515812352 1105607040 11.891 97 11 22 0 1144384400 36773472 2003888
10:29:16 77337 8192000000 10116112704 10573893632 1139913136 12.215 100 15 30 0 1205075840 61854096 3308608
10:31:43 75812 8192000000 10133106512 10601914368 1089535632 11.738 95 19 36 1 1198215712 104853664 3826416
10:33:59 81330 8192000000 10061244016 10531102720 1088412480 11.728 96 16 33 1 1179190288 87306640 3471168
10:36:22 46966 8192000000 10146456944 10592665600 1122622992 12.052 98 31 58 1 1233825456 104709632 6492832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-12-2022 10:39:07 to 13-12-2022 10:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-12-2022 10:39:24 to 13-12-2022 10:39:26.

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          208260
MON$OLDEST_ACTIVE               211615
MON$OLDEST_SNAPSHOT             211615
MON$NEXT_TRANSACTION            211615
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                       2071584
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             765
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 ---
:219: 
:220: mscs-test-161.budzdorov.ru    Tue Dec 13 10:37:32 2022
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:222: 
:223: 
:224: mscs-test-161.budzdorov.ru    Tue Dec 13 10:37:32 2022
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:226: 
:227: 
:228: mscs-test-161.budzdorov.ru    Tue Dec 13 10:37:32 2022
:229:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:230: 
--- end of diff output ---

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

13.12.22 10:39:27. 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-13 10:39:30 No severe PSQL-related problems occured
13.12.22 10:39:31. End of report.