Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.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 16697012 kB
MemAvailable 31414528 kB
Buffers 0 kB
Cached 14975300 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-18 22:37:59
DTS_END                         2021-12-18 22:37:59.0040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58650
TRN_ID                          246893
ATT_ID                          684
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: 9382.88 23087 1125945
customer order: creation 177.13 319 21255
customer order: refuse 15.62 336 1874
order to supplier: creation 47.01 1196 5641
order to supplier: removal 3.51 99129 421
invoice (draft): creation 48.08 3211 5769
invoice (draft): removal 50.95 824 6114
invoice accept: apply 43.74 17396 5249
invoice accept: cancel 45.78 847 5494
customer reserve: creation 4090.55 170 490866
customer reserve: removal 4556.93 60 546832
realization accept: apply 87.53 177 10503
realization accept: cancel 78.58 114 9430
payment from customer: creation 62.89 98 7547
payment from customer: removal 5.70 62 684
payment to supplier: creation 51.75 125 6210
payment to supplier: removal 3.59 78 431
service: total inventory turnovers 5.64 866 677
service: total monetary turnovers 7.02 30 842
service: aggregate perf. data 0.85 18396 102
service: refresh index statistics 0.03 318315 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-12-2021 22:39:19 to 18-12-2021 22:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1615
TEST_TIME 2 2729
TEST_TIME 3 4026
TEST_TIME 4 4100
TEST_TIME 5 4312
TEST_TIME 6 5259
TEST_TIME 7 5945
TEST_TIME 8 5914
TEST_TIME 9 5880
TEST_TIME 10 6033
TEST_TIME 11 6364
TEST_TIME 12 6699
TEST_TIME 13 6812
TEST_TIME 14 6966
TEST_TIME 15 7105
TEST_TIME 16 7150
TEST_TIME 17 7363
TEST_TIME 18 7863
TEST_TIME 19 8071
TEST_TIME 20 8515
TEST_TIME 21 8837
TEST_TIME 22 9166
TEST_TIME 23 9246
TEST_TIME 24 9264
TEST_TIME 25 9673
TEST_TIME 26 9918
TEST_TIME 27 10634
TEST_TIME 28 10842
TEST_TIME 29 10811
TEST_TIME 30 10501
TEST_TIME 31 10468
TEST_TIME 32 10361
TEST_TIME 33 10148
TEST_TIME 34 10058
TEST_TIME 35 9907
TEST_TIME 36 9925
TEST_TIME 37 10039
TEST_TIME 38 9973
TEST_TIME 39 9868
TEST_TIME 40 9824
TEST_TIME 41 9727
TEST_TIME 42 9541
TEST_TIME 43 9530
TEST_TIME 44 9497
TEST_TIME 45 9479
TEST_TIME 46 9516
TEST_TIME 47 9494
TEST_TIME 48 9473
TEST_TIME 49 9485
TEST_TIME 50 9472
TEST_TIME 51 9391
TEST_TIME 52 9500
TEST_TIME 53 9616
TEST_TIME 54 9611
TEST_TIME 55 9580
TEST_TIME 56 9657
TEST_TIME 57 9545
TEST_TIME 58 9799
TEST_TIME 59 9758
TEST_TIME 60 9635
TEST_TIME 61 9546
TEST_TIME 62 9494
TEST_TIME 63 9563
TEST_TIME 64 9502
TEST_TIME 65 9424
TEST_TIME 66 9420
TEST_TIME 67 9362
TEST_TIME 68 9369
TEST_TIME 69 9397
TEST_TIME 70 9427
TEST_TIME 71 9368
TEST_TIME 72 9405
TEST_TIME 73 9387
TEST_TIME 74 9384
TEST_TIME 75 9427
TEST_TIME 76 9465
TEST_TIME 77 9423
TEST_TIME 78 9494
TEST_TIME 79 9559
TEST_TIME 80 9600
TEST_TIME 81 9558
TEST_TIME 82 9533
TEST_TIME 83 9477
TEST_TIME 84 9494
TEST_TIME 85 9440
TEST_TIME 86 9439
TEST_TIME 87 9437
TEST_TIME 88 9398
TEST_TIME 89 9367
TEST_TIME 90 9386
TEST_TIME 91 9353
TEST_TIME 92 9416
TEST_TIME 93 9401
TEST_TIME 94 9422
TEST_TIME 95 9429
TEST_TIME 96 9427
TEST_TIME 97 9415
TEST_TIME 98 9423
TEST_TIME 99 9454
TEST_TIME 100 9527
TEST_TIME 101 9539
TEST_TIME 102 9558
TEST_TIME 103 9567
TEST_TIME 104 9563
TEST_TIME 105 9572
TEST_TIME 106 9494
TEST_TIME 107 9508
TEST_TIME 108 9499
TEST_TIME 109 9483
TEST_TIME 110 9449
TEST_TIME 111 9403
TEST_TIME 112 9372
TEST_TIME 113 9331
TEST_TIME 114 9308
TEST_TIME 115 9334
TEST_TIME 116 9345
TEST_TIME 117 9307
TEST_TIME 118 9314
TEST_TIME 119 9387
TEST_TIME 120 9387

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-12-2021 22:39:23 to 18-12-2021 22:39:26.

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 1578164 1578164 0 0.00 0 12314 6 0 0
doc_list_biud 1578164 1578164 0 0.00 0 25408 26 0 0
sp_add_invoice_to_stock 5338 5249 89 1.66 42 205916 17396 0 89
sp_cancel_adding_invoice 5494 5494 0 0.00 6 55018 847 0 0
sp_cancel_client_order 1874 1874 0 0.00 4 20744 337 0 0
sp_cancel_customer_reserve 546832 546832 0 0.00 1 22012 60 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 2988 63 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 5244 79 0 0
sp_cancel_supplier_invoice 6121 6114 7 0.11 2 16337 824 0 7
sp_cancel_supplier_order 421 421 0 0.00 7 1073502 99129 0 0
sp_cancel_write_off 9430 9430 0 0.00 1 15319 115 0 0
sp_client_order 21255 21255 0 0.00 3 19799 320 0 0
sp_customer_reserve 490866 490866 0 0.00 0 79300 170 0 0
sp_fill_shopping_cart 32652 32652 0 0.00 1 15335 40 0 0
sp_fill_shopping_cart_clo_res 490862 490862 0 0.00 0 28318 14 0 0
sp_get_clo_for_invoice 5245 5245 0 0.00 0 29390 326 0 0
sp_kill_cost_storno 16039 16039 0 0.00 0 9768 58 0 0
sp_kill_qstorno_ret_qs2qd 1109629 1109629 0 0.00 0 21997 33 0 0
sp_kill_qty_storno 585913 585913 0 0.00 0 25407 70 0 0
sp_lock_selected_doc 21749 21749 0 0.00 0 7078 4 0 0
sp_make_cost_storno 29505 29505 0 0.00 0 12288 45 0 0
sp_multiply_rows_for_pdistr 29505 29505 0 0.00 0 8222 32 0 0
sp_multiply_rows_for_qdistr 21255 21255 0 0.00 0 12920 152 0 0
sp_pay_from_customer 7547 7547 0 0.00 4 15706 98 0 0
sp_pay_to_supplier 6210 6210 0 0.00 4 15283 125 0 0
sp_payment_common 13757 13757 0 0.00 1 7766 19 0 0
sp_qd_handle_on_cancel_clo 1874 1874 0 0.00 0 20712 320 0 0
sp_qd_handle_on_invoice_upd_sts 10739 10739 0 0.00 0 25406 323 0 0
sp_qd_handle_on_reserve_upd_sts 10503 10503 0 0.00 0 21359 66 0 0
sp_reserve_write_off 10503 10503 0 0.00 6 21484 177 0 0
sp_supplier_invoice 5769 5769 0 0.00 6 88621 3212 0 0
sp_supplier_order 5641 5641 0 0.00 5 36071 1196 0 0
srv_aggregate_perf_data 102 102 0 0.00 2103 343065 18397 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1208 161285 46207 0 0
srv_find_qd_qs_mism 1074611 1074611 0 0.00 0 10061 6 0 0
srv_make_invnt_saldo 677 677 0 0.00 32 43151 867 0 0
srv_make_money_saldo 842 842 0 0.00 1 1977 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 152049 456327 318315 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5249 5249 0 0.00 0 10662 172 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 4217 131 0 0
t$perf-norm:sp_cancel_client_order 1874 1874 0 0.00 0 7123 16 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 838 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 5257 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 898 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 2003 20 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 23180 1694 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 4212 14 0 0
t$perf-norm:sp_client_order 21255 21255 0 0.00 0 6503 18 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 2517 9 0 0
t$perf-norm:sp_pay_from_customer 7547 7547 0 0.00 0 4544 10 0 0
t$perf-norm:sp_pay_to_supplier 6210 6210 0 0.00 0 6399 12 0 0
t$perf-norm:sp_reserve_write_off 10503 10503 0 0.00 0 5645 11 0 0
t$perf-norm:sp_supplier_invoice 5769 5769 0 0.00 0 6257 23 0 0
t$perf-norm:sp_supplier_order 5641 5641 0 0.00 0 7170 18 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 1142 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 13 1 0 0
t$perf-norm:srv_make_invnt_saldo 677 677 0 0.00 0 685 15 0 0
t$perf-norm:srv_make_money_saldo 842 842 0 0.00 0 8121 31 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10668 10490 178 1.66 0 4493 15 0 178
v_all_customers 39272 39272 0 0.00 0 63 1 0 0
v_all_suppliers 31882 31882 0 0.00 0 25 1 0 0
v_all_wares 42510 42510 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 1092 9 0 0
v_cancel_client_order 3748 3748 0 0.00 0 1661 3 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 1724 5 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 389 4 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 756 4 0 0
v_cancel_supplier_invoice 864 850 14 1.62 0 820 13 0 14
v_cancel_supplier_order 842 842 0 0.00 0 464 4 0 0
v_cancel_write_off 2036 2036 0 0.00 0 1744 14 0 0
v_max_id_clo_ord 5639 5639 0 0.00 0 13028 4 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 1118 7 0 0
v_max_id_ord_sup 5758 5758 0 0.00 0 5748 5 0 0
v_max_non_paid_invoice 1666 1666 0 0.00 0 725 4 0 0
v_max_non_paid_realizn 3914 3914 0 0.00 0 2374 5 0 0
v_min_id_clo_ord 5639 5639 0 0.00 0 7703 9 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 8490 15 0 0
v_min_id_ord_sup 5758 5758 0 0.00 0 8284 12 0 0
v_min_non_paid_invoice 6210 6210 0 0.00 0 7762 12 0 0
v_min_non_paid_realizn 7547 7547 0 0.00 0 6347 13 0 0
v_our_firm 10504 10504 0 0.00 0 13 1 0 0
v_reserve_write_off 21006 21006 0 0.00 0 2480 4 0 0
x_lock_depdocs_on_canc_invoice 11183 11183 0 0.00 0 19816 128 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 10699 418 0 0
x_make_qstorno_1000_1200 5641 5641 0 0.00 2 36024 1114 0 0
x_make_qstorno_1200_2000 5769 5769 0 0.00 3 88593 3064 0 0
x_make_qstorno_2100_3300 490866 490866 0 0.00 0 79299 154 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 18-12-2021 22:39:27 to 18-12-2021 22:39:32.

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:02 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:04 33638 8192000000 9933663312 10252521472 1006787408 10.944 100 88 175 1 1034977984 5893552 22297024
20:10:42 24229 8192000000 9973048912 10300096512 1055779488 11.416 100 88 176 0 1086566000 8591472 22195040
20:12:12 20448 8192000000 10002378720 10340745216 1084253104 11.688 100 81 162 0 1113722432 9813344 19655984
20:13:42 14738 8192000000 10055180816 10410541056 1142638224 12.240 100 74 148 0 1170126896 9960672 17528000
20:15:01 6582 8192000000 10094183344 10456768512 1184516400 12.632 100 82 164 0 1216423408 13542144 18364864
20:16:10 5623 8192000000 10139754336 10510344192 1221262352 12.973 100 85 169 0 1253477968 13889584 18326032
20:17:18 3305 8192000000 10171218736 10548031488 1252163072 13.258 100 87 173 1 1285661200 14685392 18812736
20:18:23 1570 8192000000 10200380320 10581204992 1285073248 13.559 100 88 176 1 1317968528 12951744 19943536
20:19:26 14760 8192000000 10199452944 10594598912 1294595504 13.646 100 67 132 2 1320411424 11245856 14570064
20:20:48 4577 8192000000 10197899392 10605121536 1308045792 13.768 100 38 76 1 1324393968 7810208 8537968
20:21:55 8847 8192000000 10195142176 10616336384 1316821072 13.848 100 28 56 1 1329607440 6387344 6399024
20:23:05 13290 8192000000 10204874528 10626207744 1324776848 13.920 100 9 18 1 1331793904 5164304 1852752
20:24:19 38295 8192000000 10212560192 10637758464 1327278000 13.943 100 22 44 2 1337672368 5465376 4928992
20:25:59 4248 8192000000 10228082960 10650914816 1335479552 14.017 100 28 56 0 1348561152 6934400 6147200
20:27:05 8949 8192000000 10210098544 10633990144 1296123280 13.660 97 23 45 1 1307567840 5963808 5480752
20:28:18 70087 8192000000 10183157440 10592296960 1186671408 12.652 90 26 52 2 1197633376 4401248 6560720
20:30:32 5907 8192000000 10152674848 10554695680 1239776400 13.144 98 29 58 1 1252963040 6630240 6556400
20:31:40 8585 8192000000 10150162192 10552565760 1247060656 13.211 99 33 66 1 1261363664 6162736 8140272
20:32:49 57496 8192000000 10152030640 10559614976 1255985136 13.293 99 30 60 2 1269393088 6123024 7284928
20:34:48 7176 8192000000 10185185760 10586877952 1284204544 13.551 100 39 78 0 1300067264 7044528 8818192
20:35:58 9830 8192000000 10194157712 10612989952 1308949040 13.777 100 30 60 1 1321651744 5663520 7039184
20:37:09 9002 8192000000 10204353904 10624487424 1317156960 13.851 100 17 33 1 1326804912 5566896 4081056
20:38:24 36820 8192000000 10248374912 10660290560 1338235248 14.041 100 52 105 2 1357215840 6267104 12713488
20:40:09 20847 8192000000 10277730336 10691858432 1365122080 14.283 100 53 106 3 1390409312 13622000 11665232
20:41:41 6281 8192000000 10298104896 10713153536 1377789664 14.397 100 58 116 0 1405589696 13327808 14472224
20:42:56 35665 8192000000 10299853744 10730008576 1385560592 14.466 100 40 80 1 1410730400 15984928 9184880
20:44:45 12462 8192000000 10322543104 10746388480 1396635312 14.565 100 63 122 0 1433825520 22212720 14977488
20:46:03 10484 8192000000 10333226304 10758434816 1408998032 14.675 100 60 121 2 1445373968 22775424 13600512
20:47:18 11285 8192000000 10330377648 10753675264 1396267600 14.562 100 63 124 3 1436870208 24513392 16089216
20:48:36 3690 8192000000 10354855184 10773712896 1413868944 14.718 100 68 136 0 1459506256 29292160 16345152
20:49:43 6100 8192000000 10352636656 10767618048 1401038064 14.604 99 74 148 1 1447382448 29307216 17037168
20:50:53 1206 8192000000 10355984416 10770575360 1409034224 14.675 100 81 160 4 1460361792 31117248 20210320
20:52:01 3895 8192000000 10323749792 10734321664 1376625408 14.386 99 63 125 1 1425934384 33628112 15680864
20:53:08 7928 8192000000 10355126256 10771886080 1404801584 14.638 100 68 134 1 1459734080 37660880 17271616
20:54:21 3994 8192000000 10335832960 10741612544 1365985296 14.291 99 88 176 0 1435163440 46209696 22968448
20:55:43 4470 8192000000 10310521536 10714542080 1351136224 14.158 100 71 142 0 1415451088 46134256 18180608
20:56:55 6168 8192000000 10324322464 10739752960 1372635664 14.351 100 52 104 1 1430999600 43237824 15126112
20:58:17 4128 8192000000 10348205968 10761977856 1388710080 14.494 100 67 134 1 1449073648 43859232 16504336
20:59:24 6043 8192000000 10347373552 10771005440 1397788208 14.575 100 50 100 0 1451509552 40253600 13467744
21:00:42 27494 8192000000 10332474512 10775625728 1399753072 14.593 100 29 58 3 1441028128 33064176 8210880
21:02:14 22127 8192000000 10322375712 10764652544 1384974560 14.461 100 24 48 0 1434218800 42219536 7024704
21:03:43 41529 8192000000 10284779504 10727985152 1342239312 14.078 99 10 20 0 1376251408 31023840 2988256
21:05:28 92593 8192000000 10271064912 10712739840 1321498416 13.890 97 28 56 1 1346359888 18551024 6310448
21:08:07 110903 8192000000 10272071440 10727391232 1344568640 14.099 98 20 40 0 1361352080 11986672 4796768
21:11:01 12597 8192000000 10282992560 10722918400 1363975120 14.273 100 16 31 1 1378806976 11132000 3699856
21:12:15 30623 8192000000 10278051168 10716491776 1363705200 14.271 99 34 66 1 1383956688 12273072 7978416
21:13:49 42756 8192000000 10271853456 10730311680 1377541744 14.395 100 21 42 1 1397307088 14800496 4964848
21:15:33 62428 8192000000 10273525936 10732064768 1369488592 14.322 99 12 25 0 1382132000 10047600 2595808
21:17:36 21955 8192000000 10279213408 10736111616 1363938416 14.273 98 16 31 2 1376524560 8956704 3629440
21:18:59 55539 8192000000 10277782960 10735927296 1372468512 14.349 99 15 30 0 1390569232 14753968 3346752
21:20:55 56640 8192000000 10283342928 10739347456 1359358800 14.232 98 13 26 1 1374286672 11745680 3182192
21:23:00 89500 8192000000 10288080784 10755903488 1391441456 14.519 100 14 28 0 1404596480 9885712 3269312
21:25:32 95048 8192000000 10306457600 10766319616 1386561936 14.475 99 12 24 1 1402767760 13760752 2445072
21:28:08 34179 8192000000 10323642304 10758696960 1387703280 14.485 99 32 62 0 1405980768 11266640 7010848
21:29:52 64381 8192000000 10315358704 10781171712 1390257744 14.508 99 20 40 0 1408604176 13786720 4559712
21:31:58 92897 8192000000 10323066224 10779496448 1362346912 14.258 96 16 32 0 1378513168 12785632 3380624
21:34:37 67859 8192000000 10321655280 10788298752 1393265024 14.535 99 14 28 0 1421718944 24697728 3756192
21:38:40 17259 8192000000 10298482864 10748559360 1393530928 14.537 100 33 61 2 1407894752 7668128 6695696
21:39:58 70667 8192000000 10271383232 10743599104 1355003344 14.192 97 18 36 0 1373797840 14916688 3877808
21:42:13 50359 8192000000 10256498768 10710994944 1313198928 13.815 95 11 21 1 1323299040 7904048 2196064
21:44:04 43735 8192000000 10242161360 10707324928 1315514352 13.836 97 5 10 0 1324376640 8083040 779248
21:45:48 20599 8192000000 10275040464 10723328000 1369582656 14.323 100 49 99 2 1388036896 6521792 11932448
21:47:10 69625 8192000000 10241816208 10685485056 1303111728 13.724 96 13 26 0 1313298912 7963280 2223904
21:49:20 38054 8192000000 10264174800 10710503424 1350862528 14.155 99 17 34 0 1362361072 7853920 3644624
21:51:04 28610 8192000000 10268735824 10708021248 1352481136 14.170 100 36 71 1 1371009168 10527552 8000480
21:52:36 85475 8192000000 10270475280 10723364864 1364654048 14.279 100 20 39 0 1380436000 11322304 4459648
21:55:02 36514 8192000000 10281095888 10739290112 1371282912 14.339 100 19 38 2 1390201408 14651152 4267344
21:56:46 104424 8192000000 10281601904 10725941248 1361207984 14.248 99 9 18 0 1373457360 10511792 1737584
21:59:30 107303 8192000000 10262008832 10710396928 1315931168 13.840 96 12 24 1 1329105680 10644000 2530512
22:02:29 20714 8192000000 10269695136 10717859840 1348155744 14.131 99 24 48 0 1368537520 15004624 5377152
22:03:50 96373 8192000000 10267435168 10736410624 1354634192 14.189 99 7 14 0 1375929072 19342032 1952848
22:06:28 62192 8192000000 10271589264 10728042496 1350487344 14.152 98 17 34 2 1365710384 11175232 4047808
22:10:01 59897 8192000000 10279497040 10734833664 1340368240 14.061 96 24 46 0 1354361392 8616368 5376784
22:12:29 45972 8192000000 10307764528 10742259712 1378091120 14.399 99 24 48 1 1390134144 6732384 5310640
22:14:15 17759 8192000000 10302195120 10746486784 1390224336 14.508 100 35 70 0 1407492960 9242928 8025696
22:15:39 19458 8192000000 10322768048 10755776512 1390717520 14.512 100 63 126 3 1415388112 11024976 13645616
22:17:01 94240 8192000000 10290936224 10752643072 1357226624 14.212 97 12 24 1 1376303824 16428880 2648320
22:19:36 161285 8192000000 10279782848 10733481984 1254162080 13.276 90 9 18 0 1270654640 14777024 1715536
22:23:18 92872 8192000000 10269754432 10725801984 1351559120 14.162 99 34 68 2 1370330400 11109216 7662064
22:25:51 53673 8192000000 10269472400 10738057216 1351936576 14.165 99 8 16 1 1375952736 22533792 1482368
22:27:45 66635 8192000000 10255778960 10707849216 1303034000 13.723 95 8 16 0 1313251776 8258064 1959712
22:29:52 75294 8192000000 10277749424 10729283584 1366799904 14.298 100 24 48 0 1384443120 12183568 5459648
22:32:17 60293 8192000000 10273834208 10740129792 1334309280 14.006 97 15 30 1 1350852064 13151904 3390880
22:34:30 72867 8192000000 10276555008 10727337984 1330911568 13.975 96 24 48 1 1354301792 17562240 5827984
22:36:43 36244 8192000000 10294953296 10745606144 1379131744 14.409 100 18 36 1 1396725408 13622288 3971376

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-2021 22:39:33 to 18-12-2021 22:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-12-2021 22:39:49 to 18-12-2021 22:39:51.

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          242526
MON$OLDEST_ACTIVE               247049
MON$OLDEST_SNAPSHOT             247049
MON$NEXT_TRANSACTION            247049
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                       2016448
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.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 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 ---
:92: 
:93: mscs-test-161.budzdorov.ru    Sat Dec 18 22:38:14 2021
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Sat Dec 18 22:38:14 2021
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Sat Dec 18 22:38:14 2021
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:103: 
--- end of diff output ---

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

18.12.21 22:39:52. 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-18 22:39:54 No severe PSQL-related problems occured
18.12.21 22:39:56. End of report.