Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.2023 19: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 14876600 kB
MemAvailable 30406596 kB
Buffers 0 kB
Cached 16846064 kB
SwapCached 4548 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-05 19:38:36
DTS_END                         2023-01-05 19:38:36.1330
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/49702
TRN_ID                          245897
ATT_ID                          727
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: 9632.14 24910 1155857
customer order: creation 171.48 332 20577
customer order: refuse 14.63 328 1756
order to supplier: creation 44.93 1293 5392
order to supplier: removal 3.35 109580 402
invoice (draft): creation 45.32 3116 5438
invoice (draft): removal 56.89 741 6827
invoice accept: apply 41.43 16540 4971
invoice accept: cancel 51.10 758 6132
customer reserve: creation 3838.03 161 460564
customer reserve: removal 5069.57 57 608348
realization accept: apply 83.46 195 10015
realization accept: cancel 84.28 111 10113
payment from customer: creation 59.13 147 7096
payment from customer: removal 5.87 99 704
payment to supplier: creation 48.32 182 5798
payment to supplier: removal 3.29 113 395
service: total inventory turnovers 4.11 1379 493
service: total monetary turnovers 6.28 54 754
service: aggregate perf. data 0.65 35757 78
service: refresh index statistics 0.03 327258 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-01-2023 19:40:07 to 05-01-2023 19:40:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4610
TEST_TIME 2 5416
TEST_TIME 3 4847
TEST_TIME 4 4966
TEST_TIME 5 5548
TEST_TIME 6 5789
TEST_TIME 7 6050
TEST_TIME 8 5981
TEST_TIME 9 5546
TEST_TIME 10 5844
TEST_TIME 11 5917
TEST_TIME 12 5944
TEST_TIME 13 5979
TEST_TIME 14 6363
TEST_TIME 15 6672
TEST_TIME 16 6742
TEST_TIME 17 6588
TEST_TIME 18 6618
TEST_TIME 19 6663
TEST_TIME 20 6860
TEST_TIME 21 6847
TEST_TIME 22 7339
TEST_TIME 23 7680
TEST_TIME 24 7973
TEST_TIME 25 7997
TEST_TIME 26 8162
TEST_TIME 27 8349
TEST_TIME 28 8761
TEST_TIME 29 9089
TEST_TIME 30 9656
TEST_TIME 31 9778
TEST_TIME 32 9734
TEST_TIME 33 10347
TEST_TIME 34 10679
TEST_TIME 35 10550
TEST_TIME 36 10389
TEST_TIME 37 10407
TEST_TIME 38 10522
TEST_TIME 39 10459
TEST_TIME 40 10425
TEST_TIME 41 10435
TEST_TIME 42 10257
TEST_TIME 43 10181
TEST_TIME 44 10178
TEST_TIME 45 10151
TEST_TIME 46 10078
TEST_TIME 47 10129
TEST_TIME 48 10170
TEST_TIME 49 10156
TEST_TIME 50 10056
TEST_TIME 51 9918
TEST_TIME 52 9833
TEST_TIME 53 9967
TEST_TIME 54 9895
TEST_TIME 55 9816
TEST_TIME 56 9909
TEST_TIME 57 9867
TEST_TIME 58 9850
TEST_TIME 59 9787
TEST_TIME 60 9852
TEST_TIME 61 9844
TEST_TIME 62 9837
TEST_TIME 63 9806
TEST_TIME 64 9689
TEST_TIME 65 9683
TEST_TIME 66 9622
TEST_TIME 67 9606
TEST_TIME 68 9646
TEST_TIME 69 9585
TEST_TIME 70 9674
TEST_TIME 71 9654
TEST_TIME 72 9700
TEST_TIME 73 9704
TEST_TIME 74 9674
TEST_TIME 75 9761
TEST_TIME 76 9827
TEST_TIME 77 9860
TEST_TIME 78 9829
TEST_TIME 79 9930
TEST_TIME 80 9967
TEST_TIME 81 9962
TEST_TIME 82 9859
TEST_TIME 83 9907
TEST_TIME 84 9870
TEST_TIME 85 9879
TEST_TIME 86 9793
TEST_TIME 87 9789
TEST_TIME 88 9796
TEST_TIME 89 9774
TEST_TIME 90 9832
TEST_TIME 91 9802
TEST_TIME 92 9794
TEST_TIME 93 9798
TEST_TIME 94 9836
TEST_TIME 95 9824
TEST_TIME 96 9791
TEST_TIME 97 9868
TEST_TIME 98 9953
TEST_TIME 99 9944
TEST_TIME 100 9968
TEST_TIME 101 9981
TEST_TIME 102 10014
TEST_TIME 103 10010
TEST_TIME 104 9942
TEST_TIME 105 9906
TEST_TIME 106 9867
TEST_TIME 107 9867
TEST_TIME 108 9830
TEST_TIME 109 9797
TEST_TIME 110 9814
TEST_TIME 111 9835
TEST_TIME 112 9807
TEST_TIME 113 9757
TEST_TIME 114 9699
TEST_TIME 115 9716
TEST_TIME 116 9686
TEST_TIME 117 9704
TEST_TIME 118 9677
TEST_TIME 119 9688
TEST_TIME 120 9635

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-01-2023 19:40:11 to 05-01-2023 19:40:15.

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 1583568 1583568 0 0.00 0 23185 6 0 0
doc_list_biud 1583563 1583563 0 0.00 0 46703 28 0 0
sp_add_invoice_to_stock 5076 4971 105 2.06 36 193571 16540 0 105
sp_cancel_adding_invoice 6132 6132 0 0.00 4 65846 758 0 0
sp_cancel_client_order 1756 1756 0 0.00 4 7737 329 0 0
sp_cancel_customer_reserve 608348 608348 0 0.00 0 46706 58 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 2 9296 99 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 2 7824 113 0 0
sp_cancel_supplier_invoice 6837 6827 10 0.14 1 17734 742 0 10
sp_cancel_supplier_order 402 402 0 0.00 14 1053493 109581 0 0
sp_cancel_write_off 10113 10113 0 0.00 1 13974 111 0 0
sp_client_order 20577 20577 0 0.00 3 54058 333 0 0
sp_customer_reserve 460564 460564 0 0.00 0 136654 162 0 0
sp_fill_shopping_cart 31397 31397 0 0.00 1 13585 34 0 0
sp_fill_shopping_cart_clo_res 460563 460563 0 0.00 0 16524 13 0 0
sp_get_clo_for_invoice 4968 4968 0 0.00 0 51053 334 0 0
sp_kill_cost_storno 17344 17344 0 0.00 0 9287 56 0 0
sp_kill_qstorno_ret_qs2qd 1234038 1234038 0 0.00 0 46698 31 0 0
sp_kill_qty_storno 648557 648557 0 0.00 0 46702 67 0 0
sp_lock_selected_doc 20750 20750 0 0.00 0 9094 7 0 0
sp_make_cost_storno 27877 27877 0 0.00 0 22038 82 0 0
sp_multiply_rows_for_pdistr 27873 27873 0 0.00 0 9612 29 0 0
sp_multiply_rows_for_qdistr 20577 20577 0 0.00 0 54050 147 0 0
sp_pay_from_customer 7096 7096 0 0.00 4 14732 148 0 0
sp_pay_to_supplier 5798 5798 0 0.00 5 22084 182 0 0
sp_payment_common 12893 12893 0 0.00 2 14499 35 0 0
sp_qd_handle_on_cancel_clo 1756 1756 0 0.00 0 7400 296 0 0
sp_qd_handle_on_invoice_upd_sts 11098 11098 0 0.00 0 13743 316 0 0
sp_qd_handle_on_reserve_upd_sts 10013 10013 0 0.00 0 10510 54 0 0
sp_reserve_write_off 10015 10015 0 0.00 6 17615 195 0 0
sp_supplier_invoice 5438 5438 0 0.00 7 81774 3116 0 0
sp_supplier_order 5392 5392 0 0.00 4 41003 1293 0 0
srv_aggregate_perf_data 78 78 0 0.00 2902 581331 35758 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1272 149826 42732 0 0
srv_find_qd_qs_mism 1109069 1109069 0 0.00 0 23185 4 0 0
srv_make_invnt_saldo 493 493 0 0.00 37 74458 1379 0 0
srv_make_money_saldo 754 754 0 0.00 1 11150 54 0 0
srv_recalc_idx_stat 4 4 0 0.00 145218 628597 327258 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 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 4971 4971 0 0.00 0 13089 166 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 12373 123 0 0
t$perf-norm:sp_cancel_client_order 1756 1756 0 0.00 0 3111 12 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 765 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 4507 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 4034 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 15214 56 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 24006 1960 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 1666 7 0 0
t$perf-norm:sp_client_order 20577 20577 0 0.00 0 16252 20 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 3450 10 0 0
t$perf-norm:sp_pay_from_customer 7096 7096 0 0.00 0 16481 16 0 0
t$perf-norm:sp_pay_to_supplier 5798 5798 0 0.00 0 9095 14 0 0
t$perf-norm:sp_reserve_write_off 10015 10015 0 0.00 0 6671 13 0 0
t$perf-norm:sp_supplier_invoice 5438 5438 0 0.00 0 9393 15 0 0
t$perf-norm:sp_supplier_order 5392 5392 0 0.00 0 8378 19 0 0
t$perf-norm:srv_aggregate_perf_data 78 78 0 0.00 0 166 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 493 493 0 0.00 0 1189 18 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 4691 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10136 9926 210 2.07 0 12554 22 0 210
v_all_customers 37490 37490 0 0.00 0 75 1 0 0
v_all_suppliers 29928 29928 0 0.00 0 59 1 0 0
v_all_wares 41152 41152 0 0.00 0 103 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 343 6 0 0
v_cancel_client_order 3512 3512 0 0.00 0 7660 8 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 3790 9 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 2633 6 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 7806 23 0 0
v_cancel_supplier_invoice 886 866 20 2.25 0 1019 15 0 20
v_cancel_supplier_order 804 804 0 0.00 0 1401 5 0 0
v_cancel_write_off 1994 1994 0 0.00 0 8429 25 0 0
v_max_id_clo_ord 5392 5392 0 0.00 0 1006 1 0 0
v_max_id_clo_res 988 988 0 0.00 0 6922 27 0 0
v_max_id_ord_sup 5429 5429 0 0.00 0 5292 6 0 0
v_max_non_paid_invoice 1652 1652 0 0.00 0 763 3 0 0
v_max_non_paid_realizn 3780 3780 0 0.00 0 3651 8 0 0
v_min_id_clo_ord 5392 5392 0 0.00 0 3620 7 0 0
v_min_id_clo_res 988 988 0 0.00 0 7443 15 0 0
v_min_id_ord_sup 5429 5429 0 0.00 0 2702 4 0 0
v_min_non_paid_invoice 5795 5795 0 0.00 0 11675 28 0 0
v_min_non_paid_realizn 7095 7095 0 0.00 0 14003 24 0 0
v_our_firm 10292 10292 0 0.00 0 19 1 0 0
v_reserve_write_off 20026 20026 0 0.00 0 7611 7 0 0
x_lock_depdocs_on_canc_invoice 12526 12526 0 0.00 0 9910 101 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 7734 372 0 0
x_make_qstorno_1000_1200 5392 5392 0 0.00 1 40992 1217 0 0
x_make_qstorno_1200_2000 5438 5438 0 0.00 3 81582 3003 0 0
x_make_qstorno_2100_3300 460563 460563 0 0.00 0 136642 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2023 19:40:16 to 05-01-2023 19:40:20.

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:40 4 8192000000 8801822112 8814170112 7611568 0.092 3 2 3 0 7754000 67472 74960
17:09:40 16427 8192000000 9898311632 10207449088 975006304 10.636 100 90 180 0 1004687296 5870592 23810400
17:11:04 23492 8192000000 9944315936 10263617536 1024234448 11.113 100 93 186 0 1056874960 8637184 24003328
17:12:33 9986 8192000000 9968953632 10298376192 1049256496 11.354 100 83 166 0 1079995408 9821472 20917440
17:13:47 10464 8192000000 10025408736 10367881216 1105588720 11.891 100 77 155 0 1134044528 9904112 18551696
17:14:58 6523 8192000000 10075713536 10433261568 1163451248 12.436 100 79 158 1 1194627440 12686944 18489248
17:16:06 3958 8192000000 10118982320 10487734272 1208270672 12.853 100 75 150 0 1237710512 12882656 16557184
17:17:12 1569 8192000000 10160276176 10534375424 1249686192 13.235 100 88 173 0 1281279712 13639328 17954192
17:18:15 1674 8192000000 10190238848 10570100736 1276318624 13.479 100 78 154 0 1305499680 12237728 16943328
17:19:17 2968 8192000000 10220461456 10605539328 1301986640 13.713 100 81 163 0 1332778928 13135104 17657184
17:20:22 20006 8192000000 10198629024 10617843712 1315366240 13.835 100 39 76 0 1330345744 5367824 9611680
17:21:43 6411 8192000000 10219018080 10633740288 1327828928 13.948 100 25 43 3 1338473008 5324176 5319904
17:22:51 11170 8192000000 10225265408 10648498176 1338487232 14.044 100 12 25 0 1347161744 5213200 3461312
17:24:03 11545 8192000000 10230495600 10649759744 1344360336 14.097 100 18 36 0 1354211504 6025584 3825584
17:25:15 25335 8192000000 10226655232 10657591296 1330800192 13.974 99 21 42 0 1342636400 6907792 4928416
17:26:41 7414 8192000000 10235941696 10653949952 1323161440 13.905 98 31 64 0 1336925040 6643152 7120448
17:27:50 4221 8192000000 10214469328 10632232960 1304375712 13.735 98 33 65 0 1317875376 6443632 7056032
17:28:55 52844 8192000000 10188040304 10590986240 1260749168 13.337 96 28 56 0 1273350080 5268848 7332064
17:30:56 9463 8192000000 10130679616 10528804864 1172524336 12.520 93 22 42 1 1187212864 10130608 4557920
17:32:05 7169 8192000000 10142958256 10539753472 1243254816 13.176 99 26 52 0 1254951584 5636304 6060464
17:33:15 9715 8192000000 10173506160 10580062208 1276690176 13.483 100 21 42 0 1287519312 5771696 5057440
17:34:25 34051 8192000000 10184217264 10599112704 1291925840 13.622 100 35 70 0 1306070832 5698944 8446048
17:36:00 8741 8192000000 10203204544 10614390784 1304294160 13.734 100 31 61 0 1321410672 9805024 7311488
17:37:16 8166 8192000000 10207844256 10625179648 1310078272 13.787 100 27 54 0 1326731312 10412448 6240592
17:38:24 8525 8192000000 10219953280 10641920000 1332805488 13.992 100 16 32 0 1340477984 4752400 2920096
17:39:37 68069 8192000000 10258958960 10683666432 1353116768 14.176 100 47 94 0 1379989600 16170608 10702224
17:41:49 6910 8192000000 10261564816 10683326464 1358558400 14.224 100 39 78 0 1376260224 8924976 8776848
17:43:03 16659 8192000000 10279218992 10701824000 1371661760 14.342 100 40 75 1 1391815632 11173024 8980848
17:44:21 7055 8192000000 10286790272 10707255296 1377012640 14.390 100 51 101 1 1399852496 11333136 11506720
17:45:37 4087 8192000000 10298779904 10716553216 1378290864 14.401 100 55 109 0 1406412176 15277088 12844224
17:46:50 53192 8192000000 10292124464 10718629888 1379684528 14.414 100 36 72 0 1401826448 13650400 8491520
17:48:51 15168 8192000000 10299406448 10726760448 1364954496 14.282 98 38 76 0 1390367248 16344368 9068384
17:50:08 10613 8192000000 10296459808 10716512256 1378395120 14.402 99 47 94 0 1410379808 20445584 11539104
17:51:23 3497 8192000000 10296673760 10707107840 1357489824 14.215 99 55 110 0 1400579920 29710256 13379840
17:52:30 3872 8192000000 10326551216 10744197120 1384073104 14.453 100 57 112 0 1432931344 34978816 13879424
17:53:37 3319 8192000000 10307071392 10712334336 1358032800 14.220 99 74 144 2 1410551968 34740480 17778688
17:54:44 7723 8192000000 10312232320 10724712448 1373707904 14.360 100 53 103 2 1421554400 33760240 14086256
17:55:59 7916 8192000000 10330600416 10734927872 1376294704 14.383 100 79 155 2 1434536160 39498656 18742800
17:57:12 4039 8192000000 10315376528 10727886848 1369755360 14.325 100 52 103 2 1425833440 42674176 13403904
17:58:25 1270 8192000000 10320890592 10718728192 1353141424 14.176 99 85 165 3 1416396768 42038624 21216720
17:59:33 6767 8192000000 10326619216 10740764672 1373321632 14.357 100 45 89 1 1430551536 44982432 12247472
18:01:00 1889 8192000000 10342436656 10749861888 1374501600 14.367 100 76 152 0 1442114768 48297312 19315856
18:02:08 1556 8192000000 10344644000 10748723200 1378012784 14.399 100 80 157 0 1442326464 44542096 19771584
18:03:11 6302 8192000000 10321444208 10743750656 1370929104 14.335 99 54 103 1 1428053216 43169424 13954688
18:04:32 21164 8192000000 10326778000 10749005824 1336416208 14.025 97 31 62 0 1396305248 50605744 9283296
18:06:10 8998 8192000000 10345807808 10770997248 1377551024 14.395 100 35 70 2 1449273440 62377408 9345008
18:07:25 11170 8192000000 10339409072 10768703488 1381241360 14.428 100 30 60 2 1442344384 52698528 8404496
18:09:00 31057 8192000000 10340310816 10778304512 1382689120 14.441 100 30 60 0 1444252272 53938240 7624912
18:10:58 16142 8192000000 10332803712 10771578880 1382768080 14.441 100 17 35 1 1435647824 48222256 4657488
18:12:16 32303 8192000000 10289636304 10738442240 1375302736 14.375 100 19 38 0 1407827152 27835008 4689408
18:13:49 56981 8192000000 10289118512 10738712576 1381102720 14.426 100 10 21 0 1391466592 8538176 1825696
18:15:47 112778 8192000000 10300365536 10753269760 1363316480 14.267 98 11 22 0 1383603856 17915712 2371664
18:18:40 101351 8192000000 10306012208 10743578624 1372572208 14.350 99 9 16 0 1385184224 11213088 1398928
18:21:34 60075 8192000000 10322605184 10762166272 1395025056 14.551 100 28 54 0 1418218624 17140192 6053376
18:23:37 59951 8192000000 10330682800 10775474176 1409422720 14.679 100 38 69 1 1428578928 10584608 8571600
18:25:41 111481 8192000000 10320435504 10784755712 1383253008 14.446 98 6 12 1 1403488752 19316704 919040
18:28:34 83342 8192000000 10285382960 10731409408 1347100944 14.121 96 30 61 0 1364111344 10299952 6710448
18:31:47 45424 8192000000 10290477328 10731192320 1338303440 14.042 97 27 52 0 1351997712 7833728 5860544
18:33:37 59376 8192000000 10291860928 10751418368 1363533920 14.269 98 34 66 0 1389027872 17262016 8231936
18:35:40 94631 8192000000 10269591952 10712129536 1302492064 13.718 94 18 36 1 1313942896 7424608 4026224
18:38:16 64728 8192000000 10266935248 10721738752 1340175984 14.059 98 30 58 1 1357034000 10525264 6332752
18:40:22 32199 8192000000 10270372048 10724102144 1359041568 14.229 99 4 8 0 1372741904 12925728 774608
18:41:55 29465 8192000000 10260762320 10718023680 1367222272 14.302 100 7 14 0 1375109248 6371872 1515104
18:43:34 17695 8192000000 10268561456 10729238528 1356095168 14.202 98 22 41 1 1373503248 12962432 4445648
18:44:54 51745 8192000000 10243162752 10695081984 1295355840 13.653 95 18 36 0 1308571008 9394896 3820272
18:46:46 42298 8192000000 10278601616 10724532224 1356737936 14.208 99 22 44 2 1376707232 14526320 5442976
18:48:29 61161 8192000000 10248036528 10686976000 1291639232 13.619 95 18 36 0 1304332320 8827728 3865360
18:50:30 71635 8192000000 10264184192 10716442624 1318368464 13.862 97 19 38 0 1331516224 8957280 4190480
18:52:42 32376 8192000000 10295895600 10741125120 1369326368 14.321 100 36 72 1 1393352896 15991216 8035312
18:54:26 73159 8192000000 10278242848 10720256000 1328560896 13.954 97 13 26 0 1344681200 13133152 2987152
18:56:44 46493 8192000000 10300600560 10750849024 1377260528 14.392 100 23 46 0 1402668320 19982960 5424832
18:58:36 149826 8192000000 10292199616 10753003520 1374464240 14.367 99 16 32 0 1399150432 20701008 3985184
19:02:25 11253 8192000000 10275397280 10733797376 1349850416 14.146 98 21 42 1 1368795504 14494048 4451040
19:03:36 67839 8192000000 10287145808 10751328256 1366318496 14.294 99 11 22 0 1384926816 16284256 2324064
19:05:54 78241 8192000000 10282984112 10747346944 1342513264 14.080 97 16 32 1 1365250912 18972992 3764656
19:08:20 55577 8192000000 10291754592 10742345728 1384859824 14.460 100 23 46 1 1396321056 7067008 4394224
19:10:17 75070 8192000000 10300862576 10762936320 1377482160 14.394 99 15 30 0 1388284576 8207312 2595104
19:12:34 71582 8192000000 10298071440 10756468736 1358777920 14.226 97 12 24 0 1371157872 9726960 2652992
19:14:46 31946 8192000000 10328122016 10774454272 1387924112 14.487 99 42 83 1 1417041184 19501200 9615872
19:16:22 74117 8192000000 10295642080 10744741888 1342296736 14.078 96 10 20 0 1357211344 12819808 2094800
19:18:56 39692 8192000000 10309274144 10750480384 1350952000 14.156 97 25 50 0 1369875904 13337056 5586848
19:20:37 45050 8192000000 10309823360 10748067840 1356704384 14.208 98 21 42 0 1378430592 17250544 4475664
19:22:23 78897 8192000000 10279151488 10729537536 1341027296 14.067 97 33 65 1 1359162064 11029888 7104880
19:24:51 6393 8192000000 10276530832 10727837696 1353287024 14.177 99 37 73 0 1375791296 13610336 8893936
19:25:59 58978 8192000000 10270421136 10728570880 1347454432 14.125 98 13 26 0 1364241856 13703472 3083952
19:27:58 49219 8192000000 10275023728 10734043136 1365703184 14.289 100 21 42 0 1381311040 10433760 5174096
19:29:50 56544 8192000000 10247045488 10702589952 1331380752 13.980 97 12 24 1 1340585712 6628256 2576704
19:31:50 72772 8192000000 10255642016 10714800128 1350026560 14.148 99 14 28 0 1363244544 10042704 3175280
19:34:14 58469 8192000000 10227235440 10684841984 1307727184 13.765 97 13 28 0 1326509344 15707584 3074576
19:36:17 29418 8192000000 10252100880 10697973760 1323501712 13.908 98 25 50 0 1340320976 11033120 5786144
19:37:47 50319 8192000000 10136370064 10556620800 1222545680 12.985 91 32 64 0 1245326352 15751552 7029120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-01-2023 19:40:22 to 05-01-2023 19:40:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 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 05-01-2023 19:40:38 to 05-01-2023 19:40:40.

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          242831
MON$OLDEST_ACTIVE               246664
MON$OLDEST_SNAPSHOT             246664
MON$NEXT_TRANSACTION            246664
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                       2083296
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1697: 
:1698: mscs-test-161.budzdorov.ru    Thu Jan  5 19:38:45 2023
:1699:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1700: 
--- end of diff output ---

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

05.01.23 19:40:41. 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-05 19:40:44 No severe PSQL-related problems occured
05.01.23 19:40:45. End of report.