Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.12.2021 22:38
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 16451376 kB
MemAvailable 31455316 kB
Buffers 0 kB
Cached 15188424 kB
SwapCached 0 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.9
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 2021-12-07 22:38:01
DTS_END                         2021-12-07 22:38:01.5180
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/56312
TRN_ID                          244199
ATT_ID                          667
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: 9631.04 19961 1155725
customer order: creation 178.71 341 21445
customer order: refuse 14.95 396 1794
order to supplier: creation 46.69 1233 5603
order to supplier: removal 3.33 109087 400
invoice (draft): creation 46.59 3189 5591
invoice (draft): removal 53.61 784 6433
invoice accept: apply 42.88 16528 5145
invoice accept: cancel 48.45 827 5814
customer reserve: creation 4051.83 158 486219
customer reserve: removal 4837.46 59 580495
realization accept: apply 87.08 213 10450
realization accept: cancel 83.78 117 10053
payment from customer: creation 62.93 157 7552
payment from customer: removal 6.17 80 740
payment to supplier: creation 50.70 176 6084
payment to supplier: removal 3.42 68 410
service: total inventory turnovers 4.61 1210 553
service: total monetary turnovers 6.92 38 830
service: aggregate perf. data 0.92 15011 110
service: refresh index statistics 0.03 249543 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-12-2021 22:38:49 to 07-12-2021 22:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6633
TEST_TIME 2 5165
TEST_TIME 3 3994
TEST_TIME 4 4226
TEST_TIME 5 4954
TEST_TIME 6 5311
TEST_TIME 7 5541
TEST_TIME 8 6288
TEST_TIME 9 6391
TEST_TIME 10 6261
TEST_TIME 11 6505
TEST_TIME 12 7035
TEST_TIME 13 7220
TEST_TIME 14 7518
TEST_TIME 15 7508
TEST_TIME 16 7733
TEST_TIME 17 8143
TEST_TIME 18 8460
TEST_TIME 19 8782
TEST_TIME 20 9205
TEST_TIME 21 9219
TEST_TIME 22 9210
TEST_TIME 23 9822
TEST_TIME 24 9850
TEST_TIME 25 10338
TEST_TIME 26 10174
TEST_TIME 27 10489
TEST_TIME 28 10474
TEST_TIME 29 10795
TEST_TIME 30 11139
TEST_TIME 31 11029
TEST_TIME 32 10930
TEST_TIME 33 10815
TEST_TIME 34 10726
TEST_TIME 35 10671
TEST_TIME 36 10591
TEST_TIME 37 10501
TEST_TIME 38 10506
TEST_TIME 39 10509
TEST_TIME 40 10633
TEST_TIME 41 10471
TEST_TIME 42 10347
TEST_TIME 43 10302
TEST_TIME 44 10277
TEST_TIME 45 10180
TEST_TIME 46 10188
TEST_TIME 47 10089
TEST_TIME 48 10051
TEST_TIME 49 10001
TEST_TIME 50 10076
TEST_TIME 51 10057
TEST_TIME 52 9951
TEST_TIME 53 9882
TEST_TIME 54 9824
TEST_TIME 55 9757
TEST_TIME 56 9825
TEST_TIME 57 9873
TEST_TIME 58 9916
TEST_TIME 59 9901
TEST_TIME 60 9834
TEST_TIME 61 9801
TEST_TIME 62 9875
TEST_TIME 63 9919
TEST_TIME 64 9913
TEST_TIME 65 9987
TEST_TIME 66 9974
TEST_TIME 67 9997
TEST_TIME 68 9929
TEST_TIME 69 9952
TEST_TIME 70 9927
TEST_TIME 71 10027
TEST_TIME 72 10038
TEST_TIME 73 10006
TEST_TIME 74 10055
TEST_TIME 75 9929
TEST_TIME 76 9967
TEST_TIME 77 9926
TEST_TIME 78 9856
TEST_TIME 79 9859
TEST_TIME 80 9810
TEST_TIME 81 9818
TEST_TIME 82 9782
TEST_TIME 83 9753
TEST_TIME 84 9669
TEST_TIME 85 9689
TEST_TIME 86 9642
TEST_TIME 87 9643
TEST_TIME 88 9635
TEST_TIME 89 9667
TEST_TIME 90 9676
TEST_TIME 91 9677
TEST_TIME 92 9720
TEST_TIME 93 9775
TEST_TIME 94 9826
TEST_TIME 95 9829
TEST_TIME 96 9852
TEST_TIME 97 9850
TEST_TIME 98 9833
TEST_TIME 99 9792
TEST_TIME 100 9929
TEST_TIME 101 9928
TEST_TIME 102 9842
TEST_TIME 103 9776
TEST_TIME 104 9722
TEST_TIME 105 9739
TEST_TIME 106 9722
TEST_TIME 107 9703
TEST_TIME 108 9673
TEST_TIME 109 9652
TEST_TIME 110 9595
TEST_TIME 111 9577
TEST_TIME 112 9554
TEST_TIME 113 9554
TEST_TIME 114 9570
TEST_TIME 115 9619
TEST_TIME 116 9693
TEST_TIME 117 9693
TEST_TIME 118 9731
TEST_TIME 119 9715
TEST_TIME 120 9634

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-12-2021 22:38:53 to 07-12-2021 22:38:56.

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 1605003 1605003 0 0.00 0 45349 6 0 0
doc_list_biud 1605003 1605003 0 0.00 0 27330 27 0 0
sp_add_invoice_to_stock 5275 5145 130 2.46 26 196568 16529 0 130
sp_cancel_adding_invoice 5814 5814 0 0.00 5 62260 828 0 0
sp_cancel_client_order 1794 1794 0 0.00 5 20889 396 0 0
sp_cancel_customer_reserve 580495 580495 0 0.00 1 18469 59 0 0
sp_cancel_pay_from_customer 740 740 0 0.00 2 9279 81 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 6193 68 0 0
sp_cancel_supplier_invoice 6446 6433 13 0.20 2 26543 785 0 13
sp_cancel_supplier_order 400 400 0 0.00 7 1153226 109087 0 0
sp_cancel_write_off 10053 10053 0 0.00 1 20263 117 0 0
sp_client_order 21445 21445 0 0.00 3 22405 342 0 0
sp_customer_reserve 486219 486219 0 0.00 0 58292 159 0 0
sp_fill_shopping_cart 32635 32635 0 0.00 1 27589 37 0 0
sp_fill_shopping_cart_clo_res 486217 486217 0 0.00 0 24435 13 0 0
sp_get_clo_for_invoice 5143 5143 0 0.00 0 47129 307 0 0
sp_kill_cost_storno 17017 17017 0 0.00 0 20248 60 0 0
sp_kill_qstorno_ret_qs2qd 1177876 1177876 0 0.00 0 26533 32 0 0
sp_kill_qty_storno 620581 620581 0 0.00 0 27330 69 0 0
sp_lock_selected_doc 21467 21467 0 0.00 0 18245 6 0 0
sp_make_cost_storno 29228 29228 0 0.00 0 41312 90 0 0
sp_multiply_rows_for_pdistr 29228 29228 0 0.00 0 18368 33 0 0
sp_multiply_rows_for_qdistr 21445 21445 0 0.00 0 20595 145 0 0
sp_pay_from_customer 7552 7552 0 0.00 4 45073 157 0 0
sp_pay_to_supplier 6084 6084 0 0.00 5 45553 177 0 0
sp_payment_common 13636 13636 0 0.00 2 36742 38 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 11888 363 0 0
sp_qd_handle_on_invoice_upd_sts 10956 10956 0 0.00 0 23151 332 0 0
sp_qd_handle_on_reserve_upd_sts 10450 10450 0 0.00 0 27330 55 0 0
sp_reserve_write_off 10450 10450 0 0.00 6 37771 213 0 0
sp_supplier_invoice 5591 5591 0 0.00 7 78935 3189 0 0
sp_supplier_order 5603 5603 0 0.00 5 30413 1233 0 0
srv_aggregate_perf_data 111 110 1 0.90 2708 79102 15012 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1680 159817 48354 0 0
srv_find_qd_qs_mism 1105402 1105402 0 0.00 0 11569 4 0 0
srv_make_invnt_saldo 553 553 0 0.00 49 74735 1210 0 0
srv_make_money_saldo 830 830 0 0.00 1 8455 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 197215 321547 249543 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 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 5146 5146 0 0.00 0 9977 150 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 4001 88 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 2725 10 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 3462 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 740 740 0 0.00 0 1982 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 2303 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 4400 25 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 47761 1927 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 4972 15 0 0
t$perf-norm:sp_client_order 21446 21446 0 0.00 0 11757 17 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 1112 6 0 0
t$perf-norm:sp_pay_from_customer 7552 7552 0 0.00 0 29157 14 0 0
t$perf-norm:sp_pay_to_supplier 6084 6084 0 0.00 0 6180 11 0 0
t$perf-norm:sp_reserve_write_off 10450 10450 0 0.00 0 12251 11 0 0
t$perf-norm:sp_supplier_invoice 5591 5591 0 0.00 0 10631 22 0 0
t$perf-norm:sp_supplier_order 5603 5603 0 0.00 0 6051 17 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 2883 36 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 678 12 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 833 18 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 708 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 18 6 0 0
v_add_invoice_to_stock 10544 10284 260 2.46 0 8917 20 0 260
v_all_customers 39174 39174 0 0.00 0 26 1 0 0
v_all_suppliers 31466 31466 0 0.00 0 18 1 0 0
v_all_wares 42890 42890 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 2959 11 0 0
v_cancel_client_order 3588 3588 0 0.00 0 12088 9 0 0
v_cancel_customer_prepayment 1480 1480 0 0.00 0 2063 6 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 4755 9 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 2048 5 0 0
v_cancel_supplier_invoice 814 788 26 3.19 0 913 15 0 26
v_cancel_supplier_order 800 800 0 0.00 0 4964 11 0 0
v_cancel_write_off 2070 2070 0 0.00 0 7549 16 0 0
v_max_id_clo_ord 5601 5601 0 0.00 0 438 1 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 1539 10 0 0
v_max_id_ord_sup 5589 5589 0 0.00 0 5552 3 0 0
v_max_non_paid_invoice 1541 1541 0 0.00 0 783 3 0 0
v_max_non_paid_realizn 3926 3926 0 0.00 0 8403 7 0 0
v_min_id_clo_ord 5601 5601 0 0.00 0 3995 7 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 2454 7 0 0
v_min_id_ord_sup 5589 5589 0 0.00 0 5951 5 0 0
v_min_non_paid_invoice 6084 6084 0 0.00 0 16810 25 0 0
v_min_non_paid_realizn 7552 7552 0 0.00 0 31137 31 0 0
v_our_firm 10968 10968 0 0.00 0 14 1 0 0
v_reserve_write_off 20900 20900 0 0.00 0 10489 11 0 0
x_lock_depdocs_on_canc_invoice 11853 11853 0 0.00 0 21327 114 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 4051 330 0 0
x_make_qstorno_1000_1200 5603 5603 0 0.00 1 30390 1154 0 0
x_make_qstorno_1200_2000 5591 5591 0 0.00 3 78930 3054 0 0
x_make_qstorno_2100_3300 486219 486219 0 0.00 0 48021 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-12-2021 22:38:57 to 07-12-2021 22:39:01.

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
20:08:05 35 8192000000 8817094448 8834945024 18843952 0.229 5 4 5 0 19070688 87456 139280
20:09:05 19401 8192000000 9902967280 10214948864 986614736 10.749 100 90 180 0 1017314400 6491648 24208016
20:10:41 21524 8192000000 9965631104 10291900416 1048613392 11.347 100 88 176 0 1080246768 9130448 22502928
20:12:06 12478 8192000000 9997772048 10331717632 1078831936 11.636 100 88 176 0 1111844592 10918224 22094432
20:13:22 16608 8192000000 10041113904 10390233088 1123580000 12.061 100 73 144 1 1151937408 11154272 17203136
20:14:42 9784 8192000000 10079526176 10438926336 1164950704 12.450 100 83 165 2 1196909024 12832960 19125360
20:15:57 5896 8192000000 10121432512 10491367424 1211187120 12.880 100 69 139 1 1238044096 11398848 15458128
20:17:06 5195 8192000000 10154053728 10535264256 1248189936 13.222 100 69 138 0 1274641728 11744464 14707328
20:18:13 2643 8192000000 10191046000 10574376960 1276144272 13.478 100 87 174 1 1310100656 14781344 19175040
20:19:17 1543 8192000000 10215652480 10598109184 1294497184 13.645 100 92 182 0 1328783360 13799984 20486192
20:20:22 13393 8192000000 10185889552 10595409920 1297099760 13.669 100 33 66 0 1312695664 7918320 7677584
20:21:37 3808 8192000000 10199454416 10610081792 1306691776 13.756 100 41 82 1 1324150848 7116048 10343024
20:22:43 9618 8192000000 10195856992 10615627776 1314458112 13.827 100 19 38 1 1324899168 5755456 4685600
20:23:56 15192 8192000000 10209722112 10627932160 1320788864 13.884 100 15 29 1 1329772160 5827920 3155376
20:25:11 13074 8192000000 10243359696 10644520960 1323621552 13.909 100 66 135 1 1344919488 5765424 15532512
20:26:25 6845 8192000000 10223770928 10642948096 1330124112 13.968 100 30 60 1 1343100784 6423824 6552848
20:27:38 8632 8192000000 10218595056 10640453632 1329048160 13.959 100 32 65 2 1343418944 6730912 7639872
20:28:47 14522 8192000000 10190428400 10596143104 1235322240 13.103 94 26 51 0 1247405744 4818192 7265312
20:30:05 12616 8192000000 10148044528 10557308928 1192634192 12.708 92 21 42 1 1206888848 9413344 4841312
20:31:17 6950 8192000000 10128833760 10530140160 1220418880 12.966 98 35 71 1 1235308832 6399280 8490672
20:32:26 8589 8192000000 10144294224 10547458048 1238797568 13.135 98 27 53 1 1250654752 5894048 5963136
20:33:37 20328 8192000000 10170550192 10572189696 1249148896 13.230 98 31 62 0 1266647248 10311376 7186976
20:34:59 15001 8192000000 10172470816 10584752128 1285539632 13.564 100 25 50 0 1297029472 5891776 5598064
20:36:17 8255 8192000000 10200819328 10612285440 1303079520 13.723 100 25 50 2 1314284768 5404112 5801136
20:37:28 7979 8192000000 10199084944 10619224064 1310309328 13.789 100 9 18 0 1318107760 5634880 2163552
20:38:45 5504 8192000000 10239084912 10650062848 1336596816 14.027 100 39 78 0 1353606304 8377856 8631632
20:39:56 39667 8192000000 10265957840 10682318848 1353666944 14.180 100 37 75 0 1375202480 13276288 8259248
20:41:39 78354 8192000000 10280867024 10708869120 1376118160 14.382 100 44 88 1 1403998384 17014832 10865392
20:44:24 44948 8192000000 10311292000 10743021568 1390971104 14.515 100 30 60 0 1419206352 21149328 7085920
20:46:14 5261 8192000000 10348882576 10768740352 1409487824 14.679 100 67 134 2 1456430048 31238288 15703936
20:47:24 6307 8192000000 10349708272 10774052864 1420433248 14.777 100 62 122 1 1458415264 23452784 14529232
20:48:35 6731 8192000000 10351019808 10777006080 1419689744 14.770 100 65 130 1 1463994048 28475456 15828848
20:49:46 1698 8192000000 10361335760 10781265920 1419599600 14.769 100 83 160 2 1472465136 32505952 20359584
20:50:55 7845 8192000000 10353283664 10777300992 1407423120 14.661 99 70 138 0 1455209312 29761392 18024800
20:52:08 14626 8192000000 10347096016 10779414528 1406681904 14.654 99 50 99 0 1450450528 30833552 12935072
20:53:25 11076 8192000000 10358448784 10783531008 1411130912 14.694 100 65 128 0 1460296800 32686192 16479696
20:54:41 2118 8192000000 10361427760 10771001344 1380970656 14.425 98 76 151 1 1441487968 41744896 18772416
20:55:50 1678 8192000000 10371651008 10785423360 1408482336 14.670 100 81 162 0 1468578208 40114112 19981760
20:56:58 4364 8192000000 10357447600 10776772608 1407849168 14.665 100 68 136 0 1461381040 36232304 17299568
20:58:10 2153 8192000000 10372907680 10786971648 1413622016 14.716 100 92 183 0 1472901600 37144544 22135040
20:59:14 15955 8192000000 10318196336 10751471616 1386633248 14.476 100 30 60 1 1427428896 32346512 8449136
21:00:45 16632 8192000000 10349531440 10785132544 1396096816 14.560 100 38 76 1 1453873456 46773184 11003456
21:02:05 20090 8192000000 10329121632 10770640896 1368943824 14.318 98 27 54 1 1414895264 38289536 7661904
21:03:28 26115 8192000000 10318512096 10761474048 1365557200 14.287 99 34 68 3 1420901728 45942208 9402320
21:04:58 36242 8192000000 10299584768 10747731968 1361801200 14.254 99 24 48 0 1408294048 40203216 6289632
21:07:00 28388 8192000000 10302118320 10754080768 1358009856 14.219 99 15 30 1 1402837088 40578208 4249024
21:08:43 159811 8192000000 10281510736 10735783936 1314736192 13.829 96 17 34 0 1332157264 14207968 3213104
21:12:30 50543 8192000000 10267099152 10704732160 1317196592 13.851 97 15 30 0 1336677536 16596272 2884672
21:14:21 42442 8192000000 10276083520 10704306176 1331632736 13.982 98 25 47 1 1348068064 11480672 4954656
21:16:05 121727 8192000000 10280584032 10733654016 1340432848 14.061 98 17 34 0 1362123568 18172912 3517808
21:19:26 35766 8192000000 10315960432 10748612608 1385593088 14.467 100 40 80 2 1404743680 10582432 8568160
21:21:03 114717 8192000000 10291627856 10747269120 1388273952 14.490 100 14 27 0 1406545312 14902640 3368720
21:23:58 29650 8192000000 10295737264 10746806272 1382647056 14.440 100 46 92 0 1406371888 12387056 11337776
21:25:29 17435 8192000000 10282273648 10739695616 1369000208 14.318 99 11 22 0 1380343664 8976816 2366640
21:26:48 33012 8192000000 10301470384 10749063168 1381063856 14.426 99 17 35 0 1394264576 9229296 3971424
21:28:23 20913 8192000000 10298206000 10746290176 1383003440 14.443 100 33 66 1 1401459024 10630416 7825168
21:29:52 58085 8192000000 10302157792 10757021696 1381551872 14.430 99 17 33 1 1399515008 14486976 3476160
21:31:51 38631 8192000000 10290946976 10744315904 1360220304 14.239 97 15 30 0 1385314992 21262528 3832160
21:33:30 62550 8192000000 10310533808 10774036480 1397573968 14.573 100 21 43 0 1424132512 21027792 5530752
21:35:33 40604 8192000000 10283270928 10749698048 1363171072 14.266 98 30 59 1 1383027056 12578672 7277312
21:38:05 72435 8192000000 10283328704 10733264896 1312057392 13.805 94 16 32 0 1327022992 11322464 3643136
21:40:21 58355 8192000000 10282467408 10730569728 1343968512 14.093 98 19 37 0 1362156400 13813728 4374160
21:42:23 18476 8192000000 10282253920 10719571968 1357966048 14.219 99 56 112 1 1379312272 8439600 12906624
21:43:44 46436 8192000000 10273463376 10719862784 1352867760 14.173 99 27 55 1 1368124960 9054672 6202528
21:45:47 39417 8192000000 10268149312 10695438336 1315141856 13.833 96 32 64 1 1334461216 11982848 7336512
21:47:28 129074 8192000000 10245824832 10701467648 1212266320 12.890 89 9 18 1 1231863200 17655856 1941024
21:50:38 53680 8192000000 10266195008 10703273984 1331158032 13.978 98 20 40 0 1345151536 9653328 4340176
21:52:35 125347 8192000000 10264921328 10715672576 1333061904 13.995 98 23 46 1 1355592112 16891360 5638848
21:55:40 37773 8192000000 10258510544 10716274688 1351146496 14.158 100 18 35 1 1369137040 14046832 3943712
21:57:20 66251 8192000000 10260654480 10710159360 1333271616 13.997 99 12 23 0 1345675040 9854816 2548608
21:59:29 70438 8192000000 10258426400 10719703040 1344098928 14.094 99 9 18 1 1363378544 17214240 2065376
22:01:41 68250 8192000000 10274813152 10732015616 1372503696 14.349 100 12 24 1 1385960928 10663328 2793904
22:03:49 70971 8192000000 10266397248 10730426368 1348868224 14.137 98 9 18 1 1358384336 7332000 2184112
22:06:01 51615 8192000000 10288717744 10749620224 1383100816 14.444 100 18 36 0 1396265088 9309312 3854960
22:07:52 71549 8192000000 10307750528 10772672512 1381584400 14.431 99 23 46 1 1396236688 9734176 4918112
22:10:05 83456 8192000000 10319364816 10788634624 1380477200 14.421 98 19 38 0 1406276736 21558112 4241424
22:12:29 82919 8192000000 10309414288 10760507392 1340226496 14.059 96 15 30 2 1361291696 17825328 3239872
22:14:54 47768 8192000000 10321887264 10768658432 1395018048 14.551 100 14 28 0 1406860416 8877120 2965248
22:16:43 48619 8192000000 10310493680 10751234048 1354395168 14.187 97 37 74 1 1375703264 13008960 8299136
22:18:32 131284 8192000000 10265138352 10723737600 1314446448 13.826 94 12 24 0 1329811264 12865808 2499008
22:22:41 53027 8192000000 10283617856 10749333504 1371782976 14.343 99 23 46 0 1389013168 12108688 5121504
22:24:47 44645 8192000000 10263003632 10718793728 1335152896 14.014 97 16 33 1 1348583280 9717600 3712784
22:26:41 71886 8192000000 10250232608 10716815360 1332288672 13.988 97 6 12 0 1340564176 6909088 1366416
22:28:54 67686 8192000000 10274764896 10734501888 1350624896 14.153 98 16 32 0 1367158288 12937152 3596240
22:31:03 42900 8192000000 10297586192 10746806272 1365099120 14.283 99 30 57 3 1391338816 19120144 7119552
22:32:51 88320 8192000000 10282349216 10764341248 1368399632 14.313 100 15 30 1 1405332736 32331440 4601664
22:35:25 40678 8192000000 10283820672 10737111040 1369314512 14.321 100 21 42 0 1386177008 12111344 4751152
22:37:08 61328 8192000000 9238418960 9508548608 391280512 4.558 29 9 17 0 399369936 5982896 2106528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-12-2021 22:39:03 to 07-12-2021 22:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 130 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 07-12-2021 22:39:19 to 07-12-2021 22:39:21.

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          244449
MON$OLDEST_ACTIVE               244450
MON$OLDEST_SNAPSHOT             244450
MON$NEXT_TRANSACTION            244450
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                       2055200
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Tue Dec  7 22:38:13 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Tue Dec  7 22:38:13 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Dec  7 22:38:17 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Tue Dec  7 22:38:17 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Tue Dec  7 22:38:17 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
--- end of diff output ---

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

07.12.21 22:39:22. 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
2021-12-07 22:39:24 No severe PSQL-related problems occured
07.12.21 22:39:25. End of report.