Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.12.2022 01:41
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 14942172 kB
MemAvailable 30092776 kB
Buffers 0 kB
Cached 16786804 kB
SwapCached 3648 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-12-18 01:38:36
DTS_END                         2022-12-18 01:38:36.7320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46022
TRN_ID                          245963
ATT_ID                          731
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: 9477.75 18001 1137330
customer order: creation 178.33 333 21399
customer order: refuse 14.88 322 1786
order to supplier: creation 47.09 1209 5651
order to supplier: removal 3.66 83770 439
invoice (draft): creation 46.66 3147 5599
invoice (draft): removal 50.48 722 6057
invoice accept: apply 44.75 19094 5370
invoice accept: cancel 46.37 814 5564
customer reserve: creation 4183.10 187 501972
customer reserve: removal 4565.08 52 547809
realization accept: apply 86.57 191 10388
realization accept: cancel 76.99 106 9239
payment from customer: creation 62.06 151 7447
payment from customer: removal 5.79 161 695
payment to supplier: creation 50.13 177 6016
payment to supplier: removal 3.48 81 417
service: total inventory turnovers 4.68 1141 561
service: total monetary turnovers 6.78 45 813
service: aggregate perf. data 0.87 18133 104
service: refresh index statistics 0.03 230175 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-12-2022 01:41:33 to 18-12-2022 01:41:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2722
TEST_TIME 2 2591
TEST_TIME 3 2598
TEST_TIME 4 2866
TEST_TIME 5 3083
TEST_TIME 6 4671
TEST_TIME 7 4728
TEST_TIME 8 4868
TEST_TIME 9 5159
TEST_TIME 10 5866
TEST_TIME 11 5940
TEST_TIME 12 6177
TEST_TIME 13 6404
TEST_TIME 14 7060
TEST_TIME 15 7496
TEST_TIME 16 7958
TEST_TIME 17 7918
TEST_TIME 18 8367
TEST_TIME 19 8385
TEST_TIME 20 8282
TEST_TIME 21 8343
TEST_TIME 22 8656
TEST_TIME 23 8997
TEST_TIME 24 9244
TEST_TIME 25 9353
TEST_TIME 26 9485
TEST_TIME 27 9520
TEST_TIME 28 9647
TEST_TIME 29 9831
TEST_TIME 30 10331
TEST_TIME 31 10195
TEST_TIME 32 10389
TEST_TIME 33 10380
TEST_TIME 34 10181
TEST_TIME 35 10595
TEST_TIME 36 10510
TEST_TIME 37 10417
TEST_TIME 38 10281
TEST_TIME 39 10167
TEST_TIME 40 10464
TEST_TIME 41 10242
TEST_TIME 42 10080
TEST_TIME 43 10051
TEST_TIME 44 9917
TEST_TIME 45 9894
TEST_TIME 46 9820
TEST_TIME 47 9725
TEST_TIME 48 9723
TEST_TIME 49 9858
TEST_TIME 50 9837
TEST_TIME 51 9843
TEST_TIME 52 9769
TEST_TIME 53 9693
TEST_TIME 54 9615
TEST_TIME 55 9591
TEST_TIME 56 9643
TEST_TIME 57 9688
TEST_TIME 58 9678
TEST_TIME 59 9781
TEST_TIME 60 9721
TEST_TIME 61 9791
TEST_TIME 62 9790
TEST_TIME 63 9788
TEST_TIME 64 9817
TEST_TIME 65 9812
TEST_TIME 66 9880
TEST_TIME 67 9876
TEST_TIME 68 9925
TEST_TIME 69 9914
TEST_TIME 70 9794
TEST_TIME 71 9727
TEST_TIME 72 9702
TEST_TIME 73 9672
TEST_TIME 74 9653
TEST_TIME 75 9653
TEST_TIME 76 9585
TEST_TIME 77 9589
TEST_TIME 78 9533
TEST_TIME 79 9526
TEST_TIME 80 9528
TEST_TIME 81 9508
TEST_TIME 82 9540
TEST_TIME 83 9540
TEST_TIME 84 9589
TEST_TIME 85 9575
TEST_TIME 86 9563
TEST_TIME 87 9637
TEST_TIME 88 9621
TEST_TIME 89 9635
TEST_TIME 90 9599
TEST_TIME 91 9707
TEST_TIME 92 9732
TEST_TIME 93 9748
TEST_TIME 94 9687
TEST_TIME 95 9617
TEST_TIME 96 9574
TEST_TIME 97 9553
TEST_TIME 98 9575
TEST_TIME 99 9537
TEST_TIME 100 9512
TEST_TIME 101 9459
TEST_TIME 102 9439
TEST_TIME 103 9399
TEST_TIME 104 9422
TEST_TIME 105 9395
TEST_TIME 106 9386
TEST_TIME 107 9487
TEST_TIME 108 9499
TEST_TIME 109 9457
TEST_TIME 110 9525
TEST_TIME 111 9501
TEST_TIME 112 9503
TEST_TIME 113 9569
TEST_TIME 114 9621
TEST_TIME 115 9590
TEST_TIME 116 9660
TEST_TIME 117 9582
TEST_TIME 118 9512
TEST_TIME 119 9504
TEST_TIME 120 9478

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-12-2022 01:41:37 to 18-12-2022 01:41:40.

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 1600552 1600552 0 0.00 0 35401 7 0 0
doc_list_biud 1600551 1600551 0 0.00 0 32197 23 0 0
sp_add_invoice_to_stock 5512 5370 142 2.57 24 179349 19094 0 142
sp_cancel_adding_invoice 5564 5564 0 0.00 2 60488 814 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 11684 322 0 0
sp_cancel_customer_reserve 547809 547809 0 0.00 0 22993 53 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 34623 161 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 2 4545 82 0 0
sp_cancel_supplier_invoice 6070 6057 13 0.21 2 13491 723 0 13
sp_cancel_supplier_order 439 439 0 0.00 6 952398 83771 0 0
sp_cancel_write_off 9239 9239 0 0.00 1 9804 106 0 0
sp_client_order 21399 21399 0 0.00 3 25126 334 0 0
sp_customer_reserve 501972 501972 0 0.00 0 36606 188 0 0
sp_fill_shopping_cart 32643 32643 0 0.00 1 18761 38 0 0
sp_fill_shopping_cart_clo_res 501971 501971 0 0.00 0 19181 13 0 0
sp_get_clo_for_invoice 5369 5369 0 0.00 0 33771 317 0 0
sp_kill_cost_storno 15915 15915 0 0.00 0 33352 56 0 0
sp_kill_qstorno_ret_qs2qd 1111353 1111353 0 0.00 0 22988 29 0 0
sp_kill_qty_storno 586651 586651 0 0.00 0 32195 63 0 0
sp_lock_selected_doc 21687 21687 0 0.00 0 7496 5 0 0
sp_make_cost_storno 29220 29220 0 0.00 0 35374 66 0 0
sp_multiply_rows_for_pdistr 29219 29219 0 0.00 0 33357 35 0 0
sp_multiply_rows_for_qdistr 21399 21399 0 0.00 0 21735 156 0 0
sp_pay_from_customer 7447 7447 0 0.00 4 35414 152 0 0
sp_pay_to_supplier 6016 6016 0 0.00 5 34459 178 0 0
sp_payment_common 13462 13462 0 0.00 1 32905 63 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 11675 299 0 0
sp_qd_handle_on_invoice_upd_sts 10933 10933 0 0.00 0 32192 334 0 0
sp_qd_handle_on_reserve_upd_sts 10388 10388 0 0.00 0 12008 50 0 0
sp_reserve_write_off 10388 10388 0 0.00 6 34289 192 0 0
sp_supplier_invoice 5599 5599 0 0.00 6 74839 3147 0 0
sp_supplier_order 5651 5651 0 0.00 5 48969 1209 0 0
srv_aggregate_perf_data 105 104 1 0.95 2226 104609 18134 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1426 118703 43137 0 0
srv_find_qd_qs_mism 1086317 1086317 0 0.00 0 23116 6 0 0
srv_make_invnt_saldo 561 561 0 0.00 22 71858 1141 0 0
srv_make_money_saldo 813 813 0 0.00 1 13140 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 140064 404898 230175 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5370 5370 0 0.00 0 17264 190 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 4332 111 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 2118 13 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 4734 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1951 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 623 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 5375 36 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 28150 1347 0 0
t$perf-norm:sp_cancel_write_off 1073 1073 0 0.00 0 6484 19 0 0
t$perf-norm:sp_client_order 21399 21399 0 0.00 0 7327 16 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 1327 6 0 0
t$perf-norm:sp_pay_from_customer 7447 7447 0 0.00 0 7648 11 0 0
t$perf-norm:sp_pay_to_supplier 6016 6016 0 0.00 0 10472 12 0 0
t$perf-norm:sp_reserve_write_off 10388 10388 0 0.00 0 10631 13 0 0
t$perf-norm:sp_supplier_invoice 5599 5599 0 0.00 0 12929 24 0 0
t$perf-norm:sp_supplier_order 5651 5651 0 0.00 0 3715 15 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 360 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 2453 72 0 0
t$perf-norm:srv_make_invnt_saldo 561 561 0 0.00 0 1284 28 0 0
t$perf-norm:srv_make_money_saldo 813 813 0 0.00 0 927 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 5 0 0
v_add_invoice_to_stock 11021 10737 284 2.57 0 8660 17 0 284
v_all_customers 38790 38790 0 0.00 0 27 1 0 0
v_all_suppliers 30930 30930 0 0.00 0 52 1 0 0
v_all_wares 42798 42798 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 204 5 0 0
v_cancel_client_order 3572 3572 0 0.00 0 2638 5 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 5543 11 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 2644 6 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 4503 15 0 0
v_cancel_supplier_invoice 850 824 26 3.05 0 1740 15 0 26
v_cancel_supplier_order 878 878 0 0.00 0 2727 12 0 0
v_cancel_write_off 2146 2146 0 0.00 0 3546 15 0 0
v_max_id_clo_ord 5650 5650 0 0.00 0 765 2 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 2817 23 0 0
v_max_id_ord_sup 5594 5594 0 0.00 0 3799 5 0 0
v_max_non_paid_invoice 1795 1795 0 0.00 0 3758 8 0 0
v_max_non_paid_realizn 4079 4079 0 0.00 0 4461 6 0 0
v_min_id_clo_ord 5650 5650 0 0.00 0 3324 8 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 6465 23 0 0
v_min_id_ord_sup 5594 5594 0 0.00 0 5951 6 0 0
v_min_non_paid_invoice 6016 6016 0 0.00 0 32752 59 0 0
v_min_non_paid_realizn 7446 7446 0 0.00 0 31144 50 0 0
v_our_firm 10742 10742 0 0.00 0 17 1 0 0
v_reserve_write_off 20776 20776 0 0.00 0 4512 6 0 0
x_lock_depdocs_on_canc_invoice 11209 11209 0 0.00 0 25331 119 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 7289 320 0 0
x_make_qstorno_1000_1200 5651 5651 0 0.00 1 48962 1125 0 0
x_make_qstorno_1200_2000 5599 5599 0 0.00 2 70946 3014 0 0
x_make_qstorno_2100_3300 501972 501972 0 0.00 0 36602 172 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 18-12-2022 01:41:41 to 18-12-2022 01:41:46.

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
23:08:40 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:41 20094 8192000000 9916572368 10228785152 995318720 10.833 100 89 178 0 1028156720 10209696 22628304
23:11:05 19013 8192000000 9943367824 10266333184 1027446720 11.144 100 89 178 0 1058707008 8400352 22859936
23:12:29 18090 8192000000 9976502112 10309025792 1061068464 11.467 100 83 166 0 1091534064 9701968 20763632
23:13:50 9384 8192000000 10029227664 10375372800 1115678736 11.986 100 86 172 0 1147413808 11238320 20496752
23:15:10 8093 8192000000 10071413824 10434093056 1166431184 12.463 100 76 152 0 1196539280 12353712 17754384
23:16:22 3877 8192000000 10129125168 10502955008 1223468064 12.994 100 74 147 0 1251047136 10913328 16665744
23:17:28 2319 8192000000 10162174096 10537979904 1247452864 13.215 100 78 153 0 1276897040 12980368 16463808
23:18:32 1554 8192000000 10199456384 10584956928 1286862864 13.576 100 83 162 0 1317659504 13321104 17475536
23:19:35 1085 8192000000 10212854560 10600206336 1300792672 13.702 100 81 161 2 1331500000 13077296 17630032
23:20:38 8477 8192000000 10203800032 10609799168 1310048912 13.787 100 49 97 1 1328710336 7931072 10730352
23:21:53 12580 8192000000 10212666896 10620768256 1319394096 13.871 100 28 56 0 1332072816 6619744 6058976
23:23:06 7176 8192000000 10211304992 10632753152 1332478976 13.990 100 30 60 0 1347171472 7819296 6873200
23:24:16 8199 8192000000 10226192032 10641952768 1334025936 14.004 100 34 68 0 1353147264 12021584 7099744
23:25:24 31411 8192000000 10226981248 10647662592 1335800944 14.020 100 28 54 0 1347468112 5207376 6459792
23:26:56 6941 8192000000 10224084688 10650783744 1339260992 14.051 100 20 40 0 1349271632 4841808 5168832
23:28:03 8448 8192000000 10221641312 10639761408 1313531424 13.818 98 19 38 0 1323358352 5622992 4203936
23:29:14 18108 8192000000 10182370864 10590195712 1235423248 13.104 93 36 69 2 1248033024 5002592 7607184
23:30:32 16194 8192000000 10175845536 10585956352 1209095616 12.861 93 22 44 0 1220105184 6340368 4669200
23:31:49 7343 8192000000 10123328656 10522939392 1220249680 12.964 98 36 70 1 1234864208 6595328 8019200
23:32:59 5431 8192000000 10154078464 10549878784 1254342112 13.278 100 26 51 0 1265381376 5179552 5859712
23:34:07 15864 8192000000 10161880672 10566287360 1270640544 13.427 100 37 74 0 1284338912 5914576 7783792
23:35:24 11245 8192000000 10167008784 10580361216 1281949792 13.531 100 14 28 1 1290411344 5377920 3083632
23:36:36 9567 8192000000 10191726272 10604634112 1300994448 13.704 100 22 44 0 1311825200 5552912 5277840
23:37:46 9367 8192000000 10197526832 10611539968 1305777040 13.748 100 13 27 0 1314103776 5198176 3128560
23:39:01 11265 8192000000 10234497056 10646519808 1332360752 13.988 100 34 68 1 1344270608 4354400 7555456
23:40:21 107181 8192000000 10262614064 10680987648 1349109696 14.139 100 33 66 0 1368146256 11823696 7212864
23:43:15 9243 8192000000 10275634416 10693558272 1363296848 14.267 100 61 118 1 1392231440 15748912 13185680
23:44:35 19393 8192000000 10281012496 10698203136 1355316176 14.195 99 42 85 0 1382280896 16599280 10365440
23:46:00 10883 8192000000 10295254240 10717544448 1380220784 14.419 100 49 96 0 1406606000 15999232 10385984
23:47:14 2277 8192000000 10324472096 10734440448 1391379008 14.518 100 73 142 0 1426608656 19434080 15795568
23:48:20 1423 8192000000 10333181616 10751311872 1402482736 14.617 100 79 159 0 1444425344 23639792 18302816
23:49:25 3262 8192000000 10337063328 10758148096 1410980272 14.693 100 62 119 1 1448332944 22615680 14736992
23:50:34 2633 8192000000 10321014672 10735456256 1374773392 14.370 98 71 141 1 1418492864 26107600 17611872
23:51:41 8039 8192000000 10301099824 10707353600 1356037680 14.202 99 73 144 1 1407546384 34122704 17386000
23:52:54 2870 8192000000 10317616016 10727645184 1372338016 14.348 100 78 155 1 1419833040 28571216 18923808
23:53:59 2778 8192000000 10312332048 10720342016 1362448832 14.259 100 79 155 1 1411102848 30046448 18607568
23:55:07 6798 8192000000 10307482896 10722197504 1369923344 14.326 100 67 132 0 1410153104 24542592 15687168
23:56:31 3363 8192000000 10305685136 10717986816 1368714016 14.316 100 77 151 0 1411357104 24554192 18088896
23:57:40 2656 8192000000 10310922048 10719363072 1367904544 14.308 100 76 148 1 1408016480 23325808 16786128
23:58:47 2882 8192000000 10292823904 10701606912 1351665648 14.162 100 74 145 3 1397776800 28271904 17839248
00:00:02 3723 8192000000 10315696544 10728718336 1366810064 14.298 100 66 131 1 1420754800 37492368 16452368
00:01:10 2206 8192000000 10324810320 10738421760 1379188528 14.409 100 58 115 1 1427623168 33607536 14827104
00:02:29 26675 8192000000 10314318000 10753642496 1381528448 14.430 100 27 54 0 1424424640 35229184 7667008
00:04:04 50207 8192000000 10312636976 10751672320 1374622784 14.368 100 40 80 2 1420685520 34204320 11858416
00:06:07 20670 8192000000 10296131792 10736447488 1360215632 14.239 99 21 40 0 1396847936 31381632 5250672
00:07:52 30315 8192000000 10299991776 10746003456 1349695664 14.145 99 11 22 0 1390277024 37652512 2928848
00:09:24 52802 8192000000 10284710448 10725044224 1324144704 13.914 97 14 29 0 1348934160 21873184 2916272
00:11:17 70920 8192000000 10296678464 10722717696 1361434096 14.250 100 34 70 0 1386390224 16762496 8193632
00:13:31 74323 8192000000 10265145328 10710757376 1333311296 13.997 98 6 12 0 1353716784 19170816 1234672
00:16:01 26425 8192000000 10290892032 10719821824 1369011120 14.318 100 47 92 0 1387600672 7950080 10639472
00:17:32 67355 8192000000 10292235824 10751602688 1358156480 14.221 99 38 77 1 1384746832 17324704 9265648
00:19:40 88806 8192000000 10296824912 10742349824 1381441232 14.429 100 24 48 0 1396903552 9536032 5926288
00:22:22 21470 8192000000 10299620320 10751717376 1391739584 14.521 100 23 46 0 1405082192 8201760 5140848
00:24:06 14018 8192000000 10321836256 10768068608 1395122864 14.552 100 36 70 1 1415112288 11044512 8944912
00:25:25 49635 8192000000 10327473744 10781446144 1406278048 14.651 100 35 68 0 1433986512 19527888 8180576
00:27:26 40064 8192000000 10311590816 10793930752 1402644176 14.619 100 13 26 0 1429805504 23813280 3348048
00:29:09 50676 8192000000 10307399120 10781335552 1399129296 14.587 99 9 18 0 1414550336 13424288 1996752
00:31:07 41362 8192000000 10310937920 10773626880 1405374912 14.643 100 22 45 0 1418667472 8573152 4719408
00:33:12 100018 8192000000 10317941456 10787532800 1373963856 14.363 97 11 22 0 1390228800 14028896 2236048
00:35:55 93413 8192000000 10310882320 10783055872 1360264816 14.240 96 12 24 0 1377668784 14675616 2728352
00:38:34 25003 8192000000 10299105136 10747953152 1381644944 14.431 99 44 84 0 1399472736 8209456 9618336
00:40:15 71420 8192000000 10279117296 10738507776 1317028784 13.850 95 12 24 0 1326919264 8009808 1880672
00:42:27 48848 8192000000 10287168240 10732728320 1325930416 13.930 96 21 42 1 1344056720 13597952 4528352
00:44:16 64783 8192000000 10256992672 10707714048 1280558400 13.518 94 23 46 0 1300412384 15222384 4631600
00:46:21 70970 8192000000 10280195264 10730790912 1342565776 14.081 99 20 41 0 1363806192 16332032 4908384
00:48:32 75295 8192000000 10254874544 10705993728 1307111728 13.760 96 20 40 0 1322076096 10370640 4593728
00:51:26 26407 8192000000 10219121520 10667171840 1305672832 13.747 97 28 56 0 1324157632 11930784 6554016
00:52:55 54385 8192000000 10252058368 10714402816 1333241824 13.996 99 13 26 0 1353073232 16488400 3343008
00:54:57 18729 8192000000 10252562992 10696105984 1344450016 14.098 100 25 45 2 1365579424 15823936 5305472
00:56:17 53162 8192000000 10252312560 10706010112 1341930192 14.075 99 11 22 0 1353164192 9049168 2184832
00:58:13 66234 8192000000 10256700640 10713817088 1331571520 13.981 98 14 29 0 1348036784 13347088 3118176
01:00:20 43145 8192000000 10249282400 10704384000 1327353760 13.943 98 12 24 0 1340603008 10260672 2988576
01:02:03 52413 8192000000 10254277200 10711093248 1320624240 13.882 97 11 20 0 1330549008 7867856 2056912
01:04:02 43282 8192000000 10295245920 10738966528 1356720528 14.208 99 26 52 1 1375105488 12329440 6055520
01:05:46 94422 8192000000 10284958400 10749665280 1361034752 14.247 99 19 38 0 1375300944 10336272 3929920
01:08:20 78914 8192000000 10299400992 10770870272 1359032832 14.229 98 13 26 0 1384305248 22725136 2547280
01:10:39 32429 8192000000 10307820544 10760867840 1388442800 14.492 99 32 62 0 1404828416 8929584 7456032
01:12:12 109413 8192000000 10303161952 10757341184 1387825792 14.486 99 15 30 0 1399214320 8182624 3205904
01:15:08 5323 8192000000 10317570368 10774552576 1407811984 14.664 100 36 72 0 1428713008 13020496 7880528
01:16:15 62045 8192000000 10290887488 10767327232 1385356928 14.464 99 4 8 0 1397640992 11410720 873344
01:18:18 74017 8192000000 10279626896 10751733760 1369526816 14.323 98 10 19 0 1377761376 6439424 1795136
01:20:54 64414 8192000000 10296930080 10760609792 1380171168 14.418 99 14 28 0 1397873632 14505056 3197408
01:22:59 41499 8192000000 10301363424 10759647232 1378412848 14.402 99 21 40 0 1397344448 14387552 4544048
01:24:42 55674 8192000000 10302132160 10765500416 1367164384 14.302 98 21 42 0 1388295008 16200208 4930416
01:26:43 41075 8192000000 10286981680 10731384832 1322028048 13.895 96 20 40 0 1334059360 7574768 4456544
01:28:28 32428 8192000000 10262512944 10716475392 1349009840 14.139 100 29 58 0 1370600160 15055008 6535312
01:30:05 21149 8192000000 10264465040 10700972032 1323603456 13.909 98 37 74 0 1342183536 10538544 8041536
01:31:36 114914 8192000000 10264942528 10717700096 1300031360 13.696 96 13 26 0 1321835616 19205568 2598688
01:34:32 118703 8192000000 10250235904 10702024704 1343094240 14.085 99 20 40 0 1361566448 13898992 4573216
01:37:39 64451 8192000000 9958585216 10377818112 1066578256 11.519 79 14 28 0 1080310464 10933008 2799200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-12-2022 01:41:48 to 18-12-2022 01:42:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 142 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-12-2022 01:42:04 to 18-12-2022 01:42:06.

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          245515
MON$OLDEST_ACTIVE               246177
MON$OLDEST_SNAPSHOT             246177
MON$NEXT_TRANSACTION            246177
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                       2039456
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:791: 
:792: mscs-test-161.budzdorov.ru    Sun Dec 18 01:39:09 2022
:793:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:794: 
:795: 
:796: mscs-test-161.budzdorov.ru    Sun Dec 18 01:39:21 2022
:797:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:798: 
:799: 
:800: mscs-test-161.budzdorov.ru    Sun Dec 18 01:39:25 2022
:801:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:802: 
--- end of diff output ---

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

18.12.22 01:42:07. 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-12-18 01:42:10 No severe PSQL-related problems occured
18.12.22 01:42:11. End of report.