Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.11.2021 01: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 16874420 kB
MemAvailable 31527308 kB
Buffers 0 kB
Cached 14765348 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.8
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-11-23 01:37:56
DTS_END                         2021-11-23 01:37:56.6820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49240
TRN_ID                          238928
ATT_ID                          690
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: 9501.88 24241 1140225
customer order: creation 175.32 365 21038
customer order: refuse 14.88 348 1786
order to supplier: creation 47.13 1286 5655
order to supplier: removal 3.31 110686 397
invoice (draft): creation 47.53 3426 5703
invoice (draft): removal 52.36 771 6283
invoice accept: apply 43.65 17937 5238
invoice accept: cancel 47.57 789 5708
customer reserve: creation 4051.33 176 486159
customer reserve: removal 4716.31 61 565957
realization accept: apply 86.17 171 10340
realization accept: cancel 82.47 113 9896
payment from customer: creation 60.26 118 7231
payment from customer: removal 6.13 75 735
payment to supplier: creation 51.09 139 6131
payment to supplier: removal 3.64 113 437
service: total inventory turnovers 4.97 954 597
service: total monetary turnovers 6.88 37 825
service: aggregate perf. data 0.88 22592 105
service: refresh index statistics 0.03 324660 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-11-2021 01:38:33 to 23-11-2021 01:38:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1937
TEST_TIME 2 2921
TEST_TIME 3 4298
TEST_TIME 4 4575
TEST_TIME 5 4794
TEST_TIME 6 4877
TEST_TIME 7 5682
TEST_TIME 8 5690
TEST_TIME 9 5712
TEST_TIME 10 5887
TEST_TIME 11 6089
TEST_TIME 12 6226
TEST_TIME 13 6361
TEST_TIME 14 7152
TEST_TIME 15 7219
TEST_TIME 16 7291
TEST_TIME 17 7411
TEST_TIME 18 7845
TEST_TIME 19 8374
TEST_TIME 20 8595
TEST_TIME 21 8654
TEST_TIME 22 9165
TEST_TIME 23 8961
TEST_TIME 24 9136
TEST_TIME 25 9474
TEST_TIME 26 9663
TEST_TIME 27 9926
TEST_TIME 28 10984
TEST_TIME 29 11220
TEST_TIME 30 10851
TEST_TIME 31 10701
TEST_TIME 32 10440
TEST_TIME 33 10471
TEST_TIME 34 10436
TEST_TIME 35 10364
TEST_TIME 36 10381
TEST_TIME 37 10271
TEST_TIME 38 10174
TEST_TIME 39 10018
TEST_TIME 40 9952
TEST_TIME 41 9735
TEST_TIME 42 9658
TEST_TIME 43 9785
TEST_TIME 44 9835
TEST_TIME 45 9755
TEST_TIME 46 9761
TEST_TIME 47 9806
TEST_TIME 48 9726
TEST_TIME 49 9765
TEST_TIME 50 9762
TEST_TIME 51 9769
TEST_TIME 52 9737
TEST_TIME 53 9771
TEST_TIME 54 9767
TEST_TIME 55 9771
TEST_TIME 56 9622
TEST_TIME 57 9537
TEST_TIME 58 9555
TEST_TIME 59 9453
TEST_TIME 60 9444
TEST_TIME 61 9425
TEST_TIME 62 9430
TEST_TIME 63 9383
TEST_TIME 64 9359
TEST_TIME 65 9423
TEST_TIME 66 9469
TEST_TIME 67 9535
TEST_TIME 68 9500
TEST_TIME 69 9501
TEST_TIME 70 9538
TEST_TIME 71 9527
TEST_TIME 72 9566
TEST_TIME 73 9530
TEST_TIME 74 9623
TEST_TIME 75 9648
TEST_TIME 76 9674
TEST_TIME 77 9662
TEST_TIME 78 9673
TEST_TIME 79 9596
TEST_TIME 80 9585
TEST_TIME 81 9508
TEST_TIME 82 9503
TEST_TIME 83 9503
TEST_TIME 84 9459
TEST_TIME 85 9400
TEST_TIME 86 9365
TEST_TIME 87 9305
TEST_TIME 88 9321
TEST_TIME 89 9370
TEST_TIME 90 9368
TEST_TIME 91 9426
TEST_TIME 92 9434
TEST_TIME 93 9430
TEST_TIME 94 9425
TEST_TIME 95 9522
TEST_TIME 96 9540
TEST_TIME 97 9527
TEST_TIME 98 9650
TEST_TIME 99 9608
TEST_TIME 100 9620
TEST_TIME 101 9634
TEST_TIME 102 9672
TEST_TIME 103 9594
TEST_TIME 104 9543
TEST_TIME 105 9505
TEST_TIME 106 9513
TEST_TIME 107 9454
TEST_TIME 108 9409
TEST_TIME 109 9387
TEST_TIME 110 9391
TEST_TIME 111 9346
TEST_TIME 112 9356
TEST_TIME 113 9391
TEST_TIME 114 9416
TEST_TIME 115 9409
TEST_TIME 116 9403
TEST_TIME 117 9407
TEST_TIME 118 9503
TEST_TIME 119 9527
TEST_TIME 120 9503

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-11-2021 01:38:36 to 23-11-2021 01:38:40.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1587166 1587166 0 0.00 0 35048 6 0 0
doc_list_biud 1587166 1587166 0 0.00 0 41535 28 0 0
sp_add_invoice_to_stock 5395 5238 157 2.91 44 186158 17938 0 157
sp_cancel_adding_invoice 5708 5708 0 0.00 4 55313 789 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 12929 349 0 0
sp_cancel_customer_reserve 565957 565957 0 0.00 1 34319 62 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 2 7936 76 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 6297 113 0 0
sp_cancel_supplier_invoice 6295 6283 12 0.19 1 14620 772 0 12
sp_cancel_supplier_order 397 397 0 0.00 8 1094370 110687 0 0
sp_cancel_write_off 9896 9896 0 0.00 1 18057 114 0 0
sp_client_order 21038 21038 0 0.00 3 35076 365 0 0
sp_customer_reserve 486159 486159 0 0.00 0 97143 176 0 0
sp_fill_shopping_cart 32380 32380 0 0.00 1 13351 42 0 0
sp_fill_shopping_cart_clo_res 486151 486151 0 0.00 0 17762 14 0 0
sp_get_clo_for_invoice 5223 5223 0 0.00 0 39231 337 0 0
sp_kill_cost_storno 16776 16776 0 0.00 0 35038 56 0 0
sp_kill_qstorno_ret_qs2qd 1148490 1148490 0 0.00 0 34231 33 0 0
sp_kill_qty_storno 605589 605589 0 0.00 0 41533 72 0 0
sp_lock_selected_doc 21465 21465 0 0.00 0 6431 4 0 0
sp_make_cost_storno 28924 28924 0 0.00 0 34697 51 0 0
sp_multiply_rows_for_pdistr 28924 28924 0 0.00 0 8439 28 0 0
sp_multiply_rows_for_qdistr 21038 21038 0 0.00 0 10586 153 0 0
sp_pay_from_customer 7231 7231 0 0.00 4 35001 119 0 0
sp_pay_to_supplier 6131 6131 0 0.00 5 35040 140 0 0
sp_payment_common 13362 13362 0 0.00 1 13049 28 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 12919 322 0 0
sp_qd_handle_on_invoice_upd_sts 10930 10930 0 0.00 0 41530 332 0 0
sp_qd_handle_on_reserve_upd_sts 10340 10340 0 0.00 0 10573 53 0 0
sp_reserve_write_off 10340 10340 0 0.00 6 34723 172 0 0
sp_supplier_invoice 5703 5703 0 0.00 7 111941 3427 0 0
sp_supplier_order 5655 5655 0 0.00 5 37140 1286 0 0
srv_aggregate_perf_data 105 105 0 0.00 2247 533427 22593 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1988 163194 47884 0 0
srv_find_qd_qs_mism 1089122 1089122 0 0.00 0 23900 6 0 0
srv_make_invnt_saldo 597 597 0 0.00 20 40469 954 0 0
srv_make_money_saldo 825 825 0 0.00 0 7087 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 176832 489773 324660 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5238 5238 0 0.00 0 14227 159 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 3142 98 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 1078 9 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 2267 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 6715 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 876 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 7322 35 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 27577 1473 0 0
t$perf-norm:sp_cancel_write_off 1055 1055 0 0.00 0 442 3 0 0
t$perf-norm:sp_client_order 21038 21038 0 0.00 0 7279 15 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 1066 4 0 0
t$perf-norm:sp_pay_from_customer 7231 7231 0 0.00 0 7151 11 0 0
t$perf-norm:sp_pay_to_supplier 6131 6131 0 0.00 0 7229 10 0 0
t$perf-norm:sp_reserve_write_off 10340 10340 0 0.00 0 7406 13 0 0
t$perf-norm:sp_supplier_invoice 5703 5703 0 0.00 0 6516 22 0 0
t$perf-norm:sp_supplier_order 5655 5655 0 0.00 0 3131 14 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 386 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 117 2 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 990 23 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 747 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10752 10438 314 2.92 0 10708 21 0 314
v_all_customers 38142 38142 0 0.00 0 20 1 0 0
v_all_suppliers 31548 31548 0 0.00 0 15 1 0 0
v_all_wares 42076 42076 0 0.00 0 69 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 639 6 0 0
v_cancel_client_order 3572 3572 0 0.00 0 6155 7 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 434 3 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 2389 6 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 6158 15 0 0
v_cancel_supplier_invoice 816 792 24 2.94 0 2198 17 0 24
v_cancel_supplier_order 794 794 0 0.00 0 2773 7 0 0
v_cancel_write_off 2110 2110 0 0.00 0 6246 17 0 0
v_max_id_clo_ord 5655 5655 0 0.00 0 2278 2 0 0
v_max_id_clo_res 992 992 0 0.00 0 1248 7 0 0
v_max_id_ord_sup 5687 5687 0 0.00 0 3765 3 0 0
v_max_non_paid_invoice 1699 1699 0 0.00 0 384 2 0 0
v_max_non_paid_realizn 3975 3975 0 0.00 0 2933 4 0 0
v_min_id_clo_ord 5655 5655 0 0.00 0 2743 5 0 0
v_min_id_clo_res 992 992 0 0.00 0 2624 7 0 0
v_min_id_ord_sup 5687 5687 0 0.00 0 4341 7 0 0
v_min_non_paid_invoice 6131 6131 0 0.00 0 13045 18 0 0
v_min_non_paid_realizn 7231 7231 0 0.00 0 8416 25 0 0
v_our_firm 10446 10446 0 0.00 0 14 1 0 0
v_reserve_write_off 20680 20680 0 0.00 0 10041 8 0 0
x_lock_depdocs_on_canc_invoice 11595 11595 0 0.00 0 16117 112 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 7059 365 0 0
x_make_qstorno_1000_1200 5655 5655 0 0.00 2 37056 1205 0 0
x_make_qstorno_1200_2000 5703 5703 0 0.00 3 111502 3268 0 0
x_make_qstorno_2100_3300 486159 486159 0 0.00 0 97130 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-11-2021 01:38:41 to 23-11-2021 01:38:45.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:59 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:59 22021 8192000000 9901250096 10213294080 986414080 10.747 100 90 180 0 1016872320 6354752 24103488
23:10:27 12172 8192000000 9944434496 10267721728 1028420112 11.153 100 82 164 0 1057989040 8423360 21145568
23:11:48 18416 8192000000 9989595824 10321141760 1068874752 11.541 100 84 168 0 1099626320 10070432 20681136
23:13:10 11344 8192000000 10030780544 10376519680 1116510384 11.994 100 80 160 1 1146636128 11214192 18911552
23:14:28 8516 8192000000 10068780144 10425188352 1154174560 12.349 100 76 152 2 1183597424 11837072 17585792
23:15:40 4023 8192000000 10104486000 10471944192 1194264368 12.723 100 76 152 0 1223566784 12487072 16815344
23:16:46 5798 8192000000 10152690928 10529697792 1241347152 13.159 100 85 170 0 1272997264 12928768 18721344
23:17:54 2251 8192000000 10187756576 10571497472 1276711456 13.483 100 87 175 1 1309098672 13002560 19384656
23:18:58 1852 8192000000 10216270544 10600583168 1297784496 13.675 100 95 184 1 1333209616 14728336 20696784
23:20:01 11412 8192000000 10215757072 10612518912 1308467888 13.772 100 64 126 0 1331746496 8847552 14431056
23:21:14 2591 8192000000 10214449824 10622193664 1315994816 13.840 100 54 108 1 1337514160 9811904 11707440
23:22:21 3086 8192000000 10219766576 10629271552 1323064592 13.904 100 33 68 0 1337602208 6714784 7822832
23:23:26 30922 8192000000 10211467136 10637082624 1325457776 13.926 100 22 43 2 1335365168 5043696 4863696
23:25:03 5819 8192000000 10235718144 10651103232 1336207328 14.023 100 35 69 0 1351961632 8000000 7754304
23:26:10 10562 8192000000 10225474528 10651103232 1327068912 13.941 99 17 34 0 1336878560 6027120 3782528
23:27:23 65096 8192000000 10236831792 10647506944 1324556352 13.918 99 41 82 3 1339705584 5428288 9720944
23:29:29 6923 8192000000 10191662416 10596646912 1267313536 13.397 97 22 44 0 1277903664 4995472 5594656
23:30:38 9018 8192000000 10171127904 10576928768 1255273424 13.287 98 27 54 1 1267835376 6221200 6340752
23:31:48 7493 8192000000 10135260960 10544906240 1204980320 12.823 95 18 35 4 1214419152 5017216 4421616
23:32:57 32815 8192000000 10128309696 10534748160 1237905712 13.127 99 33 66 1 1251327920 5288208 8134000
23:34:30 7654 8192000000 10179105728 10578792448 1274006976 13.458 100 44 88 3 1290488848 7121264 9360608
23:35:39 4578 8192000000 10184270400 10589777920 1290248352 13.606 100 33 66 2 1303246016 5419872 7577792
23:36:47 12801 8192000000 10188356320 10604646400 1297622000 13.674 100 19 39 1 1307310992 5185056 4503936
23:38:03 18028 8192000000 10226958768 10637180928 1320612000 13.882 100 55 109 1 1339789824 7307184 11870640
23:39:34 21575 8192000000 10255507424 10666356736 1340635568 14.063 100 54 109 1 1369378880 17090032 11653280
23:40:59 15432 8192000000 10276533680 10693414912 1357858544 14.218 100 54 108 1 1396388848 26683312 11846992
23:42:28 9214 8192000000 10281859904 10700554240 1371495488 14.340 100 39 78 0 1397922784 17024864 9402432
23:43:42 37083 8192000000 10306206688 10728955904 1383194544 14.445 100 53 106 0 1420546592 25102832 12249216
23:45:24 10101 8192000000 10326183984 10746482688 1398938560 14.586 100 49 96 2 1439362256 28855232 11568464
23:46:38 3032 8192000000 10347099552 10765131776 1409614400 14.681 100 68 135 0 1452153104 26713856 15824848
23:47:48 7406 8192000000 10346741776 10773680128 1403967920 14.630 99 46 90 1 1447138880 31899728 11271232
23:49:01 5164 8192000000 10364413264 10787901440 1420619424 14.778 100 60 120 0 1476113296 40905776 14588096
23:50:11 4670 8192000000 10362266928 10779238400 1394405600 14.545 99 75 149 3 1463040656 50063792 18571264
23:51:21 2900 8192000000 10371954592 10783342592 1407369840 14.661 100 73 145 0 1473602704 48579728 17653136
23:52:29 1986 8192000000 10359025344 10764443648 1398622736 14.583 100 94 186 1 1464018016 41956320 23438960
23:53:39 3874 8192000000 10381772704 10793177088 1414296752 14.722 100 87 172 2 1487210256 50863680 22049824
23:54:53 5146 8192000000 10390306704 10804670464 1411882624 14.701 100 78 156 0 1489718016 57041712 20793680
23:56:07 4513 8192000000 10350440688 10766639104 1379507472 14.412 99 69 138 1 1455136944 57263808 18365664
23:57:17 4131 8192000000 10351577744 10775728128 1397532768 14.573 100 51 101 0 1461269856 50354016 13383072
23:58:29 3162 8192000000 10340634128 10759061504 1376198432 14.383 100 54 108 0 1444403760 53360800 14844528
23:59:42 19361 8192000000 10353779744 10797101056 1397548816 14.573 100 38 74 1 1467895600 60519376 9827408
00:01:08 17431 8192000000 10361682512 10791165952 1396814336 14.567 100 42 84 0 1470076736 61125040 12137360
00:02:35 35616 8192000000 10341152144 10787000320 1386779968 14.477 99 30 61 0 1450954432 55699184 8475280
00:04:33 25377 8192000000 10326502160 10778370048 1371501280 14.340 100 20 40 1 1431786592 53337744 6947568
00:05:59 70130 8192000000 10269325296 10714816512 1308644640 13.774 95 19 40 1 1325573248 12294112 4634496
00:08:59 48052 8192000000 10274690384 10712588288 1352862320 14.173 99 40 77 0 1373478608 11790528 8825760
00:10:51 18910 8192000000 10292327360 10731413504 1374211552 14.365 100 21 41 0 1390384864 11115472 5057840
00:12:18 64811 8192000000 10295424576 10749390848 1373758832 14.361 99 21 43 0 1394748816 16458144 4531840
00:14:23 121671 8192000000 10286155056 10742284288 1352842432 14.173 97 13 26 0 1370845936 14742528 3260976
00:17:26 45624 8192000000 10285472960 10740150272 1355663856 14.198 98 20 40 2 1378356960 18148544 4544560
00:20:09 81203 8192000000 10265154624 10734542848 1319357712 13.871 96 10 20 0 1343139632 21183408 2598512
00:22:31 48845 8192000000 10294916768 10734657536 1374751168 14.370 100 38 76 0 1395159856 12246800 8161888
00:24:23 74128 8192000000 10292401424 10748260352 1360361248 14.241 98 15 30 0 1376251552 12917696 2972608
00:26:37 60100 8192000000 10307628208 10755481600 1390752384 14.513 100 26 52 1 1409353104 11927936 6672784
00:28:40 37321 8192000000 10315033328 10759774208 1384661536 14.458 99 35 69 0 1401858864 9611552 7585776
00:30:22 134833 8192000000 10299688176 10763481088 1354023568 14.184 97 12 24 0 1369282400 12639952 2618880
00:33:37 71852 8192000000 10300486848 10757341184 1391040224 14.515 100 31 62 0 1408855440 10754512 7060704
00:35:50 62155 8192000000 10302644624 10756923392 1397511984 14.573 100 34 68 1 1413946512 8889872 7544656
00:37:53 55730 8192000000 10305662624 10765869056 1368087360 14.310 97 18 36 0 1393649024 21359696 4201968
00:39:53 32877 8192000000 10299873008 10764554240 1399495136 14.591 100 6 12 0 1416282144 15375008 1412000
00:41:32 44705 8192000000 10291908128 10770231296 1371301680 14.339 98 9 17 0 1386187424 13279184 1606560
00:44:12 63165 8192000000 10259779408 10717757440 1288265392 13.588 93 15 28 1 1302300736 10149696 3885648
00:46:15 65826 8192000000 10267345472 10711261184 1335194496 14.014 98 25 50 0 1352285728 11668416 5422816
00:48:22 91553 8192000000 10269958192 10726735872 1315013952 13.832 96 16 32 0 1328858688 10229488 3615248
00:51:02 163194 8192000000 10265627984 10720620544 1278663600 13.501 93 6 12 0 1298362112 18389792 1308720
00:54:51 78210 8192000000 10282156928 10728275968 1349911024 14.147 98 25 49 0 1368332304 13213776 5207504
00:57:09 61911 8192000000 10261325024 10705604608 1315103280 13.832 96 29 58 2 1330351472 8837744 6410448
00:59:12 31904 8192000000 10245657744 10692501504 1329618416 13.964 98 54 106 1 1350332816 8210320 12504080
01:00:45 62004 8192000000 10236562256 10688049152 1330269440 13.970 99 11 22 0 1341177040 8234096 2673504
01:02:47 48620 8192000000 10241041248 10691624960 1334045680 14.004 99 16 32 1 1347358960 9495040 3818240
01:04:36 43253 8192000000 10253998592 10706268160 1325103056 13.923 98 20 39 1 1338753392 9527504 4122832
01:06:52 67123 8192000000 10266106960 10714828800 1333968320 14.003 98 10 20 0 1347748064 11214864 2564880
01:09:00 58362 8192000000 10267960752 10722045952 1340229552 14.059 97 19 39 1 1353322928 9107840 3985536
01:11:00 47660 8192000000 10303973008 10747129856 1384083056 14.453 100 32 64 0 1407514464 16301552 7129856
01:13:01 83603 8192000000 10313309072 10771451904 1369462528 14.322 99 7 14 0 1384539376 13612352 1464496
01:15:26 71605 8192000000 10294448368 10743562240 1383033296 14.444 100 13 26 1 1411640576 25561392 3045888
01:17:37 83810 8192000000 10300375296 10753634304 1370909072 14.335 99 11 22 0 1388763328 15471328 2382928
01:20:02 101344 8192000000 10296370352 10751815680 1378923760 14.407 99 22 44 0 1391289456 7674816 4690880
01:22:49 65074 8192000000 10293487008 10769526784 1387185040 14.481 99 9 18 0 1401760432 12801744 1773648
01:24:54 31512 8192000000 10296577504 10771746816 1392303392 14.526 99 5 10 0 1404554720 10718688 1532640
01:26:26 57251 8192000000 10294882432 10769739776 1400573392 14.600 100 7 14 1 1417515568 15054208 1887968
01:28:37 41186 8192000000 10289864544 10748567552 1369696816 14.324 98 24 48 1 1385772016 10701136 5374064
01:30:27 66179 8192000000 10274874128 10744803328 1352047152 14.166 98 23 46 1 1372744544 15013280 5684112
01:32:34 64210 8192000000 10237743584 10680274944 1243236992 13.176 90 24 49 1 1258285152 9682240 5365920
01:34:38 107628 8192000000 10264588448 10721366016 1324220768 13.915 98 18 36 0 1345546320 16972688 4352864
01:37:27 32301 8192000000 8958097600 9137385472 127649648 1.534 10 2 4 0 128764560 868480 246432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-11-2021 01:38:47 to 23-11-2021 01:39:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 157 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-11-2021 01:39:02 to 23-11-2021 01:39:04.

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          239422
MON$OLDEST_ACTIVE               239423
MON$OLDEST_SNAPSHOT             239423
MON$NEXT_TRANSACTION            239423
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                       2014976
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
--- end of diff output ---

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

23.11.21 01:39:05. 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-11-23 01:39:08 No severe PSQL-related problems occured
23.11.21 01:39:09. End of report.