Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.04.2022 19: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 16582600 kB
MemAvailable 31569512 kB
Buffers 0 kB
Cached 15160388 kB
SwapCached 2500 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.10
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-04-23 19:38:15
DTS_END                         2022-04-23 19:38:15.7760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51028
TRN_ID                          242938
ATT_ID                          693
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: 9274.83 21078 1112980
customer order: creation 175.98 346 21118
customer order: refuse 15.41 363 1849
order to supplier: creation 47.80 1297 5736
order to supplier: removal 3.60 97133 432
invoice (draft): creation 48.23 3426 5788
invoice (draft): removal 53.81 786 6457
invoice accept: apply 42.50 16861 5100
invoice accept: cancel 47.46 725 5695
customer reserve: creation 3892.76 168 467131
customer reserve: removal 4638.74 59 556649
realization accept: apply 86.19 176 10343
realization accept: cancel 89.35 103 10722
payment from customer: creation 61.58 120 7390
payment from customer: removal 5.55 94 666
payment to supplier: creation 49.74 127 5969
payment to supplier: removal 3.58 99 429
service: total inventory turnovers 5.12 926 614
service: total monetary turnovers 6.54 22 785
service: aggregate perf. data 0.86 16171 103
service: refresh index statistics 0.03 282550 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-04-2022 19:40:40 to 23-04-2022 19:40:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6458
TEST_TIME 2 6819
TEST_TIME 3 6025
TEST_TIME 4 6073
TEST_TIME 5 6142
TEST_TIME 6 6215
TEST_TIME 7 6448
TEST_TIME 8 6344
TEST_TIME 9 6218
TEST_TIME 10 7081
TEST_TIME 11 7048
TEST_TIME 12 6818
TEST_TIME 13 6561
TEST_TIME 14 6522
TEST_TIME 15 6739
TEST_TIME 16 6896
TEST_TIME 17 7082
TEST_TIME 18 7406
TEST_TIME 19 7773
TEST_TIME 20 7841
TEST_TIME 21 8376
TEST_TIME 22 8746
TEST_TIME 23 9135
TEST_TIME 24 9805
TEST_TIME 25 9551
TEST_TIME 26 10321
TEST_TIME 27 10733
TEST_TIME 28 10811
TEST_TIME 29 10672
TEST_TIME 30 10946
TEST_TIME 31 10813
TEST_TIME 32 10720
TEST_TIME 33 10682
TEST_TIME 34 10685
TEST_TIME 35 10598
TEST_TIME 36 10703
TEST_TIME 37 10571
TEST_TIME 38 10337
TEST_TIME 39 10152
TEST_TIME 40 10242
TEST_TIME 41 10151
TEST_TIME 42 10034
TEST_TIME 43 10008
TEST_TIME 44 9912
TEST_TIME 45 9859
TEST_TIME 46 10178
TEST_TIME 47 10232
TEST_TIME 48 10128
TEST_TIME 49 10069
TEST_TIME 50 9906
TEST_TIME 51 9988
TEST_TIME 52 9898
TEST_TIME 53 9907
TEST_TIME 54 9839
TEST_TIME 55 9803
TEST_TIME 56 9800
TEST_TIME 57 9805
TEST_TIME 58 9868
TEST_TIME 59 9852
TEST_TIME 60 9903
TEST_TIME 61 9903
TEST_TIME 62 9981
TEST_TIME 63 9930
TEST_TIME 64 10028
TEST_TIME 65 10025
TEST_TIME 66 10107
TEST_TIME 67 10066
TEST_TIME 68 9967
TEST_TIME 69 9996
TEST_TIME 70 9883
TEST_TIME 71 9871
TEST_TIME 72 9800
TEST_TIME 73 9852
TEST_TIME 74 9770
TEST_TIME 75 9706
TEST_TIME 76 9666
TEST_TIME 77 9650
TEST_TIME 78 9608
TEST_TIME 79 9591
TEST_TIME 80 9633
TEST_TIME 81 9671
TEST_TIME 82 9708
TEST_TIME 83 9674
TEST_TIME 84 9665
TEST_TIME 85 9689
TEST_TIME 86 9632
TEST_TIME 87 9671
TEST_TIME 88 9723
TEST_TIME 89 9677
TEST_TIME 90 9767
TEST_TIME 91 9764
TEST_TIME 92 9691
TEST_TIME 93 9615
TEST_TIME 94 9548
TEST_TIME 95 9592
TEST_TIME 96 9522
TEST_TIME 97 9483
TEST_TIME 98 9473
TEST_TIME 99 9540
TEST_TIME 100 9547
TEST_TIME 101 9520
TEST_TIME 102 9562
TEST_TIME 103 9526
TEST_TIME 104 9504
TEST_TIME 105 9570
TEST_TIME 106 9587
TEST_TIME 107 9572
TEST_TIME 108 9609
TEST_TIME 109 9622
TEST_TIME 110 9606
TEST_TIME 111 9627
TEST_TIME 112 9568
TEST_TIME 113 9515
TEST_TIME 114 9475
TEST_TIME 115 9483
TEST_TIME 116 9439
TEST_TIME 117 9385
TEST_TIME 118 9369
TEST_TIME 119 9339
TEST_TIME 120 9276

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-04-2022 19:40:44 to 23-04-2022 19:40:47.

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 1546017 1546017 0 0.00 0 19514 6 0 0
doc_list_biud 1546016 1546016 0 0.00 0 59688 27 0 0
sp_add_invoice_to_stock 5281 5100 181 3.42 39 229531 16862 0 181
sp_cancel_adding_invoice 5695 5695 0 0.00 4 47481 726 0 0
sp_cancel_client_order 1849 1849 0 0.00 4 11944 363 0 0
sp_cancel_customer_reserve 556649 556649 0 0.00 1 34863 59 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 2 7006 94 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 6518 100 0 0
sp_cancel_supplier_invoice 6469 6457 12 0.18 1 59694 787 0 12
sp_cancel_supplier_order 432 432 0 0.00 8 1050788 97133 0 0
sp_cancel_write_off 10722 10722 0 0.00 1 9763 104 0 0
sp_client_order 21118 21118 0 0.00 4 19966 346 0 0
sp_customer_reserve 467131 467131 0 0.00 0 84413 169 0 0
sp_fill_shopping_cart 32636 32636 0 0.00 1 9869 39 0 0
sp_fill_shopping_cart_clo_res 467127 467127 0 0.00 0 15493 12 0 0
sp_get_clo_for_invoice 5096 5096 0 0.00 0 15343 287 0 0
sp_kill_cost_storno 17512 17512 0 0.00 0 6299 51 0 0
sp_kill_qstorno_ret_qs2qd 1130909 1130909 0 0.00 0 59685 32 0 0
sp_kill_qty_storno 597243 597243 0 0.00 0 59687 69 0 0
sp_lock_selected_doc 21541 21541 0 0.00 0 13434 7 0 0
sp_make_cost_storno 28798 28798 0 0.00 0 10859 52 0 0
sp_multiply_rows_for_pdistr 28798 28798 0 0.00 0 8086 29 0 0
sp_multiply_rows_for_qdistr 21118 21118 0 0.00 0 13322 156 0 0
sp_pay_from_customer 7390 7390 0 0.00 4 12858 121 0 0
sp_pay_to_supplier 5969 5969 0 0.00 5 12841 128 0 0
sp_payment_common 13359 13359 0 0.00 1 9283 28 0 0
sp_qd_handle_on_cancel_clo 1849 1849 0 0.00 0 11937 330 0 0
sp_qd_handle_on_invoice_upd_sts 10791 10791 0 0.00 0 44714 328 0 0
sp_qd_handle_on_reserve_upd_sts 10343 10343 0 0.00 0 15445 59 0 0
sp_reserve_write_off 10343 10343 0 0.00 6 15582 177 0 0
sp_supplier_invoice 5788 5788 0 0.00 6 82373 3426 0 0
sp_supplier_order 5736 5736 0 0.00 5 34792 1297 0 0
srv_aggregate_perf_data 103 103 0 0.00 2825 127279 16171 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2446 129733 44099 0 0
srv_find_qd_qs_mism 1064408 1064408 0 0.00 0 19513 5 0 0
srv_make_invnt_saldo 614 614 0 0.00 38 73470 927 0 0
srv_make_money_saldo 785 785 0 0.00 1 695 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 146608 343734 282550 0 0
t$perf-abend:sp_add_invoice_to_stock 181 181 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5100 5100 0 0.00 0 11158 153 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 10526 96 0 0
t$perf-norm:sp_cancel_client_order 1849 1849 0 0.00 0 12495 17 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 718 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 6945 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 1008 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 482 482 0 0.00 0 1165 15 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 21395 1378 0 0
t$perf-norm:sp_cancel_write_off 1105 1105 0 0.00 0 882 3 0 0
t$perf-norm:sp_client_order 21118 21118 0 0.00 0 12407 15 0 0
t$perf-norm:sp_customer_reserve 1079 1079 0 0.00 0 5110 7 0 0
t$perf-norm:sp_pay_from_customer 7390 7390 0 0.00 0 10035 9 0 0
t$perf-norm:sp_pay_to_supplier 5969 5969 0 0.00 0 4012 9 0 0
t$perf-norm:sp_reserve_write_off 10343 10343 0 0.00 0 13405 11 0 0
t$perf-norm:sp_supplier_invoice 5788 5788 0 0.00 0 11197 19 0 0
t$perf-norm:sp_supplier_order 5736 5736 0 0.00 0 4724 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 2106 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1093 26 0 0
t$perf-norm:srv_make_invnt_saldo 614 614 0 0.00 0 692 17 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 3653 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 5 0 0
v_add_invoice_to_stock 10552 10190 362 3.43 0 4957 19 0 362
v_all_customers 38828 38828 0 0.00 0 19 0 0 0
v_all_suppliers 31638 31638 0 0.00 0 14 1 0 0
v_all_wares 42236 42236 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 490 5 0 0
v_cancel_client_order 3698 3698 0 0.00 0 3677 9 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 3020 9 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 3570 10 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 4946 15 0 0
v_cancel_supplier_invoice 988 964 24 2.42 0 2779 22 0 24
v_cancel_supplier_order 864 864 0 0.00 0 2888 7 0 0
v_cancel_write_off 2210 2210 0 0.00 0 6528 18 0 0
v_max_id_clo_ord 5735 5735 0 0.00 0 379 1 0 0
v_max_id_clo_res 1079 1079 0 0.00 0 5367 19 0 0
v_max_id_ord_sup 5783 5783 0 0.00 0 2336 3 0 0
v_max_non_paid_invoice 1668 1668 0 0.00 0 1449 3 0 0
v_max_non_paid_realizn 3824 3824 0 0.00 0 2893 4 0 0
v_min_id_clo_ord 5735 5735 0 0.00 0 2429 7 0 0
v_min_id_clo_res 1079 1079 0 0.00 0 5223 20 0 0
v_min_id_ord_sup 5783 5783 0 0.00 0 6098 7 0 0
v_min_non_paid_invoice 5969 5969 0 0.00 0 6065 17 0 0
v_min_non_paid_realizn 7390 7390 0 0.00 0 7331 23 0 0
v_our_firm 10540 10540 0 0.00 0 12 1 0 0
v_reserve_write_off 20686 20686 0 0.00 0 3416 7 0 0
x_lock_depdocs_on_canc_invoice 11670 11670 0 0.00 0 14581 117 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 19735 340 0 0
x_make_qstorno_1000_1200 5736 5736 0 0.00 1 34783 1215 0 0
x_make_qstorno_1200_2000 5788 5788 0 0.00 3 82360 3287 0 0
x_make_qstorno_2100_3300 467131 467131 0 0.00 0 84410 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-04-2022 19:40:48 to 23-04-2022 19:40:52.

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
17:08:19 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:21 23322 8192000000 9885379392 10193088512 969688256 10.584 100 88 177 0 998890320 5653504 23548560
17:10:47 6278 8192000000 9922972304 10239913984 1006891712 10.945 100 88 176 0 1037610416 7719632 22999072
17:12:01 17776 8192000000 9952405872 10276732928 1036192192 11.228 100 87 174 0 1067839824 9405344 22242288
17:13:23 15284 8192000000 10022469712 10372763648 1111776016 11.949 100 72 144 0 1138253536 9905728 16571792
17:14:43 7834 8192000000 10072341680 10427625472 1161122160 12.414 100 78 152 1 1192078096 12956080 17999856
17:15:53 5401 8192000000 10108754560 10475442176 1200553696 12.781 100 72 144 1 1228912224 12212400 16146128
17:17:01 3010 8192000000 10156738144 10527084544 1237797056 13.126 100 87 175 2 1272397760 14889072 19711632
17:18:06 3187 8192000000 10181140624 10562076672 1271725328 13.437 100 87 172 1 1302728960 11763264 19240368
17:19:11 11681 8192000000 10199098608 10585182208 1287965728 13.586 100 87 176 1 1321457264 13748992 19742544
17:20:25 4239 8192000000 10191313008 10591621120 1291849360 13.621 100 50 100 0 1313411968 10493184 11069424
17:21:32 8837 8192000000 10199329840 10607853568 1307616800 13.764 100 28 56 1 1320144448 6374720 6152928
17:22:46 10692 8192000000 10213523072 10624487424 1324545920 13.918 100 21 41 1 1334545872 5508096 4491856
17:24:01 13196 8192000000 10226929360 10639388672 1327595216 13.945 100 40 76 1 1342213440 5167648 9450576
17:25:16 5092 8192000000 10238244816 10654265344 1338502496 14.044 100 34 68 2 1352798944 6607728 7688720
17:26:21 8135 8192000000 10236527200 10659528704 1345472960 14.107 100 18 35 1 1354945968 5495888 3977120
17:27:30 17665 8192000000 10205398048 10615808000 1277367088 13.489 96 30 61 2 1290475200 5879232 7228880
17:28:49 19189 8192000000 10199218784 10609991680 1269409328 13.416 96 30 59 2 1282254192 6752976 6091888
17:30:09 9009 8192000000 10162563984 10576429056 1269914176 13.421 99 26 53 0 1282500592 6584528 6001888
17:31:20 6422 8192000000 10153457184 10546970624 1223135120 12.991 97 36 71 2 1237486400 5899920 8451360
17:32:29 73378 8192000000 10125616624 10519973888 1189366592 12.677 94 34 66 1 1202228320 4937232 7924496
17:34:43 5401 8192000000 10180340624 10582605824 1285101616 13.560 100 23 46 2 1296167632 5872720 5193296
17:35:52 12497 8192000000 10178972512 10590076928 1288407776 13.590 100 15 30 2 1297229840 5524336 3297728
17:37:05 73418 8192000000 10206489408 10620383232 1306766928 13.757 100 20 40 1 1316333360 4573744 4992688
17:39:24 3922 8192000000 10236591408 10648821760 1334292832 14.006 100 45 86 2 1352753440 7892512 10568096
17:40:35 20299 8192000000 10251912272 10667589632 1348826608 14.137 100 47 94 1 1369782848 10964992 9991248
17:41:59 13784 8192000000 10277178336 10695761920 1366063840 14.292 100 55 108 0 1397627792 19453488 12110464
17:43:17 19464 8192000000 10293576448 10714759168 1375402960 14.375 100 40 80 0 1408482880 23689904 9390016
17:44:41 15241 8192000000 10298784624 10722009088 1386528336 14.475 100 48 96 0 1412784048 15191792 11063920
17:45:58 2446 8192000000 10317686144 10736320512 1387829392 14.486 99 69 138 1 1421218912 16401008 16988512
17:47:17 13132 8192000000 10341457840 10763747328 1408699024 14.672 100 56 112 1 1446119872 23377392 14043456
17:48:38 13290 8192000000 10330989488 10764800000 1400955968 14.604 99 49 98 0 1441425296 27566256 12903072
17:49:59 11150 8192000000 10328692688 10752905216 1393197360 14.534 99 51 102 0 1430680064 23752096 13730608
17:51:18 7976 8192000000 10352710976 10778296320 1422010544 14.791 100 49 98 2 1462406352 27913728 12482080
17:52:30 5232 8192000000 10365701264 10783363072 1417571456 14.751 100 68 136 1 1471078192 37067008 16439728
17:53:40 3314 8192000000 10367782464 10786676736 1418333456 14.758 100 66 132 2 1475457312 40228768 16895088
17:54:47 8758 8192000000 10356800144 10768502784 1397633728 14.574 100 76 152 0 1463488928 46876224 18978976
17:56:02 4953 8192000000 10367189552 10779590656 1405334848 14.642 100 73 143 0 1477735552 53579520 18821184
17:57:11 3680 8192000000 10355798448 10765934592 1376957824 14.389 99 63 126 2 1450014368 56600992 16455552
17:58:18 12541 8192000000 10352113920 10773078016 1381550064 14.430 99 42 83 3 1453780672 60623632 11606976
17:59:33 18155 8192000000 10341648640 10770452480 1367098672 14.301 98 32 64 2 1432901280 56510288 9292320
18:00:53 15362 8192000000 10326397632 10754560000 1367966112 14.309 100 36 72 0 1434194928 56550880 9677936
18:02:11 26099 8192000000 10308317664 10754928640 1358396048 14.223 99 23 46 0 1411631520 46984384 6251088
18:04:13 30633 8192000000 10286973840 10739597312 1334954080 14.012 98 20 40 0 1385548288 44577808 6016400
18:05:44 45726 8192000000 10273065232 10696110080 1310365952 13.789 97 35 70 2 1326968224 8798416 7803856
18:08:03 129733 8192000000 10256136128 10692739072 1287338320 13.580 95 11 22 1 1303225280 13870224 2016736
18:11:37 40916 8192000000 10275474720 10720444416 1360516320 14.242 100 9 18 1 1374747216 12392832 1838064
18:13:20 24245 8192000000 10280202272 10719961088 1359823360 14.236 99 17 34 1 1374893280 11563440 3506480
18:14:45 89345 8192000000 10264636816 10701926400 1320234080 13.879 96 13 26 0 1334274368 10240848 3799440
18:18:15 18082 8192000000 10299013920 10738626560 1363082768 14.265 99 56 110 1 1393230368 16465264 13682336
18:19:36 72999 8192000000 10311351888 10757308416 1387332032 14.482 100 11 22 0 1410572208 20399152 2841024
18:21:50 29084 8192000000 10322806896 10769240064 1402296304 14.615 100 21 42 0 1427354416 19700960 5357152
18:23:26 19970 8192000000 10346450096 10780286976 1402914976 14.621 100 63 123 1 1443660336 25594176 15151184
18:24:59 38595 8192000000 10293809600 10759811072 1389673680 14.503 100 8 16 1 1409711360 18332080 1705600
18:26:42 61357 8192000000 10307171712 10768130048 1398599344 14.583 100 11 22 1 1418631232 17206432 2825456
18:28:44 46383 8192000000 10295687424 10758303744 1373864944 14.362 99 20 40 0 1394753216 15857600 5030672
18:30:52 60452 8192000000 10287906432 10752905216 1384251184 14.455 100 12 24 1 1398149536 11260576 2637776
18:33:13 46514 8192000000 10299991488 10753568768 1383810848 14.451 100 21 42 1 1400350304 12028432 4511024
18:35:00 14526 8192000000 10290220176 10744631296 1365555008 14.287 98 32 64 0 1384315792 11725712 7035072
18:36:18 43196 8192000000 10267951936 10714435584 1331502160 13.981 97 19 38 3 1343969584 7998000 4469424
18:38:04 52569 8192000000 10281603584 10745368576 1346690864 14.118 98 25 50 0 1369033344 16628096 5714384
18:39:57 64632 8192000000 10277258448 10731057152 1337897568 14.038 98 12 24 0 1356851968 16222464 2731936
18:42:05 76548 8192000000 10277279680 10711662592 1298109360 13.678 95 19 38 0 1313281264 11043328 4128576
18:44:22 103827 8192000000 10261919504 10706628608 1256754752 13.300 92 11 21 0 1269745920 10703056 2288112
18:47:07 18243 8192000000 10274790640 10701922304 1328464752 13.953 98 51 102 1 1353714064 14191200 11058112
18:48:28 94561 8192000000 10263795296 10715267072 1344272944 14.096 99 27 54 0 1360818560 10185616 6360000
18:51:29 41671 8192000000 10247208800 10716393472 1343559888 14.089 99 23 46 0 1360621840 11087104 5974848
18:53:24 73044 8192000000 10268760560 10725539840 1350267232 14.150 99 8 16 2 1362302944 10394432 1641280
18:55:37 77293 8192000000 10281184352 10746822656 1361840720 14.254 99 14 28 1 1377873280 12874224 3158336
18:57:55 48354 8192000000 10289642848 10748293120 1381759456 14.432 100 19 38 0 1401099808 14931712 4408640
18:59:43 67020 8192000000 10290977248 10751766528 1351047808 14.157 98 20 40 0 1369722736 13905776 4769152
19:02:02 23757 8192000000 10296945440 10758393856 1385955040 14.470 100 26 52 0 1403585072 11733728 5896304
19:03:32 53026 8192000000 10306717536 10764156928 1393341440 14.536 100 20 41 0 1408964720 11057824 4565456
19:05:33 64990 8192000000 10316503584 10775482368 1392410176 14.527 99 28 56 0 1410756304 12370224 5975904
19:07:43 21713 8192000000 10315209712 10757836800 1391452512 14.519 100 30 60 1 1408787952 11016832 6318608
19:09:05 115056 8192000000 10280862624 10736091136 1332093008 13.986 95 12 24 0 1352216160 17375264 2747888
19:12:26 7974 8192000000 10314523344 10763968512 1397291904 14.571 100 40 80 0 1421374688 14476144 9606640
19:13:36 64983 8192000000 10310870192 10779058176 1392117712 14.525 99 15 31 0 1405118592 9720528 3280352
19:15:41 65330 8192000000 10322683712 10778120192 1396938064 14.568 99 16 33 2 1412193472 10803520 4451888
19:17:48 70167 8192000000 10293189696 10759221248 1357421360 14.214 97 14 28 0 1368364160 7864960 3077840
19:20:07 107143 8192000000 10314832240 10772045824 1374497616 14.367 98 11 22 0 1389616464 12469984 2648864
19:23:10 53661 8192000000 10284941344 10752921600 1337524752 14.035 96 24 48 1 1362659760 19261216 5873792
19:25:08 35394 8192000000 10275344560 10717360128 1322134752 13.896 96 24 48 0 1337242352 9822336 5285264
19:27:26 80811 8192000000 10272464016 10723799040 1282193184 13.533 93 13 26 0 1297850688 12098272 3559232
19:29:47 96723 8192000000 10279393040 10726408192 1364750224 14.280 100 21 42 0 1381064016 11429312 4884480
19:32:27 50156 8192000000 10266226768 10721853440 1344923376 14.102 98 23 46 1 1360732896 10903392 4906128
19:34:35 72839 8192000000 10252950192 10704764928 1319674752 13.874 97 19 38 2 1340256448 16191504 4390192
19:36:48 60934 8192000000 10252074528 10704637952 1328139520 13.950 98 16 32 1 1344694096 12450480 4104096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-04-2022 19:40:54 to 23-04-2022 19:41:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 181 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-04-2022 19:41:10 to 23-04-2022 19:41:12.

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          238309
MON$OLDEST_ACTIVE               243054
MON$OLDEST_SNAPSHOT             243054
MON$NEXT_TRANSACTION            243054
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                       2034784
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1521: 
:1522: mscs-test-161.budzdorov.ru    Sat Apr 23 19:38:20 2022
:1523:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1524: 
:1525: 
:1526: mscs-test-161.budzdorov.ru    Sat Apr 23 19:38:29 2022
:1527:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1528: 
:1529: 
:1530: mscs-test-161.budzdorov.ru    Sat Apr 23 19:38:34 2022
:1531:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1532: 
--- end of diff output ---

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

23.04.22 19:41:13. 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-04-23 19:41:15 No severe PSQL-related problems occured
23.04.22 19:41:17. End of report.