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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16584152 kB
MemAvailable 31539676 kB
Buffers 0 kB
Cached 15103212 kB
SwapCached 3016 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-04-26 13:37:14
DTS_END                         2022-04-26 13:37:14.7870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(720:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51136
TRN_ID                          221901
ATT_ID                          654
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: 8915.03 24004 1069803
customer order: creation 172.88 424 20745
customer order: refuse 14.79 374 1775
order to supplier: creation 46.13 1314 5535
order to supplier: removal 3.27 160124 392
invoice (draft): creation 45.63 3151 5476
invoice (draft): removal 49.10 896 5892
invoice accept: apply 41.98 25921 5037
invoice accept: cancel 44.13 1110 5296
customer reserve: creation 3889.86 248 466783
customer reserve: removal 4316.14 95 517937
realization accept: apply 84.08 263 10089
realization accept: cancel 78.29 172 9395
payment from customer: creation 58.45 178 7014
payment from customer: removal 5.93 111 712
payment to supplier: creation 48.29 185 5795
payment to supplier: removal 3.62 105 434
service: total inventory turnovers 4.06 3620 487
service: total monetary turnovers 7.43 290 891
service: aggregate perf. data 0.95 17507 114
service: refresh index statistics 0.03 263999 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-04-2022 13:38:13 to 26-04-2022 13:38:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8168
TEST_TIME 2 6976
TEST_TIME 3 6660
TEST_TIME 4 6971
TEST_TIME 5 6527
TEST_TIME 6 6324
TEST_TIME 7 6008
TEST_TIME 8 6374
TEST_TIME 9 6539
TEST_TIME 10 6419
TEST_TIME 11 6466
TEST_TIME 12 6519
TEST_TIME 13 6661
TEST_TIME 14 6919
TEST_TIME 15 7014
TEST_TIME 16 7274
TEST_TIME 17 7492
TEST_TIME 18 7678
TEST_TIME 19 8047
TEST_TIME 20 8519
TEST_TIME 21 8572
TEST_TIME 22 8547
TEST_TIME 23 8874
TEST_TIME 24 9008
TEST_TIME 25 9087
TEST_TIME 26 9488
TEST_TIME 27 9757
TEST_TIME 28 10045
TEST_TIME 29 10069
TEST_TIME 30 10805
TEST_TIME 31 11159
TEST_TIME 32 10880
TEST_TIME 33 10829
TEST_TIME 34 10578
TEST_TIME 35 10493
TEST_TIME 36 10483
TEST_TIME 37 10265
TEST_TIME 38 10194
TEST_TIME 39 10112
TEST_TIME 40 10148
TEST_TIME 41 10044
TEST_TIME 42 10017
TEST_TIME 43 10062
TEST_TIME 44 10037
TEST_TIME 45 10065
TEST_TIME 46 9948
TEST_TIME 47 9940
TEST_TIME 48 10021
TEST_TIME 49 9910
TEST_TIME 50 9762
TEST_TIME 51 9623
TEST_TIME 52 9574
TEST_TIME 53 9629
TEST_TIME 54 9533
TEST_TIME 55 9440
TEST_TIME 56 9409
TEST_TIME 57 9330
TEST_TIME 58 9258
TEST_TIME 59 9172
TEST_TIME 60 9163
TEST_TIME 61 9230
TEST_TIME 62 9299
TEST_TIME 63 9289
TEST_TIME 64 9383
TEST_TIME 65 9435
TEST_TIME 66 9392
TEST_TIME 67 9328
TEST_TIME 68 9395
TEST_TIME 69 9374
TEST_TIME 70 9351
TEST_TIME 71 9449
TEST_TIME 72 9440
TEST_TIME 73 9444
TEST_TIME 74 9453
TEST_TIME 75 9398
TEST_TIME 76 9455
TEST_TIME 77 9467
TEST_TIME 78 9485
TEST_TIME 79 9387
TEST_TIME 80 9330
TEST_TIME 81 9283
TEST_TIME 82 9226
TEST_TIME 83 9246
TEST_TIME 84 9230
TEST_TIME 85 9225
TEST_TIME 86 9174
TEST_TIME 87 9128
TEST_TIME 88 9067
TEST_TIME 89 8973
TEST_TIME 90 9040
TEST_TIME 91 9038
TEST_TIME 92 9019
TEST_TIME 93 9108
TEST_TIME 94 9080
TEST_TIME 95 9097
TEST_TIME 96 9099
TEST_TIME 97 9162
TEST_TIME 98 9120
TEST_TIME 99 9144
TEST_TIME 100 9204
TEST_TIME 101 9183
TEST_TIME 102 9177
TEST_TIME 103 9198
TEST_TIME 104 9181
TEST_TIME 105 9142
TEST_TIME 106 9124
TEST_TIME 107 9070
TEST_TIME 108 9058
TEST_TIME 109 9045
TEST_TIME 110 9064
TEST_TIME 111 9026
TEST_TIME 112 8976
TEST_TIME 113 9002
TEST_TIME 114 8977
TEST_TIME 115 9018
TEST_TIME 116 8975
TEST_TIME 117 8985
TEST_TIME 118 8994
TEST_TIME 119 8996
TEST_TIME 120 8935

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-04-2022 13:38:17 to 26-04-2022 13:38:20.

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 1500188 1500188 0 0.00 0 18415 13 0 0
doc_list_biud 1500177 1500177 0 0.00 0 45360 38 0 0
sp_add_invoice_to_stock 5131 5037 94 1.83 31 189697 25922 0 94
sp_cancel_adding_invoice 5296 5296 0 0.00 6 83119 1111 0 0
sp_cancel_client_order 1775 1775 0 0.00 7 10838 375 0 0
sp_cancel_customer_reserve 517937 517937 0 0.00 1 45383 96 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 4 1582 112 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 2000 105 0 0
sp_cancel_supplier_invoice 5898 5892 6 0.10 2 12929 896 0 6
sp_cancel_supplier_order 392 392 0 0.00 9 1356713 160124 0 0
sp_cancel_write_off 9395 9395 0 0.00 2 21064 173 0 0
sp_client_order 20745 20745 0 0.00 6 25630 424 0 0
sp_customer_reserve 466783 466783 0 0.00 1 131565 249 0 0
sp_fill_shopping_cart 31747 31747 0 0.00 2 18941 86 0 0
sp_fill_shopping_cart_clo_res 466757 466757 0 0.00 0 14511 20 0 0
sp_get_clo_for_invoice 4999 4999 0 0.00 0 17604 298 0 0
sp_kill_cost_storno 15837 15837 0 0.00 0 6749 72 0 0
sp_kill_qstorno_ret_qs2qd 1051553 1051553 0 0.00 0 45332 42 0 0
sp_kill_qty_storno 555775 555775 0 0.00 0 45356 95 0 0
sp_lock_selected_doc 20857 20857 0 0.00 0 10104 8 0 0
sp_make_cost_storno 27897 27897 0 0.00 0 8580 73 0 0
sp_multiply_rows_for_pdistr 27897 27897 0 0.00 0 6046 36 0 0
sp_multiply_rows_for_qdistr 20745 20745 0 0.00 0 25595 142 0 0
sp_pay_from_customer 7014 7014 0 0.00 6 10487 179 0 0
sp_pay_to_supplier 5795 5795 0 0.00 6 10928 185 0 0
sp_payment_common 12807 12807 0 0.00 2 8140 50 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 1 10832 301 0 0
sp_qd_handle_on_invoice_upd_sts 10295 10295 0 0.00 0 32607 473 0 0
sp_qd_handle_on_reserve_upd_sts 10089 10089 0 0.00 0 15670 64 0 0
sp_reserve_write_off 10089 10089 0 0.00 8 15726 263 0 0
sp_supplier_invoice 5476 5476 0 0.00 10 88640 3152 0 0
sp_supplier_order 5535 5535 0 0.00 8 29421 1314 0 0
srv_aggregate_perf_data 114 114 0 0.00 2462 138436 17508 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 762 150591 41398 0 0
srv_find_qd_qs_mism 1021012 1021012 0 0.00 0 18414 10 0 0
srv_make_invnt_saldo 487 487 0 0.00 74 61597 3621 0 0
srv_make_money_saldo 891 891 0 0.00 1 7282 290 0 0
srv_recalc_idx_stat 4 4 0 0.00 52945 443818 263999 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5041 5041 0 0.00 0 15183 872 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 1 6466 435 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 2657 29 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 657 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 869 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 2648 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 854 33 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 88088 6685 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 1915 20 0 0
t$perf-norm:sp_client_order 20745 20745 0 0.00 0 5803 34 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 776 14 0 0
t$perf-norm:sp_pay_from_customer 7014 7014 0 0.00 0 2943 17 0 0
t$perf-norm:sp_pay_to_supplier 5796 5796 0 0.00 0 3824 16 0 0
t$perf-norm:sp_reserve_write_off 10089 10089 0 0.00 0 4810 23 0 0
t$perf-norm:sp_supplier_invoice 5476 5476 0 0.00 0 8452 31 0 0
t$perf-norm:sp_supplier_order 5535 5535 0 0.00 0 4777 31 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 1079 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 219 14 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 753 12 0 0
t$perf-norm:srv_make_money_saldo 891 891 0 0.00 0 1461 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 183 116 0 0
v_add_invoice_to_stock 10168 9980 188 1.84 0 10801 23 0 188
v_all_customers 37503 37503 0 0.00 0 104 3 0 0
v_all_suppliers 30526 30526 0 0.00 0 188 4 0 0
v_all_wares 41486 41486 0 0.00 0 56 4 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 583 13 0 0
v_cancel_client_order 3550 3550 0 0.00 0 3238 11 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 1029 12 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 2931 11 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 1982 12 0 0
v_cancel_supplier_invoice 814 802 12 1.47 0 1937 22 0 12
v_cancel_supplier_order 784 784 0 0.00 0 1300 12 0 0
v_cancel_write_off 1930 1930 0 0.00 0 1499 20 0 0
v_max_id_clo_ord 5534 5534 0 0.00 0 2759 5 0 0
v_max_id_clo_res 988 988 0 0.00 0 1920 14 0 0
v_max_id_ord_sup 5468 5468 0 0.00 0 3181 6 0 0
v_max_non_paid_invoice 1530 1530 0 0.00 0 427 7 0 0
v_max_non_paid_realizn 3774 3774 0 0.00 0 3474 8 0 0
v_min_id_clo_ord 5534 5534 0 0.00 0 2218 8 0 0
v_min_id_clo_res 988 988 0 0.00 0 4634 17 0 0
v_min_id_ord_sup 5468 5468 0 0.00 0 5275 9 0 0
v_min_non_paid_invoice 5793 5793 0 0.00 0 6271 20 0 0
v_min_non_paid_realizn 7014 7014 0 0.00 0 6288 21 0 0
v_our_firm 10460 10460 0 0.00 0 82 4 0 0
v_reserve_write_off 20178 20178 0 0.00 0 2341 9 0 0
x_lock_depdocs_on_canc_invoice 10787 10787 0 0.00 0 13215 129 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 3345 352 0 0
x_make_qstorno_1000_1200 5535 5535 0 0.00 3 29405 1155 0 0
x_make_qstorno_1200_2000 5476 5476 0 0.00 4 80868 2902 0 0
x_make_qstorno_2100_3300 466783 466783 0 0.00 0 131562 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-04-2022 13:38:21 to 26-04-2022 13:38:25.

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:18 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:19 38757 8192000000 9743162176 10048450560 820865904 9.107 100 87 174 0 849229872 7754464 20609504
11:10:00 7958 8192000000 9773992352 10086506496 854739792 9.448 100 89 178 0 887905936 12296688 20869456
11:11:14 18244 8192000000 9809004208 10132348928 881209376 9.712 100 85 170 0 921851552 20991824 19650352
11:12:58 10922 8192000000 9890347456 10229563392 938871120 10.282 100 80 160 0 1003662080 47613648 17177312
11:14:11 3276 8192000000 9950221680 10298769408 978986352 10.674 100 92 180 0 1064792192 67147472 18658368
11:15:18 1369 8192000000 9974476272 10333798400 1009647264 10.972 100 85 162 0 1093951744 67943424 16361056
11:16:22 1230 8192000000 9986327040 10355187712 1032083664 11.189 100 79 152 3 1111850688 65444560 14322464
11:17:24 826 8192000000 10033494416 10406436864 1054811312 11.407 100 97 179 3 1152841200 79602080 18427808
11:18:27 1067 8192000000 10034615552 10410807296 1062572688 11.481 100 96 185 2 1155782352 75309440 17900224
11:19:29 5936 8192000000 10061644400 10434949120 1067032784 11.524 100 97 191 2 1176410288 91138816 18238688
11:20:37 1157 8192000000 10038586880 10421125120 1076184304 11.611 100 98 191 0 1159587248 65540848 17862096
11:21:38 1137 8192000000 10048606512 10432802816 1083316976 11.679 100 95 187 0 1172519856 70670160 18532720
11:22:40 1316 8192000000 10077984656 10462871552 1090677392 11.749 100 96 188 1 1194080080 84189104 19213584
11:23:43 6048 8192000000 10050758192 10440269824 1096194576 11.802 100 72 121 0 1168759712 60761648 11803488
11:24:50 994 8192000000 10045441600 10436472832 1104114096 11.877 100 87 166 2 1175918384 53791376 18012912
11:25:51 1015 8192000000 10029098752 10417901568 1098908976 11.827 100 86 160 2 1152015552 35751248 17355328
11:26:53 1280 8192000000 10065708752 10453549056 1099869760 11.836 100 97 189 0 1189564304 70008464 19686080
11:27:55 3589 8192000000 10047644960 10439258112 1100168048 11.839 100 87 167 1 1169203568 52439136 16596384
11:29:00 1120 8192000000 10048618704 10443599872 1090234672 11.745 100 76 145 0 1170482560 66119824 14128064
11:30:01 1274 8192000000 10061417664 10446671872 1081158320 11.659 100 94 180 3 1182679984 83083568 18438096
11:31:03 1063 8192000000 10018677424 10395160576 1066432192 11.518 100 96 179 2 1140444576 55731376 18281008
11:32:06 4664 8192000000 10008480192 10391388160 1074914048 11.599 100 71 137 0 1126033280 37342240 13776992
11:33:13 1391 8192000000 9967459632 10343239680 1028518208 11.154 99 68 130 0 1094116992 51516400 14082384
11:34:15 928 8192000000 9987913552 10362933248 1047787936 11.339 100 96 182 3 1109410144 42331824 19290384
11:35:17 1081 8192000000 10010821120 10387361792 1041908560 11.283 100 91 177 3 1131520000 71081104 18530336
11:36:18 4340 8192000000 9989591008 10367135744 1028421920 11.153 98 66 129 1 1094883280 53731392 12729968
11:37:24 1992 8192000000 9991932960 10372714496 1061907936 11.475 100 84 160 1 1117144944 38686352 16550656
11:38:33 15647 8192000000 10031180160 10417999872 1082872432 11.675 100 65 125 1 1152862448 57669440 12320576
11:39:57 950 8192000000 10066253456 10452729856 1093397216 11.775 100 92 170 1 1185240032 74507424 17335392
11:41:01 10203 8192000000 10101005664 10495430656 1109770384 11.930 100 73 144 0 1219563536 94968832 14824320
11:42:23 5305 8192000000 10154315968 10544979968 1128027248 12.103 100 79 157 0 1268409280 124205280 16176752
11:43:33 1232 8192000000 10175084880 10564927488 1137078208 12.188 100 90 170 2 1291570768 137601888 16890672
11:44:42 2027 8192000000 10233523648 10622976000 1141168576 12.227 100 88 165 1 1340703376 179911776 19623024
11:45:46 3163 8192000000 10246575792 10645549056 1152003200 12.328 100 80 157 0 1362107968 193256816 16847952
11:46:53 2171 8192000000 10271241632 10673266688 1161709728 12.419 100 80 153 1 1386139472 206114816 18314928
11:47:58 1280 8192000000 10297456272 10707906560 1172740384 12.522 100 87 164 0 1407661392 214899360 20021648
11:49:06 763 8192000000 10331455440 10729111552 1178788496 12.579 100 95 184 3 1442095280 241226128 22080656
11:50:11 746 8192000000 10363482768 10763689984 1187539632 12.660 100 94 180 0 1469738544 262396048 19802864
11:51:17 1565 8192000000 10370640112 10795339776 1195218336 12.732 100 93 182 2 1480810224 263761456 21830432
11:52:21 939 8192000000 10409955280 10835935232 1196333776 12.742 100 93 180 1 1520870160 302945680 21590704
11:53:25 882 8192000000 10397422688 10828025856 1184146256 12.629 100 96 188 1 1506129376 299911776 22071344
11:54:30 1082 8192000000 10409828400 10831769600 1185065856 12.637 100 95 182 1 1523705392 317182432 21457104
11:55:34 799 8192000000 10429676848 10882277376 1181494000 12.604 100 100 196 0 1536438480 331237664 23706816
11:56:38 1917 8192000000 10410531072 10847604736 1188086512 12.666 100 92 183 0 1519577936 310489312 21002112
11:57:43 956 8192000000 10400792352 10813927424 1167510944 12.474 98 98 189 0 1503656944 314250656 21895344
11:58:45 2879 8192000000 10435795392 10869243904 1182103312 12.610 100 77 152 1 1552106256 351935616 18067328
11:59:54 2027 8192000000 10459287952 10903113728 1175167312 12.545 100 72 140 3 1563211680 370586656 17457712
12:00:58 2328 8192000000 10467923216 10899980288 1180213088 12.592 100 74 146 1 1570520816 372111088 18196640
12:02:14 8396 8192000000 10461381056 10923585536 1180685808 12.597 100 64 129 3 1573572000 375948768 16937424
12:03:37 8297 8192000000 10440729648 10924236800 1166097600 12.460 100 36 71 0 1540826848 364986720 9742528
12:04:53 25730 8192000000 10365470064 10846679040 1165023488 12.450 100 28 57 0 1489317120 317130928 7162704
12:06:26 42807 8192000000 10271177888 10819981312 1145365136 12.266 100 15 30 1 1425361280 275327824 4668320
12:08:21 19843 8192000000 10095539008 10530381824 1152527232 12.333 100 33 62 2 1185339584 26008960 6803392
12:09:42 39687 8192000000 10128641952 10574008320 1133181184 12.151 98 31 60 0 1190838736 50891312 6766240
12:11:23 73937 8192000000 10119902848 10610167808 1148912368 12.299 100 13 26 1 1219755408 67830064 3012976
12:13:57 47343 8192000000 10116650608 10557239296 1123101408 12.056 97 29 58 0 1184935552 55526864 6307280
12:15:47 72393 8192000000 10103670816 10534375424 1090081408 11.743 95 25 48 1 1138465008 43256400 5127200
12:18:06 87496 8192000000 10090029872 10568998912 1122158016 12.047 99 8 16 0 1166975408 43171280 1646112
12:20:36 54040 8192000000 10113179296 10580365312 1128700688 12.109 99 19 38 1 1202901104 70232320 3968096
12:22:31 65189 8192000000 10088364176 10530811904 1097382800 11.813 96 6 12 0 1136802336 38297728 1121808
12:24:56 83678 8192000000 10061823440 10502979584 1112635840 11.957 98 15 30 0 1185455056 68609968 4209248
12:27:20 79639 8192000000 10088238272 10512437248 1123754864 12.062 99 43 86 0 1175280016 42187472 9337680
12:29:40 70490 8192000000 10073489456 10538422272 1129142912 12.113 99 28 49 3 1159541008 24680864 5717232
12:31:54 67029 8192000000 10089222304 10501804032 1100033152 11.838 95 87 165 2 1148358560 29679936 18645472
12:34:02 65043 8192000000 10202240896 10628087808 1145881536 12.271 99 50 99 2 1245396640 87992688 11522416
12:36:14 150591 8192000000 10262505360 10768171008 1173972272 12.534 100 24 48 0 1357220784 177356320 5892192
12:39:46 87347 8192000000 10131877392 10591293440 1108248992 11.916 96 12 24 0 1221107616 110661232 2197392
12:42:13 111622 8192000000 10067212736 10504552448 1049821264 11.359 92 11 19 0 1106161424 54692304 1647856
12:45:10 26201 8192000000 10103032304 10572787712 1136013312 12.178 100 40 78 0 1222464176 77792400 8658464
12:46:38 19571 8192000000 10084558128 10511765504 1120385600 12.031 99 54 99 1 1152152160 20374064 11392496
12:48:00 134865 8192000000 10093922320 10531241984 1107953040 11.913 97 19 38 1 1180290896 68239952 4097904
12:51:15 21039 8192000000 10104283248 10546712576 1103947456 11.875 97 29 58 0 1169108848 59661936 5499456
12:52:37 86044 8192000000 10111322608 10585317376 1092162592 11.763 96 33 64 0 1182005936 83128560 6714784
12:55:09 83672 8192000000 10080801680 10558578688 1079867168 11.646 96 21 42 2 1156544528 72502896 4174464
12:58:02 74471 8192000000 10074233280 10521280512 1126214768 12.086 100 24 48 1 1191158000 60271456 4671776
13:00:16 75171 8192000000 10065732096 10559930368 1113017184 11.961 99 21 40 1 1201247456 84026240 4204032
13:02:37 50297 8192000000 10067673968 10535723008 1134243712 12.161 100 15 28 0 1169135712 30809376 4082624
13:04:34 79946 8192000000 10126660848 10580393984 1146292416 12.275 100 14 28 0 1214706960 65120176 3294368
13:06:54 72541 8192000000 10146687520 10677702656 1150808208 12.317 100 17 34 1 1284192768 129896896 3487664
13:09:28 99685 8192000000 10071117600 10519506944 1084273728 11.688 94 14 26 1 1155459376 68458896 2726752
13:12:17 41508 8192000000 10085879632 10533695488 1076510464 11.614 94 23 46 1 1149001552 67559312 4931776
13:14:00 78313 8192000000 10075891408 10503680000 1083180816 11.678 95 32 63 0 1129493744 40044016 6268912
13:16:26 47073 8192000000 10112415152 10586800128 1122671152 12.052 99 40 80 1 1240799456 110229520 7898784
13:18:13 24932 8192000000 10088904944 10527731712 1137847472 12.195 100 52 103 1 1184860160 37238912 9773776
13:19:41 61422 8192000000 10099157088 10559299584 1113173488 11.962 98 35 70 1 1183358208 62883296 7301424
13:21:44 78553 8192000000 10086884160 10549198848 1116815424 11.997 98 35 70 2 1181818720 58428848 6574448
13:24:03 29664 8192000000 10043584976 10478546944 1092088512 11.763 96 34 68 1 1134166688 35315008 6763168
13:25:36 31394 8192000000 10110588288 10573316096 1108248880 11.916 98 40 80 0 1209220800 91990544 8981376
13:27:18 39043 8192000000 10067777968 10558980096 1121039632 12.037 99 13 26 1 1172504880 48318192 3147056
13:28:57 62443 8192000000 10110308048 10563047424 1109616976 11.929 98 16 32 1 1182341536 69284816 3439744
13:32:01 69818 8192000000 10027083264 10476756992 1088650976 11.730 97 16 30 0 1132897264 41082144 3164144
13:34:13 27971 8192000000 10110605168 10555207680 1135469264 12.173 100 44 88 0 1215233440 70916688 8847488
13:35:46 36328 8192000000 10076602656 10524069888 1130189392 12.123 100 33 66 1 1172991584 36455536 6346656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-04-2022 13:38:27 to 26-04-2022 13:38:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-04-2022 13:38:44 to 26-04-2022 13:38:46.

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          218351
MON$OLDEST_ACTIVE               222226
MON$OLDEST_SNAPSHOT             222226
MON$NEXT_TRANSACTION            222226
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                       2040160
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:1414: 
:1415: mscs-test-161.budzdorov.ru    Tue Apr 26 13:37:15 2022
:1416:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1417: 
:1418: 
:1419: mscs-test-161.budzdorov.ru    Tue Apr 26 13:37:22 2022
:1420:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:1421: 
--- end of diff output ---

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

26.04.22 13:38:47. 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-04-26 13:38:49 No severe PSQL-related problems occured
26.04.22 13:38:50. End of report.