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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-09 16:38:15
DTS_END                         2022-05-09 16:38:15.3750
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/36708
TRN_ID                          242951
ATT_ID                          707
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: 9453.74 21930 1134449
customer order: creation 172.53 380 20703
customer order: refuse 15.23 351 1828
order to supplier: creation 47.86 1332 5743
order to supplier: removal 3.72 107116 446
invoice (draft): creation 46.25 3456 5550
invoice (draft): removal 54.94 835 6593
invoice accept: apply 41.96 18171 5035
invoice accept: cancel 48.96 914 5875
customer reserve: creation 3870.97 180 464516
customer reserve: removal 4852.15 65 582258
realization accept: apply 83.79 184 10055
realization accept: cancel 83.54 121 10025
payment from customer: creation 59.85 120 7182
payment from customer: removal 5.41 88 649
payment to supplier: creation 50.09 131 6011
payment to supplier: removal 3.42 73 410
service: total inventory turnovers 4.93 1212 591
service: total monetary turnovers 7.38 30 885
service: aggregate perf. data 0.75 22875 90
service: refresh index statistics 0.03 280975 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-05-2022 16:40:46 to 09-05-2022 16:40:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3771
TEST_TIME 2 4703
TEST_TIME 3 5046
TEST_TIME 4 5528
TEST_TIME 5 5107
TEST_TIME 6 4811
TEST_TIME 7 5473
TEST_TIME 8 5661
TEST_TIME 9 5550
TEST_TIME 10 6087
TEST_TIME 11 6035
TEST_TIME 12 5951
TEST_TIME 13 6046
TEST_TIME 14 6340
TEST_TIME 15 7275
TEST_TIME 16 7176
TEST_TIME 17 7339
TEST_TIME 18 7312
TEST_TIME 19 7373
TEST_TIME 20 7548
TEST_TIME 21 8042
TEST_TIME 22 8433
TEST_TIME 23 8329
TEST_TIME 24 8450
TEST_TIME 25 9172
TEST_TIME 26 9210
TEST_TIME 27 10428
TEST_TIME 28 11379
TEST_TIME 29 11335
TEST_TIME 30 11159
TEST_TIME 31 10950
TEST_TIME 32 10858
TEST_TIME 33 10783
TEST_TIME 34 10996
TEST_TIME 35 10806
TEST_TIME 36 10699
TEST_TIME 37 10661
TEST_TIME 38 10553
TEST_TIME 39 10378
TEST_TIME 40 10283
TEST_TIME 41 10228
TEST_TIME 42 10303
TEST_TIME 43 10500
TEST_TIME 44 10371
TEST_TIME 45 10238
TEST_TIME 46 10256
TEST_TIME 47 10148
TEST_TIME 48 10097
TEST_TIME 49 10018
TEST_TIME 50 9864
TEST_TIME 51 9861
TEST_TIME 52 9826
TEST_TIME 53 9676
TEST_TIME 54 9744
TEST_TIME 55 9705
TEST_TIME 56 9639
TEST_TIME 57 9577
TEST_TIME 58 9836
TEST_TIME 59 9760
TEST_TIME 60 9909
TEST_TIME 61 9940
TEST_TIME 62 9966
TEST_TIME 63 9884
TEST_TIME 64 10048
TEST_TIME 65 10104
TEST_TIME 66 10025
TEST_TIME 67 10107
TEST_TIME 68 10122
TEST_TIME 69 10129
TEST_TIME 70 10026
TEST_TIME 71 9981
TEST_TIME 72 9913
TEST_TIME 73 9915
TEST_TIME 74 9869
TEST_TIME 75 9820
TEST_TIME 76 9755
TEST_TIME 77 9692
TEST_TIME 78 9677
TEST_TIME 79 9612
TEST_TIME 80 9597
TEST_TIME 81 9523
TEST_TIME 82 9534
TEST_TIME 83 9510
TEST_TIME 84 9537
TEST_TIME 85 9548
TEST_TIME 86 9605
TEST_TIME 87 9575
TEST_TIME 88 9619
TEST_TIME 89 9647
TEST_TIME 90 9636
TEST_TIME 91 9679
TEST_TIME 92 9797
TEST_TIME 93 9802
TEST_TIME 94 9799
TEST_TIME 95 9807
TEST_TIME 96 9734
TEST_TIME 97 9690
TEST_TIME 98 9654
TEST_TIME 99 9724
TEST_TIME 100 9697
TEST_TIME 101 9662
TEST_TIME 102 9632
TEST_TIME 103 9565
TEST_TIME 104 9549
TEST_TIME 105 9540
TEST_TIME 106 9531
TEST_TIME 107 9492
TEST_TIME 108 9534
TEST_TIME 109 9566
TEST_TIME 110 9558
TEST_TIME 111 9600
TEST_TIME 112 9610
TEST_TIME 113 9638
TEST_TIME 114 9654
TEST_TIME 115 9638
TEST_TIME 116 9679
TEST_TIME 117 9608
TEST_TIME 118 9582
TEST_TIME 119 9532
TEST_TIME 120 9455

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-05-2022 16:40:50 to 09-05-2022 16:40:53.

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 1566110 1566110 0 0.00 0 28243 6 0 0
doc_list_biud 1566109 1566109 0 0.00 0 29264 30 0 0
sp_add_invoice_to_stock 5172 5035 137 2.64 46 279166 18172 0 137
sp_cancel_adding_invoice 5875 5875 0 0.00 5 81313 915 0 0
sp_cancel_client_order 1828 1828 0 0.00 4 9461 351 0 0
sp_cancel_customer_reserve 582258 582258 0 0.00 1 22880 66 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 6917 89 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 1844 73 0 0
sp_cancel_supplier_invoice 6604 6593 11 0.16 1 18291 836 0 11
sp_cancel_supplier_order 446 446 0 0.00 10 1354577 107116 0 0
sp_cancel_write_off 10025 10025 0 0.00 1 21629 122 0 0
sp_client_order 20703 20703 0 0.00 3 20682 381 0 0
sp_customer_reserve 464516 464516 0 0.00 0 42927 181 0 0
sp_fill_shopping_cart 31989 31989 0 0.00 1 16758 40 0 0
sp_fill_shopping_cart_clo_res 464513 464513 0 0.00 0 16779 14 0 0
sp_get_clo_for_invoice 5031 5031 0 0.00 0 18086 257 0 0
sp_kill_cost_storno 16959 16959 0 0.00 0 21626 62 0 0
sp_kill_qstorno_ret_qs2qd 1181580 1181580 0 0.00 0 22877 35 0 0
sp_kill_qty_storno 622110 622110 0 0.00 0 29264 76 0 0
sp_lock_selected_doc 20989 20989 0 0.00 0 9917 6 0 0
sp_make_cost_storno 28279 28279 0 0.00 0 28227 59 0 0
sp_multiply_rows_for_pdistr 28278 28278 0 0.00 0 11125 30 0 0
sp_multiply_rows_for_qdistr 20703 20703 0 0.00 0 13555 179 0 0
sp_pay_from_customer 7182 7182 0 0.00 4 24472 120 0 0
sp_pay_to_supplier 6011 6011 0 0.00 5 11182 131 0 0
sp_payment_common 13193 13193 0 0.00 1 8333 24 0 0
sp_qd_handle_on_cancel_clo 1828 1828 0 0.00 0 9453 323 0 0
sp_qd_handle_on_invoice_upd_sts 10905 10905 0 0.00 0 29262 340 0 0
sp_qd_handle_on_reserve_upd_sts 10055 10055 0 0.00 0 12187 58 0 0
sp_reserve_write_off 10055 10055 0 0.00 6 18157 184 0 0
sp_supplier_invoice 5550 5550 0 0.00 5 96468 3457 0 0
sp_supplier_order 5743 5743 0 0.00 4 30427 1333 0 0
srv_aggregate_perf_data 90 90 0 0.00 2485 190322 22875 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1864 109015 41297 0 0
srv_find_qd_qs_mism 1086987 1086987 0 0.00 0 11263 5 0 0
srv_make_invnt_saldo 591 591 0 0.00 29 172551 1212 0 0
srv_make_money_saldo 885 885 0 0.00 1 3941 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 209861 400250 280975 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5036 5036 0 0.00 0 14042 177 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 1 17536 138 0 0
t$perf-norm:sp_cancel_client_order 1828 1828 0 0.00 0 10736 20 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 1384 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 1204 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 776 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 458 458 0 0.00 0 3156 15 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 26270 1418 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 4263 14 0 0
t$perf-norm:sp_client_order 20703 20703 0 0.00 0 10737 22 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 6706 16 0 0
t$perf-norm:sp_pay_from_customer 7182 7182 0 0.00 0 10746 19 0 0
t$perf-norm:sp_pay_to_supplier 6011 6011 0 0.00 0 29378 19 0 0
t$perf-norm:sp_reserve_write_off 10055 10055 0 0.00 0 10661 15 0 0
t$perf-norm:sp_supplier_invoice 5550 5550 0 0.00 0 8272 22 0 0
t$perf-norm:sp_supplier_order 5743 5743 0 0.00 0 6883 17 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 136 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 14 1 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 942 18 0 0
t$perf-norm:srv_make_money_saldo 885 885 0 0.00 0 1057 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 1673 421 0 0
v_add_invoice_to_stock 10334 10060 274 2.65 0 12768 23 0 274
v_all_customers 38160 38160 0 0.00 0 18 1 0 0
v_all_suppliers 31646 31646 0 0.00 0 23 1 0 0
v_all_wares 41402 41402 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 3507 13 0 0
v_cancel_client_order 3656 3656 0 0.00 0 6254 8 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 717 4 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 730 3 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 1663 5 0 0
v_cancel_supplier_invoice 938 916 22 2.34 0 3139 19 0 22
v_cancel_supplier_order 892 892 0 0.00 0 1617 7 0 0
v_cancel_write_off 1974 1974 0 0.00 0 877 11 0 0
v_max_id_clo_ord 5743 5743 0 0.00 0 6125 4 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 3235 15 0 0
v_max_id_ord_sup 5545 5545 0 0.00 0 5002 6 0 0
v_max_non_paid_invoice 1476 1476 0 0.00 0 6739 7 0 0
v_max_non_paid_realizn 3627 3627 0 0.00 0 1758 4 0 0
v_min_id_clo_ord 5743 5743 0 0.00 0 1926 7 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 3299 7 0 0
v_min_id_ord_sup 5545 5545 0 0.00 0 9954 13 0 0
v_min_non_paid_invoice 6011 6011 0 0.00 0 6732 13 0 0
v_min_non_paid_realizn 7182 7182 0 0.00 0 6766 19 0 0
v_our_firm 10352 10352 0 0.00 0 17 1 0 0
v_reserve_write_off 20110 20110 0 0.00 0 6957 8 0 0
x_lock_depdocs_on_canc_invoice 12010 12010 0 0.00 0 16863 119 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 5398 319 0 0
x_make_qstorno_1000_1200 5743 5743 0 0.00 1 30404 1241 0 0
x_make_qstorno_1200_2000 5550 5550 0 0.00 3 92542 3320 0 0
x_make_qstorno_2100_3300 464516 464516 0 0.00 0 40203 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-05-2022 16:40:54 to 09-05-2022 16:40:58.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:19 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:19 19976 8192000000 9887378736 10198081536 974972624 10.635 100 85 170 0 1003573888 5897936 22703328
14:10:45 18360 8192000000 9956516336 10276864000 1032688576 11.194 100 91 182 0 1068285840 12414240 23183024
14:12:05 22839 8192000000 9970638832 10302160896 1058710160 11.444 100 88 176 0 1090809808 10076576 22023072
14:13:32 6938 8192000000 10028352208 10370359296 1111532704 11.947 100 90 178 1 1143973216 11112928 21327584
14:14:48 11385 8192000000 10063285920 10416652288 1144224528 12.255 100 81 162 1 1177428336 14274816 18928992
14:16:01 7716 8192000000 10109163488 10476019712 1192508784 12.707 100 72 145 0 1222385232 12722112 17154336
14:17:14 6800 8192000000 10133819072 10510995456 1222784128 12.987 100 81 162 2 1254423776 13757760 17881888
14:18:22 2186 8192000000 10189770912 10568404992 1272038352 13.440 100 88 176 1 1304043616 12746848 19258416
14:19:26 1301 8192000000 10206969456 10591911936 1292978672 13.631 100 91 183 0 1325973104 12188192 20806240
14:20:29 5533 8192000000 10215280272 10603425792 1298514416 13.682 100 79 161 2 1327763088 11379376 17869296
14:21:36 9458 8192000000 10204543520 10614210560 1308161264 13.769 100 23 46 1 1323420144 10362208 4896672
14:22:49 14079 8192000000 10201865536 10618253312 1319337696 13.871 100 21 40 3 1329883472 5917312 4628464
14:24:08 46944 8192000000 10233443344 10642399232 1326118608 13.932 100 48 94 0 1342347904 5280688 10948608
14:26:20 5102 8192000000 10228532208 10643623936 1329621440 13.964 100 19 38 1 1340354912 6379728 4353744
14:27:26 9716 8192000000 10218680944 10634514432 1313341808 13.816 99 27 54 0 1326059472 6400256 6317408
14:28:38 9371 8192000000 10206766304 10624659456 1307671472 13.765 99 12 25 0 1315255168 5023408 2560288
14:29:50 29154 8192000000 10147386464 10552827904 1141023328 12.225 88 18 36 0 1150337328 4942880 4371120
14:31:20 7860 8192000000 10134660928 10531270656 1222624480 12.986 98 31 63 1 1235484608 5485024 7375104
14:32:29 5917 8192000000 10154364576 10561196032 1260793440 13.337 100 33 66 1 1275889760 6327792 8768528
14:33:37 13481 8192000000 10166127344 10575978496 1277578192 13.491 100 23 47 2 1288269888 5411840 5279856
14:34:52 25269 8192000000 10170256752 10584256512 1271343776 13.434 99 21 41 1 1282203424 6225376 4634272
14:36:17 6818 8192000000 10188098320 10605719552 1300478608 13.700 100 19 38 0 1309944048 5167728 4297712
14:37:26 12039 8192000000 10199912112 10621280256 1313728912 13.820 100 20 40 2 1328394640 9990064 4675664
14:38:48 8603 8192000000 10242189760 10655019008 1336022736 14.022 100 35 68 1 1349642496 5794096 7825664
14:40:09 11368 8192000000 10252534592 10670710784 1350512656 14.152 100 38 76 0 1371168784 11292128 9364000
14:41:22 19819 8192000000 10278144160 10695561216 1361547104 14.251 100 47 94 0 1384919696 12750784 10621808
14:42:46 9894 8192000000 10279676896 10701090816 1363816592 14.272 99 56 113 0 1391813040 14600560 13395888
14:44:01 12204 8192000000 10312597280 10730774528 1385223408 14.463 100 54 108 1 1421836880 24232608 12380864
14:45:19 6120 8192000000 10317245408 10743734272 1398484528 14.582 100 57 114 1 1435306160 22768976 14052656
14:46:28 15631 8192000000 10333545072 10760699904 1410417552 14.688 100 48 96 1 1447769376 25798512 11553312
14:47:48 1895 8192000000 10348621936 10769764352 1417421712 14.750 100 58 116 1 1457658560 26767552 13469296
14:48:55 11849 8192000000 10336696816 10766422016 1405817904 14.647 99 44 87 0 1440212320 23962832 10431584
14:50:15 7297 8192000000 10339225328 10764349440 1410223360 14.686 100 44 88 3 1454219952 32692144 11304448
14:51:27 2424 8192000000 10370255712 10782543872 1414883872 14.727 100 78 157 3 1474388400 40527664 18976864
14:52:32 1862 8192000000 10368391248 10786099200 1397262160 14.571 99 82 163 0 1467889952 50262656 20365136
14:53:40 4483 8192000000 10345266992 10761302016 1383435616 14.447 99 66 132 1 1439995648 40070704 16489328
14:54:50 5617 8192000000 10366926000 10777554944 1404556064 14.636 100 79 158 2 1472482992 48150224 19776704
14:56:02 3419 8192000000 10368889248 10780962816 1405864416 14.647 100 81 162 1 1475782320 48614224 21303680
14:57:14 3118 8192000000 10368414288 10770927616 1382394192 14.438 99 86 172 0 1458617120 53973760 22249168
14:58:21 2163 8192000000 10386383200 10797871104 1406906896 14.656 100 79 156 3 1489277664 62119328 20251440
14:59:36 11152 8192000000 10388965744 10823458816 1419066976 14.764 100 49 96 1 1498260128 65482384 13710768
15:01:00 10242 8192000000 10367518656 10794668032 1393915536 14.541 99 49 95 2 1462732368 55100512 13716320
15:02:24 16561 8192000000 10388425632 10822017024 1410934640 14.692 100 41 82 3 1493393008 70144640 12313728
15:04:02 21268 8192000000 10367015136 10812076032 1390177808 14.507 99 29 58 0 1463332528 64410704 8744016
15:05:38 42481 8192000000 10304626080 10767749120 1359213216 14.230 99 12 24 0 1408803184 45763904 3826064
15:07:22 76577 8192000000 10294527472 10730831872 1346989376 14.120 98 18 37 0 1363889040 12571456 4328208
15:09:41 107961 8192000000 10301710000 10747498496 1352368768 14.169 97 29 58 1 1371016880 11779680 6868432
15:12:58 63228 8192000000 10296368768 10725556224 1370760864 14.334 100 33 61 1 1383999232 6046160 7192208
15:15:03 37830 8192000000 10289893248 10737115136 1373535328 14.359 99 40 78 1 1395367920 13174176 8658416
15:16:47 78727 8192000000 10288224240 10738421760 1361408336 14.250 98 21 42 0 1379709344 13299968 5001040
15:19:08 42534 8192000000 10323794944 10756038656 1391739536 14.521 100 37 74 0 1412300016 11604000 8956480
15:20:57 43149 8192000000 10299802416 10775068672 1392153024 14.525 100 43 86 2 1421904304 19873072 9878208
15:23:39 57019 8192000000 10300816656 10768355328 1397960848 14.577 100 10 20 0 1412139632 11770416 2408368
15:25:58 64964 8192000000 10258906240 10729570304 1367687264 14.306 99 12 24 0 1380277056 9676272 2913520
15:28:10 50613 8192000000 10294914240 10746806272 1384038960 14.453 100 31 63 3 1403448448 12076992 7332496
15:30:01 42036 8192000000 10295018304 10764517376 1394599968 14.547 99 21 42 0 1413695344 13182528 5912848
15:31:55 61613 8192000000 10293599824 10775519232 1398069392 14.578 100 9 18 0 1415304160 15020464 2214304
15:34:02 29506 8192000000 10296205936 10750746624 1375176032 14.373 98 29 58 0 1391280544 9424320 6680192
15:35:32 41445 8192000000 10291280560 10763845632 1367324160 14.303 98 20 40 0 1393334112 20589088 5420864
15:37:21 53678 8192000000 10272985632 10733912064 1329223360 13.960 96 25 50 0 1348578816 13494112 5861344
15:39:15 39780 8192000000 10295828752 10749415424 1383485968 14.448 100 11 22 0 1398207248 12593296 2127984
15:40:59 60684 8192000000 10318359840 10762956800 1339305056 14.051 96 20 40 0 1358397808 15029472 4063280
15:43:00 65169 8192000000 10283256336 10728747008 1293319024 13.634 93 14 28 0 1311777200 15422592 3035584
15:45:08 28534 8192000000 10291871632 10738843648 1355177664 14.194 98 34 68 0 1377164944 14680400 7306880
15:46:38 58719 8192000000 10278954928 10739154944 1345040960 14.103 98 5 10 0 1361921872 15811840 1069072
15:48:40 58020 8192000000 10246827008 10689138688 1317711712 13.856 96 31 62 2 1331755312 7179712 6863888
15:50:39 20898 8192000000 10263042400 10714025984 1347159424 14.122 99 4 8 0 1358770096 10829184 781488
15:52:01 46824 8192000000 10265896832 10717585408 1347407760 14.124 98 30 60 0 1364800064 10287648 7104656
15:53:48 70308 8192000000 10265651488 10718449664 1346966528 14.120 98 11 22 0 1357407408 7811552 2629328
15:55:59 43509 8192000000 10257818640 10721599488 1367292336 14.303 100 10 20 0 1380132864 9635968 3204560
15:58:02 22121 8192000000 10266221600 10723323904 1373822560 14.361 100 43 86 1 1392068752 8837808 9408384
15:59:35 62892 8192000000 10264091264 10734235648 1359932000 14.237 99 7 14 0 1370928544 9043120 1953424
16:01:39 29088 8192000000 10283673968 10739961856 1383961952 14.452 100 23 46 1 1398508688 8969664 5577072
16:03:17 85647 8192000000 10283138176 10750525440 1361763168 14.253 98 15 30 1 1378619648 13360816 3495664
16:05:43 58757 8192000000 10273427936 10724376576 1324793472 13.920 96 34 68 1 1344112016 11729808 7588736
16:07:42 85225 8192000000 10297169264 10735980544 1310420848 13.790 95 18 34 2 1322652896 8810032 3422016
16:10:16 109015 8192000000 10285515312 10743713792 1342518224 14.080 97 8 16 0 1363007312 18893392 1595696
16:13:07 32596 8192000000 10302604176 10747707392 1380756832 14.423 100 31 62 1 1396588048 9096864 6734352
16:14:42 81363 8192000000 10301230496 10756231168 1374534576 14.368 99 25 50 1 1388439664 8035824 5869264
16:17:19 58642 8192000000 10280356336 10754084864 1360636848 14.243 98 11 22 1 1383056672 19878960 2540864
16:19:26 68534 8192000000 10274204128 10732507136 1379206096 14.409 100 11 23 0 1387765184 6298096 2260992
16:21:36 63859 8192000000 10268514544 10737364992 1372220656 14.347 99 10 20 1 1381674576 7316752 2137168
16:23:43 52025 8192000000 10290643408 10753748992 1387651184 14.485 100 19 38 0 1402951872 10936480 4364208
16:25:41 61056 8192000000 10297003040 10756104192 1369937136 14.326 99 26 52 4 1391995360 15583632 6474592
16:27:42 88717 8192000000 10288018192 10745651200 1342245840 14.078 97 16 33 0 1360015664 14149488 3620336
16:30:20 41973 8192000000 10299305056 10741616640 1378189200 14.400 100 24 48 0 1396549184 12724672 5635312
16:32:03 60769 8192000000 10275645968 10726543360 1322842656 13.902 96 22 44 0 1338506624 11130640 4533328
16:34:06 26382 8192000000 10284326736 10733477888 1344616528 14.099 98 34 68 0 1368871408 16872544 7382336
16:35:42 68472 8192000000 10237733360 10695491584 1321562416 13.891 97 22 44 1 1338084848 12127344 4395088
16:38:01 36878 8192000000 9929185168 10337267712 1055724272 11.416 77 2 4 0 1063458720 7649376 85072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-05-2022 16:41:00 to 09-05-2022 16:41:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-05-2022 16:41:16 to 09-05-2022 16:41:18.

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          242944
MON$OLDEST_ACTIVE               243305
MON$OLDEST_SNAPSHOT             243305
MON$NEXT_TRANSACTION            243305
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                       2040416
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

09.05.22 16:41:19. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-05-09 16:41:21 No severe PSQL-related problems occured
09.05.22 16:41:23. End of report.