Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.05.2022 16: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 16308188 kB
MemAvailable 31547368 kB
Buffers 0 kB
Cached 15428048 kB
SwapCached 3284 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-04 16:38:19
DTS_END                         2022-05-04 16:38:19.4750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47528
TRN_ID                          250583
ATT_ID                          697
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: 9621.70 18860 1154604
customer order: creation 183.13 349 21975
customer order: refuse 15.45 340 1854
order to supplier: creation 48.03 1383 5763
order to supplier: removal 3.64 104938 437
invoice (draft): creation 47.48 3696 5698
invoice (draft): removal 55.08 890 6610
invoice accept: apply 44.35 18563 5322
invoice accept: cancel 49.47 890 5936
customer reserve: creation 4028.99 186 483479
customer reserve: removal 4829.34 61 579521
realization accept: apply 89.21 201 10705
realization accept: cancel 90.30 106 10836
payment from customer: creation 63.58 123 7629
payment from customer: removal 5.87 89 704
payment to supplier: creation 51.08 140 6130
payment to supplier: removal 3.31 108 397
service: total inventory turnovers 5.07 1194 608
service: total monetary turnovers 7.42 30 890
service: aggregate perf. data 0.88 19467 106
service: refresh index statistics 0.03 224449 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-05-2022 16:40:14 to 04-05-2022 16:40:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7268
TEST_TIME 2 4652
TEST_TIME 3 5625
TEST_TIME 4 5517
TEST_TIME 5 5739
TEST_TIME 6 6521
TEST_TIME 7 6692
TEST_TIME 8 6483
TEST_TIME 9 7309
TEST_TIME 10 7093
TEST_TIME 11 6815
TEST_TIME 12 6702
TEST_TIME 13 6718
TEST_TIME 14 6902
TEST_TIME 15 7188
TEST_TIME 16 7515
TEST_TIME 17 7708
TEST_TIME 18 8009
TEST_TIME 19 8111
TEST_TIME 20 8174
TEST_TIME 21 8430
TEST_TIME 22 8481
TEST_TIME 23 8569
TEST_TIME 24 8786
TEST_TIME 25 8658
TEST_TIME 26 8804
TEST_TIME 27 8851
TEST_TIME 28 9215
TEST_TIME 29 9480
TEST_TIME 30 10132
TEST_TIME 31 10293
TEST_TIME 32 11141
TEST_TIME 33 10939
TEST_TIME 34 10862
TEST_TIME 35 10973
TEST_TIME 36 10819
TEST_TIME 37 10727
TEST_TIME 38 10626
TEST_TIME 39 10541
TEST_TIME 40 10424
TEST_TIME 41 10220
TEST_TIME 42 10234
TEST_TIME 43 10175
TEST_TIME 44 10138
TEST_TIME 45 10024
TEST_TIME 46 9974
TEST_TIME 47 9950
TEST_TIME 48 9902
TEST_TIME 49 9847
TEST_TIME 50 9944
TEST_TIME 51 10019
TEST_TIME 52 9970
TEST_TIME 53 9875
TEST_TIME 54 9732
TEST_TIME 55 9791
TEST_TIME 56 9759
TEST_TIME 57 9751
TEST_TIME 58 9735
TEST_TIME 59 9743
TEST_TIME 60 9723
TEST_TIME 61 9769
TEST_TIME 62 9702
TEST_TIME 63 9883
TEST_TIME 64 9935
TEST_TIME 65 9978
TEST_TIME 66 9908
TEST_TIME 67 10031
TEST_TIME 68 10039
TEST_TIME 69 10050
TEST_TIME 70 9969
TEST_TIME 71 9921
TEST_TIME 72 9912
TEST_TIME 73 9913
TEST_TIME 74 9847
TEST_TIME 75 9821
TEST_TIME 76 9854
TEST_TIME 77 9807
TEST_TIME 78 9772
TEST_TIME 79 9691
TEST_TIME 80 9642
TEST_TIME 81 9593
TEST_TIME 82 9702
TEST_TIME 83 9692
TEST_TIME 84 9660
TEST_TIME 85 9687
TEST_TIME 86 9738
TEST_TIME 87 9718
TEST_TIME 88 9759
TEST_TIME 89 9753
TEST_TIME 90 9811
TEST_TIME 91 9810
TEST_TIME 92 9843
TEST_TIME 93 9805
TEST_TIME 94 9781
TEST_TIME 95 9843
TEST_TIME 96 9792
TEST_TIME 97 9745
TEST_TIME 98 9703
TEST_TIME 99 9707
TEST_TIME 100 9682
TEST_TIME 101 9637
TEST_TIME 102 9601
TEST_TIME 103 9612
TEST_TIME 104 9554
TEST_TIME 105 9589
TEST_TIME 106 9562
TEST_TIME 107 9606
TEST_TIME 108 9559
TEST_TIME 109 9578
TEST_TIME 110 9585
TEST_TIME 111 9627
TEST_TIME 112 9624
TEST_TIME 113 9635
TEST_TIME 114 9588
TEST_TIME 115 9649
TEST_TIME 116 9737
TEST_TIME 117 9768
TEST_TIME 118 9762
TEST_TIME 119 9687
TEST_TIME 120 9624

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-05-2022 16:40:18 to 04-05-2022 16:40:21.

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 1602817 1602817 0 0.00 0 19596 7 0 0
doc_list_biud 1602817 1602817 0 0.00 0 29165 29 0 0
sp_add_invoice_to_stock 5409 5322 87 1.60 42 166564 18563 0 87
sp_cancel_adding_invoice 5936 5936 0 0.00 4 60035 890 0 0
sp_cancel_client_order 1854 1854 0 0.00 4 11353 340 0 0
sp_cancel_customer_reserve 579521 579521 0 0.00 1 22700 61 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 5903 90 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 4677 109 0 0
sp_cancel_supplier_invoice 6619 6610 9 0.13 2 23762 890 0 9
sp_cancel_supplier_order 437 437 0 0.00 5 1060439 104938 0 0
sp_cancel_write_off 10836 10836 0 0.00 1 13264 107 0 0
sp_client_order 21975 21975 0 0.00 3 18255 349 0 0
sp_customer_reserve 483479 483479 0 0.00 0 117609 186 0 0
sp_fill_shopping_cart 33428 33428 0 0.00 1 12698 40 0 0
sp_fill_shopping_cart_clo_res 483474 483474 0 0.00 0 31207 12 0 0
sp_get_clo_for_invoice 5317 5317 0 0.00 0 37883 271 0 0
sp_kill_cost_storno 17873 17873 0 0.00 0 6926 58 0 0
sp_kill_qstorno_ret_qs2qd 1176925 1176925 0 0.00 0 29164 33 0 0
sp_kill_qty_storno 621214 621214 0 0.00 0 29165 73 0 0
sp_lock_selected_doc 22059 22059 0 0.00 0 6601 6 0 0
sp_make_cost_storno 29779 29779 0 0.00 0 19585 61 0 0
sp_multiply_rows_for_pdistr 29779 29779 0 0.00 0 8870 31 0 0
sp_multiply_rows_for_qdistr 21975 21975 0 0.00 0 17414 152 0 0
sp_pay_from_customer 7629 7629 0 0.00 4 10582 124 0 0
sp_pay_to_supplier 6130 6130 0 0.00 5 15367 140 0 0
sp_payment_common 13759 13759 0 0.00 1 10576 35 0 0
sp_qd_handle_on_cancel_clo 1854 1854 0 0.00 0 11341 313 0 0
sp_qd_handle_on_invoice_upd_sts 11251 11251 0 0.00 0 20234 363 0 0
sp_qd_handle_on_reserve_upd_sts 10705 10705 0 0.00 0 16259 66 0 0
sp_reserve_write_off 10705 10705 0 0.00 6 20685 202 0 0
sp_supplier_invoice 5698 5698 0 0.00 7 111555 3697 0 0
sp_supplier_order 5763 5763 0 0.00 6 46158 1384 0 0
srv_aggregate_perf_data 107 106 1 0.93 2665 342969 19468 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1134 133428 43691 0 0
srv_find_qd_qs_mism 1104592 1104592 0 0.00 0 17482 6 0 0
srv_make_invnt_saldo 608 608 0 0.00 42 82192 1194 0 0
srv_make_money_saldo 890 890 0 0.00 1 1486 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 111361 383343 224449 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 5322 5322 0 0.00 0 17336 200 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 1 3721 99 0 0
t$perf-norm:sp_cancel_client_order 1854 1854 0 0.00 0 1286 13 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 4512 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 3798 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 5030 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 469 469 0 0.00 0 4446 31 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 30710 1765 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 523 5 0 0
t$perf-norm:sp_client_order 21975 21975 0 0.00 0 9277 23 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 2277 9 0 0
t$perf-norm:sp_pay_from_customer 7629 7629 0 0.00 0 10296 16 0 0
t$perf-norm:sp_pay_to_supplier 6130 6130 0 0.00 0 10301 15 0 0
t$perf-norm:sp_reserve_write_off 10705 10705 0 0.00 0 19426 14 0 0
t$perf-norm:sp_supplier_invoice 5698 5698 0 0.00 0 20796 27 0 0
t$perf-norm:sp_supplier_order 5763 5763 0 0.00 0 8842 20 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 900 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 139 5 0 0
t$perf-norm:srv_make_invnt_saldo 608 608 0 0.00 0 1031 22 0 0
t$perf-norm:srv_make_money_saldo 890 890 0 0.00 0 5692 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10804 10630 174 1.61 0 3844 18 0 174
v_all_customers 39964 39964 0 0.00 0 40 1 0 0
v_all_suppliers 31508 31508 0 0.00 0 20 1 0 0
v_all_wares 43950 43950 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 3295 15 0 0
v_cancel_client_order 3708 3708 0 0.00 0 3851 7 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 4607 12 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 3633 10 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1214 5 0 0
v_cancel_supplier_invoice 956 938 18 1.88 0 2958 21 0 18
v_cancel_supplier_order 874 874 0 0.00 0 2782 13 0 0
v_cancel_write_off 2058 2058 0 0.00 0 3875 20 0 0
v_max_id_clo_ord 5763 5763 0 0.00 0 1086 1 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 5062 23 0 0
v_max_id_ord_sup 5690 5690 0 0.00 0 7699 4 0 0
v_max_non_paid_invoice 1829 1829 0 0.00 0 4564 8 0 0
v_max_non_paid_realizn 4189 4189 0 0.00 0 2838 4 0 0
v_min_id_clo_ord 5763 5763 0 0.00 0 9203 9 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 6757 10 0 0
v_min_id_ord_sup 5690 5690 0 0.00 0 8523 9 0 0
v_min_non_paid_invoice 6130 6130 0 0.00 0 6098 22 0 0
v_min_non_paid_realizn 7629 7629 0 0.00 0 10570 31 0 0
v_our_firm 10866 10866 0 0.00 0 16 1 0 0
v_reserve_write_off 21410 21410 0 0.00 0 3840 7 0 0
x_lock_depdocs_on_canc_invoice 12077 12077 0 0.00 0 13911 116 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 14363 445 0 0
x_make_qstorno_1000_1200 5763 5763 0 0.00 1 46151 1285 0 0
x_make_qstorno_1200_2000 5698 5698 0 0.00 3 105908 3561 0 0
x_make_qstorno_2100_3300 483479 483479 0 0.00 0 117609 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-05-2022 16:40:22 to 04-05-2022 16:40:26.

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
14:08:22 3 8192000000 8801782368 8814100480 7939312 0.096 2 2 3 0 8080416 66144 74960
14:09:23 34971 8192000000 9904661024 10214379520 984903520 10.732 100 89 178 0 1014648848 6191392 23553936
14:11:05 16621 8192000000 9938450464 10259378176 1023775360 11.108 100 86 172 0 1054892000 8940352 22176288
14:12:24 19285 8192000000 9973217120 10303016960 1054711120 11.406 100 86 172 0 1086264624 10346816 21206688
14:13:48 11190 8192000000 10029199568 10374914048 1113139568 11.962 100 78 156 0 1142563808 10897616 18526624
14:15:05 8726 8192000000 10084154416 10445524992 1169156768 12.489 100 71 140 1 1196565680 11090544 16318368
14:16:15 5091 8192000000 10121215616 10492768256 1214768176 12.913 100 82 160 2 1246461600 14080592 17612832
14:17:22 2025 8192000000 10154716928 10530783232 1248015408 13.220 100 77 154 0 1279094992 14127504 16952080
14:18:26 2078 8192000000 10194717392 10574139392 1272972336 13.449 100 87 175 0 1307259904 14934384 19353184
14:19:30 2217 8192000000 10217465568 10599309312 1296346832 13.662 100 94 189 0 1331112240 12741264 22024144
14:20:33 11574 8192000000 10191534016 10598125568 1299370336 13.690 100 49 97 4 1318722720 8726288 10626096
14:21:49 4060 8192000000 10200181904 10610745344 1312256864 13.807 100 36 72 1 1328105472 7457680 8390928
14:22:54 10219 8192000000 10210535040 10624552960 1324225376 13.915 100 27 54 0 1335771776 5214688 6331712
14:24:06 7291 8192000000 10224478000 10638471168 1332902944 13.993 100 17 34 1 1342552880 5202496 4447440
14:25:16 38079 8192000000 10238146208 10662391808 1339536128 14.053 100 28 56 1 1355177392 9147168 6494096
14:26:55 8153 8192000000 10229569488 10655834112 1340811536 14.065 100 22 44 0 1351076928 5373504 4891888
14:28:05 8287 8192000000 10211297280 10633068544 1309549568 13.782 98 32 64 0 1322907504 5786112 7571824
14:29:14 15003 8192000000 10137074288 10534936576 1157529824 12.380 89 17 34 0 1166568528 4890912 4147792
14:30:32 6968 8192000000 10116621312 10520412160 1191925712 12.701 95 22 44 1 1202901104 6029520 4945872
14:31:42 5892 8192000000 10149031888 10553815040 1254426464 13.279 100 26 51 2 1267048528 6671360 5950704
14:32:54 7877 8192000000 10163321872 10563092480 1242193728 13.166 98 23 45 1 1253435520 6178416 5063376
14:34:03 15398 8192000000 10186577216 10585976832 1287309440 13.580 100 38 77 1 1302559792 4989104 10261248
14:35:19 12211 8192000000 10178669168 10590633984 1286164944 13.569 100 21 42 1 1296475920 5710576 4600400
14:36:31 4486 8192000000 10190497776 10604720128 1298552384 13.682 100 34 68 1 1312641760 5908528 8180848
14:37:38 9068 8192000000 10196105984 10616516608 1307182800 13.761 100 15 29 1 1316099600 5175152 3741648
14:38:52 17195 8192000000 10237211936 10655993856 1340795168 14.065 100 45 91 1 1359746704 8361168 10590368
14:40:16 47215 8192000000 10274737504 10691801088 1360529840 14.242 100 62 122 2 1390959728 16445056 13984832
14:42:07 5553 8192000000 10292689728 10707238912 1371323568 14.339 100 75 148 0 1403322800 15162400 16836832
14:43:17 11551 8192000000 10293068384 10710183936 1364465840 14.277 99 54 108 1 1394377344 17957264 11954240
14:44:31 2841 8192000000 10289026192 10710249472 1372944128 14.353 100 55 110 0 1399599776 14330096 12325552
14:45:37 8820 8192000000 10296019552 10714914816 1365768160 14.289 99 55 109 1 1396906896 18115856 13022880
14:46:49 3791 8192000000 10323771152 10743533568 1391697856 14.521 100 60 120 0 1428508768 22169760 14641152
14:48:02 10948 8192000000 10316969680 10741874688 1394116912 14.543 100 50 100 2 1427011440 20419152 12475376
14:49:18 4642 8192000000 10302498384 10726903808 1375158240 14.373 99 43 84 1 1406087872 20344224 10585408
14:50:31 12158 8192000000 10315821808 10736140288 1389072560 14.498 100 56 112 0 1427560816 24115440 14372816
14:51:50 6996 8192000000 10309666880 10728620032 1368800512 14.316 99 50 100 1 1409088960 27277200 13011248
14:53:05 8122 8192000000 10314257632 10735058944 1377082784 14.390 100 61 121 0 1425722160 32222368 16417008
14:54:19 2964 8192000000 10305682384 10706919424 1341391104 14.070 98 84 167 2 1395013376 33456304 20165968
14:55:27 2221 8192000000 10323970832 10733940736 1369476352 14.322 100 73 147 4 1424284880 37443088 17365440
14:56:32 2881 8192000000 10311111488 10717990912 1363875280 14.272 100 71 141 2 1414968624 33689424 17403920
14:57:39 1133 8192000000 10337437184 10738954240 1372468768 14.349 99 93 186 2 1437926048 41676752 23780528
14:58:47 3348 8192000000 10347222192 10750935040 1379807952 14.415 100 91 183 0 1445116736 42436880 22871904
14:59:54 5140 8192000000 10342982368 10753642496 1375746864 14.379 99 61 122 1 1432638272 41115136 15776272
15:01:05 1810 8192000000 10370452608 10777960448 1402032976 14.613 100 76 150 1 1467299056 46976176 18289904
15:02:17 1783 8192000000 10384968896 10792595456 1408218576 14.668 100 85 170 1 1479473392 50051152 21203664
15:03:23 4884 8192000000 10371202480 10786562048 1399195216 14.588 100 70 141 0 1469018704 51278160 18545328
15:04:33 2898 8192000000 10368625184 10786508800 1391081328 14.516 99 56 111 0 1465739728 58886512 15771888
15:05:40 12197 8192000000 10358554624 10793025536 1394881600 14.549 100 37 74 3 1467805744 62100496 10823648
15:06:57 10982 8192000000 10331741568 10771435520 1382074656 14.435 100 29 58 0 1445035248 53495440 9465152
15:08:17 17668 8192000000 10325920288 10770677760 1376482720 14.385 100 28 56 1 1435215376 50797568 7935088
15:09:55 42930 8192000000 10315053600 10785755136 1370596336 14.332 99 13 26 1 1420062992 45997104 3469552
15:12:14 68271 8192000000 10293074096 10746933248 1370277008 14.330 99 17 34 0 1386900816 12598544 4025264
15:14:25 76992 8192000000 10290787232 10738565120 1358242192 14.222 98 17 33 2 1370387376 8516608 3628576
15:16:46 48015 8192000000 10309472624 10757337088 1390955888 14.514 100 31 62 0 1411033200 12675728 7401584
15:18:39 133428 8192000000 10312540624 10759376896 1368943664 14.318 98 38 76 0 1391339712 13324080 9071968
15:22:00 34638 8192000000 10313265888 10757046272 1386232576 14.472 99 42 85 1 1403692352 7573632 9886144
15:23:38 50090 8192000000 10317135184 10764533760 1392073472 14.524 100 25 50 1 1406510112 8362368 6074272
15:25:30 27780 8192000000 10306799040 10757509120 1390049664 14.506 100 26 51 0 1404319344 8525792 5743888
15:26:58 63138 8192000000 10285219296 10737340416 1330845280 13.975 96 36 71 1 1359635184 19973216 8816688
15:29:05 103290 8192000000 10275533440 10732347392 1333151200 13.996 97 9 18 0 1351804480 16846320 1806960
15:31:53 51427 8192000000 10278988928 10736644096 1363179776 14.266 99 12 24 1 1380535808 14392640 2963392
15:34:18 55877 8192000000 10292802288 10754531328 1382152976 14.436 100 9 18 0 1392853472 8671200 2029296
15:36:20 27178 8192000000 10279802128 10724458496 1347201584 14.122 98 23 43 1 1362879536 10474080 5203872
15:37:50 57193 8192000000 10271895680 10719711232 1308970208 13.777 95 18 36 0 1325715776 13271728 3473840
15:39:50 83588 8192000000 10298405584 10747473920 1349876672 14.146 98 18 37 1 1370310320 16446336 3987312
15:42:14 69627 8192000000 10284550352 10749026304 1337221824 14.032 97 11 22 0 1365325072 25227168 2876080
15:44:24 52567 8192000000 10284863264 10723102720 1338422736 14.043 98 18 37 0 1358814048 16063536 4327776
15:46:20 131933 8192000000 10285935456 10737278976 1284013744 13.550 93 13 26 1 1297859888 11016272 2829872
15:49:32 49791 8192000000 10290145520 10746834944 1375018768 14.372 100 14 28 0 1386535600 8068080 3448752
15:51:23 38137 8192000000 10291691792 10750291968 1380496208 14.421 100 39 78 2 1398856192 9362848 8997136
15:53:02 24276 8192000000 10302175952 10743033856 1370056896 14.328 99 49 97 0 1392580608 11621168 10902544
15:54:27 64659 8192000000 10272442528 10737430528 1343505472 14.089 98 15 30 0 1357610672 10670064 3435136
15:56:45 83981 8192000000 10280960064 10740875264 1366318464 14.294 99 15 30 1 1383915296 13871408 3725424
15:59:12 46574 8192000000 10285384464 10747465728 1349164272 14.140 98 14 28 0 1371485088 18657024 3663792
16:00:59 125451 8192000000 10311503408 10757095424 1335198064 14.014 96 12 24 0 1350828336 12992896 2637376
16:04:05 63875 8192000000 10301160000 10762825728 1385544528 14.466 99 17 34 0 1407419856 18072816 3802512
16:06:53 62686 8192000000 10285326080 10742861824 1356404208 14.205 98 15 29 0 1372399616 12881872 3113536
16:09:08 126547 8192000000 10295680912 10757648384 1316688496 13.847 94 9 18 0 1330699104 12474928 1535680
16:12:15 40137 8192000000 10303171888 10753544192 1375013488 14.372 99 21 42 2 1393657392 14243296 4400608
16:13:59 104423 8192000000 10285762720 10746724352 1374788144 14.370 99 22 44 1 1390491216 10261552 5441520
16:16:44 81447 8192000000 10289573072 10754875392 1368297184 14.312 99 22 44 0 1388006944 14484576 5225184
16:19:07 69105 8192000000 10294389840 10755043328 1373404832 14.358 99 13 26 1 1391861216 15947840 2508544
16:21:27 53115 8192000000 10278272512 10743627776 1342928912 14.084 97 13 25 0 1360306944 14462352 2915680
16:24:18 46842 8192000000 10264816656 10719150080 1315219040 13.833 96 17 34 0 1334118352 15201120 3698192
16:26:06 26199 8192000000 10264409648 10713321472 1342649856 14.081 99 27 54 0 1358626784 9839216 6137712
16:27:42 44302 8192000000 10273007264 10725801984 1344754192 14.100 99 29 58 1 1366359008 15207984 6396832
16:29:28 86655 8192000000 10259875616 10709905408 1291371536 13.617 95 9 18 0 1307521376 14218400 1931440
16:32:13 32774 8192000000 10267313312 10697613312 1314720336 13.829 98 41 82 0 1337145664 13223856 9201472
16:33:48 66295 8192000000 10276872976 10736590848 1335103824 14.013 99 10 20 1 1360476528 23162272 2210432
16:35:55 114482 8192000000 10253596000 10694774784 1316901744 13.849 97 13 26 0 1333599520 13750704 2947072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-05-2022 16:40:28 to 04-05-2022 16:40:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 87 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-05-2022 16:40:44 to 04-05-2022 16:40:46.

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          249998
MON$OLDEST_ACTIVE               250725
MON$OLDEST_SNAPSHOT             250725
MON$NEXT_TRANSACTION            250725
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                       2066080
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33585 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33585 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33585 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33585 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 ---
:34: 
:35: mscs-test-161.budzdorov.ru    Wed May  4 16:38:53 2022
:36:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:37: 
--- end of diff output ---

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

04.05.22 16:40:47. 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-05-04 16:40:49 No severe PSQL-related problems occured
04.05.22 16:40:51. End of report.