Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.2022 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 15505012 kB
MemAvailable 30511884 kB
Buffers 0 kB
Cached 16240264 kB
SwapCached 2100 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-10-19 19:38:29
DTS_END                         2022-10-19 19:38:29.2930
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/34750
TRN_ID                          230464
ATT_ID                          617
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: 9173.05 27672 1100766
customer order: creation 156.55 382 18786
customer order: refuse 13.38 374 1605
order to supplier: creation 41.45 1226 4974
order to supplier: removal 3.51 103259 421
invoice (draft): creation 42.04 3318 5045
invoice (draft): removal 55.81 770 6697
invoice accept: apply 38.85 17876 4662
invoice accept: cancel 51.24 762 6149
customer reserve: creation 3536.14 177 424337
customer reserve: removal 4948.95 57 593874
realization accept: apply 76.80 222 9216
realization accept: cancel 89.25 112 10710
payment from customer: creation 54.10 162 6492
payment from customer: removal 5.19 85 623
payment to supplier: creation 45.36 187 5443
payment to supplier: removal 3.26 97 391
service: total inventory turnovers 4.38 1075 525
service: total monetary turnovers 6.06 34 727
service: aggregate perf. data 0.71 22434 85
service: refresh index statistics 0.03 400840 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-10-2022 19:40:08 to 19-10-2022 19:40:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2410
TEST_TIME 2 2902
TEST_TIME 3 2650
TEST_TIME 4 2999
TEST_TIME 5 4571
TEST_TIME 6 4642
TEST_TIME 7 4710
TEST_TIME 8 4858
TEST_TIME 9 5214
TEST_TIME 10 5493
TEST_TIME 11 5679
TEST_TIME 12 5868
TEST_TIME 13 6009
TEST_TIME 14 5991
TEST_TIME 15 5932
TEST_TIME 16 6366
TEST_TIME 17 6632
TEST_TIME 18 6774
TEST_TIME 19 7051
TEST_TIME 20 7982
TEST_TIME 21 8089
TEST_TIME 22 8385
TEST_TIME 23 8435
TEST_TIME 24 8920
TEST_TIME 25 9299
TEST_TIME 26 9528
TEST_TIME 27 10852
TEST_TIME 28 10768
TEST_TIME 29 10740
TEST_TIME 30 10645
TEST_TIME 31 10615
TEST_TIME 32 10426
TEST_TIME 33 10396
TEST_TIME 34 10233
TEST_TIME 35 10057
TEST_TIME 36 9867
TEST_TIME 37 9973
TEST_TIME 38 9788
TEST_TIME 39 9820
TEST_TIME 40 9685
TEST_TIME 41 9610
TEST_TIME 42 9606
TEST_TIME 43 9564
TEST_TIME 44 9466
TEST_TIME 45 9410
TEST_TIME 46 9300
TEST_TIME 47 9192
TEST_TIME 48 9104
TEST_TIME 49 9152
TEST_TIME 50 9117
TEST_TIME 51 9175
TEST_TIME 52 9148
TEST_TIME 53 9163
TEST_TIME 54 9137
TEST_TIME 55 9201
TEST_TIME 56 9222
TEST_TIME 57 9214
TEST_TIME 58 9307
TEST_TIME 59 9370
TEST_TIME 60 9369
TEST_TIME 61 9470
TEST_TIME 62 9414
TEST_TIME 63 9477
TEST_TIME 64 9590
TEST_TIME 65 9646
TEST_TIME 66 9514
TEST_TIME 67 9465
TEST_TIME 68 9411
TEST_TIME 69 9429
TEST_TIME 70 9378
TEST_TIME 71 9348
TEST_TIME 72 9304
TEST_TIME 73 9267
TEST_TIME 74 9230
TEST_TIME 75 9249
TEST_TIME 76 9327
TEST_TIME 77 9283
TEST_TIME 78 9250
TEST_TIME 79 9262
TEST_TIME 80 9221
TEST_TIME 81 9329
TEST_TIME 82 9286
TEST_TIME 83 9403
TEST_TIME 84 9350
TEST_TIME 85 9465
TEST_TIME 86 9418
TEST_TIME 87 9468
TEST_TIME 88 9483
TEST_TIME 89 9480
TEST_TIME 90 9409
TEST_TIME 91 9346
TEST_TIME 92 9355
TEST_TIME 93 9316
TEST_TIME 94 9305
TEST_TIME 95 9253
TEST_TIME 96 9187
TEST_TIME 97 9164
TEST_TIME 98 9261
TEST_TIME 99 9274
TEST_TIME 100 9280
TEST_TIME 101 9278
TEST_TIME 102 9346
TEST_TIME 103 9365
TEST_TIME 104 9392
TEST_TIME 105 9420
TEST_TIME 106 9410
TEST_TIME 107 9388
TEST_TIME 108 9366
TEST_TIME 109 9412
TEST_TIME 110 9438
TEST_TIME 111 9362
TEST_TIME 112 9323
TEST_TIME 113 9298
TEST_TIME 114 9275
TEST_TIME 115 9225
TEST_TIME 116 9206
TEST_TIME 117 9161
TEST_TIME 118 9123
TEST_TIME 119 9173
TEST_TIME 120 9174

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-10-2022 19:40:11 to 19-10-2022 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 1495734 1495734 0 0.00 0 22863 6 0 0
doc_list_biud 1495733 1495733 0 0.00 0 36391 29 0 0
sp_add_invoice_to_stock 4779 4662 117 2.44 37 168613 17877 0 117
sp_cancel_adding_invoice 6149 6149 0 0.00 4 52939 763 0 0
sp_cancel_client_order 1605 1605 0 0.00 4 10334 375 0 0
sp_cancel_customer_reserve 593874 593874 0 0.00 0 32710 57 0 0
sp_cancel_pay_from_customer 623 623 0 0.00 3 9577 85 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 7113 98 0 0
sp_cancel_supplier_invoice 6708 6697 11 0.16 1 25867 771 0 11
sp_cancel_supplier_order 421 421 0 0.00 5 1066611 103259 0 0
sp_cancel_write_off 10710 10710 0 0.00 1 31004 113 0 0
sp_client_order 18786 18786 0 0.00 3 29827 382 0 0
sp_customer_reserve 424337 424337 0 0.00 0 63788 178 0 0
sp_fill_shopping_cart 28794 28794 0 0.00 1 19624 39 0 0
sp_fill_shopping_cart_clo_res 424335 424335 0 0.00 0 19167 15 0 0
sp_get_clo_for_invoice 4660 4660 0 0.00 0 55287 455 0 0
sp_kill_cost_storno 17873 17873 0 0.00 0 17610 57 0 0
sp_kill_qstorno_ret_qs2qd 1205576 1205576 0 0.00 0 36390 31 0 0
sp_kill_qty_storno 633332 633332 0 0.00 0 36391 67 0 0
sp_lock_selected_doc 19227 19227 0 0.00 0 9205 7 0 0
sp_make_cost_storno 25811 25811 0 0.00 0 21744 66 0 0
sp_multiply_rows_for_pdistr 25811 25811 0 0.00 0 14037 37 0 0
sp_multiply_rows_for_qdistr 18786 18786 0 0.00 0 27244 182 0 0
sp_pay_from_customer 6492 6492 0 0.00 4 21438 163 0 0
sp_pay_to_supplier 5443 5443 0 0.00 4 21329 187 0 0
sp_payment_common 11935 11935 0 0.00 1 21346 68 0 0
sp_qd_handle_on_cancel_clo 1605 1605 0 0.00 0 10327 343 0 0
sp_qd_handle_on_invoice_upd_sts 10809 10809 0 0.00 0 28043 316 0 0
sp_qd_handle_on_reserve_upd_sts 9216 9216 0 0.00 0 25105 69 0 0
sp_reserve_write_off 9216 9216 0 0.00 6 25137 223 0 0
sp_supplier_invoice 5045 5045 0 0.00 6 77383 3319 0 0
sp_supplier_order 4974 4974 0 0.00 5 43037 1226 0 0
srv_aggregate_perf_data 85 85 0 0.00 2606 157978 22434 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1681 125945 47702 0 0
srv_find_qd_qs_mism 1058064 1058064 0 0.00 0 11197 5 0 0
srv_make_invnt_saldo 525 525 0 0.00 52 70146 1076 0 0
srv_make_money_saldo 727 727 0 0.00 1 4826 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 328489 497884 400840 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4662 4662 0 0.00 0 21094 191 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 6769 134 0 0
t$perf-norm:sp_cancel_client_order 1605 1605 0 0.00 0 1837 11 0 0
t$perf-norm:sp_cancel_customer_reserve 616 616 0 0.00 0 1162 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 623 623 0 0.00 0 3961 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 8164 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 332 7 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 40349 1718 0 0
t$perf-norm:sp_cancel_write_off 909 909 0 0.00 0 3291 14 0 0
t$perf-norm:sp_client_order 18786 18786 0 0.00 0 13556 19 0 0
t$perf-norm:sp_customer_reserve 906 906 0 0.00 0 1764 12 0 0
t$perf-norm:sp_pay_from_customer 6492 6492 0 0.00 0 31711 18 0 0
t$perf-norm:sp_pay_to_supplier 5443 5443 0 0.00 0 13845 18 0 0
t$perf-norm:sp_reserve_write_off 9216 9216 0 0.00 0 9955 15 0 0
t$perf-norm:sp_supplier_invoice 5045 5045 0 0.00 0 17713 21 0 0
t$perf-norm:sp_supplier_order 4974 4974 0 0.00 0 9910 18 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 291 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 92 3 0 0
t$perf-norm:srv_make_invnt_saldo 525 525 0 0.00 0 1597 21 0 0
t$perf-norm:srv_make_money_saldo 727 727 0 0.00 0 4360 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9554 9320 234 2.44 0 6252 17 0 234
v_all_customers 34308 34308 0 0.00 0 18 1 0 0
v_all_suppliers 27824 27824 0 0.00 0 30 1 0 0
v_all_wares 37572 37572 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 546 6 0 0
v_cancel_client_order 3210 3210 0 0.00 0 1567 5 0 0
v_cancel_customer_prepayment 1246 1246 0 0.00 0 1094 4 0 0
v_cancel_customer_reserve 1232 1232 0 0.00 0 6098 15 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 6763 20 0 0
v_cancel_supplier_invoice 754 732 22 2.91 0 1873 20 0 22
v_cancel_supplier_order 842 842 0 0.00 0 1168 7 0 0
v_cancel_write_off 1818 1818 0 0.00 0 7460 16 0 0
v_max_id_clo_ord 4971 4971 0 0.00 0 822 1 0 0
v_max_id_clo_res 906 906 0 0.00 0 1630 12 0 0
v_max_id_ord_sup 5037 5037 0 0.00 0 2975 4 0 0
v_max_non_paid_invoice 1539 1539 0 0.00 0 4774 7 0 0
v_max_non_paid_realizn 3446 3446 0 0.00 0 7043 5 0 0
v_min_id_clo_ord 4971 4971 0 0.00 0 12269 12 0 0
v_min_id_clo_res 906 906 0 0.00 0 3722 8 0 0
v_min_id_ord_sup 5037 5037 0 0.00 0 6693 9 0 0
v_min_non_paid_invoice 5443 5443 0 0.00 0 15166 47 0 0
v_min_non_paid_realizn 6492 6492 0 0.00 0 21331 65 0 0
v_our_firm 9356 9356 0 0.00 0 19 1 0 0
v_reserve_write_off 18432 18432 0 0.00 0 6545 7 0 0
x_lock_depdocs_on_canc_invoice 12480 12480 0 0.00 0 9851 115 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 16438 420 0 0
x_make_qstorno_1000_1200 4974 4974 0 0.00 2 43012 1147 0 0
x_make_qstorno_1200_2000 5045 5045 0 0.00 3 77294 3181 0 0
x_make_qstorno_2100_3300 424337 424337 0 0.00 0 63788 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-10-2022 19:40:16 to 19-10-2022 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:33 2 8192000000 8798891888 8810299392 5309616 0.064 2 1 2 0 5409248 56848 42784
17:09:34 25651 8192000000 9900016688 10210439168 982874096 10.712 100 85 171 0 1011417808 6298304 22245408
17:11:08 19222 8192000000 9947275744 10267848704 1029375408 11.162 100 83 166 0 1059169456 8569984 21224064
17:12:33 10136 8192000000 9976751088 10307182592 1059745936 11.454 100 84 169 0 1090573024 9893840 20933248
17:13:52 7892 8192000000 10034739680 10383245312 1121178992 12.038 100 81 162 1 1151488576 11026064 19283520
17:15:08 5830 8192000000 10085015216 10445340672 1174881424 12.542 100 80 160 0 1205116624 11683088 18552112
17:16:15 3282 8192000000 10127038352 10494734336 1214575664 12.911 100 83 167 0 1246352080 13526672 18249744
17:17:20 2479 8192000000 10162331424 10533617664 1244227216 13.185 100 79 158 0 1275337504 13512752 17597536
17:18:24 1438 8192000000 10188033680 10565074944 1265948240 13.385 100 89 179 0 1300712400 13724928 21039232
17:19:26 1397 8192000000 10207179248 10591371264 1299082768 13.687 100 89 179 1 1331769392 13153136 19533488
17:20:29 2966 8192000000 10208133872 10598629376 1299989616 13.695 100 56 112 0 1321393104 8314304 13089184
17:21:33 5616 8192000000 10189739936 10602774528 1307789472 13.766 100 17 34 0 1317859664 6500208 3569984
17:22:41 9833 8192000000 10208367280 10624360448 1319581792 13.873 100 13 27 0 1328312496 5563808 3166896
17:23:53 10697 8192000000 10209869488 10632359936 1328734496 13.956 100 20 39 0 1338615040 5771616 4108928
17:25:07 51450 8192000000 10211775888 10637709312 1328322480 13.952 100 16 32 0 1337226496 5040032 3863984
17:26:59 6112 8192000000 10229566688 10649141248 1337348928 14.034 100 16 33 0 1346123600 4893152 3881520
17:28:09 4674 8192000000 10219464064 10640834560 1316369920 13.844 99 17 34 0 1333836912 13899040 3567952
17:29:17 9448 8192000000 10193422336 10603606016 1260875952 13.338 96 11 22 0 1272811600 9218080 2717568
17:30:30 27887 8192000000 10156600752 10557222912 1188243216 12.667 92 21 42 0 1197864832 4955728 4665888
17:32:00 7008 8192000000 10105944784 10497179648 1179592752 12.586 95 31 62 0 1193288928 5910640 7785536
17:33:09 10296 8192000000 10156112240 10555043840 1262580336 13.354 100 23 46 1 1274068656 5739744 5748576
17:34:24 66641 8192000000 10179870992 10588438528 1288711120 13.592 100 35 69 0 1301870640 5736624 7422896
17:36:31 4862 8192000000 10201949024 10609025024 1305693872 13.747 100 42 78 1 1320935024 5896112 9345040
17:37:40 9819 8192000000 10211151056 10623262720 1314759952 13.829 100 24 47 0 1325243200 5053632 5429616
17:38:53 28388 8192000000 10243576032 10657431552 1341331088 14.069 100 27 54 0 1355616832 7627184 6658560
17:40:37 125944 8192000000 10273777136 10702393344 1361251072 14.249 100 34 68 0 1379154608 9535728 8367808
17:43:48 9016 8192000000 10299583232 10722185216 1383830048 14.451 100 55 111 2 1411992384 16084416 12077920
17:45:11 10692 8192000000 10320009584 10740707328 1396001632 14.559 100 63 124 1 1430463808 20145440 14316736
17:46:28 5836 8192000000 10337107664 10759528448 1406918944 14.657 100 56 112 0 1448218960 28551360 12748656
17:47:37 3921 8192000000 10330123392 10758955008 1408130224 14.667 99 43 86 0 1444123696 25278384 10715088
17:48:46 4149 8192000000 10367194560 10790584320 1432385760 14.882 100 66 133 1 1477902640 29961776 15555104
17:49:58 4148 8192000000 10380608368 10807754752 1441787536 14.965 100 62 119 1 1491330320 34956480 14586304
17:51:08 2061 8192000000 10398819600 10820079616 1449848864 15.037 100 73 146 0 1506606288 39036736 17720688
17:52:14 5988 8192000000 10372412256 10792067072 1406882576 14.656 98 61 122 0 1462765280 40274464 15608240
17:53:25 3860 8192000000 10369950512 10780815360 1407838896 14.665 100 77 149 0 1472257056 45681248 18736912
17:54:34 7881 8192000000 10371675648 10786447360 1388013872 14.488 97 71 141 1 1450397264 44644064 17739328
17:55:55 1681 8192000000 10354086160 10764304384 1387288128 14.482 99 74 143 1 1457284368 51843536 18152704
17:57:00 2938 8192000000 10329399008 10733744128 1345067280 14.103 98 67 128 0 1420926880 59258528 16601072
17:58:08 9989 8192000000 10341322224 10767921152 1375340320 14.375 99 42 84 0 1441565392 54595840 11629232
17:59:23 8792 8192000000 10342174320 10765660160 1385601728 14.467 100 41 80 0 1446489280 50130400 10757152
18:00:40 22497 8192000000 10347749232 10789584896 1391354160 14.518 100 33 66 0 1461810912 60996368 9460384
18:02:19 21045 8192000000 10352640368 10796630016 1401324144 14.607 100 31 62 0 1468562928 58502640 8736144
18:03:58 31168 8192000000 10318577552 10759532544 1359651584 14.234 99 17 34 1 1417870496 52869392 5349520
18:05:31 46183 8192000000 10270028944 10713763840 1353080576 14.175 99 14 28 0 1393291696 36512096 3699024
18:07:25 4574 8192000000 10298559888 10738311168 1383259792 14.446 100 61 117 0 1407344592 10359056 13725744
18:08:38 56923 8192000000 10282414176 10730901504 1376073760 14.381 100 15 28 0 1390330064 11176208 3080096
18:10:35 44937 8192000000 10289845888 10738499584 1365462560 14.286 99 11 22 0 1382570528 14809648 2298320
18:12:22 83423 8192000000 10303299920 10752425984 1377332848 14.393 99 19 38 0 1403604928 21825632 4446448
18:14:47 83132 8192000000 10308876352 10764791808 1388122768 14.489 100 19 38 0 1405800048 13601536 4075744
18:17:31 71332 8192000000 10288012016 10748272640 1372290560 14.348 99 12 24 0 1391791328 17416432 2084336
18:19:43 51784 8192000000 10279877360 10725064704 1349285328 14.141 97 38 77 2 1367208016 8740480 9182208
18:21:35 49736 8192000000 10287766432 10747666432 1388031216 14.488 100 9 18 0 1398302512 8487344 1783952
18:23:34 61903 8192000000 10285074480 10747195392 1390899824 14.514 100 8 16 0 1402813584 10214944 1698816
18:26:25 89370 8192000000 10296724720 10765824000 1402344416 14.616 100 13 26 0 1418493072 13109264 3039392
18:29:49 73782 8192000000 10299089008 10765762560 1392747344 14.530 99 15 30 1 1403288016 7189344 3351328
18:32:04 47751 8192000000 10299260864 10758660096 1376648848 14.387 98 8 16 0 1388918864 10655840 1614176
18:33:58 60797 8192000000 10296841504 10750771200 1373318208 14.357 98 16 32 0 1384847792 8252752 3276832
18:36:00 78517 8192000000 10310864576 10765369344 1389290192 14.500 99 17 34 0 1413253520 19635440 4327888
18:38:22 44199 8192000000 10301062944 10745413632 1353339920 14.178 97 27 54 1 1370278832 11040896 5898016
18:40:10 53507 8192000000 10276880224 10713370624 1313261472 13.816 95 22 44 0 1327811008 10013088 4536448
18:42:12 69827 8192000000 10245451008 10686058496 1239180528 13.139 91 6 12 0 1249652176 9500016 971632
18:44:26 82271 8192000000 10254646368 10692567040 1335585632 14.018 99 34 68 0 1355012848 11210752 8216464
18:46:50 24712 8192000000 10241451760 10688663552 1315309344 13.834 97 20 41 0 1327620656 7628688 4682624
18:48:17 48681 8192000000 10250903520 10702393344 1323343984 13.907 98 12 23 0 1336238368 10337680 2556704
18:50:08 53885 8192000000 10250113552 10698416128 1323666128 13.910 98 10 20 0 1340175024 13786752 2722144
18:52:03 52312 8192000000 10268917824 10728165376 1356814784 14.209 100 18 36 1 1376220960 14997664 4408512
18:53:55 69379 8192000000 10248169584 10717777920 1345393952 14.106 99 13 28 0 1362564656 13348160 3822544
18:56:14 49806 8192000000 10260169504 10710810624 1343387616 14.088 99 23 46 1 1363298592 14856272 5054704
18:58:04 78326 8192000000 10281733104 10733588480 1368938032 14.318 100 25 50 0 1391170176 16331184 5900960
19:00:23 45858 8192000000 10285311104 10734166016 1372487968 14.349 100 22 43 0 1390050896 13219472 4343456
19:02:45 35483 8192000000 10287892832 10735300608 1367009408 14.300 99 44 88 0 1387582032 10852064 9720560
19:04:45 87679 8192000000 10282335056 10746454016 1339941248 14.057 97 17 34 0 1362082272 18423744 3717280
19:07:13 49734 8192000000 10276315408 10711162880 1330261328 13.970 97 54 104 4 1351887904 9762448 11864128
19:09:11 123188 8192000000 10246133456 10696925184 1332405456 13.989 97 17 34 0 1344450032 8552352 3492224
19:12:17 46558 8192000000 10265509360 10726821888 1370348208 14.330 100 15 31 0 1382913520 9625920 2939392
19:14:13 59298 8192000000 10295492160 10749534208 1385833568 14.469 100 16 32 1 1407408016 18188032 3386416
19:16:19 56419 8192000000 10284884528 10736160768 1379238240 14.410 99 18 36 0 1398140336 14743120 4158976
19:18:19 78839 8192000000 10294976528 10752634880 1377686576 14.396 99 21 41 1 1395194016 12243520 5263920
19:20:38 88672 8192000000 10309435200 10768547840 1377501472 14.394 99 9 18 0 1397425824 17888576 2035776
19:23:16 56995 8192000000 10298957872 10740703232 1345668032 14.108 97 15 30 0 1364457472 15514048 3275392
19:25:17 25469 8192000000 10304115008 10752339968 1395229984 14.553 100 23 46 0 1407522288 7711104 4581200
19:26:47 8267 8192000000 10295749760 10731397120 1373755024 14.361 100 43 85 1 1392569520 9612544 9201952
19:27:56 65876 8192000000 10295738112 10761539584 1381372832 14.429 99 15 30 0 1406146448 21129712 3643904
19:30:06 95759 8192000000 10288876928 10749681664 1367920832 14.308 98 26 52 0 1384123600 10471136 5731632
19:33:19 82970 8192000000 10253245280 10703265792 1320487392 13.881 96 16 33 0 1333451920 9582912 3381616
19:35:42 109358 8192000000 10260022512 10721419264 1350503088 14.152 99 13 26 0 1370890880 17295376 3092416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-10-2022 19:40:38 to 19-10-2022 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          230053
MON$OLDEST_ACTIVE               230618
MON$OLDEST_SNAPSHOT             230618
MON$NEXT_TRANSACTION            230618
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                       2027296
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.33635 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33635 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33635 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33635 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 ---
:151: 
:152: mscs-test-161.budzdorov.ru    Wed Oct 19 19:38:39 2022
:153:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:154: 
:155: 
:156: mscs-test-161.budzdorov.ru    Wed Oct 19 19:38:39 2022
:157:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:158: 
:159: 
:160: mscs-test-161.budzdorov.ru    Wed Oct 19 19:38:39 2022
:161:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:162: 
:163: 
:164: mscs-test-161.budzdorov.ru    Wed Oct 19 19:38:39 2022
:165:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:166: 
:167: 
:168: mscs-test-161.budzdorov.ru    Wed Oct 19 19:38:39 2022
:169:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:170: 
:171: 
:172: mscs-test-161.budzdorov.ru    Wed Oct 19 19:38:39 2022
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:174: 
:175: 
:176: mscs-test-161.budzdorov.ru    Wed Oct 19 19:38:39 2022
:177:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:178: 
--- end of diff output ---

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

19.10.22 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
2022-10-19 19:40:43 No severe PSQL-related problems occured
19.10.22 19:40:45. End of report.