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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15229508 kB
MemAvailable 30459408 kB
Buffers 0 kB
Cached 16457460 kB
SwapCached 2080 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-10-20 10:37:23
DTS_END                         2022-10-20 10:37:23.2670
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/59300
TRN_ID                          211180
ATT_ID                          649
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: 8696.12 24848 1043534
customer order: creation 159.35 523 19122
customer order: refuse 13.59 491 1631
order to supplier: creation 42.62 1500 5114
order to supplier: removal 3.50 178095 420
invoice (draft): creation 42.43 3618 5091
invoice (draft): removal 49.83 1051 5979
invoice accept: apply 39.02 28280 4682
invoice accept: cancel 45.52 1329 5462
customer reserve: creation 3620.34 269 434441
customer reserve: removal 4399.79 112 527975
realization accept: apply 78.87 288 9464
realization accept: cancel 79.86 183 9583
payment from customer: creation 55.26 177 6631
payment from customer: removal 5.37 161 644
payment to supplier: creation 45.99 203 5519
payment to supplier: removal 2.99 127 359
service: total inventory turnovers 3.81 4276 457
service: total monetary turnovers 7.13 429 855
service: aggregate perf. data 0.83 16883 100
service: refresh index statistics 0.04 258958 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2022 10:38:36 to 20-10-2022 10:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6950
TEST_TIME 2 5484
TEST_TIME 3 4993
TEST_TIME 4 4596
TEST_TIME 5 5276
TEST_TIME 6 5305
TEST_TIME 7 5527
TEST_TIME 8 5616
TEST_TIME 9 5719
TEST_TIME 10 5890
TEST_TIME 11 6154
TEST_TIME 12 6058
TEST_TIME 13 6203
TEST_TIME 14 6502
TEST_TIME 15 6671
TEST_TIME 16 6719
TEST_TIME 17 6751
TEST_TIME 18 6785
TEST_TIME 19 6709
TEST_TIME 20 6739
TEST_TIME 21 6945
TEST_TIME 22 7127
TEST_TIME 23 7448
TEST_TIME 24 7481
TEST_TIME 25 7698
TEST_TIME 26 8055
TEST_TIME 27 8176
TEST_TIME 28 8238
TEST_TIME 29 8450
TEST_TIME 30 8555
TEST_TIME 31 9039
TEST_TIME 32 9195
TEST_TIME 33 9568
TEST_TIME 34 9955
TEST_TIME 35 10291
TEST_TIME 36 10150
TEST_TIME 37 10178
TEST_TIME 38 10066
TEST_TIME 39 10239
TEST_TIME 40 10168
TEST_TIME 41 9990
TEST_TIME 42 9900
TEST_TIME 43 9922
TEST_TIME 44 9926
TEST_TIME 45 9809
TEST_TIME 46 9690
TEST_TIME 47 9552
TEST_TIME 48 9408
TEST_TIME 49 9396
TEST_TIME 50 9426
TEST_TIME 51 9347
TEST_TIME 52 9222
TEST_TIME 53 9124
TEST_TIME 54 9106
TEST_TIME 55 9102
TEST_TIME 56 9119
TEST_TIME 57 9151
TEST_TIME 58 9046
TEST_TIME 59 9018
TEST_TIME 60 9044
TEST_TIME 61 9057
TEST_TIME 62 9001
TEST_TIME 63 8946
TEST_TIME 64 8927
TEST_TIME 65 8904
TEST_TIME 66 8958
TEST_TIME 67 9137
TEST_TIME 68 9231
TEST_TIME 69 9259
TEST_TIME 70 9214
TEST_TIME 71 9194
TEST_TIME 72 9303
TEST_TIME 73 9281
TEST_TIME 74 9213
TEST_TIME 75 9204
TEST_TIME 76 9189
TEST_TIME 77 9108
TEST_TIME 78 9051
TEST_TIME 79 9072
TEST_TIME 80 9065
TEST_TIME 81 9032
TEST_TIME 82 8995
TEST_TIME 83 8948
TEST_TIME 84 8952
TEST_TIME 85 8883
TEST_TIME 86 8891
TEST_TIME 87 8921
TEST_TIME 88 8934
TEST_TIME 89 8918
TEST_TIME 90 8956
TEST_TIME 91 8961
TEST_TIME 92 9030
TEST_TIME 93 9069
TEST_TIME 94 9061
TEST_TIME 95 9043
TEST_TIME 96 9055
TEST_TIME 97 9068
TEST_TIME 98 9079
TEST_TIME 99 9109
TEST_TIME 100 9078
TEST_TIME 101 8995
TEST_TIME 102 8945
TEST_TIME 103 8916
TEST_TIME 104 8918
TEST_TIME 105 8865
TEST_TIME 106 8820
TEST_TIME 107 8784
TEST_TIME 108 8779
TEST_TIME 109 8765
TEST_TIME 110 8737
TEST_TIME 111 8726
TEST_TIME 112 8703
TEST_TIME 113 8690
TEST_TIME 114 8710
TEST_TIME 115 8682
TEST_TIME 116 8710
TEST_TIME 117 8726
TEST_TIME 118 8793
TEST_TIME 119 8772
TEST_TIME 120 8720

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2022 10:38:40 to 20-10-2022 10:38:43.

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 1443455 1443455 0 0.00 0 14453 14 0 0
doc_list_biud 1443443 1443443 0 0.00 0 49546 47 0 0
sp_add_invoice_to_stock 4800 4682 118 2.45 57 227125 28281 0 118
sp_cancel_adding_invoice 5462 5462 0 0.00 7 95625 1329 0 0
sp_cancel_client_order 1631 1631 0 0.00 6 13584 492 0 0
sp_cancel_customer_reserve 527975 527975 0 0.00 1 49548 112 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 4 21015 161 0 0
sp_cancel_pay_to_supplier 359 359 0 0.00 4 3557 128 0 0
sp_cancel_supplier_invoice 5988 5979 9 0.15 3 20457 1051 0 9
sp_cancel_supplier_order 420 420 0 0.00 7 1528390 178096 0 0
sp_cancel_write_off 9583 9583 0 0.00 2 15665 184 0 0
sp_client_order 19122 19122 0 0.00 6 34972 523 0 0
sp_customer_reserve 434441 434441 0 0.00 0 168964 269 0 0
sp_fill_shopping_cart 29317 29317 0 0.00 1 36749 92 0 0
sp_fill_shopping_cart_clo_res 434397 434397 0 0.00 0 20701 21 0 0
sp_get_clo_for_invoice 4629 4629 0 0.00 0 26291 320 0 0
sp_kill_cost_storno 16048 16048 0 0.00 0 9276 79 0 0
sp_kill_qstorno_ret_qs2qd 1071932 1071932 0 0.00 0 49543 49 0 0
sp_kill_qty_storno 565141 565141 0 0.00 0 49546 112 0 0
sp_lock_selected_doc 19468 19468 0 0.00 0 8748 7 0 0
sp_make_cost_storno 26242 26242 0 0.00 0 13672 83 0 0
sp_multiply_rows_for_pdistr 26241 26241 0 0.00 0 7330 37 0 0
sp_multiply_rows_for_qdistr 19122 19122 0 0.00 0 11555 196 0 0
sp_pay_from_customer 6631 6631 0 0.00 6 12512 177 0 0
sp_pay_to_supplier 5519 5519 0 0.00 6 13455 204 0 0
sp_payment_common 12149 12149 0 0.00 2 7134 43 0 0
sp_qd_handle_on_cancel_clo 1631 1631 0 0.00 1 13491 413 0 0
sp_qd_handle_on_invoice_upd_sts 10090 10090 0 0.00 0 24937 548 0 0
sp_qd_handle_on_reserve_upd_sts 9463 9463 0 0.00 0 11648 68 0 0
sp_reserve_write_off 9464 9464 0 0.00 8 24476 289 0 0
sp_supplier_invoice 5091 5091 0 0.00 8 100991 3618 0 0
sp_supplier_order 5114 5114 0 0.00 7 128809 1501 0 0
srv_aggregate_perf_data 100 100 0 0.00 2082 154590 16883 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 808 166633 35383 0 0
srv_find_qd_qs_mism 997735 997735 0 0.00 0 14450 10 0 0
srv_make_invnt_saldo 457 457 0 0.00 56 82385 4276 0 0
srv_make_money_saldo 855 855 0 0.00 2 9170 430 0 0
srv_recalc_idx_stat 5 5 0 0.00 45786 380944 258959 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4687 4687 0 0.00 1 20756 994 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 2 5378 466 0 0
t$perf-norm:sp_cancel_client_order 1631 1631 0 0.00 0 1855 29 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 1376 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 1722 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 359 359 0 0.00 0 1647 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 845 34 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 106993 7376 0 0
t$perf-norm:sp_cancel_write_off 912 912 0 0.00 0 3882 26 0 0
t$perf-norm:sp_client_order 19122 19122 0 0.00 0 9521 41 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 7024 25 0 0
t$perf-norm:sp_pay_from_customer 6631 6631 0 0.00 0 5221 21 0 0
t$perf-norm:sp_pay_to_supplier 5519 5519 0 0.00 0 4997 21 0 0
t$perf-norm:sp_reserve_write_off 9464 9464 0 0.00 0 5607 27 0 0
t$perf-norm:sp_supplier_invoice 5091 5091 0 0.00 0 5751 34 0 0
t$perf-norm:sp_supplier_order 5114 5114 0 0.00 0 9457 38 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 207 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 554 20 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 628 13 0 0
t$perf-norm:srv_make_money_saldo 855 855 0 0.00 0 4414 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 16 195 77 0 0
v_add_invoice_to_stock 9480 9244 236 2.48 0 6084 26 0 236
v_all_customers 34744 34744 0 0.00 0 66 4 0 0
v_all_suppliers 28873 28873 0 0.00 0 85 4 0 0
v_all_wares 38244 38244 0 0.00 0 127 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 635 14 0 0
v_cancel_client_order 3262 3262 0 0.00 0 3363 11 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 20953 31 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 1 690 10 0 0
v_cancel_payment_to_supplier 718 718 0 0.00 0 3483 16 0 0
v_cancel_supplier_invoice 750 732 18 2.40 0 4719 36 0 18
v_cancel_supplier_order 840 840 0 0.00 0 2778 16 0 0
v_cancel_write_off 1824 1824 0 0.00 0 4795 26 0 0
v_max_id_clo_ord 5111 5111 0 0.00 0 3710 6 0 0
v_max_id_clo_res 950 950 0 0.00 0 1647 15 0 0
v_max_id_ord_sup 5084 5084 0 0.00 0 9763 11 0 0
v_max_non_paid_invoice 1276 1276 0 0.00 0 60 7 0 0
v_max_non_paid_realizn 3533 3533 0 0.00 0 2727 8 0 0
v_min_id_clo_ord 5111 5111 0 0.00 0 2155 8 0 0
v_min_id_clo_res 950 950 0 0.00 0 10413 32 0 0
v_min_id_ord_sup 5084 5084 0 0.00 0 8569 12 0 0
v_min_non_paid_invoice 5518 5518 0 0.00 0 7125 13 0 0
v_min_non_paid_realizn 6631 6631 0 0.00 0 6738 16 0 0
v_our_firm 9696 9696 0 0.00 0 77 5 0 0
v_reserve_write_off 18926 18926 0 0.00 0 24376 15 0 0
x_lock_depdocs_on_canc_invoice 11075 11075 0 0.00 0 10491 127 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 11484 415 0 0
x_make_qstorno_1000_1200 5114 5114 0 0.00 3 128404 1318 0 0
x_make_qstorno_1200_2000 5091 5091 0 0.00 3 100134 3348 0 0
x_make_qstorno_2100_3300 434440 434440 0 0.00 0 168958 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2022 10:38:44 to 20-10-2022 10:38:48.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:28 19251 8192000000 9729882816 10033188864 812757136 9.025 100 88 176 0 841806144 7599728 21449280
08:09:58 13884 8192000000 9767762176 10079289344 841198800 9.312 100 92 184 0 877184032 13869360 22115872
08:11:19 11456 8192000000 9786214320 10104115200 860346720 9.504 100 84 168 0 899272512 19259984 19665808
08:12:35 7939 8192000000 9842049328 10171994112 891292096 9.812 100 83 164 0 952984592 43603536 18088960
08:14:06 3468 8192000000 9900286224 10244173824 948567472 10.377 100 84 165 1 1018873296 52781600 17524224
08:15:13 2507 8192000000 9944373536 10296811520 990201152 10.783 100 82 162 1 1065541984 58577920 16762912
08:16:18 1265 8192000000 9985975264 10348650496 1014116176 11.015 100 88 161 1 1108439168 77732448 16590544
08:17:21 1438 8192000000 10016134768 10383065088 1036618880 11.232 100 96 186 1 1133473648 77702240 19152528
08:18:25 1298 8192000000 10014567216 10388627456 1048595728 11.347 100 92 165 1 1137988400 71771344 17621328
08:19:28 1144 8192000000 10051728992 10427019264 1064096128 11.496 100 96 176 2 1168648576 87097392 17455056
08:20:30 5536 8192000000 10039805024 10416455680 1070985824 11.561 100 86 163 3 1157593904 70837248 15770832
08:21:38 1523 8192000000 10033645776 10419060736 1076000048 11.609 100 80 158 2 1157371072 66189392 15181632
08:22:40 1307 8192000000 10048170432 10435596288 1081672000 11.663 100 99 195 1 1168523184 67183760 19667424
08:23:43 1253 8192000000 10043940944 10431012864 1088600192 11.729 100 96 173 1 1165916720 59379312 17937216
08:24:45 1682 8192000000 10037472688 10429120512 1093611856 11.777 100 79 140 0 1164153920 56218048 14324016
08:25:49 2037 8192000000 10074247568 10469851136 1097086480 11.810 100 72 142 0 1194734352 83872160 13775712
08:26:52 3128 8192000000 10057378320 10451050496 1101094208 11.848 100 75 143 3 1178328672 62605776 14628688
08:27:57 954 8192000000 10055164240 10454077440 1107766032 11.911 100 81 156 1 1176838768 53306544 15766192
08:28:58 1093 8192000000 10073475152 10468741120 1105233280 11.887 100 89 168 2 1192691328 68137744 19320304
08:30:01 1185 8192000000 10065610400 10455076864 1096940704 11.809 100 83 157 2 1188121888 75730448 15450736
08:31:03 2251 8192000000 10061623552 10452480000 1095605168 11.796 100 94 174 1 1184540576 72458144 16477264
08:32:07 1572 8192000000 10020555696 10410348544 1063231456 11.487 99 83 156 0 1140773040 61909648 15631936
08:33:09 1214 8192000000 10047710960 10431291392 1075318032 11.603 100 94 176 0 1175175696 79694288 20163376
08:34:11 1297 8192000000 9996347056 10374680576 1040406368 11.269 99 82 153 3 1120703072 64250976 16045728
08:35:13 1278 8192000000 10005182640 10378354688 1036276816 11.229 100 95 172 2 1129700512 76090816 17332880
08:36:16 1196 8192000000 9994697360 10374651904 1040490384 11.269 99 96 184 0 1123648704 63209936 19948384
08:37:18 1326 8192000000 9997000288 10372288512 1060928816 11.465 100 95 179 3 1123224128 43594592 18700720
08:38:28 6100 8192000000 10015857888 10390777856 1061350336 11.469 100 88 158 1 1132561952 54871392 16340224
08:40:05 10623 8192000000 10032902448 10424455168 1084894000 11.694 100 60 106 4 1162950400 66279616 11776784
08:41:28 3552 8192000000 10068359968 10450305024 1086423088 11.709 100 79 146 2 1191760288 90060192 15277008
08:42:37 9939 8192000000 10130670720 10522841088 1101517344 11.852 100 70 131 1 1248329392 133082672 13729376
08:43:55 1055 8192000000 10174417952 10563276800 1113542544 11.966 100 78 154 0 1293219488 162847936 16829008
08:45:03 3543 8192000000 10213012960 10606333952 1131889264 12.139 100 82 159 1 1326350224 177245312 17215648
08:46:14 6870 8192000000 10203850000 10601422848 1141152720 12.226 100 64 118 1 1318656000 163979264 13524016
08:47:28 812 8192000000 10223733360 10627313664 1148357520 12.294 100 89 167 1 1335132016 168705808 18068688
08:48:32 875 8192000000 10270012880 10667790336 1157036128 12.375 100 94 185 1 1386044880 206484112 22524640
08:49:37 1810 8192000000 10311005184 10717859840 1166840624 12.467 100 86 172 0 1420483568 233572864 20070080
08:50:46 1614 8192000000 10325769392 10743844864 1174334656 12.537 100 87 173 0 1434205952 240014640 19856656
08:51:51 1026 8192000000 10344756464 10748239872 1183025760 12.618 100 87 171 1 1457626432 255708112 18892560
08:52:58 1009 8192000000 10375330032 10780942336 1189776560 12.681 100 96 185 0 1487784352 274922960 23084832
08:54:05 1135 8192000000 10401270864 10816131072 1189169440 12.676 100 91 175 0 1509712528 300689232 19853856
08:55:11 901 8192000000 10442872576 10848395264 1198038000 12.758 100 92 178 3 1549557952 330772464 20747488
08:56:15 1741 8192000000 10491396800 10900283392 1203906016 12.813 100 96 187 2 1598593488 372880528 21806944
08:57:19 3187 8192000000 10517366192 10950045696 1206109936 12.833 100 88 176 1 1620401632 392793248 21498448
08:58:27 1137 8192000000 10548092336 11001237504 1211343872 12.882 100 92 182 0 1655176928 421882736 21950320
08:59:34 836 8192000000 10505277664 10965016576 1197309184 12.751 100 95 178 0 1610644112 391040288 22294640
09:00:40 2169 8192000000 10551186528 10986688512 1199917104 12.776 100 95 189 0 1654540224 432307984 22315136
09:01:46 802 8192000000 10549975744 10987581440 1199202512 12.769 100 96 187 0 1650285168 428454544 22628112
09:02:51 12853 8192000000 10534949152 11014762496 1186440288 12.650 99 59 116 1 1625117280 423261008 15415984
09:04:08 8192 8192000000 10499004496 10959020032 1182140816 12.610 100 57 115 0 1591430032 394486784 14802432
09:05:27 1346 8192000000 10510482592 10943627264 1184360944 12.631 100 60 107 2 1608513872 410821920 13331008
09:06:36 9755 8192000000 10495016112 10943164416 1177217632 12.564 100 55 110 0 1597847712 407322320 13307760
09:07:54 15235 8192000000 10457782384 10930503680 1159902832 12.402 100 35 69 0 1546114496 377454944 8756720
09:09:12 22478 8192000000 10384131456 10884141056 1168498720 12.483 100 29 58 0 1481457360 305981088 6977552
09:10:36 19112 8192000000 10250132544 10813329408 1145804064 12.270 100 20 40 1 1421143600 269938432 5401104
09:11:57 15537 8192000000 10178028192 10661875712 1139712880 12.213 99 26 52 0 1268737616 123089520 5935216
09:13:19 1779 8192000000 10188255888 10609729536 1166968336 12.468 100 86 158 2 1289976192 102331776 20676080
09:14:21 1443 8192000000 10218889296 10635943936 1165508272 12.455 100 76 140 7 1316780496 135426304 15845920
09:15:25 36675 8192000000 10212919952 10654646272 1141104944 12.226 98 29 58 0 1284964992 137262368 6597680
09:18:08 69319 8192000000 10124081328 10591883264 1127081632 12.094 98 22 43 1 1224374608 91952608 5340368
09:20:20 70311 8192000000 10102485616 10591391744 1133873200 12.158 99 16 32 0 1233898144 96171904 3853040
09:22:31 142742 8192000000 10067327840 10532208640 1126295632 12.086 98 27 54 2 1167997888 35200400 6501856
09:25:54 61894 8192000000 10115485520 10577256448 1135911344 12.177 99 37 73 0 1224754176 81067584 7775248
09:28:06 166632 8192000000 10127906528 10572009472 1094710400 11.787 96 39 73 0 1148513200 46319200 7483600
09:31:55 17082 8192000000 10161828864 10607992832 1152297968 12.331 100 46 92 1 1278226464 116018288 9910208
09:33:25 44759 8192000000 10130427456 10583916544 1104662208 11.882 96 20 32 2 1201928784 93779072 3487504
09:35:10 53070 8192000000 10119168960 10596409344 1129100336 12.113 99 10 20 0 1211143856 79449712 2593808
09:37:05 80664 8192000000 10071510560 10587021312 1130200144 12.123 99 15 30 0 1187991888 54503920 3287824
09:39:32 26685 8192000000 10109050928 10554236928 1141509936 12.230 99 20 37 2 1203002928 57576304 3916688
09:41:00 82633 8192000000 10226055824 10676817920 1139162400 12.208 99 22 44 0 1291785360 147262784 5360176
09:43:32 73287 8192000000 10156574160 10713501696 1138955904 12.206 99 21 42 0 1330051648 186206640 4889104
09:45:47 30139 8192000000 10103808240 10543169536 1137991776 12.197 99 42 83 0 1186971648 40669888 8309984
09:47:21 34770 8192000000 10124532528 10548940800 1140876080 12.224 99 47 93 0 1212193776 61448128 9869568
09:49:13 65677 8192000000 10080759040 10526384128 1087906496 11.723 95 20 40 0 1173393984 81182496 4304992
09:51:25 14775 8192000000 10107061568 10517860352 1103817440 11.874 98 64 128 1 1177551408 60961008 12772960
09:52:40 74763 8192000000 10090128672 10590285824 1107101888 11.905 99 64 126 0 1205044096 85264752 12677456
09:54:56 79442 8192000000 10086279184 10528808960 1086620064 11.711 97 28 54 0 1159651680 67495824 5535792
09:57:35 48922 8192000000 10077017568 10519814144 1110729008 11.939 99 16 32 0 1157722560 43758720 3234832
09:59:30 36066 8192000000 10065490976 10507673600 1093900960 11.780 97 16 30 0 1155773104 58527104 3345040
10:01:07 83614 8192000000 10101275584 10577657856 1131834432 12.139 100 10 20 0 1208980848 74719968 2426448
10:03:41 64674 8192000000 10076855536 10577846272 1106730720 11.901 98 15 28 1 1209109472 98897184 3481568
10:05:47 32641 8192000000 10089763664 10535100416 1129124912 12.113 100 31 62 1 1183887280 48164960 6597408
10:07:36 40792 8192000000 10119179872 10558853120 1129633920 12.118 98 36 71 1 1190671760 53775008 7262832
10:09:22 61266 8192000000 10104881664 10572509184 1139684016 12.213 99 12 24 0 1220268176 78191312 2392848
10:11:26 60429 8192000000 10095065152 10531729408 1121738704 12.043 98 38 76 0 1171199664 42308464 7152496
10:13:30 7836 8192000000 10137659008 10560163840 1146460080 12.276 100 61 121 1 1225514592 67361744 11692768
10:14:38 97959 8192000000 10138177264 10592632832 1033011264 11.197 90 24 48 0 1087980032 50368288 4600480
10:17:28 112505 8192000000 10061798928 10511384576 1130486496 12.126 100 34 68 1 1163102032 24718288 7897248
10:20:38 30037 8192000000 10106594384 10562998272 1137018336 12.187 100 35 68 1 1221392256 77665552 6708368
10:22:08 57761 8192000000 10061466768 10539962368 1113440816 11.965 98 7 12 0 1152614016 37515840 1657360
10:24:06 57299 8192000000 10069781120 10540793856 1115846416 11.988 98 11 22 0 1173975152 55047296 3081440
10:26:15 35764 8192000000 10066050160 10514288640 1126914016 12.092 99 31 54 2 1160153312 27639584 5599712
10:27:57 58863 8192000000 10120453056 10568753152 1143378320 12.247 100 18 36 0 1209553328 62053168 4121840
10:30:20 26280 8192000000 10152472240 10583785472 1116814976 11.997 97 41 80 0 1234761456 109264224 8682256
10:31:53 59665 8192000000 10156590496 10596159488 1104725232 11.882 97 32 64 1 1225942800 114093632 7123936
10:33:53 69819 8192000000 10139018160 10609676288 1113504656 11.966 98 31 61 0 1249660096 129577936 6577504
10:36:08 40533 8192000000 10118438544 10536648704 1091731504 11.759 97 41 79 1 1171769408 71762656 8275248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 20-10-2022 10:38:50 to 20-10-2022 10:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2022 10:39:08 to 20-10-2022 10:39:10.

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          207150
MON$OLDEST_ACTIVE               211332
MON$OLDEST_SNAPSHOT             211332
MON$NEXT_TRANSACTION            211332
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                       2058400
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2861 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2861 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2861 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Oct 20 10:37:30 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Oct 20 10:37:39 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Thu Oct 20 10:37:40 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Thu Oct 20 10:37:41 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

20.10.22 10:39:11. 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-10-20 10:39:13 No severe PSQL-related problems occured
20.10.22 10:39:15. End of report.