Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.2023 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 15149900 kB
MemAvailable 30412688 kB
Buffers 0 kB
Cached 16570200 kB
SwapCached 5564 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-03 10:37:22
DTS_END                         2023-01-03 10:37:22.8650
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/55682
TRN_ID                          220181
ATT_ID                          626
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: 8861.22 20569 1063346
customer order: creation 173.67 504 20840
customer order: refuse 14.88 387 1785
order to supplier: creation 45.91 1406 5509
order to supplier: removal 3.68 149246 442
invoice (draft): creation 46.54 3035 5585
invoice (draft): removal 47.80 969 5736
invoice accept: apply 41.14 26300 4937
invoice accept: cancel 43.58 1249 5230
customer reserve: creation 3846.12 249 461534
customer reserve: removal 4309.13 101 517095
realization accept: apply 82.79 281 9935
realization accept: cancel 76.04 183 9125
payment from customer: creation 59.05 176 7086
payment from customer: removal 5.43 140 652
payment to supplier: creation 48.81 192 5857
payment to supplier: removal 3.47 144 416
service: total inventory turnovers 4.23 3682 508
service: total monetary turnovers 7.87 378 944
service: aggregate perf. data 1.04 13841 125
service: refresh index statistics 0.04 208924 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2023 10:38:46 to 03-01-2023 10:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10118
TEST_TIME 2 6983
TEST_TIME 3 6247
TEST_TIME 4 6166
TEST_TIME 5 6744
TEST_TIME 6 6621
TEST_TIME 7 6267
TEST_TIME 8 6231
TEST_TIME 9 6506
TEST_TIME 10 6465
TEST_TIME 11 6495
TEST_TIME 12 6579
TEST_TIME 13 6843
TEST_TIME 14 6903
TEST_TIME 15 7006
TEST_TIME 16 7181
TEST_TIME 17 7326
TEST_TIME 18 7392
TEST_TIME 19 7510
TEST_TIME 20 7664
TEST_TIME 21 7839
TEST_TIME 22 8077
TEST_TIME 23 8289
TEST_TIME 24 8318
TEST_TIME 25 8504
TEST_TIME 26 8654
TEST_TIME 27 9016
TEST_TIME 28 9236
TEST_TIME 29 9341
TEST_TIME 30 9857
TEST_TIME 31 10270
TEST_TIME 32 10526
TEST_TIME 33 10569
TEST_TIME 34 11035
TEST_TIME 35 10896
TEST_TIME 36 10810
TEST_TIME 37 10653
TEST_TIME 38 10453
TEST_TIME 39 10322
TEST_TIME 40 10456
TEST_TIME 41 10341
TEST_TIME 42 10220
TEST_TIME 43 10131
TEST_TIME 44 10011
TEST_TIME 45 9926
TEST_TIME 46 9956
TEST_TIME 47 9926
TEST_TIME 48 9839
TEST_TIME 49 9779
TEST_TIME 50 9692
TEST_TIME 51 9639
TEST_TIME 52 9558
TEST_TIME 53 9716
TEST_TIME 54 9611
TEST_TIME 55 9599
TEST_TIME 56 9594
TEST_TIME 57 9627
TEST_TIME 58 9701
TEST_TIME 59 9616
TEST_TIME 60 9607
TEST_TIME 61 9722
TEST_TIME 62 9839
TEST_TIME 63 9795
TEST_TIME 64 9877
TEST_TIME 65 9881
TEST_TIME 66 9766
TEST_TIME 67 9707
TEST_TIME 68 9689
TEST_TIME 69 9598
TEST_TIME 70 9554
TEST_TIME 71 9527
TEST_TIME 72 9504
TEST_TIME 73 9497
TEST_TIME 74 9465
TEST_TIME 75 9435
TEST_TIME 76 9321
TEST_TIME 77 9370
TEST_TIME 78 9312
TEST_TIME 79 9302
TEST_TIME 80 9312
TEST_TIME 81 9342
TEST_TIME 82 9351
TEST_TIME 83 9377
TEST_TIME 84 9399
TEST_TIME 85 9388
TEST_TIME 86 9389
TEST_TIME 87 9390
TEST_TIME 88 9452
TEST_TIME 89 9469
TEST_TIME 90 9389
TEST_TIME 91 9339
TEST_TIME 92 9311
TEST_TIME 93 9283
TEST_TIME 94 9229
TEST_TIME 95 9212
TEST_TIME 96 9173
TEST_TIME 97 9154
TEST_TIME 98 9134
TEST_TIME 99 9114
TEST_TIME 100 9120
TEST_TIME 101 9091
TEST_TIME 102 9118
TEST_TIME 103 9106
TEST_TIME 104 9142
TEST_TIME 105 9110
TEST_TIME 106 9143
TEST_TIME 107 9176
TEST_TIME 108 9166
TEST_TIME 109 9156
TEST_TIME 110 9122
TEST_TIME 111 9085
TEST_TIME 112 9049
TEST_TIME 113 9031
TEST_TIME 114 9023
TEST_TIME 115 9018
TEST_TIME 116 8990
TEST_TIME 117 8969
TEST_TIME 118 8945
TEST_TIME 119 8940
TEST_TIME 120 8887

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-01-2023 10:38:49 to 03-01-2023 10:38:52.

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 1489634 1489634 0 0.00 0 13542 13 0 0
doc_list_biud 1489620 1489620 0 0.00 0 49367 41 0 0
sp_add_invoice_to_stock 5059 4937 122 2.41 62 207122 26300 0 122
sp_cancel_adding_invoice 5230 5230 0 0.00 6 98430 1250 0 0
sp_cancel_client_order 1785 1785 0 0.00 6 11357 387 0 0
sp_cancel_customer_reserve 517095 517095 0 0.00 1 49377 102 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 4 7746 140 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 4 7670 145 0 0
sp_cancel_supplier_invoice 5745 5736 9 0.15 2 21835 969 0 9
sp_cancel_supplier_order 442 442 0 0.00 14 1465188 149246 0 0
sp_cancel_write_off 9125 9125 0 0.00 2 17659 183 0 0
sp_client_order 20840 20840 0 0.00 5 31730 504 0 0
sp_customer_reserve 461534 461534 0 0.00 0 88788 249 0 0
sp_fill_shopping_cart 31923 31923 0 0.00 1 24606 79 0 0
sp_fill_shopping_cart_clo_res 461491 461491 0 0.00 0 21531 19 0 0
sp_get_clo_for_invoice 4883 4883 0 0.00 0 25738 327 0 0
sp_kill_cost_storno 15423 15423 0 0.00 0 6548 70 0 0
sp_kill_qstorno_ret_qs2qd 1049493 1049493 0 0.00 0 49354 45 0 0
sp_kill_qty_storno 554229 554229 0 0.00 0 49365 102 0 0
sp_lock_selected_doc 20631 20631 0 0.00 0 11092 10 0 0
sp_make_cost_storno 27761 27761 0 0.00 0 12670 78 0 0
sp_multiply_rows_for_pdistr 27758 27758 0 0.00 0 9246 37 0 0
sp_multiply_rows_for_qdistr 20840 20840 0 0.00 0 16272 204 0 0
sp_pay_from_customer 7086 7086 0 0.00 6 12835 177 0 0
sp_pay_to_supplier 5857 5857 0 0.00 6 13507 193 0 0
sp_payment_common 12941 12941 0 0.00 2 8552 47 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 11320 318 0 0
sp_qd_handle_on_invoice_upd_sts 10112 10112 0 0.00 0 38792 495 0 0
sp_qd_handle_on_reserve_upd_sts 9934 9934 0 0.00 0 19942 69 0 0
sp_reserve_write_off 9935 9935 0 0.00 7 19972 282 0 0
sp_supplier_invoice 5585 5585 0 0.00 8 75312 3036 0 0
sp_supplier_order 5509 5509 0 0.00 7 58490 1407 0 0
srv_aggregate_perf_data 125 125 0 0.00 2747 96057 13841 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 838 119942 34567 0 0
srv_find_qd_qs_mism 1014942 1014942 0 0.00 0 12408 10 0 0
srv_make_invnt_saldo 508 508 0 0.00 37 83059 3683 0 0
srv_make_money_saldo 944 944 0 0.00 1 11307 379 0 0
srv_recalc_idx_stat 5 5 0 0.00 80393 322292 208924 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 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 4939 4939 0 0.00 1 11186 851 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 2 10286 435 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 2078 28 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 495 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 702 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 2616 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1292 43 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 63530 5748 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 2463 21 0 0
t$perf-norm:sp_client_order 20840 20840 0 0.00 0 6752 35 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 1161 17 0 0
t$perf-norm:sp_pay_from_customer 7086 7086 0 0.00 0 4086 17 0 0
t$perf-norm:sp_pay_to_supplier 5857 5857 0 0.00 0 9831 19 0 0
t$perf-norm:sp_reserve_write_off 9935 9935 0 0.00 0 8519 21 0 0
t$perf-norm:sp_supplier_invoice 5585 5585 0 0.00 0 2589 27 0 0
t$perf-norm:sp_supplier_order 5509 5509 0 0.00 0 6410 34 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 296 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 57 4 0 0
t$perf-norm:srv_make_invnt_saldo 508 508 0 0.00 0 3223 21 0 0
t$perf-norm:srv_make_money_saldo 944 944 0 0.00 0 2223 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 221 92 0 0
v_add_invoice_to_stock 9998 9754 244 2.44 0 4579 24 0 244
v_all_customers 38138 38138 0 0.00 0 70 4 0 0
v_all_suppliers 31120 31120 0 0.00 0 69 4 0 0
v_all_wares 41676 41676 0 0.00 0 159 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 616 14 0 0
v_cancel_client_order 3570 3570 0 0.00 0 1740 11 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 2270 15 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 513 10 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 1711 15 0 0
v_cancel_supplier_invoice 840 822 18 2.14 0 1600 26 0 18
v_cancel_supplier_order 884 884 0 0.00 0 1532 14 0 0
v_cancel_write_off 2032 2032 0 0.00 0 1527 23 0 0
v_max_id_clo_ord 5503 5503 0 0.00 0 509 5 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 1236 12 0 0
v_max_id_ord_sup 5577 5577 0 0.00 0 2794 7 0 0
v_max_non_paid_invoice 1377 1377 0 0.00 0 659 8 0 0
v_max_non_paid_realizn 3614 3614 0 0.00 0 984 7 0 0
v_min_id_clo_ord 5503 5503 0 0.00 0 5561 9 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 1588 9 0 0
v_min_id_ord_sup 5577 5577 0 0.00 0 5069 10 0 0
v_min_non_paid_invoice 5857 5857 0 0.00 0 8547 20 0 0
v_min_non_paid_realizn 7084 7084 0 0.00 0 6178 21 0 0
v_our_firm 10478 10478 0 0.00 0 165 4 0 0
v_reserve_write_off 19864 19864 0 0.00 0 3954 11 0 0
x_lock_depdocs_on_canc_invoice 10555 10555 0 0.00 0 17675 118 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 6884 305 0 0
x_make_qstorno_1000_1200 5509 5509 0 0.00 3 58082 1248 0 0
x_make_qstorno_1200_2000 5585 5585 0 0.00 3 67122 2817 0 0
x_make_qstorno_2100_3300 461533 461533 0 0.00 0 88785 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2023 10:38:54 to 03-01-2023 10:38:58.

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:26 6 8192000000 8803383360 8816484352 8322592 0.101 3 3 3 0 8484544 100592 61360
08:08:27 28819 8192000000 9737282688 10038960128 813289168 9.031 100 91 182 0 844223728 8399632 22534928
08:09:58 17258 8192000000 9761611408 10071203840 838048624 9.280 100 89 179 0 873008624 13838352 21121648
08:11:19 12254 8192000000 9800176064 10119249920 867553008 9.576 100 88 174 0 909654784 21807632 20294144
08:12:34 9038 8192000000 9848571104 10176589824 906099968 9.959 100 79 157 0 959194576 36233968 16860640
08:13:46 3809 8192000000 9905807104 10244046848 934374176 10.238 100 84 162 0 1016322288 65142960 16805152
08:14:52 1329 8192000000 9937888720 10284638208 977858464 10.663 100 91 178 2 1056955376 60571456 18525456
08:15:56 1745 8192000000 9992419584 10350960640 1007321072 10.949 100 86 162 0 1114941968 91733184 15887712
08:16:59 6433 8192000000 9987159904 10356699136 1029803936 11.167 100 84 164 1 1121774016 75723072 16247008
08:18:07 953 8192000000 10036002128 10412564480 1057784976 11.435 100 93 186 0 1157369488 80465568 19118944
08:19:09 1190 8192000000 10023263200 10397802496 1070160672 11.554 100 90 170 4 1143027744 57512032 15355040
08:20:12 1458 8192000000 10050759920 10440585216 1074487168 11.595 100 84 158 1 1177993552 87955280 15551104
08:21:15 986 8192000000 10023155104 10410377216 1080523120 11.652 100 85 165 1 1149893616 50798848 18571648
08:22:17 1584 8192000000 10044125104 10434965504 1085296544 11.698 100 86 166 3 1168581776 66201920 17083312
08:23:19 1218 8192000000 10053392064 10440749056 1084916592 11.694 100 97 182 0 1174650128 70286352 19447184
08:24:22 1132 8192000000 10056267936 10442448896 1090532464 11.748 100 97 189 2 1177756464 67184560 20039440
08:25:24 947 8192000000 10058538768 10447138816 1094317680 11.784 100 91 170 3 1182527152 70062368 18147104
08:26:26 2081 8192000000 10047903136 10436296704 1095383280 11.794 100 85 164 1 1168853280 56013392 17456608
08:27:29 3875 8192000000 10068954848 10464686080 1097737424 11.816 100 64 119 0 1177905936 68315616 11852896
08:28:33 3663 8192000000 10039311840 10430078976 1100566656 11.843 100 76 141 4 1159697696 46160544 12970496
08:29:38 4204 8192000000 10045782368 10442797056 1089772752 11.740 100 66 128 2 1164653664 62128000 12752912
08:30:44 1235 8192000000 10043414512 10429349888 1076782064 11.617 100 84 163 0 1163943200 71214016 15947120
08:31:46 1301 8192000000 10064541136 10450595840 1074159984 11.592 100 95 183 1 1188054896 95384832 18510080
08:32:48 1122 8192000000 10030163568 10410262528 1049825312 11.359 100 97 183 0 1153064048 83289696 19949040
08:33:50 6988 8192000000 9994482144 10377924608 1030056416 11.169 99 62 116 0 1106591792 65648560 10886816
08:34:58 2432 8192000000 9938396032 10306916352 997347664 10.853 97 71 129 5 1065188512 53358896 14481952
08:36:01 1758 8192000000 9975309696 10350424064 1028097904 11.150 99 84 156 0 1094384096 49582000 16704192
08:37:04 5381 8192000000 9987044736 10362888192 1026026432 11.130 99 75 143 0 1103683872 62281008 15376432
08:38:15 3210 8192000000 10014490624 10398564352 1064694400 11.501 100 68 123 2 1132742656 54350160 13698096
08:39:24 1105 8192000000 10035700528 10417471488 1077401856 11.623 100 80 142 3 1154652784 62253344 14997584
08:40:30 22296 8192000000 10059989920 10452103168 1088479040 11.728 100 64 124 1 1190476512 89248416 12749056
08:41:56 7151 8192000000 10105811696 10496139264 1111687536 11.948 100 78 145 3 1219273040 91846816 15738688
08:43:08 7076 8192000000 10161648128 10547732480 1122638736 12.052 100 83 157 0 1275472720 136274496 16559488
08:44:22 1800 8192000000 10170503312 10561499136 1129561104 12.117 100 84 152 2 1283572432 137818320 16193008
08:45:28 1566 8192000000 10216790784 10611531776 1143679280 12.250 100 86 162 4 1329144864 169139632 16325952
08:46:33 1751 8192000000 10222857840 10628841472 1151823408 12.327 100 84 168 0 1334238672 164055984 18359280
08:47:38 1027 8192000000 10252699376 10650021888 1154052144 12.348 100 99 193 1 1366070592 189797440 22221008
08:48:45 2075 8192000000 10309857696 10712178688 1163216000 12.433 100 89 172 0 1422407584 239590096 19601488
08:49:51 825 8192000000 10314591584 10721636352 1159010944 12.394 100 97 191 2 1425270160 244645776 21613440
08:50:55 3494 8192000000 10345786192 10761535488 1167892064 12.477 100 74 148 1 1459779216 274626752 17260400
08:52:04 1122 8192000000 10346773824 10766827520 1172358496 12.519 100 98 191 3 1459060016 264577888 22123632
08:53:13 1432 8192000000 10395040672 10818039808 1186985520 12.655 100 94 186 2 1507445296 298682368 21777408
08:54:18 4528 8192000000 10377013344 10784882688 1182939792 12.618 100 73 145 3 1489986976 290318000 16729184
08:55:25 1530 8192000000 10418581392 10835218432 1194409056 12.724 100 94 181 0 1528899872 314130688 20360128
08:56:31 1094 8192000000 10477278240 10894331904 1199462784 12.771 100 97 189 0 1581939232 360060752 22415696
08:57:34 1424 8192000000 10478302368 10903367680 1206976672 12.841 100 92 174 0 1584490400 357329152 20184576
08:58:37 932 8192000000 10511724544 10936000512 1202477408 12.799 100 96 190 0 1619029856 393865376 22687072
08:59:40 1051 8192000000 10497472496 10929192960 1190368896 12.687 100 96 187 0 1601276736 389361328 21546512
09:00:44 1971 8192000000 10473788544 10915926016 1185533488 12.642 100 64 120 2 1576118816 375800736 14784592
09:01:51 1442 8192000000 10531096672 10967437312 1190206768 12.685 100 80 156 1 1630566288 421657040 18702480
09:02:54 9601 8192000000 10495167136 10954698752 1182171264 12.610 100 54 99 2 1600960784 405975584 12813936
09:04:14 16207 8192000000 10474588032 10973425664 1165696864 12.457 99 40 80 0 1573710032 397756784 10256384
09:05:34 31601 8192000000 10403683040 10854440960 1111765664 11.949 96 28 56 0 1497563872 378593920 7204288
09:07:07 23710 8192000000 10299390224 10825019392 1120942736 12.036 100 27 54 0 1408875904 279974896 7958272
09:08:33 25844 8192000000 10193467712 10663374848 1124576640 12.070 99 27 54 0 1321767312 190436608 6754064
09:10:02 18437 8192000000 10161866848 10609569792 1126536320 12.089 99 32 64 1 1263966896 130284992 7145584
09:11:41 52253 8192000000 10091199984 10521604096 1124905904 12.073 99 21 42 1 1179934608 50783040 4245664
09:13:36 21045 8192000000 10080895984 10504638464 1104358768 11.879 97 43 83 1 1176627264 62452320 9816176
09:15:08 119942 8192000000 10126315856 10563592192 1108657664 11.920 98 34 62 0 1209149616 93248208 7243744
09:18:15 64672 8192000000 10064003680 10512146432 1112548064 11.957 98 16 31 0 1146084672 30512048 3024560
09:20:22 1087 8192000000 10093102528 10511519744 1145852576 12.271 100 83 149 3 1187028336 25231648 15944112
09:21:23 52255 8192000000 10121279920 10582384640 1118498688 12.013 98 22 42 0 1202301584 78707536 5095360
09:23:22 38165 8192000000 10120487568 10594463744 1105556224 11.890 97 31 58 0 1227845120 115556416 6732480
09:26:20 25248 8192000000 10116884800 10580033536 1127816832 12.101 100 15 27 0 1206381456 75530400 3034224
09:27:46 61037 8192000000 10106596448 10553323520 1137840656 12.195 100 36 68 0 1209058496 63465872 7751968
09:29:49 74559 8192000000 10046222272 10497245184 1068388048 11.537 94 11 22 0 1122457408 51611696 2457664
09:32:04 29301 8192000000 10131721440 10569883648 1118998464 12.018 98 45 86 0 1207705552 79772608 8934480
09:33:35 78412 8192000000 10112410976 10575101952 1096218720 11.802 97 21 40 0 1209732736 109073216 4440800
09:35:54 45903 8192000000 10133655936 10573275136 1139393488 12.210 100 41 82 0 1216017968 67411856 9212624
09:37:42 81324 8192000000 10101358208 10664521728 1104962144 11.885 97 17 35 0 1224758896 116091088 3705664
09:40:22 48687 8192000000 10142284448 10589462528 1130615712 12.127 99 23 44 0 1193130736 58299088 4215936
09:42:15 92925 8192000000 10088098400 10562764800 1141076048 12.226 100 29 56 2 1206225328 59099328 6049952
09:44:48 52811 8192000000 10085918688 10556272640 1133017568 12.150 99 37 74 0 1192515472 51642992 7854912
09:46:42 29293 8192000000 10093236080 10534506496 1144905568 12.262 100 54 102 0 1196028784 40993872 10129344
09:48:14 24325 8192000000 10090088928 10542600192 1130953632 12.130 99 32 56 0 1186937056 49449616 6533808
09:49:38 40313 8192000000 10125295920 10579722240 1149078160 12.301 100 33 66 0 1220695520 64620000 6997360
09:51:28 51177 8192000000 10065046608 10543276032 1112127456 11.953 98 7 14 0 1184615104 70956784 1530864
09:53:20 46815 8192000000 10099366048 10568474624 1136847344 12.186 100 21 41 0 1188450688 47288640 4314704
09:55:47 71109 8192000000 10076608368 10538799104 1060038288 11.457 93 17 34 0 1108668112 44986304 3643520
09:58:05 53085 8192000000 10079864240 10553757696 1117038976 11.999 98 21 37 1 1193444688 72262048 4143664
10:00:00 71233 8192000000 10075587424 10584317952 1089895232 11.742 96 21 42 0 1164018592 70088448 4034912
10:02:38 19220 8192000000 10086196320 10535694336 1120651984 12.033 100 41 80 1 1191856736 63110528 8094224
10:04:00 17188 8192000000 10109763392 10538369024 1124841648 12.073 100 71 140 0 1191897152 52537264 14518240
10:05:29 62529 8192000000 10100049008 10562781184 1113465296 11.965 98 36 72 1 1193991552 73235408 7290848
10:07:32 95443 8192000000 10071852048 10525376512 1137372640 12.191 100 38 75 0 1176121840 31532128 7217072
10:10:08 55767 8192000000 10053665440 10517573632 1129311248 12.115 99 12 24 1 1167987200 36442944 2233008
10:12:04 81226 8192000000 10040297520 10504388608 1085725104 11.702 95 10 21 0 1114915632 27247248 1943280
10:14:26 15503 8192000000 10073042992 10505752576 1122398208 12.050 99 50 90 2 1157364880 24715056 10251616
10:15:45 31414 8192000000 10105193152 10568323072 1134253904 12.161 100 32 64 0 1217851200 76618352 6978944
10:17:19 36150 8192000000 10105017264 10571595776 1096540784 11.805 97 34 66 1 1179613440 75110016 7962640
10:19:04 64388 8192000000 10134502016 10570907648 1138621280 12.203 100 35 70 0 1213700736 67585152 7494304
10:21:12 98767 8192000000 10124968400 10573889536 1097958992 11.818 96 23 47 0 1178093728 75359248 4775488
10:23:51 23570 8192000000 10118519888 10587086848 1140376080 12.219 100 27 54 0 1208618720 62981360 5261280
10:25:21 24785 8192000000 10126470448 10565275648 1143718224 12.250 100 43 85 0 1215006144 63125648 8162272
10:26:51 89652 8192000000 10076680160 10560098304 1140120496 12.217 99 22 44 0 1223823248 78937392 4765360
10:29:24 28749 8192000000 10096323392 10548170752 1119965536 12.027 98 36 68 0 1170721840 44168864 6587440
10:31:00 90996 8192000000 10071517568 10560262144 1113998784 11.970 98 11 20 0 1174908544 59010912 1898848
10:33:44 74424 8192000000 10083505168 10538684416 1101829840 11.855 96 20 37 0 1143980416 38340976 3809600
10:36:03 21962 8192000000 10114519024 10558390272 1108566288 11.919 97 32 61 0 1199137232 83238992 7331952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-01-2023 10:39:00 to 03-01-2023 10:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 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 03-01-2023 10:39:17 to 03-01-2023 10:39:19.

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          216775
MON$OLDEST_ACTIVE               222097
MON$OLDEST_SNAPSHOT             222097
MON$NEXT_TRANSACTION            222097
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                       2054112
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:891: 
:892: mscs-test-161.budzdorov.ru    Tue Jan  3 10:37:30 2023
:893:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:894: 
:895: 
:896: mscs-test-161.budzdorov.ru    Tue Jan  3 10:37:30 2023
:897:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:898: 
:899: 
:900: mscs-test-161.budzdorov.ru    Tue Jan  3 10:37:30 2023
:901:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:902: 
:903: 
:904: mscs-test-161.budzdorov.ru    Tue Jan  3 10:37:30 2023
:905:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:906: 
:907: 
:908: mscs-test-161.budzdorov.ru    Tue Jan  3 10:37:30 2023
:909:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:910: 
--- end of diff output ---

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

03.01.23 10:39:20. 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-03 10:39:23 No severe PSQL-related problems occured
03.01.23 10:39:24. End of report.