Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.07.2022 22: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 15811552 kB
MemAvailable 31078220 kB
Buffers 0 kB
Cached 15888552 kB
SwapCached 2480 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 2022-07-16 22:38:23
DTS_END                         2022-07-16 22:38:23.7820
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(57:17)
IP                              ::1/34644
TRN_ID                          243357
ATT_ID                          716
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: 9505.50 26646 1140660
customer order: creation 170.88 372 20505
customer order: refuse 14.54 333 1745
order to supplier: creation 46.56 1265 5587
order to supplier: removal 3.52 99228 422
invoice (draft): creation 46.08 3194 5529
invoice (draft): removal 54.19 769 6503
invoice accept: apply 42.60 17066 5112
invoice accept: cancel 49.01 795 5881
customer reserve: creation 3934.61 169 472153
customer reserve: removal 4847.30 56 581676
realization accept: apply 81.76 174 9811
realization accept: cancel 87.23 102 10468
payment from customer: creation 58.29 125 6995
payment from customer: removal 5.58 91 669
payment to supplier: creation 47.90 142 5748
payment to supplier: removal 3.76 53 451
service: total inventory turnovers 4.62 1252 554
service: total monetary turnovers 6.13 28 736
service: aggregate perf. data 0.93 19299 111
service: refresh index statistics 0.03 388415 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-07-2022 22:39:49 to 16-07-2022 22:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3697
TEST_TIME 2 3236
TEST_TIME 3 3499
TEST_TIME 4 3788
TEST_TIME 5 3701
TEST_TIME 6 3871
TEST_TIME 7 4391
TEST_TIME 8 4638
TEST_TIME 9 4971
TEST_TIME 10 5341
TEST_TIME 11 5582
TEST_TIME 12 6073
TEST_TIME 13 6467
TEST_TIME 14 6918
TEST_TIME 15 6950
TEST_TIME 16 7112
TEST_TIME 17 7479
TEST_TIME 18 7818
TEST_TIME 19 7871
TEST_TIME 20 8587
TEST_TIME 21 9056
TEST_TIME 22 8976
TEST_TIME 23 8915
TEST_TIME 24 8820
TEST_TIME 25 9137
TEST_TIME 26 8955
TEST_TIME 27 9319
TEST_TIME 28 9843
TEST_TIME 29 9951
TEST_TIME 30 10785
TEST_TIME 31 10696
TEST_TIME 32 10407
TEST_TIME 33 10328
TEST_TIME 34 10217
TEST_TIME 35 10105
TEST_TIME 36 10071
TEST_TIME 37 10143
TEST_TIME 38 10031
TEST_TIME 39 10056
TEST_TIME 40 10110
TEST_TIME 41 10123
TEST_TIME 42 10094
TEST_TIME 43 10000
TEST_TIME 44 9968
TEST_TIME 45 9951
TEST_TIME 46 9922
TEST_TIME 47 10123
TEST_TIME 48 10124
TEST_TIME 49 10247
TEST_TIME 50 10187
TEST_TIME 51 10200
TEST_TIME 52 10062
TEST_TIME 53 9935
TEST_TIME 54 9917
TEST_TIME 55 9865
TEST_TIME 56 9753
TEST_TIME 57 9744
TEST_TIME 58 9618
TEST_TIME 59 9634
TEST_TIME 60 9838
TEST_TIME 61 9739
TEST_TIME 62 9723
TEST_TIME 63 9645
TEST_TIME 64 9614
TEST_TIME 65 9757
TEST_TIME 66 9721
TEST_TIME 67 9681
TEST_TIME 68 9718
TEST_TIME 69 9825
TEST_TIME 70 9776
TEST_TIME 71 9791
TEST_TIME 72 9810
TEST_TIME 73 9876
TEST_TIME 74 9836
TEST_TIME 75 9913
TEST_TIME 76 9909
TEST_TIME 77 9890
TEST_TIME 78 9892
TEST_TIME 79 9780
TEST_TIME 80 9752
TEST_TIME 81 9728
TEST_TIME 82 9688
TEST_TIME 83 9708
TEST_TIME 84 9673
TEST_TIME 85 9630
TEST_TIME 86 9587
TEST_TIME 87 9518
TEST_TIME 88 9445
TEST_TIME 89 9540
TEST_TIME 90 9578
TEST_TIME 91 9534
TEST_TIME 92 9590
TEST_TIME 93 9577
TEST_TIME 94 9629
TEST_TIME 95 9624
TEST_TIME 96 9653
TEST_TIME 97 9658
TEST_TIME 98 9664
TEST_TIME 99 9656
TEST_TIME 100 9710
TEST_TIME 101 9678
TEST_TIME 102 9711
TEST_TIME 103 9717
TEST_TIME 104 9761
TEST_TIME 105 9720
TEST_TIME 106 9655
TEST_TIME 107 9609
TEST_TIME 108 9568
TEST_TIME 109 9611
TEST_TIME 110 9616
TEST_TIME 111 9567
TEST_TIME 112 9555
TEST_TIME 113 9540
TEST_TIME 114 9504
TEST_TIME 115 9491
TEST_TIME 116 9573
TEST_TIME 117 9572
TEST_TIME 118 9571
TEST_TIME 119 9528
TEST_TIME 120 9508

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-07-2022 22:39:53 to 16-07-2022 22:39:56.

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 1577164 1577164 0 0.00 0 28182 6 0 0
doc_list_biud 1577164 1577164 0 0.00 0 47000 27 0 0
sp_add_invoice_to_stock 5215 5112 103 1.97 47 176452 17066 0 103
sp_cancel_adding_invoice 5881 5881 0 0.00 4 69199 795 0 0
sp_cancel_client_order 1745 1745 0 0.00 4 12737 333 0 0
sp_cancel_customer_reserve 581676 581676 0 0.00 0 35275 57 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 2 16999 91 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 2 2012 54 0 0
sp_cancel_supplier_invoice 6513 6503 10 0.15 1 41380 769 0 10
sp_cancel_supplier_order 422 422 0 0.00 8 907498 99228 0 0
sp_cancel_write_off 10468 10468 0 0.00 1 29890 102 0 0
sp_client_order 20505 20505 0 0.00 3 24892 373 0 0
sp_customer_reserve 472153 472153 0 0.00 0 74375 169 0 0
sp_fill_shopping_cart 31620 31620 0 0.00 1 17980 37 0 0
sp_fill_shopping_cart_clo_res 472150 472150 0 0.00 0 28056 13 0 0
sp_get_clo_for_invoice 5108 5108 0 0.00 0 30007 257 0 0
sp_kill_cost_storno 17469 17469 0 0.00 0 16989 54 0 0
sp_kill_qstorno_ret_qs2qd 1180745 1180745 0 0.00 0 41315 31 0 0
sp_kill_qty_storno 621614 621614 0 0.00 0 46999 67 0 0
sp_lock_selected_doc 20667 20667 0 0.00 0 6864 5 0 0
sp_make_cost_storno 27662 27662 0 0.00 0 21058 66 0 0
sp_multiply_rows_for_pdistr 27662 27662 0 0.00 0 6567 25 0 0
sp_multiply_rows_for_qdistr 20505 20505 0 0.00 0 20378 164 0 0
sp_pay_from_customer 6995 6995 0 0.00 4 19979 126 0 0
sp_pay_to_supplier 5748 5748 0 0.00 5 20799 142 0 0
sp_payment_common 12743 12743 0 0.00 1 20792 32 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 12728 302 0 0
sp_qd_handle_on_invoice_upd_sts 10989 10989 0 0.00 0 46999 333 0 0
sp_qd_handle_on_reserve_upd_sts 9811 9811 0 0.00 0 11484 56 0 0
sp_reserve_write_off 9811 9811 0 0.00 6 21088 174 0 0
sp_supplier_invoice 5529 5529 0 0.00 6 85303 3195 0 0
sp_supplier_order 5587 5587 0 0.00 4 40433 1266 0 0
srv_aggregate_perf_data 111 111 0 0.00 2353 301209 19300 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1345 125311 43066 0 0
srv_find_qd_qs_mism 1092458 1092458 0 0.00 0 28179 5 0 0
srv_make_invnt_saldo 554 554 0 0.00 47 110747 1252 0 0
srv_make_money_saldo 736 736 0 0.00 1 948 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 117278 761658 388416 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5113 5113 0 0.00 0 8503 150 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 4749 115 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 3806 12 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 1572 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 3298 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 2931 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 857 13 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 25752 1595 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 2915 13 0 0
t$perf-norm:sp_client_order 20505 20505 0 0.00 0 7374 16 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 6547 12 0 0
t$perf-norm:sp_pay_from_customer 6995 6995 0 0.00 0 3155 9 0 0
t$perf-norm:sp_pay_to_supplier 5749 5749 0 0.00 0 4701 10 0 0
t$perf-norm:sp_reserve_write_off 9811 9811 0 0.00 0 9540 14 0 0
t$perf-norm:sp_supplier_invoice 5529 5529 0 0.00 0 3881 14 0 0
t$perf-norm:sp_supplier_order 5587 5587 0 0.00 0 3879 16 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 428 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 47 2 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 2108 26 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 1497 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10422 10216 206 1.97 0 4295 18 0 206
v_all_customers 37816 37816 0 0.00 0 206 1 0 0
v_all_suppliers 30510 30510 0 0.00 0 30 1 0 0
v_all_wares 41008 41008 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 101 5 0 0
v_cancel_client_order 3490 3490 0 0.00 0 705 3 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 2595 5 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 491 4 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 2007 8 0 0
v_cancel_supplier_invoice 804 784 20 2.48 0 1377 14 0 20
v_cancel_supplier_order 844 844 0 0.00 0 1214 7 0 0
v_cancel_write_off 2008 2008 0 0.00 0 3660 14 0 0
v_max_id_clo_ord 5587 5587 0 0.00 0 3639 2 0 0
v_max_id_clo_res 983 983 0 0.00 0 6534 17 0 0
v_max_id_ord_sup 5529 5529 0 0.00 0 4673 6 0 0
v_max_non_paid_invoice 1609 1609 0 0.00 0 2046 4 0 0
v_max_non_paid_realizn 3625 3625 0 0.00 0 6655 6 0 0
v_min_id_clo_ord 5587 5587 0 0.00 0 17592 13 0 0
v_min_id_clo_res 983 983 0 0.00 0 1972 7 0 0
v_min_id_ord_sup 5529 5529 0 0.00 0 4926 9 0 0
v_min_non_paid_invoice 5748 5748 0 0.00 0 20787 23 0 0
v_min_non_paid_realizn 6995 6995 0 0.00 0 11158 25 0 0
v_our_firm 9932 9932 0 0.00 0 17 1 0 0
v_reserve_write_off 19622 19622 0 0.00 0 7325 6 0 0
x_lock_depdocs_on_canc_invoice 11992 11992 0 0.00 0 23714 124 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 9997 364 0 0
x_make_qstorno_1000_1200 5587 5587 0 0.00 1 40423 1178 0 0
x_make_qstorno_1200_2000 5529 5529 0 0.00 2 85286 3075 0 0
x_make_qstorno_2100_3300 472153 472153 0 0.00 0 74374 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-07-2022 22:39:57 to 16-07-2022 22:40:01.

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
20:08:27 28 8192000000 8832076752 8854872064 29573584 0.359 11 5 7 0 29866864 111376 181904
20:09:29 25686 8192000000 9898066544 10210476032 980656112 10.691 100 86 172 0 1009561632 6166912 22738608
20:11:04 19005 8192000000 9945158560 10265640960 1029006992 11.159 100 92 184 0 1061968352 8739744 24221616
20:12:27 18384 8192000000 9988928656 10322444288 1068928576 11.542 100 80 160 0 1098647856 9489440 20229840
20:13:48 11345 8192000000 10034481328 10385326080 1123121024 12.056 100 76 152 0 1152217216 10909968 18186224
20:15:10 6262 8192000000 10101202544 10461081600 1189044176 12.674 100 85 170 0 1220851008 13065600 18741232
20:16:18 3272 8192000000 10136148112 10507657216 1224116144 13.000 100 75 148 2 1253385776 12848320 16421312
20:17:24 7187 8192000000 10158847520 10537631744 1250399200 13.242 100 72 144 0 1279106656 12883920 15823536
20:18:35 1125 8192000000 10198225904 10580221952 1286585552 13.573 100 89 176 2 1319737456 14065152 19086752
20:19:37 1617 8192000000 10216860976 10603159552 1299891040 13.694 100 89 177 1 1333287552 13907360 19489152
20:20:41 16584 8192000000 10208904704 10614378496 1310791776 13.793 100 54 108 1 1333295632 10965248 11538608
20:22:00 4712 8192000000 10218928880 10626789376 1326172016 13.933 100 41 83 2 1342056880 6366320 9518544
20:23:06 19107 8192000000 10206579840 10629115904 1326810016 13.938 100 14 28 1 1335677328 5742032 3125280
20:24:26 66136 8192000000 10221795712 10642108416 1333287184 13.997 100 22 44 1 1343487200 5357552 4842464
20:26:34 6506 8192000000 10227373136 10649669632 1327209280 13.942 99 39 78 1 1342894192 6521744 9163168
20:27:43 8389 8192000000 10211650768 10632093696 1324102160 13.914 100 14 28 0 1332879104 5842032 2934912
20:28:56 9199 8192000000 10187867840 10604113920 1261834496 13.347 96 15 31 1 1270270144 4917920 3517728
20:30:07 35034 8192000000 10148287648 10556497920 1187463616 12.660 92 28 56 0 1199794336 5977104 6353616
20:31:46 6886 8192000000 10148647728 10547789824 1236575472 13.115 98 30 60 2 1248974848 5682752 6716624
20:32:55 8266 8192000000 10160820992 10562838528 1266074896 13.386 100 31 60 0 1278163296 5569696 6518704
20:34:09 54781 8192000000 10175036512 10587693056 1286608128 13.573 100 26 53 0 1297764080 4997168 6158784
20:36:04 7309 8192000000 10192861008 10603483136 1300614176 13.701 100 24 48 0 1311069888 5309552 5146160
20:37:14 10406 8192000000 10196011904 10613248000 1306858624 13.758 100 16 30 0 1315875504 5661904 3354976
20:38:28 26509 8192000000 10221484336 10634784768 1330110800 13.968 100 20 38 0 1338886848 4721296 4054752
20:40:00 82317 8192000000 10253878048 10679189504 1351197136 14.158 100 34 68 0 1368249792 9712752 7339904
20:42:29 15636 8192000000 10272961744 10695303168 1367516464 14.305 100 28 55 0 1387733552 13561904 6655184
20:43:51 8535 8192000000 10296411536 10708496384 1378192128 14.400 100 59 114 0 1408242992 16873920 13176944
20:45:03 13422 8192000000 10309876816 10729603072 1394427008 14.545 100 51 99 1 1424349184 18384896 11537280
20:46:22 7123 8192000000 10316293792 10737221632 1398422576 14.581 100 50 98 0 1430872688 20390960 12059152
20:47:42 3235 8192000000 10327205072 10745327616 1398315648 14.580 100 70 135 0 1437946176 23579360 16051168
20:48:50 7120 8192000000 10320126176 10740805632 1391733248 14.521 100 60 120 0 1430867152 23935312 15198592
20:50:02 5052 8192000000 10335998112 10754699264 1404486848 14.635 100 61 120 0 1446026208 26903712 14635648
20:51:12 1784 8192000000 10338676128 10757406720 1402889840 14.621 100 71 138 1 1449581664 29732512 16959312
20:52:20 9042 8192000000 10329882768 10749886464 1397939888 14.577 100 65 128 1 1441726416 27500544 16285984
20:53:38 3002 8192000000 10330011168 10740539392 1388704736 14.494 100 76 152 0 1438214096 31035296 18474064
20:54:51 2250 8192000000 10335152704 10740092928 1382124752 14.436 99 80 156 0 1438298160 36920880 19252528
20:55:58 1338 8192000000 10336616544 10743377920 1368856336 14.317 98 81 155 1 1426349920 38421648 19071936
20:57:02 2098 8192000000 10317141200 10729578496 1367629712 14.306 100 58 113 1 1420977264 39025168 14322384
20:58:10 7235 8192000000 10298236464 10712965120 1337428592 14.034 99 66 131 0 1400615456 46217536 16969328
20:59:21 9236 8192000000 10308824912 10730463232 1370157552 14.328 100 45 89 0 1413789968 32210016 11422400
21:00:38 9154 8192000000 10303040032 10727845888 1361165648 14.248 99 46 91 0 1404370928 31742016 11463264
21:01:55 8899 8192000000 10317151328 10745790464 1377775904 14.397 100 35 70 0 1422778400 35656992 9345504
21:03:14 10731 8192000000 10327414400 10757824512 1379996048 14.417 100 42 84 0 1435692256 44000064 11696144
21:04:38 17347 8192000000 10318016240 10750054400 1366231200 14.293 100 30 60 0 1423996528 48330192 9435136
21:06:04 31650 8192000000 10311589184 10749231104 1354390224 14.187 99 32 64 0 1412369312 48534448 9444640
21:07:38 37813 8192000000 10280564256 10737229824 1321083568 13.887 97 16 32 1 1366170384 40820592 4266224
21:09:20 83394 8192000000 10278085168 10720088064 1370660192 14.333 100 39 78 1 1388116320 7791792 9664336
21:12:05 33023 8192000000 10290933472 10731089920 1374726560 14.369 99 28 55 0 1395155168 14595296 5833312
21:13:39 92032 8192000000 10294865872 10763313152 1368003600 14.309 98 12 24 0 1388611504 18128224 2479680
21:17:19 54516 8192000000 10308333072 10763018240 1391412208 14.518 99 11 22 0 1405141776 11352224 2377344
21:19:13 78384 8192000000 10297308816 10750324736 1372817264 14.352 98 5 10 0 1383827648 10184320 826064
21:21:42 49126 8192000000 10329008016 10778406912 1415217152 14.730 100 32 63 0 1431054512 9078928 6758432
21:23:41 20670 8192000000 10333516080 10783047680 1389667632 14.503 98 25 50 0 1410281600 14440128 6173840
21:25:04 83279 8192000000 10308976096 10768285696 1355585152 14.198 96 18 36 0 1384899744 25182448 4132144
21:29:33 105899 8192000000 10327386240 10787119104 1393180928 14.534 98 18 35 2 1415792720 18709680 3902112
21:32:37 34340 8192000000 10316137264 10764525568 1360944944 14.246 96 45 88 2 1377862320 6506880 10410496
21:34:18 49984 8192000000 10322994336 10778628096 1401591936 14.609 99 26 52 1 1421095808 13785616 5718256
21:36:15 37060 8192000000 10332061360 10775613440 1385122640 14.462 98 35 69 0 1413781552 20705600 7953312
21:38:01 50912 8192000000 10297392128 10780495872 1373187216 14.356 98 10 20 0 1391832368 16426976 2218176
21:39:54 35626 8192000000 10300979472 10767097856 1392029696 14.524 100 8 14 2 1402263152 9269312 964144
21:41:39 96491 8192000000 10278439056 10750492672 1295099328 13.651 92 12 24 0 1311297088 13513072 2684688
21:44:27 46389 8192000000 10276874816 10719821824 1319455696 13.872 96 19 36 0 1330297968 6923120 3919152
21:46:14 48418 8192000000 10255711552 10704216064 1317561744 13.855 98 31 62 0 1341119616 16669568 6888304
21:48:04 63832 8192000000 10255599568 10705866752 1300315888 13.698 96 18 36 0 1314923392 10556656 4050848
21:50:30 60694 8192000000 10270106928 10724548608 1337919712 14.039 98 17 34 0 1353598752 11511008 4168032
21:52:56 27886 8192000000 10274265824 10708566016 1324725616 13.919 98 38 74 3 1345306960 12219312 8362032
21:54:34 65889 8192000000 10275490416 10714316800 1318286672 13.861 97 19 38 0 1333013472 10511312 4215488
21:56:40 83280 8192000000 10259672128 10692796416 1303284656 13.725 96 33 64 0 1319094880 8230512 7579712
21:59:22 70999 8192000000 10266737456 10713989120 1359936176 14.237 100 32 64 0 1377943952 10880272 7127504
22:01:34 68531 8192000000 10256294128 10714009600 1354401584 14.187 99 10 19 0 1367431296 11235872 1793840
22:03:43 62701 8192000000 10281174848 10739023872 1377699328 14.396 100 16 32 0 1393732336 11967328 4065680
22:05:46 77582 8192000000 10283551568 10745020416 1380875008 14.424 100 21 43 0 1402528448 16453536 5199904
22:08:18 51270 8192000000 10275766592 10743250944 1369788160 14.325 99 9 19 0 1379727392 8249344 1689888
22:10:10 67790 8192000000 10297114384 10762039296 1391011456 14.515 100 14 26 0 1404252592 10360928 2880208
22:12:18 54856 8192000000 10309530352 10774814720 1404434016 14.634 100 15 30 0 1418329264 10744848 3150400
22:14:16 41314 8192000000 10331652112 10780950528 1412611024 14.707 100 41 82 0 1436784864 14633488 9540352
22:16:10 39003 8192000000 10312873520 10773377024 1395161168 14.552 99 32 64 1 1414017856 11481440 7375248
22:17:50 79643 8192000000 10311206416 10777042944 1370772576 14.334 97 13 26 0 1384608848 10992352 2843920
22:20:10 35207 8192000000 10326373632 10768691200 1403180160 14.623 100 33 66 0 1420703280 9806352 7716768
22:21:48 20417 8192000000 10315779984 10759503872 1373684208 14.360 98 21 43 1 1388827744 10514464 4629072
22:23:14 125310 8192000000 10286161040 10732318720 1321843904 13.893 95 26 53 0 1335873328 7829808 6199616
22:26:55 46967 8192000000 10263632384 10715729920 1352766736 14.172 98 29 53 1 1373004160 13802224 6435200
22:28:42 66683 8192000000 10280470928 10756411392 1365092336 14.283 99 9 19 0 1383550976 16507168 1951472
22:30:49 64693 8192000000 10263488432 10724130816 1345103856 14.103 98 4 8 0 1355041408 9053136 884416
22:32:54 51874 8192000000 10267761648 10713186304 1309390256 13.781 95 22 42 0 1327060944 12863904 4806784
22:34:53 62347 8192000000 10260965008 10718920704 1338298416 14.042 98 22 42 2 1353571600 10054416 5218768
22:36:58 47273 8192000000 10280705472 10729115648 1342551648 14.080 98 18 36 0 1362256592 15160624 4544320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-07-2022 22:40:03 to 16-07-2022 22:40:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-07-2022 22:40:18 to 16-07-2022 22:40:20.

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          242116
MON$OLDEST_ACTIVE               244444
MON$OLDEST_SNAPSHOT             244444
MON$NEXT_TRANSACTION            244444
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                       2062560
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.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 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 ---
:16: 
:17: mscs-test-161.budzdorov.ru    Sat Jul 16 22:38:28 2022
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:19: 
:20: 
:21: mscs-test-161.budzdorov.ru    Sat Jul 16 22:38:45 2022
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Sat Jul 16 22:38:48 2022
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Sat Jul 16 22:38:49 2022
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Sat Jul 16 22:38:50 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Sat Jul 16 22:38:51 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Sat Jul 16 22:38:51 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Sat Jul 16 22:38:51 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Sat Jul 16 22:38:51 2022
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
--- end of diff output ---

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

16.07.22 22:40:21. 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
2022-07-16 22:40:24 No severe PSQL-related problems occured
16.07.22 22:40:25. End of report.