Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.01.2023 13:39
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 14776056 kB
MemAvailable 30442080 kB
Buffers 0 kB
Cached 16952352 kB
SwapCached 4388 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-01-10 13:37:24
DTS_END                         2023-01-10 13:37:24.6870
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/52252
TRN_ID                          205990
ATT_ID                          562
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: 8409.11 26092 1009093
customer order: creation 154.06 530 18487
customer order: refuse 13.17 519 1580
order to supplier: creation 42.08 1556 5050
order to supplier: removal 3.32 181173 398
invoice (draft): creation 42.06 3771 5047
invoice (draft): removal 48.83 1131 5859
invoice accept: apply 37.53 28700 4504
invoice accept: cancel 44.18 1379 5301
customer reserve: creation 3478.32 268 417398
customer reserve: removal 4280.07 109 513608
realization accept: apply 76.53 340 9183
realization accept: cancel 71.18 180 8541
payment from customer: creation 53.13 232 6375
payment from customer: removal 4.91 139 589
payment to supplier: creation 44.87 238 5384
payment to supplier: removal 3.03 205 363
service: total inventory turnovers 3.48 5182 418
service: total monetary turnovers 7.51 396 901
service: aggregate perf. data 0.85 19431 102
service: refresh index statistics 0.04 276365 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-01-2023 13:40:10 to 10-01-2023 13:40:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4214
TEST_TIME 2 4664
TEST_TIME 3 4652
TEST_TIME 4 4086
TEST_TIME 5 4568
TEST_TIME 6 4488
TEST_TIME 7 5052
TEST_TIME 8 5369
TEST_TIME 9 5700
TEST_TIME 10 6050
TEST_TIME 11 6277
TEST_TIME 12 6264
TEST_TIME 13 6523
TEST_TIME 14 6919
TEST_TIME 15 6941
TEST_TIME 16 6945
TEST_TIME 17 6948
TEST_TIME 18 7315
TEST_TIME 19 7477
TEST_TIME 20 7639
TEST_TIME 21 7949
TEST_TIME 22 7978
TEST_TIME 23 8151
TEST_TIME 24 8310
TEST_TIME 25 8721
TEST_TIME 26 8726
TEST_TIME 27 8656
TEST_TIME 28 8536
TEST_TIME 29 8625
TEST_TIME 30 8670
TEST_TIME 31 8793
TEST_TIME 32 8957
TEST_TIME 33 9231
TEST_TIME 34 9349
TEST_TIME 35 9551
TEST_TIME 36 9702
TEST_TIME 37 9821
TEST_TIME 38 10185
TEST_TIME 39 10381
TEST_TIME 40 10392
TEST_TIME 41 10307
TEST_TIME 42 10392
TEST_TIME 43 10381
TEST_TIME 44 10179
TEST_TIME 45 10059
TEST_TIME 46 10150
TEST_TIME 47 9990
TEST_TIME 48 9820
TEST_TIME 49 9728
TEST_TIME 50 9620
TEST_TIME 51 9501
TEST_TIME 52 9483
TEST_TIME 53 9426
TEST_TIME 54 9319
TEST_TIME 55 9340
TEST_TIME 56 9428
TEST_TIME 57 9485
TEST_TIME 58 9386
TEST_TIME 59 9247
TEST_TIME 60 9150
TEST_TIME 61 9190
TEST_TIME 62 9085
TEST_TIME 63 9110
TEST_TIME 64 9015
TEST_TIME 65 8954
TEST_TIME 66 8890
TEST_TIME 67 8891
TEST_TIME 68 8823
TEST_TIME 69 8745
TEST_TIME 70 8838
TEST_TIME 71 8830
TEST_TIME 72 8814
TEST_TIME 73 8806
TEST_TIME 74 8814
TEST_TIME 75 8813
TEST_TIME 76 8775
TEST_TIME 77 8746
TEST_TIME 78 8806
TEST_TIME 79 8760
TEST_TIME 80 8825
TEST_TIME 81 8889
TEST_TIME 82 8843
TEST_TIME 83 8846
TEST_TIME 84 8809
TEST_TIME 85 8900
TEST_TIME 86 8810
TEST_TIME 87 8747
TEST_TIME 88 8711
TEST_TIME 89 8671
TEST_TIME 90 8755
TEST_TIME 91 8704
TEST_TIME 92 8668
TEST_TIME 93 8643
TEST_TIME 94 8633
TEST_TIME 95 8624
TEST_TIME 96 8578
TEST_TIME 97 8553
TEST_TIME 98 8529
TEST_TIME 99 8529
TEST_TIME 100 8498
TEST_TIME 101 8465
TEST_TIME 102 8494
TEST_TIME 103 8532
TEST_TIME 104 8530
TEST_TIME 105 8560
TEST_TIME 106 8610
TEST_TIME 107 8648
TEST_TIME 108 8698
TEST_TIME 109 8673
TEST_TIME 110 8655
TEST_TIME 111 8683
TEST_TIME 112 8691
TEST_TIME 113 8618
TEST_TIME 114 8571
TEST_TIME 115 8533
TEST_TIME 116 8533
TEST_TIME 117 8555
TEST_TIME 118 8516
TEST_TIME 119 8480
TEST_TIME 120 8430

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-01-2023 13:40:14 to 10-01-2023 13:40:17.

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 1394048 1394048 0 0.00 0 20896 14 0 0
doc_list_biud 1394032 1394032 0 0.00 0 60526 47 0 0
sp_add_invoice_to_stock 4613 4504 109 2.36 18 220402 28701 0 109
sp_cancel_adding_invoice 5301 5301 0 0.00 6 107235 1380 0 0
sp_cancel_client_order 1580 1580 0 0.00 5 16067 519 0 0
sp_cancel_customer_reserve 513608 513608 0 0.00 1 60537 110 0 0
sp_cancel_pay_from_customer 589 589 0 0.00 4 5351 140 0 0
sp_cancel_pay_to_supplier 363 363 0 0.00 4 7451 205 0 0
sp_cancel_supplier_invoice 5861 5859 2 0.03 3 34438 1132 0 2
sp_cancel_supplier_order 398 398 0 0.00 7 1492831 181173 0 0
sp_cancel_write_off 8541 8541 0 0.00 2 12978 180 0 0
sp_client_order 18487 18487 0 0.00 6 21333 531 0 0
sp_customer_reserve 417398 417398 0 0.00 1 42691 269 0 0
sp_fill_shopping_cart 28571 28571 0 0.00 1 19760 108 0 0
sp_fill_shopping_cart_clo_res 417363 417363 0 0.00 0 12796 21 0 0
sp_get_clo_for_invoice 4459 4459 0 0.00 0 34662 433 0 0
sp_kill_cost_storno 14794 14794 0 0.00 0 11259 88 0 0
sp_kill_qstorno_ret_qs2qd 1042014 1042014 0 0.00 0 60509 48 0 0
sp_kill_qty_storno 548927 548927 0 0.00 0 60525 111 0 0
sp_lock_selected_doc 18862 18862 0 0.00 0 8981 8 0 0
sp_make_cost_storno 25401 25401 0 0.00 0 20885 101 0 0
sp_multiply_rows_for_pdistr 25397 25397 0 0.00 0 15471 44 0 0
sp_multiply_rows_for_qdistr 18487 18487 0 0.00 0 16848 182 0 0
sp_pay_from_customer 6375 6375 0 0.00 5 17238 233 0 0
sp_pay_to_supplier 5384 5384 0 0.00 7 17602 239 0 0
sp_payment_common 11757 11757 0 0.00 2 11347 60 0 0
sp_qd_handle_on_cancel_clo 1580 1580 0 0.00 1 8242 424 0 0
sp_qd_handle_on_invoice_upd_sts 9758 9758 0 0.00 0 21252 633 0 0
sp_qd_handle_on_reserve_upd_sts 9183 9183 0 0.00 0 17096 81 0 0
sp_reserve_write_off 9183 9183 0 0.00 9 26246 340 0 0
sp_supplier_invoice 5047 5047 0 0.00 8 65427 3772 0 0
sp_supplier_order 5050 5050 0 0.00 7 51779 1556 0 0
srv_aggregate_perf_data 102 102 0 0.00 2256 240356 19432 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 797 166822 33573 0 0
srv_find_qd_qs_mism 965141 965141 0 0.00 0 9854 10 0 0
srv_make_invnt_saldo 418 418 0 0.00 60 86261 5182 0 0
srv_make_money_saldo 901 901 0 0.00 1 11252 397 0 0
srv_recalc_idx_stat 5 5 0 0.00 61552 432022 276365 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4506 4506 0 0.00 1 19299 1163 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 2 13470 553 0 0
t$perf-norm:sp_cancel_client_order 1580 1580 0 0.00 0 1398 28 0 0
t$perf-norm:sp_cancel_customer_reserve 626 626 0 0.00 0 8230 59 0 0
t$perf-norm:sp_cancel_pay_from_customer 589 589 0 0.00 0 1607 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 363 363 0 0.00 0 3542 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 348 348 0 0.00 0 1373 44 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 121091 8687 0 0
t$perf-norm:sp_cancel_write_off 912 912 0 0.00 0 5214 27 0 0
t$perf-norm:sp_client_order 18487 18487 0 0.00 0 11846 42 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 773 15 0 0
t$perf-norm:sp_pay_from_customer 6375 6375 0 0.00 0 13786 24 0 0
t$perf-norm:sp_pay_to_supplier 5384 5384 0 0.00 0 4385 23 0 0
t$perf-norm:sp_reserve_write_off 9183 9183 0 0.00 0 7562 28 0 0
t$perf-norm:sp_supplier_invoice 5047 5047 0 0.00 0 7448 36 0 0
t$perf-norm:sp_supplier_order 5051 5051 0 0.00 0 4705 37 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 322 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1078 20 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 1696 25 0 0
t$perf-norm:srv_make_money_saldo 901 901 0 0.00 0 4103 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 209 59 0 0
v_add_invoice_to_stock 9128 8910 218 2.38 0 17354 37 0 218
v_all_customers 33934 33934 0 0.00 0 77 4 0 0
v_all_suppliers 28330 28330 0 0.00 0 85 5 0 0
v_all_wares 36960 36960 0 0.00 0 129 5 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 6859 24 0 0
v_cancel_client_order 3160 3160 0 0.00 0 15831 17 0 0
v_cancel_customer_prepayment 1178 1178 0 0.00 0 4419 14 0 0
v_cancel_customer_reserve 1252 1252 0 0.00 0 5705 17 0 0
v_cancel_payment_to_supplier 726 726 0 0.00 0 5458 26 0 0
v_cancel_supplier_invoice 700 696 4 0.57 0 3946 37 0 4
v_cancel_supplier_order 796 796 0 0.00 0 5701 21 0 0
v_cancel_write_off 1824 1824 0 0.00 0 6298 30 0 0
v_max_id_clo_ord 5048 5048 0 0.00 0 204 6 0 0
v_max_id_clo_res 943 943 0 0.00 0 4601 18 0 0
v_max_id_ord_sup 5042 5042 0 0.00 0 2759 8 0 0
v_max_non_paid_invoice 1305 1305 0 0.00 0 260 9 0 0
v_max_non_paid_realizn 3310 3310 0 0.00 0 2030 9 0 0
v_min_id_clo_ord 5048 5048 0 0.00 0 3086 11 0 0
v_min_id_clo_res 943 943 0 0.00 0 9284 41 0 0
v_min_id_ord_sup 5041 5041 0 0.00 0 9490 20 0 0
v_min_non_paid_invoice 5382 5382 0 0.00 0 11338 22 0 0
v_min_non_paid_realizn 6374 6374 0 0.00 0 10089 29 0 0
v_our_firm 9154 9154 0 0.00 0 61 5 0 0
v_reserve_write_off 18364 18364 0 0.00 0 15169 19 0 0
x_lock_depdocs_on_canc_invoice 10812 10812 0 0.00 0 12069 137 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 16068 487 0 0
x_make_qstorno_1000_1200 5050 5050 0 0.00 2 51644 1347 0 0
x_make_qstorno_1200_2000 5047 5047 0 0.00 4 61883 3458 0 0
x_make_qstorno_2100_3300 417396 417396 0 0.00 0 42674 233 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-01-2023 13:40:18 to 10-01-2023 13:40:23.

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:28 2 8192000000 8800035392 8811630592 4525216 0.055 2 1 2 0 4624640 63648 35776
11:08:30 33743 8192000000 9730627072 10032472064 799403888 8.890 100 86 173 1 828300304 7803568 21092848
11:10:10 27339 8192000000 9759655408 10066481152 831616640 9.216 100 92 184 0 868077328 14163024 22297664
11:11:46 7648 8192000000 9796829456 10116026368 862928144 9.529 100 83 166 0 908563408 25817328 19817936
11:13:06 7789 8192000000 9869774432 10203107328 917825808 10.075 100 88 170 0 984738448 48056576 18856064
11:14:16 3288 8192000000 9912541376 10253914112 956782640 10.458 100 90 170 2 1029290560 55050256 17457664
11:15:23 2756 8192000000 9947823008 10302754816 1001139744 10.890 100 87 169 1 1069794224 51473568 17180912
11:16:28 2269 8192000000 9997268160 10360119296 1028350864 11.153 100 85 166 0 1115593392 70815024 16427504
11:17:33 1073 8192000000 10022293312 10394791936 1043900896 11.302 100 88 169 1 1146097488 84775952 17420640
11:18:36 1116 8192000000 10029618640 10409242624 1067769040 11.531 100 94 175 0 1157908416 72956944 17182432
11:19:38 1060 8192000000 10042727184 10418720768 1074265984 11.593 100 95 177 0 1159898624 69363536 16269104
11:20:41 1202 8192000000 10035021840 10414899200 1075535952 11.605 100 91 176 1 1155931088 62400464 17994672
11:21:44 1122 8192000000 10062302576 10446434304 1077851984 11.627 100 94 180 1 1184321312 87400672 19068656
11:22:47 1613 8192000000 10053130592 10440855552 1083557392 11.681 100 97 178 0 1174929184 73113664 18258128
11:23:51 1105 8192000000 10048469456 10434351104 1090700400 11.749 100 93 169 0 1170269696 61273088 18296208
11:24:53 1282 8192000000 10077751904 10468044800 1093880672 11.780 100 98 190 0 1200243904 86794672 19568560
11:25:56 1327 8192000000 10055084544 10446737408 1094647824 11.787 100 91 172 1 1178714704 66852800 17214080
11:26:59 1173 8192000000 10063119568 10450722816 1092731552 11.769 100 91 171 0 1185282848 76424048 16127248
11:28:02 3899 8192000000 10051425072 10447581184 1102227024 11.859 100 77 149 3 1178984448 63099888 13657536
11:29:07 2679 8192000000 10051659792 10446098432 1097101680 11.810 100 71 136 2 1183763776 73727232 12934864
11:30:10 3691 8192000000 10053627632 10454192128 1088549248 11.729 100 75 146 0 1178401456 74676528 15175680
11:31:16 1098 8192000000 10025870144 10408747008 1066284048 11.517 100 96 180 1 1150372672 63800384 20288240
11:32:18 1322 8192000000 10053016800 10434703360 1073444832 11.585 100 98 185 1 1175979536 83309568 19225136
11:33:20 2022 8192000000 10012047808 10388770816 1051213744 11.372 99 85 161 3 1132090640 64382800 16494096
11:34:24 1199 8192000000 10014237440 10390687744 1026330720 11.133 99 95 179 2 1133557888 86984320 20242848
11:35:26 1125 8192000000 9968465056 10338283520 1031801776 11.186 100 96 171 3 1093544048 44652432 17089840
11:36:29 1166 8192000000 9971307152 10346115072 1032325664 11.191 100 83 157 2 1097747616 48835120 16586832
11:37:32 18970 8192000000 9985072688 10362179584 1045584528 11.318 100 72 132 1 1108309232 48908304 13816400
11:38:57 1365 8192000000 10049773008 10427150336 1061861824 11.474 100 96 180 1 1164327504 84224352 18241328
11:40:09 3040 8192000000 10032512560 10421526528 1072732272 11.578 100 71 138 0 1158256128 70417328 15106528
11:41:31 8757 8192000000 10072661344 10461122560 1084782384 11.693 100 73 138 1 1196703520 96399216 15521920
11:42:54 1289 8192000000 10091299936 10484035584 1095253136 11.793 100 82 144 3 1218044512 107118688 15672688
11:44:07 5275 8192000000 10153485024 10564505600 1111936560 11.951 100 65 130 0 1274509072 147923312 14649200
11:45:16 3077 8192000000 10140958944 10541584384 1118610976 12.014 100 72 143 0 1262014880 128548784 14855120
11:46:25 809 8192000000 10191332848 10596306944 1129519344 12.117 100 87 173 0 1310886208 162698336 18668528
11:47:29 1745 8192000000 10171861248 10564444160 1117483760 12.003 99 85 161 2 1277570256 144360736 15725760
11:48:35 1071 8192000000 10196153696 10590867456 1141346288 12.228 100 95 182 2 1310308912 148382688 20579936
11:49:38 1450 8192000000 10261982256 10662768640 1146948448 12.281 100 93 177 0 1370824336 203967152 19908736
11:50:44 1599 8192000000 10272332800 10671411200 1153563808 12.343 100 94 183 2 1382017984 208686752 19767424
11:51:49 1023 8192000000 10264087760 10666582016 1168676048 12.484 100 92 174 1 1374420432 185694128 20050256
11:52:52 1396 8192000000 10324966272 10730532864 1173955824 12.534 100 87 174 1 1438279280 245049936 19273520
11:54:00 7242 8192000000 10351472976 10756435968 1161700416 12.419 99 87 161 2 1456309408 275805760 18803232
11:55:12 874 8192000000 10384538256 10820108288 1181923312 12.608 100 92 177 1 1493352080 290053552 21375216
11:56:16 1114 8192000000 10401382208 10817953792 1185548736 12.642 100 97 194 1 1514193744 306436512 22208496
11:57:19 828 8192000000 10415128688 10851024896 1186054304 12.647 100 99 194 2 1522285408 313953184 22277920
11:58:24 927 8192000000 10393280368 10829307904 1182379472 12.612 100 97 189 1 1499479440 295024000 22075968
11:59:29 1025 8192000000 10427286928 10841436160 1191053296 12.693 100 97 180 1 1532074624 320634080 20387248
12:00:35 1581 8192000000 10416347680 10874953728 1186152240 12.648 100 93 178 0 1518899504 311688096 21059168
12:01:38 834 8192000000 10392063024 10819112960 1171858192 12.514 100 98 191 0 1504305504 308672304 23775008
12:02:41 787 8192000000 10424769904 10882809856 1194459536 12.725 100 96 188 0 1523588224 306718752 22409936
12:03:45 8013 8192000000 10418486912 10865287168 1186800384 12.654 100 55 107 1 1531312496 331390608 13121504
12:05:00 21082 8192000000 10447311616 10877992960 1155581680 12.362 98 48 92 3 1532829744 366064640 11183424
12:06:34 3899 8192000000 10446417792 10888130560 1161967856 12.422 99 63 124 1 1547411152 370317312 15125984
12:07:43 3802 8192000000 10454476528 10908459008 1173218784 12.527 100 63 120 1 1557423216 369626288 14578144
12:08:57 2058 8192000000 10468764336 10914914304 1183299824 12.621 100 83 163 2 1570107648 367376064 19431760
12:10:01 13504 8192000000 10408097392 10891960320 1147888992 12.290 98 50 96 1 1496697792 336435536 12373264
12:11:31 16213 8192000000 10393711312 10875035648 1161924112 12.421 100 33 64 0 1516624368 345547840 9152416
12:12:49 36593 8192000000 10384925424 10832719872 1170414144 12.501 100 40 75 1 1477529712 297808880 9306688
12:14:36 21065 8192000000 10329944400 10813337600 1153227712 12.340 100 27 54 0 1431509200 271456528 6824960
12:16:13 31035 8192000000 10182435280 10619031552 1129078624 12.113 98 38 69 0 1264137600 125396288 9662688
12:17:50 11010 8192000000 10203892640 10688548864 1153340048 12.341 100 33 66 2 1305627664 144817024 7470592
12:19:41 61422 8192000000 10130497456 10611695616 1130609344 12.127 98 14 26 0 1244085616 110428416 3047856
12:22:26 92100 8192000000 10073797968 10588991488 1134264704 12.162 100 15 31 0 1203527760 65964336 3298720
12:24:58 32968 8192000000 10080455808 10512355328 1144577600 12.259 100 59 108 1 1176643056 21449024 10616432
12:26:33 90821 8192000000 10128697264 10573627392 1102614272 11.862 97 41 83 0 1213019248 101543232 8861744
12:29:06 75739 8192000000 10144781760 10612207616 1110032608 11.933 98 34 67 0 1237022400 119607440 7382352
12:31:27 55633 8192000000 10189637232 10651119616 1100605968 11.843 97 15 30 0 1276337376 171926832 3804576
12:34:19 59356 8192000000 10067760448 10587164672 1107288656 11.907 99 7 14 0 1156210816 47706096 1216064
12:36:40 70996 8192000000 10045312448 10487382016 1096828464 11.808 98 25 50 0 1138112032 35815008 5468560
12:38:59 87473 8192000000 10091636944 10535231488 1099582992 11.834 97 34 63 1 1132733728 25902496 7248240
12:41:31 42472 8192000000 10059461680 10510635008 1135031008 12.169 100 27 52 0 1168802688 28198240 5573440
12:43:20 20256 8192000000 10089483776 10548342784 1127554656 12.098 99 22 42 0 1192921888 59992656 5374576
12:44:52 102482 8192000000 10121399312 10586169344 1131335760 12.134 99 20 40 0 1219861632 84028736 4497136
12:48:05 48666 8192000000 10088143600 10556747776 1126299664 12.086 99 18 36 0 1184351632 53913040 4138928
12:50:13 50343 8192000000 10105418176 10582155264 1129617824 12.118 99 37 74 0 1172957536 35320496 8019216
12:53:15 10073 8192000000 10092909280 10524889088 1137640928 12.193 100 82 150 1 1196308032 43800416 14866688
12:54:27 60140 8192000000 10102127440 10564071424 1136725712 12.185 99 29 59 1 1207737392 65093024 5918656
12:56:32 31178 8192000000 10135898384 10583146496 1134783744 12.166 100 32 64 0 1213225792 71972944 6469104
12:58:07 166822 8192000000 10085006896 10546933760 1075074896 11.601 94 15 30 0 1105546720 27964768 2507056
13:02:02 124862 8192000000 10074172000 10523459584 1092462368 11.766 96 23 46 0 1157216192 59810624 4943200
13:05:38 38719 8192000000 10119162672 10587095040 1124147616 12.066 100 37 69 2 1215656432 84167264 7341552
13:07:32 36632 8192000000 10064462672 10577465344 1128887280 12.111 100 13 25 0 1192500336 60935408 2677648
13:09:32 82513 8192000000 10085708144 10533302272 1094648048 11.787 97 19 38 0 1154971792 56548032 3775712
13:12:02 27671 8192000000 10045619440 10486181888 1096768144 11.807 98 16 31 1 1144291632 44250432 3273056
13:14:09 85095 8192000000 10087623088 10551685120 1111171072 11.944 98 10 20 0 1161112352 48285616 1655664
13:16:35 41007 8192000000 10117529392 10547994624 1126502272 12.088 98 32 62 0 1194269632 60507024 7260336
13:18:19 17986 8192000000 10171408160 10606026752 1154526656 12.352 100 34 67 0 1263122880 101227184 7369040
13:19:41 32586 8192000000 10152517408 10605375488 1120104848 12.028 97 24 48 0 1236527424 110452240 5970336
13:21:15 50924 8192000000 10130651648 10567938048 1107990912 11.913 97 34 62 1 1200872768 85528592 7353264
13:23:31 25602 8192000000 10134000848 10603483136 1121030176 12.037 99 32 64 0 1226917008 98877712 7009120
13:25:00 49180 8192000000 10095318960 10577203200 1094608096 11.786 97 27 54 0 1191723680 91696336 5419248
13:26:53 15759 8192000000 10092729168 10525810688 1112748912 11.958 98 48 96 1 1159586496 36583520 10254064
13:28:11 69265 8192000000 10081930032 10564808704 1127369568 12.097 100 27 54 0 1225738976 92863520 5505888
13:30:22 75049 8192000000 10058409504 10495082496 1097591632 11.815 97 38 67 2 1139355616 34342880 7421104
13:32:37 79056 8192000000 10100388416 10593230848 1122908176 12.054 99 15 30 0 1211245568 84716896 3620496
13:34:59 74642 8192000000 10069792832 10512101376 1101250272 11.850 98 11 20 0 1147782208 44445712 2086224
13:37:22 29957 8192000000 9314849760 9679577088 442177600 5.121 40 2 4 0 478176112 35620256 378256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-01-2023 13:40:25 to 10-01-2023 13:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-01-2023 13:40:42 to 10-01-2023 13:40:44.

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          206384
MON$OLDEST_ACTIVE               206385
MON$OLDEST_SNAPSHOT             206385
MON$NEXT_TRANSACTION            206385
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                       2107520
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             755
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
:152: 
:153: mscs-test-161.budzdorov.ru    Tue Jan 10 13:38:15 2023
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
--- end of diff output ---

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

10.01.23 13:40:45. 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-01-10 13:40:48 No severe PSQL-related problems occured
10.01.23 13:40:49. End of report.