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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15330908 kB
MemAvailable 30430924 kB
Buffers 0 kB
Cached 16405312 kB
SwapCached 4444 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-12 16:38:36
DTS_END                         2023-01-12 16:38:36.7980
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(57:17)
IP                              ::1/42480
TRN_ID                          246255
ATT_ID                          704
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: 9416.65 22435 1129998
customer order: creation 178.52 362 21422
customer order: refuse 14.93 348 1792
order to supplier: creation 47.46 1335 5695
order to supplier: removal 3.65 91142 438
invoice (draft): creation 47.68 3484 5721
invoice (draft): removal 54.98 788 6597
invoice accept: apply 42.74 18245 5129
invoice accept: cancel 48.57 815 5828
customer reserve: creation 3905.61 182 468673
customer reserve: removal 4769.63 54 572356
realization accept: apply 86.23 184 10348
realization accept: cancel 81.58 110 9790
payment from customer: creation 61.52 111 7382
payment from customer: removal 6.13 86 736
payment to supplier: creation 51.32 118 6158
payment to supplier: removal 3.68 72 441
service: total inventory turnovers 4.71 1115 565
service: total monetary turnovers 6.88 33 826
service: aggregate perf. data 0.81 25753 97
service: refresh index statistics 0.03 304366 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2023 16:41:21 to 12-01-2023 16:41:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2860
TEST_TIME 2 3720
TEST_TIME 3 3490
TEST_TIME 4 3333
TEST_TIME 5 4861
TEST_TIME 6 5191
TEST_TIME 7 5502
TEST_TIME 8 6070
TEST_TIME 9 6578
TEST_TIME 10 7061
TEST_TIME 11 7464
TEST_TIME 12 7553
TEST_TIME 13 7852
TEST_TIME 14 7914
TEST_TIME 15 8019
TEST_TIME 16 8117
TEST_TIME 17 8137
TEST_TIME 18 8036
TEST_TIME 19 8172
TEST_TIME 20 8247
TEST_TIME 21 8668
TEST_TIME 22 8653
TEST_TIME 23 8616
TEST_TIME 24 8529
TEST_TIME 25 8882
TEST_TIME 26 9005
TEST_TIME 27 9109
TEST_TIME 28 8972
TEST_TIME 29 9216
TEST_TIME 30 9095
TEST_TIME 31 9029
TEST_TIME 32 9483
TEST_TIME 33 9719
TEST_TIME 34 10309
TEST_TIME 35 10636
TEST_TIME 36 10626
TEST_TIME 37 10419
TEST_TIME 38 10227
TEST_TIME 39 10177
TEST_TIME 40 10186
TEST_TIME 41 10282
TEST_TIME 42 10101
TEST_TIME 43 10044
TEST_TIME 44 9938
TEST_TIME 45 9836
TEST_TIME 46 9874
TEST_TIME 47 9888
TEST_TIME 48 9977
TEST_TIME 49 9877
TEST_TIME 50 9932
TEST_TIME 51 9812
TEST_TIME 52 9782
TEST_TIME 53 9735
TEST_TIME 54 9757
TEST_TIME 55 9723
TEST_TIME 56 9695
TEST_TIME 57 9719
TEST_TIME 58 9769
TEST_TIME 59 9807
TEST_TIME 60 9852
TEST_TIME 61 9869
TEST_TIME 62 9867
TEST_TIME 63 9897
TEST_TIME 64 9907
TEST_TIME 65 9870
TEST_TIME 66 9846
TEST_TIME 67 9844
TEST_TIME 68 9718
TEST_TIME 69 9702
TEST_TIME 70 9723
TEST_TIME 71 9681
TEST_TIME 72 9688
TEST_TIME 73 9638
TEST_TIME 74 9615
TEST_TIME 75 9551
TEST_TIME 76 9569
TEST_TIME 77 9513
TEST_TIME 78 9517
TEST_TIME 79 9529
TEST_TIME 80 9564
TEST_TIME 81 9589
TEST_TIME 82 9574
TEST_TIME 83 9624
TEST_TIME 84 9588
TEST_TIME 85 9601
TEST_TIME 86 9680
TEST_TIME 87 9657
TEST_TIME 88 9650
TEST_TIME 89 9652
TEST_TIME 90 9725
TEST_TIME 91 9649
TEST_TIME 92 9586
TEST_TIME 93 9602
TEST_TIME 94 9581
TEST_TIME 95 9526
TEST_TIME 96 9516
TEST_TIME 97 9497
TEST_TIME 98 9479
TEST_TIME 99 9500
TEST_TIME 100 9514
TEST_TIME 101 9521
TEST_TIME 102 9543
TEST_TIME 103 9540
TEST_TIME 104 9585
TEST_TIME 105 9589
TEST_TIME 106 9596
TEST_TIME 107 9593
TEST_TIME 108 9625
TEST_TIME 109 9601
TEST_TIME 110 9634
TEST_TIME 111 9552
TEST_TIME 112 9564
TEST_TIME 113 9520
TEST_TIME 114 9511
TEST_TIME 115 9558
TEST_TIME 116 9498
TEST_TIME 117 9484
TEST_TIME 118 9460
TEST_TIME 119 9452
TEST_TIME 120 9417

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-01-2023 16:41:25 to 12-01-2023 16:41:28.

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 1565148 1565148 0 0.00 0 25117 6 0 0
doc_list_biud 1565148 1565148 0 0.00 0 49879 26 0 0
sp_add_invoice_to_stock 5276 5129 147 2.78 27 211400 18245 0 147
sp_cancel_adding_invoice 5828 5828 0 0.00 3 52564 816 0 0
sp_cancel_client_order 1792 1792 0 0.00 3 8145 349 0 0
sp_cancel_customer_reserve 572356 572356 0 0.00 0 49883 54 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 3 10091 86 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 2 3552 72 0 0
sp_cancel_supplier_invoice 6606 6597 9 0.13 1 15689 789 0 9
sp_cancel_supplier_order 438 438 0 0.00 6 863229 91143 0 0
sp_cancel_write_off 9790 9790 0 0.00 1 15941 110 0 0
sp_client_order 21422 21422 0 0.00 3 27503 362 0 0
sp_customer_reserve 468673 468673 0 0.00 0 89974 183 0 0
sp_fill_shopping_cart 32834 32834 0 0.00 1 8436 36 0 0
sp_fill_shopping_cart_clo_res 468671 468671 0 0.00 0 23339 16 0 0
sp_get_clo_for_invoice 5127 5127 0 0.00 0 20968 316 0 0
sp_kill_cost_storno 16795 16795 0 0.00 0 8746 55 0 0
sp_kill_qstorno_ret_qs2qd 1161537 1161537 0 0.00 0 49788 30 0 0
sp_kill_qty_storno 612276 612276 0 0.00 0 49877 65 0 0
sp_lock_selected_doc 21417 21417 0 0.00 0 9658 5 0 0
sp_make_cost_storno 29015 29015 0 0.00 0 25115 53 0 0
sp_multiply_rows_for_pdistr 29015 29015 0 0.00 0 6752 28 0 0
sp_multiply_rows_for_qdistr 21422 21422 0 0.00 0 14878 160 0 0
sp_pay_from_customer 7382 7382 0 0.00 4 27987 111 0 0
sp_pay_to_supplier 6158 6158 0 0.00 4 12901 119 0 0
sp_payment_common 13540 13540 0 0.00 1 7392 26 0 0
sp_qd_handle_on_cancel_clo 1792 1792 0 0.00 0 8135 312 0 0
sp_qd_handle_on_invoice_upd_sts 10955 10955 0 0.00 0 16153 325 0 0
sp_qd_handle_on_reserve_upd_sts 10348 10348 0 0.00 0 15173 63 0 0
sp_reserve_write_off 10348 10348 0 0.00 6 25155 185 0 0
sp_supplier_invoice 5721 5721 0 0.00 6 98378 3485 0 0
sp_supplier_order 5695 5695 0 0.00 5 37111 1336 0 0
srv_aggregate_perf_data 97 97 0 0.00 3027 734818 25754 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1405 128501 43971 0 0
srv_find_qd_qs_mism 1081356 1081356 0 0.00 0 23532 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 42 48804 1116 0 0
srv_make_money_saldo 826 826 0 0.00 1 6441 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 179595 419703 304367 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 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 5129 5129 0 0.00 0 15447 191 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 9917 108 0 0
t$perf-norm:sp_cancel_client_order 1792 1792 0 0.00 0 5367 14 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 3649 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 631 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 2735 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 465 465 0 0.00 0 3911 31 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 23192 1603 0 0
t$perf-norm:sp_cancel_write_off 967 967 0 0.00 0 1366 5 0 0
t$perf-norm:sp_client_order 21422 21422 0 0.00 0 27093 19 0 0
t$perf-norm:sp_customer_reserve 1107 1107 0 0.00 0 971 4 0 0
t$perf-norm:sp_pay_from_customer 7382 7382 0 0.00 0 27092 14 0 0
t$perf-norm:sp_pay_to_supplier 6158 6158 0 0.00 0 8318 13 0 0
t$perf-norm:sp_reserve_write_off 10348 10348 0 0.00 0 8319 14 0 0
t$perf-norm:sp_supplier_invoice 5721 5721 0 0.00 0 26638 31 0 0
t$perf-norm:sp_supplier_order 5695 5695 0 0.00 0 11314 20 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 175 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 7998 125 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 1314 27 0 0
t$perf-norm:srv_make_money_saldo 826 826 0 0.00 0 1506 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 4 0 0
v_add_invoice_to_stock 10548 10254 294 2.78 0 13712 21 0 294
v_all_customers 38768 38768 0 0.00 0 57 1 0 0
v_all_suppliers 31736 31736 0 0.00 0 39 1 0 0
v_all_wares 42844 42844 0 0.00 0 93 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 2284 9 0 0
v_cancel_client_order 3584 3584 0 0.00 0 6355 7 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 6286 14 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 6527 12 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 3264 10 0 0
v_cancel_supplier_invoice 948 930 18 1.89 0 1386 16 0 18
v_cancel_supplier_order 876 876 0 0.00 0 920 5 0 0
v_cancel_write_off 1934 1934 0 0.00 0 15892 26 0 0
v_max_id_clo_ord 5695 5695 0 0.00 0 1320 2 0 0
v_max_id_clo_res 1107 1107 0 0.00 0 2056 9 0 0
v_max_id_ord_sup 5716 5716 0 0.00 0 4875 4 0 0
v_max_non_paid_invoice 1701 1701 0 0.00 0 1299 4 0 0
v_max_non_paid_realizn 4007 4007 0 0.00 0 2109 4 0 0
v_min_id_clo_ord 5695 5695 0 0.00 0 6595 7 0 0
v_min_id_clo_res 1107 1107 0 0.00 0 2636 13 0 0
v_min_id_ord_sup 5716 5716 0 0.00 0 3668 6 0 0
v_min_non_paid_invoice 6158 6158 0 0.00 0 5628 17 0 0
v_min_non_paid_realizn 7382 7382 0 0.00 0 6795 19 0 0
v_our_firm 10826 10826 0 0.00 0 15 1 0 0
v_reserve_write_off 20696 20696 0 0.00 0 6530 5 0 0
x_lock_depdocs_on_canc_invoice 11960 11960 0 0.00 0 20596 126 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 9509 363 0 0
x_make_qstorno_1000_1200 5695 5695 0 0.00 1 37078 1257 0 0
x_make_qstorno_1200_2000 5721 5721 0 0.00 2 98350 3356 0 0
x_make_qstorno_2100_3300 468673 468673 0 0.00 0 89971 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2023 16:41:29 to 12-01-2023 16:41:33.

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
14:08:40 2 8192000000 8799993136 8811675648 6389664 0.077 2 1 2 0 6489296 56848 42784
14:09:42 31019 8192000000 9921181136 10235424768 1001512944 10.893 100 93 186 0 1032048288 6555136 23980208
14:11:18 12938 8192000000 9953592880 10278416384 1036592880 11.232 100 84 168 0 1067143952 9109856 21441216
14:12:39 14915 8192000000 9985916176 10318372864 1068419536 11.537 100 89 178 1 1101398880 10896784 22082560
14:13:59 9117 8192000000 10026324816 10374336512 1117810608 12.006 100 79 157 0 1147311488 11113648 18387232
14:15:11 8823 8192000000 10076042080 10435665920 1160636784 12.409 100 81 161 0 1192351696 13284384 18430528
14:16:20 5780 8192000000 10120955280 10489683968 1207359808 12.845 100 76 152 1 1236893472 12471152 17062512
14:17:29 1539 8192000000 10156691888 10528083968 1243472608 13.178 100 85 169 0 1274708544 12981584 18254352
14:18:33 1543 8192000000 10184031776 10564788224 1272180448 13.442 100 95 185 3 1306578944 12902784 21495712
14:19:37 1622 8192000000 10211580032 10595516416 1299082912 13.687 100 86 171 0 1332608528 14434896 19090720
14:20:40 24254 8192000000 10208593008 10612764672 1309652784 13.783 100 56 112 1 1334773216 11484304 13636128
14:22:06 9408 8192000000 10200174736 10611552256 1317611296 13.855 100 25 46 1 1328782112 5877504 5293312
14:23:16 7101 8192000000 10208337776 10623295488 1325536896 13.927 100 23 45 0 1336371424 6251920 4582608
14:24:24 8609 8192000000 10212219952 10628239360 1326413776 13.935 100 12 24 1 1334204480 4888448 2902256
14:25:33 62478 8192000000 10235293520 10653163520 1336342352 14.024 100 22 44 0 1350969568 9544576 5082640
14:27:41 6251 8192000000 10218141248 10636750848 1327153984 13.941 100 27 54 0 1340465616 7638384 5673248
14:28:48 10771 8192000000 10193909264 10614267904 1297901008 13.676 99 13 24 1 1306116944 5627136 2588800
14:30:01 9731 8192000000 10155238944 10562867200 1235703632 13.107 96 17 32 1 1244422736 4901312 3817792
14:31:12 28904 8192000000 10129251520 10532843520 1139411344 12.210 89 26 51 1 1150431952 5469312 5551296
14:32:41 4486 8192000000 10131000080 10526466048 1213388848 12.900 97 30 59 0 1227288448 5141104 8758496
14:33:49 8784 8192000000 10149865152 10554654720 1253892336 13.274 99 15 28 0 1262269952 4991504 3386112
14:35:00 73165 8192000000 10167421376 10583932928 1277554016 13.491 99 29 58 2 1289379008 5246016 6578976
14:37:14 5202 8192000000 10196577280 10603982848 1299203328 13.688 100 48 96 0 1318304576 7642128 11459120
14:38:24 4062 8192000000 10213043616 10627444736 1320901120 13.885 100 29 55 0 1333184656 5512688 6770848
14:39:37 19052 8192000000 10243940208 10657714176 1343962240 14.093 100 39 76 1 1362652480 9204464 9485776
14:41:10 65777 8192000000 10273683168 10693484544 1366074496 14.292 100 44 89 0 1389356560 13083952 10198112
14:43:40 7331 8192000000 10274300576 10687389696 1359259040 14.231 99 47 94 0 1384925856 14790336 10876480
14:44:52 3473 8192000000 10298385968 10711957504 1381565904 14.431 100 58 115 1 1408116352 13409344 13141104
14:46:04 7105 8192000000 10323433648 10737143808 1388436112 14.492 100 68 135 0 1425351968 20246336 16669520
14:47:15 17753 8192000000 10310685680 10731237376 1386553664 14.475 100 54 105 1 1422131408 22313424 13264320
14:48:38 16207 8192000000 10304368768 10727616512 1385418688 14.465 100 48 96 0 1412704480 16401408 10884384
14:49:59 5225 8192000000 10307207648 10722988032 1381593776 14.431 100 74 146 1 1412910384 14130096 17186512
14:51:11 1647 8192000000 10307629664 10718986240 1371169632 14.338 99 82 162 0 1404633488 14253136 19210720
14:52:16 2344 8192000000 10305435792 10721107968 1376122208 14.382 100 65 127 1 1412963088 21699104 15141776
14:53:28 1401 8192000000 10310480464 10723307520 1379425856 14.411 100 71 142 0 1416826368 20729088 16671424
14:54:33 4859 8192000000 10300532288 10716246016 1371495776 14.340 100 55 110 0 1411159296 26097952 13565568
14:55:42 14885 8192000000 10280139040 10695917568 1345753760 14.109 99 56 112 1 1384159136 23136256 15269120
14:57:03 2998 8192000000 10312123952 10718744576 1366814960 14.299 100 75 151 1 1413464496 28004496 18645040
14:58:12 10981 8192000000 10302245904 10720391168 1353858848 14.182 99 36 70 0 1395234656 32450416 8925392
14:59:27 7136 8192000000 10286475296 10700533760 1338454528 14.043 99 53 106 0 1381980592 31289536 12236528
15:00:55 5640 8192000000 10289470112 10700513280 1351789952 14.164 100 44 86 0 1395540544 32967632 10782960
15:02:08 3546 8192000000 10335489552 10742534144 1375859344 14.380 100 75 151 3 1436793248 42072320 18861584
15:03:16 11524 8192000000 10319422944 10751606784 1381140480 14.427 100 33 66 0 1431936880 42053104 8743296
15:04:35 2692 8192000000 10333954768 10740637696 1377516160 14.394 99 84 165 1 1435722496 37321472 20884864
15:05:41 10797 8192000000 10315945648 10748137472 1379313968 14.410 100 23 46 0 1424562864 39179440 6069456
15:06:59 44387 8192000000 10342625808 10781945856 1369249616 14.320 98 29 58 0 1421635392 44087584 8298192
15:08:45 35934 8192000000 10343223600 10768859136 1376991072 14.390 99 25 48 0 1429996320 46126128 6879120
15:10:25 17367 8192000000 10346034768 10788495360 1394101408 14.542 100 25 50 1 1456625376 55542768 6981200
15:12:05 34548 8192000000 10316488432 10765369344 1378372864 14.402 99 16 30 1 1418067696 35697728 3997104
15:13:42 83000 8192000000 10302421216 10747777024 1393003040 14.533 100 15 30 0 1414273488 18006640 3263808
15:16:08 97269 8192000000 10327170848 10761744384 1393452400 14.537 100 39 78 0 1411866096 8791984 9621712
15:18:50 59859 8192000000 10294294800 10745589760 1366877616 14.299 98 25 49 0 1386868192 14220976 5769600
15:21:31 33148 8192000000 10302802880 10754625536 1393986448 14.541 100 33 66 0 1411020464 9312336 7721680
15:23:10 49245 8192000000 10345333984 10780614656 1408803120 14.673 100 46 91 0 1436760384 16823040 11134224
15:25:04 76757 8192000000 10299833376 10772615168 1387884512 14.487 99 19 38 0 1418159152 25444080 4830560
15:27:23 34587 8192000000 10312311904 10772094976 1408666512 14.672 100 8 16 0 1422608192 11871648 2070032
15:29:02 54774 8192000000 10313803696 10779045888 1410642928 14.690 100 13 24 0 1420158400 7193584 2321888
15:31:07 74430 8192000000 10318192256 10796589056 1419916736 14.772 100 7 14 0 1433265136 11886112 1462288
15:33:22 54014 8192000000 10318352912 10780397568 1401647888 14.610 99 17 32 2 1412727312 7584928 3494496
15:35:27 120278 8192000000 10283071088 10744274944 1279482656 13.508 90 16 32 0 1294694880 11932752 3279472
15:38:57 109165 8192000000 10281047776 10729443328 1260673616 13.336 91 11 22 0 1275792416 13021616 2097184
15:41:46 105493 8192000000 10271400000 10720415744 1239302960 13.140 90 9 18 0 1251401040 10393424 1704656
15:44:32 43927 8192000000 10280929728 10712662016 1348793968 14.137 100 32 61 1 1372661104 16401184 7465952
15:46:17 93450 8192000000 10255596592 10704146432 1350512256 14.152 99 28 57 1 1367062320 10398880 6151184
15:48:52 24106 8192000000 10263632912 10710798336 1341412784 14.070 99 26 52 0 1363838416 15969280 6456352
15:50:17 61654 8192000000 10256900544 10719911936 1336644352 14.027 98 11 22 0 1354971616 16072624 2254640
15:52:19 78361 8192000000 10248312480 10708320256 1333174656 13.996 98 12 24 0 1345710448 9618576 2917216
15:55:09 68574 8192000000 10265749392 10719428608 1319146672 13.869 96 18 37 1 1338163392 14454384 4562336
15:57:25 29647 8192000000 10270773760 10723516416 1352077648 14.166 99 16 32 0 1366084144 10214848 3791648
15:58:58 32537 8192000000 10283812736 10726809600 1365739408 14.289 100 40 78 0 1386842976 11971680 9131888
16:00:35 38522 8192000000 10280448624 10728996864 1367300416 14.303 99 25 51 0 1384111680 11211904 5599360
16:02:14 31640 8192000000 10292424032 10748497920 1375049088 14.372 99 27 53 0 1390208336 9520336 5638912
16:04:20 69463 8192000000 10288823584 10746368000 1356732288 14.208 98 23 46 0 1371624304 9970576 4921440
16:06:33 60430 8192000000 10301228080 10754134016 1343441920 14.088 96 22 44 0 1360579168 12433376 4703872
16:08:34 128501 8192000000 10307654784 10761515008 1383313840 14.446 99 14 28 0 1395985440 9889072 2782528
16:11:43 12197 8192000000 10304042880 10772348928 1403305024 14.624 100 24 48 0 1421093632 13213120 4575488
16:12:58 67807 8192000000 10297827408 10771533824 1390613824 14.511 99 13 26 0 1404733120 11155440 2963856
16:15:07 50347 8192000000 10298304352 10761535488 1385400928 14.465 99 22 46 1 1398297760 7516240 5380592
16:16:57 67645 8192000000 10286721840 10759626752 1366783216 14.298 98 17 34 0 1382620240 11720208 4116816
16:19:15 46294 8192000000 10284917680 10744991744 1362143728 14.257 98 19 38 0 1375903200 9213200 4546272
16:21:02 67119 8192000000 10301641904 10754932736 1386951232 14.479 100 14 28 0 1403442928 13171584 3320112
16:23:09 54470 8192000000 10296037024 10746896384 1347152352 14.122 97 18 36 0 1358758272 7894784 3711136
16:25:09 67210 8192000000 10268665600 10715111424 1259367008 13.324 91 34 68 0 1277972000 11245440 7359552
16:27:19 51079 8192000000 10265787984 10712346624 1286735600 13.574 94 24 48 1 1300634240 8753088 5145552
16:29:10 99856 8192000000 10255524608 10705354752 1314504544 13.827 97 19 37 0 1330232416 11272752 4455120
16:32:04 53306 8192000000 10277912304 10736136192 1364610656 14.279 100 16 31 0 1389854336 21441120 3802560
16:34:05 55112 8192000000 10263310272 10724356096 1362911568 14.263 100 8 16 0 1375329136 10763072 1654496
16:36:17 58199 8192000000 10257216448 10711224320 1340759184 14.064 99 12 22 0 1353262288 9860816 2642288
16:38:20 39994 8192000000 9407879648 9729048576 559316672 6.391 41 3 6 0 567219312 7370896 531744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-01-2023 16:41:35 to 12-01-2023 16:41:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 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 12-01-2023 16:41:51 to 12-01-2023 16:41:53.

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          246485
MON$OLDEST_ACTIVE               246486
MON$OLDEST_SNAPSHOT             246486
MON$NEXT_TRANSACTION            246486
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                       2029920
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.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 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 ---
:74: 
:75: mscs-test-161.budzdorov.ru    Thu Jan 12 16:39:04 2023
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Thu Jan 12 16:39:10 2023
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:81: 
--- end of diff output ---

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

12.01.23 16:41:55. 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-12 16:41:57 No severe PSQL-related problems occured
12.01.23 16:41:58. End of report.