Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.02.2023 10:40
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 14891716 kB
MemAvailable 30431396 kB
Buffers 0 kB
Cached 16835472 kB
SwapCached 3568 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 2023-02-06 10:37:27
DTS_END                         2023-02-06 10:37:27.8930
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/40476
TRN_ID                          213790
ATT_ID                          655
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: 8966.72 33304 1076006
customer order: creation 160.85 536 19302
customer order: refuse 14.40 479 1728
order to supplier: creation 43.08 1476 5169
order to supplier: removal 3.31 190943 397
invoice (draft): creation 43.49 3398 5219
invoice (draft): removal 51.15 1066 6138
invoice accept: apply 40.13 29976 4815
invoice accept: cancel 46.11 1382 5533
customer reserve: creation 3752.37 284 450284
customer reserve: removal 4528.67 109 543440
realization accept: apply 76.94 299 9233
realization accept: cancel 81.89 194 9827
payment from customer: creation 56.53 194 6783
payment from customer: removal 5.49 151 659
payment to supplier: creation 46.39 201 5567
payment to supplier: removal 3.59 151 431
service: total inventory turnovers 3.74 4336 449
service: total monetary turnovers 7.63 352 915
service: aggregate perf. data 0.93 16948 112
service: refresh index statistics 0.04 413600 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-02-2023 10:40:42 to 06-02-2023 10:40:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5163
TEST_TIME 2 5176
TEST_TIME 3 5485
TEST_TIME 4 6131
TEST_TIME 5 6142
TEST_TIME 6 6531
TEST_TIME 7 6277
TEST_TIME 8 6270
TEST_TIME 9 6562
TEST_TIME 10 6626
TEST_TIME 11 6799
TEST_TIME 12 6719
TEST_TIME 13 6756
TEST_TIME 14 6652
TEST_TIME 15 6620
TEST_TIME 16 6766
TEST_TIME 17 6728
TEST_TIME 18 6971
TEST_TIME 19 7052
TEST_TIME 20 7062
TEST_TIME 21 7130
TEST_TIME 22 7285
TEST_TIME 23 7445
TEST_TIME 24 7399
TEST_TIME 25 7451
TEST_TIME 26 7569
TEST_TIME 27 7775
TEST_TIME 28 7893
TEST_TIME 29 8105
TEST_TIME 30 7956
TEST_TIME 31 8297
TEST_TIME 32 8991
TEST_TIME 33 9296
TEST_TIME 34 9414
TEST_TIME 35 9496
TEST_TIME 36 9670
TEST_TIME 37 10089
TEST_TIME 38 10756
TEST_TIME 39 10850
TEST_TIME 40 10679
TEST_TIME 41 10490
TEST_TIME 42 10339
TEST_TIME 43 10168
TEST_TIME 44 10140
TEST_TIME 45 10320
TEST_TIME 46 10141
TEST_TIME 47 10028
TEST_TIME 48 9971
TEST_TIME 49 9919
TEST_TIME 50 9802
TEST_TIME 51 9767
TEST_TIME 52 9731
TEST_TIME 53 9727
TEST_TIME 54 9637
TEST_TIME 55 9654
TEST_TIME 56 9583
TEST_TIME 57 9551
TEST_TIME 58 9654
TEST_TIME 59 9603
TEST_TIME 60 9540
TEST_TIME 61 9510
TEST_TIME 62 9460
TEST_TIME 63 9523
TEST_TIME 64 9492
TEST_TIME 65 9496
TEST_TIME 66 9460
TEST_TIME 67 9524
TEST_TIME 68 9545
TEST_TIME 69 9513
TEST_TIME 70 9511
TEST_TIME 71 9624
TEST_TIME 72 9571
TEST_TIME 73 9533
TEST_TIME 74 9563
TEST_TIME 75 9479
TEST_TIME 76 9390
TEST_TIME 77 9355
TEST_TIME 78 9347
TEST_TIME 79 9316
TEST_TIME 80 9307
TEST_TIME 81 9258
TEST_TIME 82 9244
TEST_TIME 83 9208
TEST_TIME 84 9163
TEST_TIME 85 9205
TEST_TIME 86 9189
TEST_TIME 87 9122
TEST_TIME 88 9123
TEST_TIME 89 9252
TEST_TIME 90 9262
TEST_TIME 91 9256
TEST_TIME 92 9253
TEST_TIME 93 9286
TEST_TIME 94 9296
TEST_TIME 95 9278
TEST_TIME 96 9194
TEST_TIME 97 9190
TEST_TIME 98 9126
TEST_TIME 99 9136
TEST_TIME 100 9098
TEST_TIME 101 9109
TEST_TIME 102 9096
TEST_TIME 103 9059
TEST_TIME 104 8995
TEST_TIME 105 9000
TEST_TIME 106 8990
TEST_TIME 107 8982
TEST_TIME 108 8986
TEST_TIME 109 9031
TEST_TIME 110 8999
TEST_TIME 111 9069
TEST_TIME 112 9058
TEST_TIME 113 9045
TEST_TIME 114 9081
TEST_TIME 115 9099
TEST_TIME 116 9102
TEST_TIME 117 9089
TEST_TIME 118 9097
TEST_TIME 119 9038
TEST_TIME 120 8980

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-02-2023 10:40:46 to 06-02-2023 10:40:49.

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 1491185 1491185 0 0.00 0 26790 15 0 0
doc_list_biud 1491179 1491179 0 0.00 0 32214 46 0 0
sp_add_invoice_to_stock 4899 4815 84 1.71 27 298942 29976 0 84
sp_cancel_adding_invoice 5533 5533 0 0.00 5 87584 1383 0 0
sp_cancel_client_order 1728 1728 0 0.00 6 11287 479 0 0
sp_cancel_customer_reserve 543440 543440 0 0.00 1 36991 109 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 4 5470 151 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 4 6345 152 0 0
sp_cancel_supplier_invoice 6142 6138 4 0.06 2 32235 1067 0 4
sp_cancel_supplier_order 397 397 0 0.00 9 1799712 190944 0 0
sp_cancel_write_off 9827 9827 0 0.00 2 10280 194 0 0
sp_client_order 19302 19302 0 0.00 5 28256 536 0 0
sp_customer_reserve 450284 450284 0 0.00 0 123944 284 0 0
sp_fill_shopping_cart 29677 29677 0 0.00 1 11225 86 0 0
sp_fill_shopping_cart_clo_res 450256 450256 0 0.00 0 17335 21 0 0
sp_get_clo_for_invoice 4775 4775 0 0.00 0 32085 301 0 0
sp_kill_cost_storno 16450 16450 0 0.00 0 26777 84 0 0
sp_kill_qstorno_ret_qs2qd 1103242 1103242 0 0.00 0 32211 47 0 0
sp_kill_qty_storno 581069 581069 0 0.00 0 32213 109 0 0
sp_lock_selected_doc 19627 19627 0 0.00 0 9093 8 0 0
sp_make_cost_storno 26357 26357 0 0.00 0 15364 84 0 0
sp_multiply_rows_for_pdistr 26356 26356 0 0.00 0 11439 43 0 0
sp_multiply_rows_for_qdistr 19302 19302 0 0.00 0 16277 196 0 0
sp_pay_from_customer 6783 6783 0 0.00 5 16337 195 0 0
sp_pay_to_supplier 5567 5567 0 0.00 6 14206 201 0 0
sp_payment_common 12350 12350 0 0.00 2 12524 47 0 0
sp_qd_handle_on_cancel_clo 1728 1728 0 0.00 1 11273 396 0 0
sp_qd_handle_on_invoice_upd_sts 10306 10306 0 0.00 0 19389 598 0 0
sp_qd_handle_on_reserve_upd_sts 9233 9233 0 0.00 0 12992 65 0 0
sp_reserve_write_off 9233 9233 0 0.00 9 20355 300 0 0
sp_supplier_invoice 5219 5219 0 0.00 10 110669 3398 0 0
sp_supplier_order 5169 5169 0 0.00 8 38880 1476 0 0
srv_aggregate_perf_data 112 112 0 0.00 2359 289562 16948 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 820 155330 32704 0 0
srv_find_qd_qs_mism 1029419 1029419 0 0.00 0 17825 12 0 0
srv_make_invnt_saldo 449 449 0 0.00 52 57921 4337 0 0
srv_make_money_saldo 915 915 0 0.00 1 18032 352 0 0
srv_recalc_idx_stat 5 5 0 0.00 214242 522922 413600 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 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 4815 4815 0 0.00 1 15771 1118 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 1 7556 588 0 0
t$perf-norm:sp_cancel_client_order 1728 1728 0 0.00 0 4719 36 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 1844 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 416 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 1811 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 8503 65 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 94628 7823 0 0
t$perf-norm:sp_cancel_write_off 954 954 0 0.00 0 3090 22 0 0
t$perf-norm:sp_client_order 19302 19302 0 0.00 0 6580 41 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 1994 17 0 0
t$perf-norm:sp_pay_from_customer 6783 6783 0 0.00 0 4370 23 0 0
t$perf-norm:sp_pay_to_supplier 5567 5567 0 0.00 0 4662 22 0 0
t$perf-norm:sp_reserve_write_off 9233 9233 0 0.00 0 11360 32 0 0
t$perf-norm:sp_supplier_invoice 5219 5219 0 0.00 0 5524 39 0 0
t$perf-norm:sp_supplier_order 5170 5170 0 0.00 0 14649 42 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 390 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 307 14 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 4484 31 0 0
t$perf-norm:srv_make_money_saldo 915 915 0 0.00 0 225 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 328 90 0 0
v_add_invoice_to_stock 9700 9532 168 1.73 0 3984 26 0 168
v_all_customers 35830 35830 0 0.00 0 72 4 0 0
v_all_suppliers 28945 28945 0 0.00 0 95 5 0 0
v_all_wares 38600 38600 0 0.00 0 93 4 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 4004 22 0 0
v_cancel_client_order 3456 3456 0 0.00 0 2086 11 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 2896 16 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 1 2720 17 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 1483 16 0 0
v_cancel_supplier_invoice 782 774 8 1.02 0 3498 33 0 8
v_cancel_supplier_order 794 794 0 0.00 0 4325 24 0 0
v_cancel_write_off 1908 1908 0 0.00 0 3042 30 0 0
v_max_id_clo_ord 5165 5165 0 0.00 0 173 5 0 0
v_max_id_clo_res 951 951 0 0.00 0 2933 18 0 0
v_max_id_ord_sup 5209 5209 0 0.00 0 3863 8 0 0
v_max_non_paid_invoice 1464 1464 0 0.00 0 108 9 0 0
v_max_non_paid_realizn 3332 3332 0 0.00 0 1995 8 0 0
v_min_id_clo_ord 5164 5164 0 0.00 0 4561 11 0 0
v_min_id_clo_res 950 950 0 0.00 0 5762 23 0 0
v_min_id_ord_sup 5209 5209 0 0.00 0 5027 11 0 0
v_min_non_paid_invoice 5567 5567 0 0.00 0 6367 16 0 0
v_min_non_paid_realizn 6783 6783 0 0.00 0 7848 14 0 0
v_our_firm 9672 9672 0 0.00 0 59 5 0 0
v_reserve_write_off 18465 18465 0 0.00 0 4298 14 0 0
x_lock_depdocs_on_canc_invoice 11284 11284 0 0.00 0 17331 144 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 3914 397 0 0
x_make_qstorno_1000_1200 5169 5169 0 0.00 3 38799 1286 0 0
x_make_qstorno_1200_2000 5219 5219 0 0.00 4 109781 3173 0 0
x_make_qstorno_2100_3300 450282 450282 0 0.00 0 123880 248 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-02-2023 10:40:50 to 06-02-2023 10:40:54.

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:31 929 8192000000 8932807264 8990892032 24943488 0.303 5 4 7 0 25620640 204544 472608
08:08:33 43145 8192000000 9724304096 10020814848 788667056 8.781 100 85 170 0 817804880 8037216 21100608
08:10:21 11475 8192000000 9753376592 10060447744 828978528 9.189 100 90 178 0 862549056 11804976 21765552
08:11:36 15415 8192000000 9798219744 10113720320 857946064 9.480 100 86 172 0 898168192 20128832 20093296
08:12:56 8188 8192000000 9834949584 10168463360 910718608 10.004 100 82 163 0 952746208 23500832 18526768
08:14:12 5050 8192000000 9903258944 10246258688 951582720 10.407 100 77 152 1 1021193856 53230080 16381056
08:15:21 1968 8192000000 9959972960 10309959680 989410752 10.776 100 92 184 0 1075882928 67510096 18962080
08:16:25 1151 8192000000 9992324736 10351124480 1015068256 11.024 100 89 178 0 1105974976 73286496 17620224
08:17:31 1200 8192000000 9999568528 10368319488 1030742592 11.176 100 92 173 0 1122571248 75231696 16596960
08:18:35 857 8192000000 10041643504 10413408256 1054868768 11.407 100 91 161 1 1160654032 89226576 16558688
08:19:37 1136 8192000000 10042409584 10421387264 1071951136 11.571 100 95 187 4 1163533984 72477616 19105232
08:20:39 853 8192000000 10050128096 10432499712 1078787424 11.636 100 84 157 3 1171504288 76526272 16190592
08:21:42 13215 8192000000 10058444240 10447417344 1079461792 11.642 100 61 120 1 1170830112 79241776 12126544
08:22:58 1000 8192000000 10058158656 10444697600 1090428848 11.747 100 100 193 4 1181125056 71192096 19504112
08:23:59 1246 8192000000 10068440320 10458783744 1089900320 11.742 100 98 183 0 1189708064 81373216 18434528
08:25:01 1602 8192000000 10059550832 10452787200 1093183392 11.773 100 77 145 1 1179428144 71276064 14968688
08:26:04 3565 8192000000 10037399888 10432569344 1092105360 11.763 100 80 142 4 1157031584 49696800 15229424
08:27:08 1177 8192000000 10062948832 10450919424 1094674288 11.787 100 94 180 3 1185057376 70891056 19492032
08:28:10 1157 8192000000 10082732160 10476732416 1094082528 11.781 100 96 183 1 1206331872 93527856 18721488
08:29:13 1840 8192000000 10058555328 10448277504 1093095840 11.772 100 82 161 0 1181325504 72088192 16141472
08:30:16 1323 8192000000 10058830736 10449838080 1089529600 11.738 100 98 177 3 1181325376 74105056 17690720
08:31:19 1190 8192000000 10053232176 10433781760 1071327296 11.565 100 98 184 0 1173399648 82083520 19988832
08:32:21 914 8192000000 10018355744 10396598272 1053641120 11.396 99 94 183 4 1143063616 70852112 18570384
08:33:24 1018 8192000000 10003311856 10385014784 1059753008 11.454 100 91 169 2 1129611968 50745056 19113904
08:34:28 2890 8192000000 9988223952 10379296768 1048288832 11.344 100 65 121 1 1110613040 49570672 12753536
08:35:31 867 8192000000 9986262608 10358661120 1022043024 11.092 99 90 168 1 1108285312 68295920 17946368
08:36:34 1342 8192000000 9984586768 10361405440 1045309264 11.316 100 92 170 2 1105933200 43306624 17317312
08:37:36 9745 8192000000 9977129376 10354475008 1043857808 11.302 100 67 129 0 1094497664 38262288 12377568
08:38:57 3948 8192000000 10028683888 10415697920 1059180000 11.449 100 71 138 3 1153118768 80329392 13609376
08:40:06 27812 8192000000 10034938304 10440339456 1074690416 11.597 100 50 95 1 1150374736 67006624 8677696
08:41:38 1532 8192000000 10068740400 10450575360 1083203472 11.678 100 98 187 2 1191243888 87283152 20757264
08:42:42 1484 8192000000 10087062752 10472366080 1090683360 11.749 99 88 170 0 1205497632 96245552 18568720
08:43:47 851 8192000000 10120986128 10508095488 1112360256 11.955 100 94 181 1 1233309280 101253072 19695952
08:44:53 4741 8192000000 10131019648 10533027840 1118820640 12.016 100 77 146 0 1253429024 117917200 16691184
08:46:01 1230 8192000000 10204054832 10593402880 1137197632 12.189 100 94 186 0 1318123152 160652224 20273296
08:47:06 1757 8192000000 10212217328 10604376064 1150972656 12.319 100 90 173 0 1328058608 157810544 19275408
08:48:16 834 8192000000 10255708032 10651344896 1151377120 12.322 100 95 175 1 1368800608 198530016 18893472
08:49:21 1069 8192000000 10278201184 10676240384 1174154992 12.536 100 89 172 1 1393252144 200849424 18247728
08:50:30 1383 8192000000 10307327472 10706759680 1188550144 12.670 100 89 168 2 1416581264 209731360 18299760
08:51:36 1895 8192000000 10350259936 10762227712 1192381184 12.706 100 91 176 0 1462345120 249199376 20764560
08:52:43 1078 8192000000 10336518640 10748571648 1183503040 12.623 100 94 187 0 1448646080 242985552 22157488
08:53:48 2041 8192000000 10405644000 10809937920 1195588304 12.735 100 85 167 0 1512763104 297301056 19873744
08:54:53 1062 8192000000 10425651376 10851201024 1194617568 12.726 100 96 185 0 1531832240 316225744 20988928
08:55:59 1740 8192000000 10455976544 10860826624 1214934432 12.915 100 95 189 0 1567763488 330261824 22567232
08:57:04 966 8192000000 10467693152 10876293120 1211041536 12.879 100 96 190 0 1573476720 340559328 21875856
08:58:10 2556 8192000000 10495212288 10908086272 1209275280 12.862 100 95 184 0 1605627152 373140176 23211696
08:59:15 3198 8192000000 10499014864 10940424192 1196151792 12.741 99 85 168 3 1619805936 403370160 20283984
09:00:22 839 8192000000 10540338144 10950475776 1196067152 12.740 100 98 185 2 1645718416 427540448 22110816
09:01:26 928 8192000000 10579104992 11005419520 1205841664 12.831 100 97 186 0 1678995040 451619776 21533600
09:02:31 1651 8192000000 10580041696 11023609856 1202974304 12.804 100 94 186 0 1687310944 461832912 22503728
09:03:35 2160 8192000000 10596107584 11062366208 1198287248 12.760 100 90 175 1 1703923808 482599120 23037440
09:04:42 6642 8192000000 10640142800 11068796928 1179953008 12.590 98 71 142 0 1736747904 538359968 18434928
09:05:53 816 8192000000 10600267216 11058278400 1182134560 12.610 100 94 181 1 1703260384 497634432 23491392
09:06:57 1915 8192000000 10657092304 11088994304 1187540720 12.660 100 61 119 0 1757691456 553936624 16214112
09:08:03 2955 8192000000 10639171984 11117137920 1177634864 12.568 100 92 172 1 1740954384 541718160 21601360
09:09:09 5112 8192000000 10586489904 11118813184 1182032304 12.609 100 53 104 0 1690730368 494678400 14019664
09:10:22 9476 8192000000 10464835152 10907271168 1178204768 12.573 100 51 100 0 1572677312 381808800 12663744
09:11:40 60358 8192000000 10425357968 10963267584 1161484800 12.417 99 21 42 0 1535972704 368714112 5773792
09:13:41 27234 8192000000 10340784080 10890539008 1142638592 12.240 99 13 26 0 1452976032 306522880 3814560
09:15:11 24869 8192000000 10162767984 10681798656 1132150512 12.142 99 24 47 0 1254192944 116215808 5826624
09:16:37 76706 8192000000 10069766464 10499203072 1101968608 11.856 97 37 66 0 1136562608 27772176 6821824
09:18:55 12289 8192000000 10124761808 10537115648 1126942768 12.093 99 71 133 4 1213669872 74025328 12701776
09:20:15 155329 8192000000 10071695568 10562158592 1113292224 11.964 98 35 66 2 1229668752 109021904 7354624
09:23:58 64048 8192000000 10081752704 10509475840 1114423408 11.974 98 46 81 3 1151906848 29039552 8443888
09:26:03 32104 8192000000 10102205472 10535129088 1134685200 12.166 99 47 83 1 1191708880 46845264 10178416
09:27:39 14597 8192000000 10167473856 10602418176 1148638640 12.297 99 59 105 1 1237840656 76573968 12628048
09:29:02 69409 8192000000 10145306176 10613088256 1136833360 12.186 98 17 34 0 1246993968 105660272 4500336
09:31:12 42663 8192000000 10175509248 10635239424 1141725824 12.232 99 27 51 0 1258394672 111161072 5507776
09:33:03 106470 8192000000 10167964256 10652606464 1117812320 12.006 97 25 52 1 1255414960 131317600 6285040
09:36:11 20109 8192000000 10084814112 10532237312 1136876672 12.186 99 41 74 4 1182380240 37670352 7833216
09:37:34 46698 8192000000 10118339440 10605654016 1143525504 12.249 100 25 50 0 1197280400 48894688 4860208
09:39:36 45099 8192000000 10134254928 10582896640 1144870192 12.261 100 39 78 0 1207630080 53764128 8995760
09:41:38 33415 8192000000 10136825520 10586288128 1141956656 12.234 99 26 48 0 1213129408 66384704 4788048
09:43:48 31647 8192000000 10129495328 10582433792 1149388208 12.304 100 32 62 0 1229136624 73217280 6531136
09:45:23 77196 8192000000 10079135776 10539778048 1106769040 11.902 97 16 31 0 1149456688 39833344 2854304
09:47:40 95876 8192000000 10091133536 10573565952 1104165872 11.877 96 25 50 0 1182396656 73388960 4841824
09:50:18 76925 8192000000 10106146192 10588516352 1126573712 12.089 99 23 46 0 1223609072 92220736 4814624
09:53:29 33935 8192000000 10062886112 10503532544 1115655600 11.986 99 53 104 1 1158280800 32119056 10506144
09:55:04 37239 8192000000 10085346128 10535579648 1116699408 11.996 99 43 86 1 1192269568 67298272 8271888
09:56:49 100285 8192000000 10082064496 10572791808 1111042128 11.942 98 14 28 0 1163783312 49109520 3631664
09:59:30 49308 8192000000 10096326064 10547519488 1134502368 12.164 99 20 39 0 1172673472 34362048 3809056
10:01:21 41268 8192000000 10142850656 10594938880 1151532096 12.324 100 39 76 0 1229804144 70502640 7769408
10:03:07 49439 8192000000 10137583072 10604773376 1151320256 12.322 100 20 40 0 1217518944 62071520 4127168
10:04:57 71149 8192000000 10206120128 10664583168 1144233792 12.255 99 27 55 0 1289825072 139726240 5865040
10:07:09 78312 8192000000 10074102160 10511867904 1103896496 11.875 96 19 36 0 1150728336 43286000 3545840
10:09:33 44504 8192000000 10095915760 10575687680 1110800656 11.940 98 37 74 0 1227647824 109481056 7366112
10:11:20 19556 8192000000 10105481072 10544263168 1122033904 12.046 99 41 80 0 1178119408 48330672 7754832
10:12:44 92344 8192000000 10068848992 10530271232 1122597856 12.052 99 46 90 0 1183970208 52289360 9082992
10:15:23 2376 8192000000 10052453248 10511024128 1128010688 12.103 99 35 68 1 1155095104 19612736 7471680
10:16:26 44649 8192000000 10084026784 10558062592 1111857600 11.950 98 14 28 0 1183918816 69066256 2994960
10:18:11 76526 8192000000 10069513312 10511220736 1114807952 11.978 98 14 26 2 1169568640 52080640 2680048
10:20:29 81535 8192000000 10081717488 10539323392 1119618752 12.023 99 20 40 0 1178214912 53984944 4611216
10:24:31 65215 8192000000 10117325536 10586906624 1147269072 12.284 100 24 46 2 1213048096 60864000 4915024
10:26:42 59193 8192000000 10129136080 10572279808 1151755664 12.326 100 18 34 0 1210254960 55210496 3288800
10:28:52 35346 8192000000 10116189056 10552696832 1127557760 12.098 98 39 76 0 1193807216 58777120 7472336
10:30:27 56957 8192000000 10084696256 10547486720 1116922016 11.998 97 29 54 1 1222871760 100273296 5676448
10:32:25 56642 8192000000 10107337456 10556592128 1125223536 12.076 98 34 68 0 1165190240 33085568 6881136
10:34:22 4064 8192000000 10123432048 10552369152 1149734448 12.307 100 70 137 0 1206040128 43330912 12974768
10:35:27 21900 8192000000 10141169264 10602635264 1114358672 11.974 98 50 94 0 1214257952 90517616 9381664
10:36:53 48849 8192000000 9316993008 9628905472 422238544 4.901 37 15 30 0 483296032 58204496 2852992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 06-02-2023 10:40:56 to 06-02-2023 10:41:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 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 06-02-2023 10:41:14 to 06-02-2023 10:41:16.

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          214031
MON$OLDEST_ACTIVE               214032
MON$OLDEST_SNAPSHOT             214032
MON$NEXT_TRANSACTION            214032
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                       2083296
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2895 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2895 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2895 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2895 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 ---
:199: 
:200: mscs-test-161.budzdorov.ru    Mon Feb  6 10:37:44 2023
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:202: 
:203: 
:204: mscs-test-161.budzdorov.ru    Mon Feb  6 10:38:13 2023
:205:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:206: 
:207: 
:208: mscs-test-161.budzdorov.ru    Mon Feb  6 10:38:18 2023
:209:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:210: 
--- end of diff output ---

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

06.02.23 10:41:17. 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
2023-02-06 10:41:20 No severe PSQL-related problems occured
06.02.23 10:41:21. End of report.