Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.2023 01: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 15317052 kB
MemAvailable 30390260 kB
Buffers 0 kB
Cached 16371568 kB
SwapCached 4412 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-11 01:38:32
DTS_END                         2023-01-11 01:38:32.0980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45548
TRN_ID                          245030
ATT_ID                          678
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: 9559.10 22540 1147092
customer order: creation 180.28 374 21633
customer order: refuse 15.25 335 1830
order to supplier: creation 47.35 1180 5682
order to supplier: removal 3.50 103082 420
invoice (draft): creation 46.46 3219 5575
invoice (draft): removal 53.51 700 6421
invoice accept: apply 43.03 17427 5163
invoice accept: cancel 49.06 832 5887
customer reserve: creation 4014.94 170 481793
customer reserve: removal 4795.10 60 575412
realization accept: apply 86.39 165 10367
realization accept: cancel 88.46 107 10615
payment from customer: creation 61.55 97 7386
payment from customer: removal 5.98 69 717
payment to supplier: creation 51.43 114 6172
payment to supplier: removal 3.58 86 430
service: total inventory turnovers 5.41 894 649
service: total monetary turnovers 7.03 25 843
service: aggregate perf. data 0.78 26066 93
service: refresh index statistics 0.03 295797 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2023 01:39:22 to 11-01-2023 01:39:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3456
TEST_TIME 2 3049
TEST_TIME 3 4583
TEST_TIME 4 4328
TEST_TIME 5 4407
TEST_TIME 6 4456
TEST_TIME 7 4953
TEST_TIME 8 5007
TEST_TIME 9 5320
TEST_TIME 10 5469
TEST_TIME 11 5734
TEST_TIME 12 5862
TEST_TIME 13 5876
TEST_TIME 14 6330
TEST_TIME 15 6440
TEST_TIME 16 6531
TEST_TIME 17 6577
TEST_TIME 18 6806
TEST_TIME 19 7098
TEST_TIME 20 7327
TEST_TIME 21 7977
TEST_TIME 22 8110
TEST_TIME 23 8502
TEST_TIME 24 8462
TEST_TIME 25 9061
TEST_TIME 26 9255
TEST_TIME 27 10273
TEST_TIME 28 10790
TEST_TIME 29 10726
TEST_TIME 30 10518
TEST_TIME 31 10357
TEST_TIME 32 10487
TEST_TIME 33 10297
TEST_TIME 34 10196
TEST_TIME 35 10260
TEST_TIME 36 10194
TEST_TIME 37 9993
TEST_TIME 38 9929
TEST_TIME 39 9822
TEST_TIME 40 9723
TEST_TIME 41 9854
TEST_TIME 42 9745
TEST_TIME 43 9609
TEST_TIME 44 9498
TEST_TIME 45 9560
TEST_TIME 46 9671
TEST_TIME 47 9693
TEST_TIME 48 9764
TEST_TIME 49 9780
TEST_TIME 50 9829
TEST_TIME 51 9851
TEST_TIME 52 9904
TEST_TIME 53 9891
TEST_TIME 54 9947
TEST_TIME 55 9964
TEST_TIME 56 9971
TEST_TIME 57 9952
TEST_TIME 58 9990
TEST_TIME 59 9958
TEST_TIME 60 9862
TEST_TIME 61 9776
TEST_TIME 62 9809
TEST_TIME 63 9785
TEST_TIME 64 9761
TEST_TIME 65 9669
TEST_TIME 66 9663
TEST_TIME 67 9685
TEST_TIME 68 9632
TEST_TIME 69 9604
TEST_TIME 70 9608
TEST_TIME 71 9605
TEST_TIME 72 9634
TEST_TIME 73 9678
TEST_TIME 74 9697
TEST_TIME 75 9735
TEST_TIME 76 9731
TEST_TIME 77 9746
TEST_TIME 78 9812
TEST_TIME 79 9768
TEST_TIME 80 9698
TEST_TIME 81 9687
TEST_TIME 82 9615
TEST_TIME 83 9599
TEST_TIME 84 9608
TEST_TIME 85 9540
TEST_TIME 86 9488
TEST_TIME 87 9487
TEST_TIME 88 9524
TEST_TIME 89 9500
TEST_TIME 90 9491
TEST_TIME 91 9537
TEST_TIME 92 9564
TEST_TIME 93 9631
TEST_TIME 94 9620
TEST_TIME 95 9578
TEST_TIME 96 9606
TEST_TIME 97 9641
TEST_TIME 98 9660
TEST_TIME 99 9640
TEST_TIME 100 9681
TEST_TIME 101 9695
TEST_TIME 102 9748
TEST_TIME 103 9698
TEST_TIME 104 9646
TEST_TIME 105 9641
TEST_TIME 106 9608
TEST_TIME 107 9609
TEST_TIME 108 9575
TEST_TIME 109 9551
TEST_TIME 110 9547
TEST_TIME 111 9499
TEST_TIME 112 9484
TEST_TIME 113 9467
TEST_TIME 114 9428
TEST_TIME 115 9488
TEST_TIME 116 9496
TEST_TIME 117 9515
TEST_TIME 118 9569
TEST_TIME 119 9611
TEST_TIME 120 9562

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2023 01:39:26 to 11-01-2023 01:39:29.

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 1593237 1593237 0 0.00 0 13853 6 0 0
doc_list_biud 1593237 1593237 0 0.00 0 65229 27 0 0
sp_add_invoice_to_stock 5305 5163 142 2.67 20 175044 17428 0 142
sp_cancel_adding_invoice 5887 5887 0 0.00 5 59876 833 0 0
sp_cancel_client_order 1830 1830 0 0.00 4 6477 335 0 0
sp_cancel_customer_reserve 575412 575412 0 0.00 0 25890 60 0 0
sp_cancel_pay_from_customer 717 717 0 0.00 3 4402 69 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 4516 87 0 0
sp_cancel_supplier_invoice 6430 6421 9 0.13 2 65242 700 0 9
sp_cancel_supplier_order 420 420 0 0.00 14 1190143 103083 0 0
sp_cancel_write_off 10615 10615 0 0.00 1 7664 108 0 0
sp_client_order 21633 21633 0 0.00 3 24391 375 0 0
sp_customer_reserve 481793 481793 0 0.00 0 104682 171 0 0
sp_fill_shopping_cart 32872 32872 0 0.00 1 12244 35 0 0
sp_fill_shopping_cart_clo_res 481791 481791 0 0.00 0 19393 15 0 0
sp_get_clo_for_invoice 5156 5156 0 0.00 0 24841 303 0 0
sp_kill_cost_storno 17649 17649 0 0.00 0 7191 54 0 0
sp_kill_qstorno_ret_qs2qd 1168280 1168280 0 0.00 0 65219 32 0 0
sp_kill_qty_storno 616108 616108 0 0.00 0 65226 69 0 0
sp_lock_selected_doc 21613 21613 0 0.00 0 5850 5 0 0
sp_make_cost_storno 29081 29081 0 0.00 0 11472 44 0 0
sp_multiply_rows_for_pdistr 29081 29081 0 0.00 0 8726 27 0 0
sp_multiply_rows_for_qdistr 21633 21633 0 0.00 0 16984 174 0 0
sp_pay_from_customer 7386 7386 0 0.00 3 9237 98 0 0
sp_pay_to_supplier 6172 6172 0 0.00 5 11480 115 0 0
sp_payment_common 13558 13558 0 0.00 1 9234 23 0 0
sp_qd_handle_on_cancel_clo 1830 1830 0 0.00 0 6472 308 0 0
sp_qd_handle_on_invoice_upd_sts 11043 11043 0 0.00 0 20405 321 0 0
sp_qd_handle_on_reserve_upd_sts 10367 10367 0 0.00 0 15749 65 0 0
sp_reserve_write_off 10367 10367 0 0.00 6 15767 166 0 0
sp_supplier_invoice 5575 5575 0 0.00 6 80385 3220 0 0
sp_supplier_order 5682 5682 0 0.00 5 24953 1181 0 0
srv_aggregate_perf_data 93 93 0 0.00 2433 598585 26066 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1019 139767 44141 0 0
srv_find_qd_qs_mism 1097328 1097328 0 0.00 0 13852 5 0 0
srv_make_invnt_saldo 649 649 0 0.00 27 44790 894 0 0
srv_make_money_saldo 843 843 0 0.00 0 1666 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 217101 469182 295797 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 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 5163 5163 0 0.00 0 14196 205 0 0
t$perf-norm:sp_cancel_adding_invoice 482 482 0 0.00 0 5557 115 0 0
t$perf-norm:sp_cancel_client_order 1830 1830 0 0.00 0 5731 14 0 0
t$perf-norm:sp_cancel_customer_reserve 739 739 0 0.00 0 814 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 717 717 0 0.00 0 7744 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 3259 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1977 21 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 22441 1669 0 0
t$perf-norm:sp_cancel_write_off 1057 1057 0 0.00 0 4231 15 0 0
t$perf-norm:sp_client_order 21633 21633 0 0.00 0 18067 21 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 3106 8 0 0
t$perf-norm:sp_pay_from_customer 7386 7386 0 0.00 0 18872 16 0 0
t$perf-norm:sp_pay_to_supplier 6172 6172 0 0.00 0 6206 14 0 0
t$perf-norm:sp_reserve_write_off 10367 10367 0 0.00 0 16173 17 0 0
t$perf-norm:sp_supplier_invoice 5575 5575 0 0.00 0 7028 24 0 0
t$perf-norm:sp_supplier_order 5682 5682 0 0.00 0 6996 15 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 1450 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1288 50 0 0
t$perf-norm:srv_make_invnt_saldo 649 649 0 0.00 0 1824 29 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 925 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 83 23 0 0
v_add_invoice_to_stock 10596 10312 284 2.68 0 3756 17 0 284
v_all_customers 39708 39708 0 0.00 0 21 1 0 0
v_all_suppliers 31586 31586 0 0.00 0 20 1 0 0
v_all_wares 43264 43264 0 0.00 0 142 1 0 0
v_cancel_adding_invoice 964 964 0 0.00 0 352 6 0 0
v_cancel_client_order 3660 3660 0 0.00 0 1641 4 0 0
v_cancel_customer_prepayment 1434 1434 0 0.00 0 2974 7 0 0
v_cancel_customer_reserve 1478 1478 0 0.00 0 4834 11 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 913 6 0 0
v_cancel_supplier_invoice 848 830 18 2.12 0 4102 29 0 18
v_cancel_supplier_order 840 840 0 0.00 0 2826 7 0 0
v_cancel_write_off 2114 2114 0 0.00 0 2306 12 0 0
v_max_id_clo_ord 5681 5681 0 0.00 0 1923 2 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 2215 13 0 0
v_max_id_ord_sup 5557 5557 0 0.00 0 8863 6 0 0
v_max_non_paid_invoice 1617 1617 0 0.00 0 207 2 0 0
v_max_non_paid_realizn 3735 3735 0 0.00 0 1155 4 0 0
v_min_id_clo_ord 5681 5681 0 0.00 0 3325 5 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 7304 21 0 0
v_min_id_ord_sup 5557 5557 0 0.00 0 9643 9 0 0
v_min_non_paid_invoice 6172 6172 0 0.00 0 5973 16 0 0
v_min_non_paid_realizn 7386 7386 0 0.00 0 9231 16 0 0
v_our_firm 10858 10858 0 0.00 0 23 1 0 0
v_reserve_write_off 20734 20734 0 0.00 0 5079 7 0 0
x_lock_depdocs_on_canc_invoice 11893 11893 0 0.00 0 23955 121 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 11227 390 0 0
x_make_qstorno_1000_1200 5682 5682 0 0.00 1 24949 1114 0 0
x_make_qstorno_1200_2000 5575 5575 0 0.00 3 80359 3078 0 0
x_make_qstorno_2100_3300 481793 481793 0 0.00 0 104680 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-01-2023 01:39:30 to 11-01-2023 01:39:34.

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
23:08:36 18 8192000000 8812474416 8828461056 17005024 0.207 4 4 5 0 17231776 87456 139296
23:09:36 25392 8192000000 9908863440 10221326336 1000713136 10.885 100 84 168 0 1028553872 6289488 21551248
23:11:12 20289 8192000000 9961119952 10283999232 1040690880 11.271 100 91 182 0 1073287824 9211504 23385440
23:12:35 11808 8192000000 9987263456 10321506304 1067522832 11.528 100 86 172 0 1098858400 9932592 21402976
23:13:57 11537 8192000000 10037123344 10386255872 1121772400 12.044 100 77 154 0 1152005984 11733104 18500480
23:15:11 5413 8192000000 10068849952 10427625472 1156439024 12.370 100 81 162 0 1188119584 12641552 19039008
23:16:19 4275 8192000000 10105074688 10468601856 1193379072 12.715 100 84 163 0 1225169712 13028640 18762000
23:17:27 3488 8192000000 10149940240 10520010752 1231750928 13.070 100 88 175 1 1265071632 13731872 19588832
23:18:32 4846 8192000000 10171598048 10550636544 1258216592 13.314 100 76 152 0 1288483968 13864768 16402608
23:19:39 782 8192000000 10203241664 10584354816 1286684320 13.574 100 91 184 1 1320083328 12849680 20549328
23:20:41 2050 8192000000 10210484688 10597371904 1298710000 13.684 100 79 156 0 1326409536 10528592 17170944
23:21:44 12207 8192000000 10182394000 10599542784 1304855584 13.739 100 23 46 0 1315668240 5254384 5558272
23:23:02 4564 8192000000 10213762688 10620919808 1315920272 13.840 100 30 60 1 1329826544 7501232 6405040
23:24:08 7838 8192000000 10205095760 10625077248 1325897104 13.930 100 29 58 0 1339458624 7269248 6292272
23:25:18 66173 8192000000 10233177856 10639241216 1329947936 13.967 100 30 58 1 1341911584 4713008 7250640
23:27:25 3685 8192000000 10232757664 10645479424 1332960352 13.994 100 43 86 1 1349142640 6505840 9676448
23:28:30 15521 8192000000 10200624928 10617401344 1263596176 13.363 95 12 24 0 1271627680 5438656 2592848
23:29:47 38136 8192000000 10190891184 10606129152 1273202672 13.451 97 33 65 0 1286367904 6003104 7162128
23:31:27 3001 8192000000 10163281248 10567151616 1268404368 13.407 100 28 55 1 1280781056 6595008 5781680
23:32:32 4641 8192000000 10131013104 10529947648 1224614880 13.004 97 21 42 0 1235702368 6165968 4921520
23:33:37 52413 8192000000 10149455072 10546761728 1231360800 13.067 97 36 72 0 1244862320 4657648 8843872
23:35:37 6561 8192000000 10175097824 10584158208 1283468896 13.545 100 37 74 0 1298308672 6605936 8233840
23:36:44 7503 8192000000 10190634720 10603937792 1298429888 13.681 100 20 40 0 1308941504 5229264 5282352
23:37:56 16196 8192000000 10199649120 10616360960 1310626880 13.792 100 12 24 0 1318441840 5473968 2340992
23:39:14 34382 8192000000 10235852672 10647793664 1336106256 14.022 100 41 81 1 1352131952 6878192 9147504
23:40:53 26143 8192000000 10266043168 10681442304 1350654064 14.153 100 50 100 0 1382084912 20950704 10480144
23:42:32 38303 8192000000 10281438496 10705293312 1374300496 14.366 100 42 84 1 1399553840 15361312 9892032
23:44:15 15130 8192000000 10294753408 10712915968 1379327936 14.411 100 49 98 1 1408413456 17816592 11268928
23:45:40 15217 8192000000 10307614448 10733158400 1390473984 14.510 100 41 82 0 1420153264 19257616 10421664
23:47:05 22449 8192000000 10321402288 10747248640 1397037376 14.569 100 40 79 0 1428555168 22049552 9468240
23:48:33 5201 8192000000 10339532352 10762121216 1410531632 14.689 100 59 118 1 1452046400 27213040 14301728
23:49:42 1016 8192000000 10350633808 10770030592 1411149312 14.694 100 68 134 0 1455017184 28579456 15288416
23:50:48 3986 8192000000 10364464256 10789728256 1426581024 14.831 100 58 116 0 1472640496 31765488 14293984
23:51:55 2309 8192000000 10371321888 10789367808 1421896304 14.790 100 72 144 0 1478276160 38863680 17516176
23:53:05 14276 8192000000 10341715824 10755510272 1395653136 14.556 100 64 128 1 1448567936 36617776 16297024
23:54:26 6868 8192000000 10366769280 10782351360 1417793920 14.753 100 68 134 1 1472360688 37676368 16890400
23:55:40 2932 8192000000 10377673264 10790920192 1417174944 14.748 100 70 139 2 1481879424 46584288 18120192
23:56:50 2600 8192000000 10365962672 10777198592 1388436208 14.492 99 69 138 0 1458665424 52478256 17750960
23:58:04 1077 8192000000 10373457728 10782699520 1399540784 14.591 100 78 153 0 1472925888 53664176 19720928
23:59:09 3096 8192000000 10362015056 10775314432 1395524128 14.555 100 64 128 0 1470381552 57530848 17326576
00:00:17 9674 8192000000 10329640464 10746568704 1367587776 14.305 97 49 96 1 1424180608 43464816 13128016
00:01:37 15918 8192000000 10335558640 10766372864 1387426944 14.483 100 31 62 0 1444909552 48229376 9253232
00:02:54 17999 8192000000 10347953072 10784731136 1392151136 14.525 100 32 64 1 1459238480 57775376 9311968
00:04:58 45219 8192000000 10296870608 10743078912 1338889248 14.047 98 19 38 0 1392439040 47453312 6096480
00:06:45 59191 8192000000 10249395264 10700599296 1309581744 13.782 97 12 24 0 1323905808 11467312 2856752
00:08:56 128369 8192000000 10273659120 10727944192 1356310240 14.204 99 20 41 1 1378865504 18426896 4128368
00:12:39 35028 8192000000 10276309296 10715189248 1356094928 14.202 99 33 66 0 1373565248 9043712 8426608
00:14:19 137775 8192000000 10281218400 10717827072 1322034944 13.895 96 35 70 0 1337201536 6912064 8254528
00:17:39 20858 8192000000 10295258560 10729529344 1376235376 14.383 100 37 74 0 1396512592 11249248 9027968
00:19:08 52663 8192000000 10265951168 10722603008 1340611408 14.063 98 18 36 0 1365913984 20596320 4706256
00:21:35 27775 8192000000 10272444544 10722394112 1371047808 14.336 100 11 22 0 1389049376 15175264 2826304
00:23:03 56565 8192000000 10282682192 10749808640 1378473328 14.403 100 15 30 1 1406460000 23882864 4103808
00:25:06 69202 8192000000 10289320384 10746417152 1371246048 14.338 100 7 14 0 1390281408 17283360 1752000
00:27:32 52819 8192000000 10284635968 10749980672 1385140256 14.462 100 35 69 0 1404210560 10922320 8147984
00:29:28 62312 8192000000 10304148064 10764656640 1393610784 14.538 100 17 34 0 1408837712 11071680 4155248
00:31:41 121468 8192000000 10291989568 10755403776 1329348144 13.961 95 10 20 0 1346292016 14635344 2308528
00:34:43 54757 8192000000 10302851200 10755493888 1354737088 14.190 97 20 40 0 1372700912 13855632 4108192
00:36:46 63400 8192000000 10289002608 10748932096 1377399072 14.393 99 25 50 0 1398389328 15302608 5687648
00:39:33 59458 8192000000 10299647392 10769534976 1394588944 14.547 99 15 30 0 1407686032 10052192 3044896
00:41:36 45297 8192000000 10281289296 10744233984 1343534736 14.089 96 32 62 0 1360360496 9050224 7775536
00:44:23 39467 8192000000 10261317056 10720059392 1342702272 14.082 97 24 43 3 1355593520 8175200 4716048
00:46:03 32528 8192000000 10245610784 10692435968 1282595872 13.537 94 24 46 1 1298659072 11117216 4945984
00:47:37 16980 8192000000 10274037328 10715193344 1362987952 14.264 100 38 76 0 1380394000 9218208 8187840
00:49:01 40625 8192000000 10281714720 10729553920 1329196000 13.960 96 29 58 0 1354948240 19335504 6416736
00:50:43 63299 8192000000 10277657264 10713477120 1317243328 13.852 96 10 20 0 1328333456 8994976 2095152
00:52:47 38925 8192000000 10272567152 10706472960 1331332880 13.979 97 30 60 0 1348286304 10724128 6229296
00:54:26 35373 8192000000 10294101168 10729463808 1362228880 14.257 99 45 89 1 1381025232 8013936 10782416
00:56:02 99744 8192000000 10290123616 10729107456 1326563824 13.936 96 28 55 0 1341907472 9075056 6268592
00:58:42 139767 8192000000 10277230128 10732453888 1345195136 14.104 98 20 40 0 1361030112 11325632 4509344
01:02:31 69608 8192000000 10259862288 10723225600 1324466336 13.917 96 8 16 0 1338123680 12026656 1630688
01:04:41 30297 8192000000 10256551008 10717757440 1361925440 14.255 100 10 20 0 1370513376 6514192 2073744
01:06:11 50503 8192000000 10251901824 10715672576 1343226800 14.086 98 19 37 0 1356565648 8907616 4431232
01:08:23 47739 8192000000 10289166080 10738667520 1346660464 14.117 97 18 37 0 1361518608 10923936 3934208
01:10:19 44460 8192000000 10294181600 10750472192 1371923328 14.344 99 32 61 0 1391083760 12087312 7073120
01:12:04 44022 8192000000 10267959600 10726817792 1342754624 14.082 97 10 21 0 1353369248 8296144 2318480
01:13:54 63667 8192000000 10281146816 10731204608 1348523648 14.134 98 29 58 1 1366046752 10843648 6679456
01:16:01 92153 8192000000 10297101600 10747215872 1334099856 14.004 96 18 36 0 1347693360 9801392 3792112
01:18:36 67823 8192000000 10293722016 10746826752 1322534416 13.900 95 19 35 1 1342549520 16094800 3920304
01:20:44 71664 8192000000 10287024192 10740690944 1364772624 14.280 99 24 48 0 1382519248 12300256 5446368
01:22:57 35276 8192000000 10277979904 10730500096 1352723200 14.172 98 15 30 0 1365253008 9093056 3436752
01:24:36 7864 8192000000 10284848304 10736848896 1377680912 14.396 100 30 60 0 1397017984 12713968 6623104
01:25:44 34843 8192000000 10269687120 10738286592 1380704080 14.423 100 18 35 0 1395548080 11321856 3522144
01:27:26 38326 8192000000 10272111968 10741985280 1369094768 14.319 99 7 14 0 1378762672 8287200 1380704
01:29:05 89126 8192000000 10270212384 10729570304 1366097824 14.292 99 19 38 0 1381086368 10231200 4757344
01:32:01 66656 8192000000 10254568848 10712584192 1316919840 13.849 96 15 29 0 1334721280 14626496 3174944
01:34:09 52310 8192000000 10256175040 10700050432 1313822992 13.821 97 23 46 0 1329213200 9755744 5634464
01:36:07 49537 8192000000 10261920816 10711339008 1336559888 14.026 99 18 36 1 1351477296 11127968 3789440
01:38:06 31665 8192000000 9012042240 9208868864 179277328 2.141 14 1 2 0 180883216 1563536 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-01-2023 01:39:36 to 11-01-2023 01:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 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 11-01-2023 01:39:52 to 11-01-2023 01:39:54.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          245337
MON$OLDEST_ACTIVE               245338
MON$OLDEST_SNAPSHOT             245338
MON$NEXT_TRANSACTION            245338
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-03 16:41:55.3470
MON$PAGES                       2026848
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
--- end of diff output ---

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

11.01.23 01:39:56. There are 3 logs in /var/tmp/logs-oltp30 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-11 01:39:58 No severe PSQL-related problems occured
11.01.23 01:39:59. End of report.