Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.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 14619488 kB
MemAvailable 30085004 kB
Buffers 0 kB
Cached 17058756 kB
SwapCached 3364 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-27 13:37:22
DTS_END                         2022-12-27 13:37:22.5910
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/34318
TRN_ID                          225357
ATT_ID                          606
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: 9165.17 24914 1099820
customer order: creation 178.33 447 21399
customer order: refuse 14.86 403 1783
order to supplier: creation 47.80 1304 5736
order to supplier: removal 3.35 156872 402
invoice (draft): creation 46.77 3242 5612
invoice (draft): removal 49.40 953 5928
invoice accept: apply 42.90 24694 5148
invoice accept: cancel 44.93 1294 5391
customer reserve: creation 3971.22 234 476546
customer reserve: removal 4465.27 93 535832
realization accept: apply 84.73 296 10167
realization accept: cancel 80.07 178 9608
payment from customer: creation 60.91 175 7309
payment from customer: removal 6.37 127 764
payment to supplier: creation 50.89 182 6107
payment to supplier: removal 3.88 117 465
service: total inventory turnovers 4.19 4127 503
service: total monetary turnovers 8.42 373 1010
service: aggregate perf. data 0.88 15246 106
service: refresh index statistics 0.03 287926 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-12-2022 13:38:05 to 27-12-2022 13:38:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7408
TEST_TIME 2 5855
TEST_TIME 3 6201
TEST_TIME 4 6247
TEST_TIME 5 6212
TEST_TIME 6 5877
TEST_TIME 7 6016
TEST_TIME 8 6419
TEST_TIME 9 6723
TEST_TIME 10 6772
TEST_TIME 11 7132
TEST_TIME 12 7215
TEST_TIME 13 7284
TEST_TIME 14 7268
TEST_TIME 15 7282
TEST_TIME 16 7535
TEST_TIME 17 7582
TEST_TIME 18 7782
TEST_TIME 19 7842
TEST_TIME 20 7924
TEST_TIME 21 8243
TEST_TIME 22 8375
TEST_TIME 23 8452
TEST_TIME 24 8596
TEST_TIME 25 8562
TEST_TIME 26 8839
TEST_TIME 27 8747
TEST_TIME 28 9037
TEST_TIME 29 9474
TEST_TIME 30 9939
TEST_TIME 31 10185
TEST_TIME 32 10642
TEST_TIME 33 11009
TEST_TIME 34 10886
TEST_TIME 35 11039
TEST_TIME 36 11137
TEST_TIME 37 10951
TEST_TIME 38 10833
TEST_TIME 39 10799
TEST_TIME 40 10669
TEST_TIME 41 10521
TEST_TIME 42 10420
TEST_TIME 43 10266
TEST_TIME 44 10300
TEST_TIME 45 10188
TEST_TIME 46 10424
TEST_TIME 47 10328
TEST_TIME 48 10399
TEST_TIME 49 10369
TEST_TIME 50 10301
TEST_TIME 51 10227
TEST_TIME 52 10044
TEST_TIME 53 9951
TEST_TIME 54 9865
TEST_TIME 55 9765
TEST_TIME 56 9722
TEST_TIME 57 9700
TEST_TIME 58 9666
TEST_TIME 59 9646
TEST_TIME 60 9821
TEST_TIME 61 9855
TEST_TIME 62 9772
TEST_TIME 63 9701
TEST_TIME 64 9669
TEST_TIME 65 9623
TEST_TIME 66 9792
TEST_TIME 67 9726
TEST_TIME 68 9772
TEST_TIME 69 9723
TEST_TIME 70 9717
TEST_TIME 71 9716
TEST_TIME 72 9721
TEST_TIME 73 9676
TEST_TIME 74 9792
TEST_TIME 75 9750
TEST_TIME 76 9746
TEST_TIME 77 9646
TEST_TIME 78 9624
TEST_TIME 79 9555
TEST_TIME 80 9510
TEST_TIME 81 9491
TEST_TIME 82 9446
TEST_TIME 83 9407
TEST_TIME 84 9365
TEST_TIME 85 9320
TEST_TIME 86 9275
TEST_TIME 87 9332
TEST_TIME 88 9344
TEST_TIME 89 9407
TEST_TIME 90 9364
TEST_TIME 91 9348
TEST_TIME 92 9450
TEST_TIME 93 9457
TEST_TIME 94 9459
TEST_TIME 95 9446
TEST_TIME 96 9477
TEST_TIME 97 9440
TEST_TIME 98 9560
TEST_TIME 99 9539
TEST_TIME 100 9454
TEST_TIME 101 9409
TEST_TIME 102 9358
TEST_TIME 103 9288
TEST_TIME 104 9236
TEST_TIME 105 9201
TEST_TIME 106 9203
TEST_TIME 107 9199
TEST_TIME 108 9169
TEST_TIME 109 9151
TEST_TIME 110 9144
TEST_TIME 111 9173
TEST_TIME 112 9155
TEST_TIME 113 9167
TEST_TIME 114 9155
TEST_TIME 115 9189
TEST_TIME 116 9164
TEST_TIME 117 9227
TEST_TIME 118 9204
TEST_TIME 119 9204
TEST_TIME 120 9191

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-12-2022 13:38:09 to 27-12-2022 13:38:12.

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 1538486 1538486 0 0.00 0 9592 14 0 0
doc_list_biud 1538485 1538485 0 0.00 0 31761 38 0 0
sp_add_invoice_to_stock 5276 5148 128 2.42 42 322057 24694 0 128
sp_cancel_adding_invoice 5391 5391 0 0.00 7 83726 1294 0 0
sp_cancel_client_order 1783 1783 0 0.00 6 18766 404 0 0
sp_cancel_customer_reserve 535832 535832 0 0.00 1 17066 94 0 0
sp_cancel_pay_from_customer 764 764 0 0.00 4 4077 127 0 0
sp_cancel_pay_to_supplier 465 465 0 0.00 4 5375 117 0 0
sp_cancel_supplier_invoice 5932 5928 4 0.06 2 31897 953 0 4
sp_cancel_supplier_order 402 402 0 0.00 10 1648350 156872 0 0
sp_cancel_write_off 9608 9608 0 0.00 2 8952 178 0 0
sp_client_order 21399 21399 0 0.00 5 25855 448 0 0
sp_customer_reserve 476546 476546 0 0.00 1 91351 235 0 0
sp_fill_shopping_cart 32742 32742 0 0.00 1 19008 85 0 0
sp_fill_shopping_cart_clo_res 476521 476521 0 0.00 0 16454 17 0 0
sp_get_clo_for_invoice 5106 5106 0 0.00 0 26910 274 0 0
sp_kill_cost_storno 16228 16228 0 0.00 0 5924 73 0 0
sp_kill_qstorno_ret_qs2qd 1087602 1087602 0 0.00 0 31752 41 0 0
sp_kill_qty_storno 574215 574215 0 0.00 0 31758 94 0 0
sp_lock_selected_doc 21271 21271 0 0.00 0 9279 9 0 0
sp_make_cost_storno 28687 28687 0 0.00 0 9579 84 0 0
sp_multiply_rows_for_pdistr 28687 28687 0 0.00 0 5456 38 0 0
sp_multiply_rows_for_qdistr 21399 21399 0 0.00 0 20395 159 0 0
sp_pay_from_customer 7309 7309 0 0.00 5 9275 176 0 0
sp_pay_to_supplier 6107 6107 0 0.00 7 8288 183 0 0
sp_payment_common 13416 13416 0 0.00 2 9260 44 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 1 18755 329 0 0
sp_qd_handle_on_invoice_upd_sts 10495 10495 0 0.00 0 30120 507 0 0
sp_qd_handle_on_reserve_upd_sts 10167 10167 0 0.00 0 24279 71 0 0
sp_reserve_write_off 10167 10167 0 0.00 8 28705 296 0 0
sp_supplier_invoice 5612 5612 0 0.00 10 86757 3242 0 0
sp_supplier_order 5736 5736 0 0.00 6 29574 1304 0 0
srv_aggregate_perf_data 106 106 0 0.00 2811 192359 15246 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 804 170968 36914 0 0
srv_find_qd_qs_mism 1049035 1049035 0 0.00 0 8101 11 0 0
srv_make_invnt_saldo 503 503 0 0.00 77 123953 4128 0 0
srv_make_money_saldo 1010 1010 0 0.00 1 14762 373 0 0
srv_recalc_idx_stat 4 4 0 0.00 88102 422448 287926 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5150 5150 0 0.00 1 12805 902 0 0
t$perf-norm:sp_cancel_adding_invoice 459 459 0 0.00 1 6264 502 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 5182 32 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 7902 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 764 764 0 0.00 0 1430 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 465 465 0 0.00 0 3238 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 1975 35 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 102156 6475 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 2194 20 0 0
t$perf-norm:sp_client_order 21399 21399 0 0.00 0 4786 35 0 0
t$perf-norm:sp_customer_reserve 1067 1067 0 0.00 0 2477 18 0 0
t$perf-norm:sp_pay_from_customer 7309 7309 0 0.00 0 5627 16 0 0
t$perf-norm:sp_pay_to_supplier 6107 6107 0 0.00 0 2690 18 0 0
t$perf-norm:sp_reserve_write_off 10167 10167 0 0.00 0 4666 22 0 0
t$perf-norm:sp_supplier_invoice 5612 5612 0 0.00 0 4676 31 0 0
t$perf-norm:sp_supplier_order 5736 5736 0 0.00 0 5092 31 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 270 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 253 9 0 0
t$perf-norm:srv_make_invnt_saldo 503 503 0 0.00 0 4147 22 0 0
t$perf-norm:srv_make_money_saldo 1010 1010 0 0.00 0 1229 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 11 180 77 0 0
v_add_invoice_to_stock 10464 10208 256 2.44 0 6474 28 0 256
v_all_customers 38798 38798 0 0.00 0 142 4 0 0
v_all_suppliers 31674 31674 0 0.00 0 186 4 0 0
v_all_wares 42798 42798 0 0.00 0 135 4 0 0
v_cancel_adding_invoice 918 918 0 0.00 0 1852 17 0 0
v_cancel_client_order 3566 3566 0 0.00 0 1928 11 0 0
v_cancel_customer_prepayment 1528 1528 0 0.00 0 1593 12 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 1 3336 18 0 0
v_cancel_payment_to_supplier 930 930 0 0.00 0 974 11 0 0
v_cancel_supplier_invoice 850 842 8 0.94 0 728 23 0 8
v_cancel_supplier_order 804 804 0 0.00 0 1655 14 0 0
v_cancel_write_off 1958 1958 0 0.00 0 8402 32 0 0
v_max_id_clo_ord 5736 5736 0 0.00 0 1205 5 0 0
v_max_id_clo_res 1067 1067 0 0.00 0 2602 21 0 0
v_max_id_ord_sup 5607 5607 0 0.00 0 5561 10 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 342 8 0 0
v_max_non_paid_realizn 3928 3928 0 0.00 0 1632 8 0 0
v_min_id_clo_ord 5736 5736 0 0.00 0 2281 8 0 0
v_min_id_clo_res 1067 1067 0 0.00 0 2505 12 0 0
v_min_id_ord_sup 5607 5607 0 0.00 0 3724 9 0 0
v_min_non_paid_invoice 6107 6107 0 0.00 0 8248 15 0 0
v_min_non_paid_realizn 7309 7309 0 0.00 0 5506 16 0 0
v_our_firm 10762 10762 0 0.00 0 82 4 0 0
v_reserve_write_off 20334 20334 0 0.00 0 4261 12 0 0
x_lock_depdocs_on_canc_invoice 10898 10898 0 0.00 0 14795 127 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 5693 371 0 0
x_make_qstorno_1000_1200 5736 5736 0 0.00 2 29538 1145 0 0
x_make_qstorno_1200_2000 5612 5612 0 0.00 4 86722 2994 0 0
x_make_qstorno_2100_3300 476546 476546 0 0.00 0 91344 205 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2022 13:38:13 to 27-12-2022 13:38:17.

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:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 40876 8192000000 9722937504 10020667392 788085248 8.775 100 85 170 0 817561232 8042512 21433472
11:10:28 20322 8192000000 9768973808 10077196288 839328944 9.293 100 90 180 0 875795264 14570608 21895712
11:11:51 9487 8192000000 9810280544 10131427328 873676848 9.637 100 81 162 0 920842768 28251072 18914848
11:13:05 9288 8192000000 9878631168 10214883328 926165120 10.157 100 76 150 0 993091664 50974704 15951840
11:14:17 3997 8192000000 9938585376 10285957120 976804768 10.653 100 90 175 0 1048361536 53336992 18219776
11:15:24 1414 8192000000 9980334656 10336321536 1012579296 11.000 100 87 170 1 1104377440 74870688 16927456
11:16:27 1191 8192000000 10009165248 10374668288 1032645072 11.194 100 96 186 1 1137909712 87893456 17371184
11:17:32 1137 8192000000 10030895024 10406350848 1053336080 11.393 100 89 174 0 1154730160 83992528 17401552
11:18:34 1023 8192000000 10038876032 10418462720 1069091984 11.543 100 93 184 0 1160493312 73309072 18092256
11:19:36 12712 8192000000 10036397456 10412539904 1071682816 11.568 100 76 142 3 1150599936 64290784 14626336
11:20:50 4096 8192000000 10058156896 10440593408 1079739440 11.645 100 75 137 2 1175978864 82854224 13385200
11:21:56 880 8192000000 10032614800 10414612480 1085486016 11.700 100 88 171 0 1158773088 55214992 18072080
11:22:58 1211 8192000000 10060373072 10449580032 1089163120 11.735 100 98 181 1 1180997472 73823648 18010704
11:24:00 1059 8192000000 10047559920 10433613824 1090621680 11.749 100 97 190 0 1166950736 56609056 19720000
11:25:02 1538 8192000000 10044636480 10436710400 1096938640 11.809 100 74 143 2 1163863792 52441488 14483664
11:26:04 3566 8192000000 10053963904 10449690624 1098700480 11.825 100 71 134 1 1179367680 67184432 13482768
11:27:09 6877 8192000000 10062590208 10457100288 1107752608 11.911 100 70 131 1 1185602784 65248080 12602096
11:28:21 6685 8192000000 10032981776 10428243968 1099792288 11.836 100 59 116 0 1162163680 50610624 11760768
11:29:28 15547 8192000000 10021805344 10423173120 1076376560 11.613 99 49 96 2 1144900320 58651696 9872064
11:30:45 1169 8192000000 10064421904 10454515712 1093261648 11.774 100 95 185 0 1186450176 74370400 18818128
11:31:47 932 8192000000 10027456816 10407116800 1065430880 11.508 100 86 161 1 1152151520 70811216 15909424
11:32:49 1210 8192000000 10032234800 10404507648 1050322992 11.364 100 97 185 1 1151121472 80395856 20402624
11:33:51 734 8192000000 10003722992 10388512768 1047515904 11.337 100 83 159 1 1125478800 60414368 17548528
11:34:53 995 8192000000 9969274928 10342682624 1022300912 11.094 99 87 162 0 1089299584 50398928 16599744
11:35:55 2247 8192000000 9972234192 10350592000 1042481872 11.289 100 70 119 2 1091754176 35727408 13544896
11:36:59 1114 8192000000 10008298864 10383646720 1056232544 11.420 100 90 176 2 1128529504 54680096 17616864
11:38:08 9967 8192000000 9990587792 10378706944 1045572192 11.318 98 58 111 2 1107500320 50064096 11864032
11:39:22 3514 8192000000 10021602048 10406301696 1070998112 11.562 100 65 123 1 1139188880 55841760 12349008
11:40:34 7796 8192000000 10057532560 10443223040 1097412480 11.813 100 81 150 0 1180485152 67570736 15501936
11:41:48 3163 8192000000 10088065504 10478944256 1113269632 11.963 100 74 130 1 1207806368 80738496 13798240
11:42:55 5422 8192000000 10114309440 10519797760 1104248832 11.878 99 67 132 2 1228865280 110396576 14219872
11:44:07 991 8192000000 10163680416 10549399552 1127930000 12.102 100 89 164 1 1276179488 130741984 17507504
11:45:12 1064 8192000000 10215940432 10612301824 1136520896 12.183 100 87 165 2 1327028368 173154832 17352640
11:46:15 953 8192000000 10221596432 10620346368 1149085808 12.301 100 88 167 0 1339377760 170371808 19920144
11:47:22 10407 8192000000 10225918496 10636689408 1149854432 12.308 100 70 136 2 1348375104 183544096 14976576
11:48:36 996 8192000000 10251381664 10665975808 1163829456 12.439 100 91 178 1 1368844512 184610256 20404800
11:49:39 1445 8192000000 10283967920 10693509120 1173665424 12.531 100 92 170 1 1393656208 200640208 19350576
11:50:47 2319 8192000000 10325018512 10728722432 1181531648 12.604 100 90 175 1 1432894768 232269056 19094064
11:51:54 866 8192000000 10348283024 10767282176 1183098608 12.619 100 93 179 3 1455255792 251953808 20203376
11:52:58 867 8192000000 10384487392 10802524160 1198425904 12.762 100 99 198 1 1493669776 272538960 22704912
11:54:01 1042 8192000000 10407211152 10822332416 1196263488 12.742 100 92 178 1 1519877712 303256336 20357888
11:55:06 863 8192000000 10420237520 10833416192 1200756784 12.783 100 92 179 0 1532308512 311393712 20158016
11:56:09 909 8192000000 10458529952 10874933248 1198028704 12.758 100 93 181 0 1563985440 343902368 22054368
11:57:12 894 8192000000 10490606640 10918051840 1200033952 12.777 100 99 187 1 1598420032 376374800 22011280
11:58:15 796 8192000000 10524519552 10965782528 1200561648 12.782 100 96 187 1 1627687856 405071936 22054272
11:59:17 1529 8192000000 10490914400 10938646528 1192186688 12.704 100 82 160 2 1602193264 389318800 20687776
12:00:21 2004 8192000000 10508600608 10953469952 1197980128 12.758 100 87 168 0 1611880912 394144880 19755904
12:01:27 3826 8192000000 10524768080 10940973056 1188314224 12.668 100 77 144 0 1628882752 422562640 18005888
12:02:33 6861 8192000000 10563071456 10985365504 1166983712 12.469 99 58 111 0 1665997552 484934880 14078960
12:03:51 6887 8192000000 10565989904 11003715584 1185569600 12.642 100 55 108 2 1661631296 460892112 15169584
12:05:02 11976 8192000000 10547852800 11037585408 1152477056 12.333 98 41 82 0 1653513440 489693648 11342736
12:06:16 25142 8192000000 10450477312 10920247296 1148265168 12.293 99 30 60 0 1547002480 390706592 8030720
12:09:01 29870 8192000000 10245102416 10794053632 1091919968 11.761 95 16 32 0 1321994816 226282656 3792192
12:10:35 47581 8192000000 10160247904 10614460416 1133422928 12.154 98 26 45 2 1260822736 121980144 5419664
12:12:28 33211 8192000000 10108738352 10625216512 1135461440 12.173 99 14 28 0 1248004880 109703152 2840288
12:14:05 59212 8192000000 10079132304 10512384000 1100301952 11.841 96 18 30 0 1120864336 17525728 3036656
12:16:15 170967 8192000000 10076583520 10515623936 1082191776 11.668 95 16 32 1 1146312752 61580928 2540048
12:20:21 73321 8192000000 10153279856 10621521920 1140920144 12.224 99 15 30 1 1246838816 102589824 3328848
12:22:37 68878 8192000000 10107173680 10631090176 1122598496 12.052 98 10 20 0 1218015152 92965760 2450896
12:24:49 84743 8192000000 10084338000 10588205056 1115316032 11.983 98 11 20 0 1162574160 45272864 1985264
12:27:15 54154 8192000000 10056650608 10500988928 1123054256 12.056 99 20 40 1 1171050192 44374368 3621568
12:29:10 67035 8192000000 10075242368 10519035904 1120894128 12.035 99 34 56 1 1141751936 14941456 5916352
12:31:22 89387 8192000000 10097641040 10522775552 1099140896 11.829 96 37 75 0 1183734304 77250592 7342816
12:33:52 53686 8192000000 10151284160 10602639360 1118892240 12.017 98 29 58 3 1244870464 119673312 6304912
12:36:05 8582 8192000000 10177980784 10596696064 1124480272 12.069 99 69 135 1 1262151760 120902240 16769248
12:37:30 71209 8192000000 10105683312 10582863872 1126688976 12.090 100 12 22 0 1228986288 99922496 2374816
12:39:48 131828 8192000000 10102450016 10570788864 1101491536 11.852 96 17 34 0 1149775584 45067440 3216608
12:43:53 9813 8192000000 10064851808 10492678144 1123876352 12.064 99 58 105 1 1152677200 15811296 12989552
12:45:05 11503 8192000000 10147592576 10584580096 1146138976 12.273 100 45 90 0 1249977680 94911584 8927120
12:46:26 22019 8192000000 10079833040 10513018880 1145196464 12.264 100 51 98 2 1177894864 20557632 12140768
12:47:56 43310 8192000000 10090030880 10537537536 1121191600 12.038 98 38 71 0 1169832832 41207648 7433584
12:49:48 50071 8192000000 10126165472 10560811008 1131709392 12.137 99 26 52 0 1206263616 69442464 5111760
12:51:39 60602 8192000000 10077883056 10518310912 1073166976 11.582 94 24 48 0 1113792928 35896448 4729504
12:54:10 73278 8192000000 10104247968 10539245568 1129379376 12.116 100 46 90 0 1200350176 61730768 9240032
12:57:03 21470 8192000000 10044507264 10517643264 1109040752 11.923 100 15 30 0 1151432832 39465840 2926240
12:58:26 98132 8192000000 10067938368 10519711744 1083986928 11.685 96 15 30 0 1152175760 64926384 3262448
13:01:11 61484 8192000000 10102687568 10551844864 1098110960 11.820 97 10 20 0 1162845184 62355872 2378352
13:03:13 80869 8192000000 10141914000 10616893440 1116354096 11.993 98 19 36 1 1229877728 108939312 4584320
13:05:50 20783 8192000000 10108221920 10556805120 1121009776 12.037 100 40 80 1 1203784928 74575072 8200080
13:07:15 19516 8192000000 10108930432 10540560384 1128858304 12.111 100 50 98 0 1184822496 44822160 11142032
13:08:41 65270 8192000000 10081271824 10558427136 1118846624 12.016 98 25 50 2 1266284384 141994000 5443760
13:10:47 48735 8192000000 10096697600 10523496448 1105887408 11.893 97 46 91 0 1166383744 50596464 9899872
13:12:37 81175 8192000000 10103501520 10584678400 1112910032 11.960 98 23 44 1 1170557824 53141936 4505856
13:14:59 66454 8192000000 10097676144 10624430080 1077425248 11.623 96 16 32 0 1221401920 140269472 3707200
13:17:06 90000 8192000000 10040893920 10468003840 1097312288 11.812 98 35 71 0 1125065696 19968144 7785264
13:19:37 60015 8192000000 10075969168 10534948864 1122820736 12.054 100 7 11 0 1183973888 60165072 988080
13:21:38 54904 8192000000 10094173808 10542829568 1086116080 11.706 96 16 32 0 1176513664 86826432 3571152
13:23:49 64204 8192000000 10077398576 10549358592 1099832784 11.836 99 27 52 0 1197688448 91697216 6158448
13:25:55 28516 8192000000 10097323568 10549506048 1094320880 11.784 98 29 57 1 1165293456 65187680 5784896
13:27:29 77680 8192000000 10066756512 10565300224 1120796512 12.035 100 11 22 0 1195396864 72231760 2368592
13:29:52 26583 8192000000 10101626128 10532220928 1109037808 11.923 99 35 68 0 1181914864 65616000 7261056
13:31:21 50163 8192000000 10113819104 10587693056 1126084064 12.084 100 42 84 0 1237274064 102835296 8354704
13:33:20 33470 8192000000 10111551616 10535583744 1111471776 11.946 99 46 92 0 1178814800 57947200 9395824
13:34:55 29129 8192000000 10114456016 10546348032 1123396352 12.059 99 34 68 0 1199105424 69034784 6674288
13:36:25 41493 8192000000 10096093696 10550571008 1110519584 11.937 99 42 80 0 1208888480 90324656 8044240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-12-2022 13:38:19 to 27-12-2022 13:38:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-12-2022 13:38:36 to 27-12-2022 13:38:38.

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          222280
MON$OLDEST_ACTIVE               225517
MON$OLDEST_SNAPSHOT             225517
MON$NEXT_TRANSACTION            225517
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                       2081248
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:592: 
:593: mscs-test-161.budzdorov.ru    Tue Dec 27 13:37:28 2022
:594:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:595: 
:596: 
:597: mscs-test-161.budzdorov.ru    Tue Dec 27 13:37:29 2022
:598:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:599: 
--- end of diff output ---

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

27.12.22 13:38:39. 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-27 13:38:42 No severe PSQL-related problems occured
27.12.22 13:38:43. End of report.