Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.06.2022 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 15859756 kB
MemAvailable 31301916 kB
Buffers 0 kB
Cached 15873048 kB
SwapCached 2460 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.2
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-06-15 13:37:13
DTS_END                         2022-06-15 13:37:13.8470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52114
TRN_ID                          227327
ATT_ID                          628
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: 9094.08 26385 1091290
customer order: creation 176.35 447 21162
customer order: refuse 15.23 388 1827
order to supplier: creation 48.18 1309 5781
order to supplier: removal 3.30 161945 396
invoice (draft): creation 48.38 3158 5805
invoice (draft): removal 48.27 980 5792
invoice accept: apply 43.97 27732 5276
invoice accept: cancel 43.54 1317 5225
customer reserve: creation 4106.50 263 492780
customer reserve: removal 4259.79 98 511175
realization accept: apply 87.61 282 10513
realization accept: cancel 77.97 181 9356
payment from customer: creation 61.69 169 7403
payment from customer: removal 5.76 128 691
payment to supplier: creation 50.37 208 6044
payment to supplier: removal 3.72 145 446
service: total inventory turnovers 4.17 4200 500
service: total monetary turnovers 8.26 319 991
service: aggregate perf. data 1.03 17101 123
service: refresh index statistics 0.03 307334 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-06-2022 13:39:15 to 15-06-2022 13:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12613
TEST_TIME 2 7558
TEST_TIME 3 6653
TEST_TIME 4 6800
TEST_TIME 5 7277
TEST_TIME 6 7675
TEST_TIME 7 7420
TEST_TIME 8 7488
TEST_TIME 9 7193
TEST_TIME 10 7444
TEST_TIME 11 7614
TEST_TIME 12 7829
TEST_TIME 13 7913
TEST_TIME 14 7938
TEST_TIME 15 7982
TEST_TIME 16 7958
TEST_TIME 17 7915
TEST_TIME 18 8124
TEST_TIME 19 8266
TEST_TIME 20 8249
TEST_TIME 21 8518
TEST_TIME 22 8760
TEST_TIME 23 8878
TEST_TIME 24 8710
TEST_TIME 25 8883
TEST_TIME 26 8921
TEST_TIME 27 9241
TEST_TIME 28 9271
TEST_TIME 29 9398
TEST_TIME 30 9525
TEST_TIME 31 9873
TEST_TIME 32 10102
TEST_TIME 33 10333
TEST_TIME 34 10740
TEST_TIME 35 10925
TEST_TIME 36 11482
TEST_TIME 37 11403
TEST_TIME 38 11331
TEST_TIME 39 11198
TEST_TIME 40 11105
TEST_TIME 41 11019
TEST_TIME 42 10768
TEST_TIME 43 10645
TEST_TIME 44 10718
TEST_TIME 45 10604
TEST_TIME 46 10467
TEST_TIME 47 10445
TEST_TIME 48 10321
TEST_TIME 49 10344
TEST_TIME 50 10242
TEST_TIME 51 10175
TEST_TIME 52 10244
TEST_TIME 53 10261
TEST_TIME 54 10168
TEST_TIME 55 10136
TEST_TIME 56 10106
TEST_TIME 57 10033
TEST_TIME 58 9921
TEST_TIME 59 9896
TEST_TIME 60 9856
TEST_TIME 61 9776
TEST_TIME 62 9733
TEST_TIME 63 9760
TEST_TIME 64 9701
TEST_TIME 65 9705
TEST_TIME 66 9729
TEST_TIME 67 9743
TEST_TIME 68 9726
TEST_TIME 69 9836
TEST_TIME 70 9818
TEST_TIME 71 9842
TEST_TIME 72 9876
TEST_TIME 73 9904
TEST_TIME 74 9900
TEST_TIME 75 9800
TEST_TIME 76 9745
TEST_TIME 77 9674
TEST_TIME 78 9655
TEST_TIME 79 9655
TEST_TIME 80 9605
TEST_TIME 81 9560
TEST_TIME 82 9488
TEST_TIME 83 9471
TEST_TIME 84 9508
TEST_TIME 85 9467
TEST_TIME 86 9488
TEST_TIME 87 9449
TEST_TIME 88 9486
TEST_TIME 89 9466
TEST_TIME 90 9472
TEST_TIME 91 9450
TEST_TIME 92 9585
TEST_TIME 93 9560
TEST_TIME 94 9573
TEST_TIME 95 9578
TEST_TIME 96 9506
TEST_TIME 97 9457
TEST_TIME 98 9443
TEST_TIME 99 9396
TEST_TIME 100 9343
TEST_TIME 101 9288
TEST_TIME 102 9314
TEST_TIME 103 9321
TEST_TIME 104 9300
TEST_TIME 105 9307
TEST_TIME 106 9319
TEST_TIME 107 9270
TEST_TIME 108 9268
TEST_TIME 109 9248
TEST_TIME 110 9263
TEST_TIME 111 9256
TEST_TIME 112 9377
TEST_TIME 113 9394
TEST_TIME 114 9373
TEST_TIME 115 9323
TEST_TIME 116 9308
TEST_TIME 117 9252
TEST_TIME 118 9238
TEST_TIME 119 9194
TEST_TIME 120 9121

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-06-2022 13:39:18 to 15-06-2022 13:39:21.

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 1543222 1543222 0 0.00 0 21400 14 0 0
doc_list_biud 1543215 1543215 0 0.00 0 51356 38 0 0
sp_add_invoice_to_stock 5397 5276 121 2.24 52 240288 27732 0 121
sp_cancel_adding_invoice 5225 5225 0 0.00 6 99927 1317 0 0
sp_cancel_client_order 1827 1827 0 0.00 6 9155 388 0 0
sp_cancel_customer_reserve 511175 511175 0 0.00 1 51360 99 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 4 5544 129 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 5 3137 146 0 0
sp_cancel_supplier_invoice 5802 5792 10 0.17 3 28048 981 0 10
sp_cancel_supplier_order 396 396 0 0.00 9 1584538 161946 0 0
sp_cancel_write_off 9356 9356 0 0.00 2 7634 182 0 0
sp_client_order 21162 21162 0 0.00 6 17703 447 0 0
sp_customer_reserve 492780 492780 0 0.00 1 82364 264 0 0
sp_fill_shopping_cart 32736 32736 0 0.00 1 10622 85 0 0
sp_fill_shopping_cart_clo_res 492744 492744 0 0.00 0 12835 19 0 0
sp_get_clo_for_invoice 5234 5234 0 0.00 0 21671 238 0 0
sp_kill_cost_storno 15718 15718 0 0.00 0 7537 71 0 0
sp_kill_qstorno_ret_qs2qd 1037894 1037894 0 0.00 0 51348 42 0 0
sp_kill_qty_storno 549518 549518 0 0.00 0 51356 98 0 0
sp_lock_selected_doc 21726 21726 0 0.00 0 8470 8 0 0
sp_make_cost_storno 29194 29194 0 0.00 0 21388 80 0 0
sp_multiply_rows_for_pdistr 29194 29194 0 0.00 0 9863 37 0 0
sp_multiply_rows_for_qdistr 21162 21162 0 0.00 0 11294 168 0 0
sp_pay_from_customer 7403 7403 0 0.00 5 11329 170 0 0
sp_pay_to_supplier 6044 6044 0 0.00 6 11466 208 0 0
sp_payment_common 13447 13447 0 0.00 2 7712 46 0 0
sp_qd_handle_on_cancel_clo 1827 1827 0 0.00 0 9149 312 0 0
sp_qd_handle_on_invoice_upd_sts 10459 10459 0 0.00 0 19903 543 0 0
sp_qd_handle_on_reserve_upd_sts 10512 10512 0 0.00 0 16980 64 0 0
sp_reserve_write_off 10513 10513 0 0.00 8 21411 283 0 0
sp_supplier_invoice 5805 5805 0 0.00 8 104263 3159 0 0
sp_supplier_order 5781 5781 0 0.00 9 40118 1310 0 0
srv_aggregate_perf_data 123 123 0 0.00 2456 160226 17101 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 752 106596 29806 0 0
srv_find_qd_qs_mism 1039091 1039091 0 0.00 0 10623 11 0 0
srv_make_invnt_saldo 500 500 0 0.00 72 95691 4201 0 0
srv_make_money_saldo 991 991 0 0.00 1 8172 320 0 0
srv_recalc_idx_stat 4 4 0 0.00 191745 427418 307334 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 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5280 5280 0 0.00 0 33972 1014 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 9761 500 0 0
t$perf-norm:sp_cancel_client_order 1827 1827 0 0.00 0 1603 28 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1907 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 754 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 2258 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 3621 51 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 112210 7068 0 0
t$perf-norm:sp_cancel_write_off 1076 1076 0 0.00 0 6580 28 0 0
t$perf-norm:sp_client_order 21162 21162 0 0.00 0 7408 35 0 0
t$perf-norm:sp_customer_reserve 1054 1054 0 0.00 0 3611 17 0 0
t$perf-norm:sp_pay_from_customer 7404 7404 0 0.00 0 10604 24 0 0
t$perf-norm:sp_pay_to_supplier 6044 6044 0 0.00 0 4833 21 0 0
t$perf-norm:sp_reserve_write_off 10513 10513 0 0.00 0 5322 23 0 0
t$perf-norm:sp_supplier_invoice 5805 5805 0 0.00 0 5288 32 0 0
t$perf-norm:sp_supplier_order 5781 5781 0 0.00 0 8072 33 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 646 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 554 12 0 0
t$perf-norm:srv_make_invnt_saldo 500 500 0 0.00 0 756 14 0 0
t$perf-norm:srv_make_money_saldo 991 991 0 0.00 0 544 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 197 59 0 0
v_add_invoice_to_stock 10710 10468 242 2.25 0 3284 27 0 242
v_all_customers 39028 39028 0 0.00 0 100 4 0 0
v_all_suppliers 31868 31868 0 0.00 0 84 4 0 0
v_all_wares 42320 42320 0 0.00 0 104 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 2199 17 0 0
v_cancel_client_order 3654 3654 0 0.00 0 3416 11 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 2353 13 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 1 607 11 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 2779 21 0 0
v_cancel_supplier_invoice 886 866 20 2.25 0 3057 33 0 20
v_cancel_supplier_order 792 792 0 0.00 0 3068 18 0 0
v_cancel_write_off 2152 2152 0 0.00 0 3692 27 0 0
v_max_id_clo_ord 5778 5778 0 0.00 0 2091 5 0 0
v_max_id_clo_res 1054 1054 0 0.00 0 3336 23 0 0
v_max_id_ord_sup 5796 5796 0 0.00 0 3677 8 0 0
v_max_non_paid_invoice 1682 1682 0 0.00 0 56 8 0 0
v_max_non_paid_realizn 3733 3733 0 0.00 0 3321 8 0 0
v_min_id_clo_ord 5778 5778 0 0.00 0 3073 12 0 0
v_min_id_clo_res 1054 1054 0 0.00 0 6419 15 0 0
v_min_id_ord_sup 5796 5796 0 0.00 0 6252 12 0 0
v_min_non_paid_invoice 6044 6044 0 0.00 0 5725 18 0 0
v_min_non_paid_realizn 7403 7403 0 0.00 0 3753 14 0 0
v_our_firm 10626 10626 0 0.00 0 71 5 0 0
v_reserve_write_off 21024 21024 0 0.00 0 2525 11 0 0
x_lock_depdocs_on_canc_invoice 10584 10584 0 0.00 0 15901 132 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 4317 384 0 0
x_make_qstorno_1000_1200 5781 5781 0 0.00 3 40090 1121 0 0
x_make_qstorno_1200_2000 5805 5805 0 0.00 2 104246 2949 0 0
x_make_qstorno_2100_3300 492780 492780 0 0.00 0 82352 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-06-2022 13:39:22 to 15-06-2022 13:39:26.

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:17 8 8192000000 8804733664 8818188288 9245088 0.112 3 3 4 0 9432944 100912 86944
11:08:19 35551 8192000000 9726185776 10026274816 795664880 8.852 100 86 172 2 824670256 7900672 21104704
11:09:59 17643 8192000000 9779588496 10091843584 849216272 9.392 100 84 168 0 882069392 13084336 19768784
11:11:20 18235 8192000000 9822022224 10147966976 883457488 9.734 100 84 168 0 926388736 23838928 19092320
11:12:42 9094 8192000000 9889783216 10225770496 925661936 10.152 100 88 176 0 997673216 52364528 19646752
11:13:55 5593 8192000000 9911481552 10259800064 951572064 10.407 100 82 164 0 1029537776 59989472 17976240
11:15:04 1637 8192000000 9966701632 10317516800 989973120 10.781 100 94 187 1 1089030224 80184944 18872160
11:16:08 2313 8192000000 9976196272 10338766848 1010494592 10.980 100 89 173 1 1094168064 66339728 17333744
11:17:17 793 8192000000 10023140688 10392121344 1041219296 11.276 100 93 185 0 1143885664 84843984 17822384
11:18:19 1678 8192000000 10022155280 10394017792 1052943008 11.389 100 96 187 3 1140796432 68029984 19823440
11:19:23 934 8192000000 10034460160 10414882816 1067123376 11.525 100 94 185 2 1159257760 73324320 18810064
11:20:25 1177 8192000000 10041097648 10424250368 1072475888 11.576 100 90 175 1 1164654928 73966976 18212064
11:21:28 1051 8192000000 10054773312 10437996544 1077682304 11.625 100 94 182 0 1177020864 80555184 18783376
11:22:30 1206 8192000000 10070907712 10457604096 1084103424 11.687 100 92 178 1 1193304704 91203488 17997792
11:23:32 1262 8192000000 10057427072 10441605120 1090059424 11.743 100 96 186 1 1176307296 66741264 19506608
11:24:34 991 8192000000 10062143792 10454306816 1091664192 11.758 100 84 162 0 1183540272 75700624 16175456
11:25:36 1111 8192000000 10084752272 10478403584 1095959216 11.799 100 92 176 1 1206243200 91314352 18969632
11:26:38 2062 8192000000 10088042288 10484871168 1099024016 11.828 100 90 179 0 1210804928 93070848 18710064
11:27:41 1427 8192000000 10054863216 10442330112 1091511280 11.757 100 97 188 3 1178403920 67691424 19201216
11:28:43 1319 8192000000 10049181360 10435612672 1089348592 11.736 100 94 183 1 1171887504 63203616 19335296
11:29:48 1137 8192000000 10068988400 10457268224 1094838832 11.789 100 97 189 0 1190380192 76237488 19303872
11:30:50 1045 8192000000 10057851408 10442444800 1081830640 11.665 100 95 180 1 1176678528 76626288 18221600
11:31:52 1093 8192000000 10008387600 10380333056 1040731392 11.272 98 90 174 1 1133348064 74424960 18191712
11:32:54 1087 8192000000 10009320896 10388410368 1054141744 11.400 100 90 174 1 1136349008 62889968 19317296
11:33:56 973 8192000000 9984247392 10354532352 1026849760 11.138 100 87 167 1 1106121744 62251856 17020128
11:34:57 4690 8192000000 9979173776 10359721984 1023214208 11.103 99 71 133 4 1096743440 58688816 14840416
11:36:03 1706 8192000000 9956728944 10324520960 1025923616 11.129 100 89 161 1 1080539312 35730480 18885216
11:37:05 7556 8192000000 9998685312 10374377472 1040156832 11.266 99 77 149 1 1125054752 70724208 14173712
11:38:19 947 8192000000 10012802592 10387398656 1058221520 11.439 100 95 182 0 1134792000 59205008 17365472
11:39:25 30971 8192000000 10033063600 10417340416 1071379600 11.565 100 78 157 1 1159267552 71437184 16450768
11:41:00 1231 8192000000 10061307680 10441621504 1084482048 11.690 100 88 169 2 1176542496 75470304 16590144
11:42:04 7643 8192000000 10096728368 10494509056 1096677696 11.806 100 70 135 0 1213347952 102134336 14535920
11:43:17 1143 8192000000 10114961856 10511851520 1111942640 11.951 100 79 155 1 1232573392 104460800 16169952
11:44:21 1171 8192000000 10114990752 10506260480 1116563536 11.995 100 83 161 0 1234921728 100331152 18027040
11:45:29 2165 8192000000 10161804992 10560974848 1120542112 12.032 100 80 157 1 1281177472 143676048 16959312
11:46:36 3595 8192000000 10203663040 10602598400 1146362816 12.275 100 80 157 0 1318683216 154883216 17437184
11:47:46 3306 8192000000 10195457104 10606002176 1155820752 12.364 100 83 154 1 1308061328 135187408 17053168
11:48:54 1291 8192000000 10243939024 10647871488 1169465184 12.492 100 90 176 1 1354421488 165564240 19392064
11:49:58 1192 8192000000 10263811488 10664521728 1172136304 12.517 100 90 174 0 1375418784 183116944 20165536
11:51:01 1431 8192000000 10262365056 10677366784 1179476576 12.585 100 91 173 1 1379251712 179876512 19898624
11:52:05 1246 8192000000 10315621088 10718031872 1183508576 12.623 100 96 188 0 1424282800 219787712 20986512
11:53:08 2536 8192000000 10354681504 10762133504 1193399552 12.715 100 92 181 0 1468356896 253134496 21822848
11:54:13 918 8192000000 10418524960 10826375168 1200386704 12.780 100 95 184 0 1525765376 304171056 21207616
11:55:17 2288 8192000000 10426607904 10850172928 1201246544 12.788 100 87 169 0 1537618512 317498960 18873008
11:56:22 1001 8192000000 10447127792 10877022208 1205667456 12.829 100 98 186 1 1558825344 331720704 21437184
11:57:25 745 8192000000 10451470224 10887671808 1185071136 12.637 100 96 185 1 1557758256 350288272 22398848
11:58:31 962 8192000000 10464905984 10910400512 1202647008 12.801 100 96 181 0 1570976336 347206352 21122976
11:59:34 938 8192000000 10488351888 10915241984 1211008144 12.878 100 98 185 1 1591552768 359902464 20642160
12:00:36 8840 8192000000 10453851648 10876923904 1187881264 12.664 100 56 111 1 1562618384 361195680 13541440
12:01:51 1022 8192000000 10495040016 10936864768 1185131840 12.638 100 85 167 2 1598954672 394046400 19776432
12:02:57 2856 8192000000 10491932144 10952749056 1171762032 12.513 99 66 128 2 1588615440 400246368 16607040
12:04:05 1365 8192000000 10540385120 10977775616 1195879760 12.738 100 88 174 0 1641770544 424094848 21795936
12:05:09 3970 8192000000 10506996496 10963099648 1176175136 12.555 99 56 110 0 1603825552 413504448 14145968
12:06:18 16300 8192000000 10503906864 10972971008 1178910064 12.580 100 39 76 1 1619896560 430950848 10035648
12:07:39 8767 8192000000 10469247152 10964746240 1173243360 12.527 100 42 82 0 1575541040 391397280 10900400
12:09:03 12166 8192000000 10404812752 10851667968 1161021008 12.413 100 48 95 2 1507392192 334240816 12130368
12:10:18 17332 8192000000 10343042640 10873655296 1151455024 12.323 100 23 46 3 1476145744 318532400 6158320
12:11:41 43635 8192000000 10257079696 10777501696 1140596560 12.221 100 13 26 0 1385345968 241205040 3544368
12:13:26 65127 8192000000 10080221760 10579296256 1116881920 11.998 98 12 22 0 1159841488 41179088 1780480
12:15:32 51872 8192000000 10060948912 10492362752 1119254912 12.020 98 43 70 4 1147632480 21454896 6922672
12:17:25 106594 8192000000 10106547104 10575704064 1126823744 12.091 97 22 44 0 1180676240 49734336 4118160
12:20:52 61535 8192000000 10077766080 10515525632 1124493472 12.069 98 37 66 0 1148916640 17975200 6447968
12:22:55 40625 8192000000 10096746864 10531237888 1135176784 12.170 98 43 78 4 1174883232 30447344 9259104
12:24:37 4919 8192000000 10136199824 10574143488 1148009424 12.291 100 37 74 2 1233609472 77620048 7980000
12:25:46 19782 8192000000 10177675264 10611929088 1136628128 12.184 99 53 106 1 1262477200 114078592 11770480
12:27:10 43241 8192000000 10151066880 10627571712 1126297936 12.086 98 15 30 0 1241745728 112042144 3405648
12:28:55 14979 8192000000 10140300848 10620116992 1145411200 12.266 100 22 44 1 1263759376 113054240 5293936
12:30:11 58998 8192000000 10096261312 10589999104 1149804944 12.308 100 14 28 0 1244258288 91216416 3236928
12:32:11 58559 8192000000 10095237296 10567102464 1130136544 12.123 98 9 20 0 1194524880 62017296 2371040
12:34:10 69211 8192000000 10108171376 10584555520 1155816768 12.364 100 20 40 1 1205067280 45181008 4069504
12:36:19 30439 8192000000 10096829392 10583429120 1140733328 12.222 99 29 58 1 1203836304 56972784 6130192
12:38:05 59494 8192000000 10104383856 10603954176 1135660880 12.175 99 16 30 1 1189874016 51169792 3043344
12:40:21 35814 8192000000 10076868960 10534281216 1132500416 12.145 99 22 42 0 1183283552 46495968 4287168
12:42:01 46777 8192000000 10140574352 10578894848 1110794400 11.940 97 33 64 3 1198877184 81516336 6566448
12:43:48 21435 8192000000 10106536208 10543140864 1114021728 11.970 98 31 62 2 1195185936 74702240 6461968
12:45:10 34072 8192000000 10124884272 10582679552 1113877248 11.969 98 25 48 1 1206224160 87275808 5071104
12:46:45 7250 8192000000 10084659072 10520424448 1137994720 12.197 100 46 92 2 1187374656 40582640 8797296
12:47:53 24910 8192000000 10127085168 10562818048 1128654256 12.109 99 42 84 0 1212396048 75698864 8042928
12:49:20 70994 8192000000 10106109120 10565332992 1093238048 11.773 96 23 46 0 1215560352 117267680 5054624
12:51:31 8203 8192000000 10112032496 10540089344 1119903392 12.026 99 50 99 0 1195134352 65218000 10012960
12:52:44 90193 8192000000 10098814224 10547838976 1100770544 11.845 97 35 70 1 1157667424 49339728 7557152
12:55:15 37737 8192000000 10114860992 10567602176 1131599120 12.136 100 50 100 0 1227276752 84960304 10717328
12:56:54 42533 8192000000 10050557952 10506711040 1121829712 12.044 99 10 19 0 1167601072 44113952 1657408
12:58:42 59891 8192000000 10077816048 10526318592 1093902064 11.780 97 23 46 3 1143766672 44748752 5115856
13:02:17 23620 8192000000 10082678384 10519306240 1107424944 11.908 98 27 54 0 1163188912 50321104 5442864
13:03:42 44241 8192000000 10104586592 10566713344 1132046208 12.141 100 23 46 1 1202808544 66156368 4605968
13:05:32 33666 8192000000 10093788000 10548121600 1110504336 11.937 99 38 76 1 1196289824 77776432 8009056
13:07:12 68234 8192000000 10115756848 10587537408 1096285888 11.802 98 13 26 1 1192286400 93073520 2926992
13:09:21 52739 8192000000 10101190816 10588168192 1122141520 12.047 100 37 73 0 1255184880 124607312 8436048
13:11:16 11675 8192000000 10060918288 10490044416 1123548640 12.061 100 47 92 1 1154879680 21678816 9652224
13:12:30 75540 8192000000 10089490656 10524930048 1117444912 12.003 99 50 98 0 1182012592 54460848 10106832
13:14:46 44034 8192000000 10081308832 10551242752 1135067088 12.169 100 39 78 0 1197172704 54343584 7762032
13:16:33 43034 8192000000 10044069904 10517716992 1113192832 11.963 98 8 17 0 1150804528 36470976 1140720
13:18:16 56645 8192000000 10053216256 10555887616 1114347344 11.974 98 8 16 0 1161325040 45489488 1488208
13:20:16 25961 8192000000 10083751104 10556903424 1123806368 12.063 99 31 57 1 1188582768 57801824 6974576
13:21:43 38956 8192000000 10115221728 10552688640 1131888960 12.139 99 38 74 0 1183765792 44534752 7342080
13:23:29 36794 8192000000 10072472144 10536038400 1104003088 11.876 97 20 40 0 1152412752 44310944 4098720
13:25:19 39565 8192000000 10135546976 10594783232 1126489488 12.088 99 44 88 1 1234327776 98886032 8952256
13:27:01 44615 8192000000 10151322560 10577002496 1110593696 11.938 98 39 78 0 1206170352 87037120 8539536
13:28:47 77043 8192000000 10068858080 10512609280 1074744320 11.597 95 21 41 0 1120392032 40387072 5260640
13:32:05 77005 8192000000 10108884464 10550362112 1117395424 12.002 98 50 100 1 1180686272 53790432 9500416
13:35:11 63872 8192000000 10046412112 10500177920 1095903968 11.799 97 18 36 0 1126276128 26391568 3980592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 15-06-2022 13:39:28 to 15-06-2022 13:39:45.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 15-06-2022 13:39:46 to 15-06-2022 13:39:47.

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          227326
MON$OLDEST_ACTIVE               228768
MON$OLDEST_SNAPSHOT             228768
MON$NEXT_TRANSACTION            228768
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                       2090432
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2779 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2779 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2779 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2779 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Wed Jun 15 13:37:21 2022
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Wed Jun 15 13:37:24 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Wed Jun 15 13:37:31 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
--- end of diff output ---

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

15.06.22 13:39:48. 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-06-15 13:39:51 No severe PSQL-related problems occured
15.06.22 13:39:52. End of report.