Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.07.2022 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16007932 kB
MemAvailable 31050020 kB
Buffers 0 kB
Cached 15678148 kB
SwapCached 2492 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.11
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-07-19 19:38:30
DTS_END                         2022-07-19 19:38:30.2220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50244
TRN_ID                          239201
ATT_ID                          681
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: 9189.52 21540 1102742
customer order: creation 169.08 349 20290
customer order: refuse 14.33 330 1719
order to supplier: creation 45.05 1263 5406
order to supplier: removal 3.61 96777 433
invoice (draft): creation 44.96 3565 5395
invoice (draft): removal 52.14 761 6257
invoice accept: apply 40.69 18896 4883
invoice accept: cancel 47.64 793 5717
customer reserve: creation 3750.92 187 450110
customer reserve: removal 4723.33 58 566800
realization accept: apply 82.53 179 9904
realization accept: cancel 87.37 114 10484
payment from customer: creation 57.60 115 6912
payment from customer: removal 5.75 91 690
payment to supplier: creation 49.44 137 5933
payment to supplier: removal 3.65 77 438
service: total inventory turnovers 4.22 1334 507
service: total monetary turnovers 6.37 60 764
service: aggregate perf. data 0.80 21162 96
service: refresh index statistics 0.03 284546 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-07-2022 19:39:28 to 19-07-2022 19:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1361
TEST_TIME 2 2478
TEST_TIME 3 4114
TEST_TIME 4 4678
TEST_TIME 5 5162
TEST_TIME 6 5783
TEST_TIME 7 5741
TEST_TIME 8 5768
TEST_TIME 9 5858
TEST_TIME 10 5932
TEST_TIME 11 6061
TEST_TIME 12 6421
TEST_TIME 13 6419
TEST_TIME 14 6718
TEST_TIME 15 6991
TEST_TIME 16 7225
TEST_TIME 17 7396
TEST_TIME 18 7824
TEST_TIME 19 8320
TEST_TIME 20 8266
TEST_TIME 21 8690
TEST_TIME 22 9210
TEST_TIME 23 9080
TEST_TIME 24 9534
TEST_TIME 25 9477
TEST_TIME 26 9625
TEST_TIME 27 9816
TEST_TIME 28 10463
TEST_TIME 29 10408
TEST_TIME 30 10140
TEST_TIME 31 11123
TEST_TIME 32 10997
TEST_TIME 33 10781
TEST_TIME 34 10599
TEST_TIME 35 10422
TEST_TIME 36 10376
TEST_TIME 37 10265
TEST_TIME 38 10237
TEST_TIME 39 10226
TEST_TIME 40 10099
TEST_TIME 41 9943
TEST_TIME 42 9804
TEST_TIME 43 9774
TEST_TIME 44 9763
TEST_TIME 45 9709
TEST_TIME 46 9609
TEST_TIME 47 9467
TEST_TIME 48 9467
TEST_TIME 49 9445
TEST_TIME 50 9365
TEST_TIME 51 9243
TEST_TIME 52 9260
TEST_TIME 53 9163
TEST_TIME 54 9129
TEST_TIME 55 9332
TEST_TIME 56 9202
TEST_TIME 57 9108
TEST_TIME 58 9056
TEST_TIME 59 9312
TEST_TIME 60 9238
TEST_TIME 61 9353
TEST_TIME 62 9399
TEST_TIME 63 9403
TEST_TIME 64 9388
TEST_TIME 65 9493
TEST_TIME 66 9498
TEST_TIME 67 9494
TEST_TIME 68 9476
TEST_TIME 69 9473
TEST_TIME 70 9562
TEST_TIME 71 9562
TEST_TIME 72 9602
TEST_TIME 73 9602
TEST_TIME 74 9517
TEST_TIME 75 9489
TEST_TIME 76 9493
TEST_TIME 77 9495
TEST_TIME 78 9434
TEST_TIME 79 9399
TEST_TIME 80 9350
TEST_TIME 81 9379
TEST_TIME 82 9353
TEST_TIME 83 9373
TEST_TIME 84 9347
TEST_TIME 85 9307
TEST_TIME 86 9258
TEST_TIME 87 9222
TEST_TIME 88 9199
TEST_TIME 89 9197
TEST_TIME 90 9174
TEST_TIME 91 9160
TEST_TIME 92 9191
TEST_TIME 93 9206
TEST_TIME 94 9314
TEST_TIME 95 9298
TEST_TIME 96 9356
TEST_TIME 97 9334
TEST_TIME 98 9384
TEST_TIME 99 9355
TEST_TIME 100 9430
TEST_TIME 101 9426
TEST_TIME 102 9432
TEST_TIME 103 9472
TEST_TIME 104 9440
TEST_TIME 105 9394
TEST_TIME 106 9345
TEST_TIME 107 9338
TEST_TIME 108 9351
TEST_TIME 109 9315
TEST_TIME 110 9282
TEST_TIME 111 9253
TEST_TIME 112 9218
TEST_TIME 113 9207
TEST_TIME 114 9224
TEST_TIME 115 9216
TEST_TIME 116 9233
TEST_TIME 117 9223
TEST_TIME 118 9218
TEST_TIME 119 9208
TEST_TIME 120 9190

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-07-2022 19:39:31 to 19-07-2022 19:39:34.

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 1521050 1521050 0 0.00 0 25593 6 0 0
doc_list_biud 1521050 1521050 0 0.00 0 123728 27 0 0
sp_add_invoice_to_stock 5007 4883 124 2.47 31 190847 18896 0 124
sp_cancel_adding_invoice 5717 5717 0 0.00 4 55147 793 0 0
sp_cancel_client_order 1719 1719 0 0.00 4 12830 331 0 0
sp_cancel_customer_reserve 566800 566800 0 0.00 0 23341 58 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 8400 91 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 2 5289 78 0 0
sp_cancel_supplier_invoice 6263 6257 6 0.09 1 41716 762 0 6
sp_cancel_supplier_order 433 433 0 0.00 17 992268 96778 0 0
sp_cancel_write_off 10484 10484 0 0.00 1 123780 115 0 0
sp_client_order 20290 20290 0 0.00 3 18990 350 0 0
sp_customer_reserve 450110 450110 0 0.00 0 127805 187 0 0
sp_fill_shopping_cart 31078 31078 0 0.00 1 36116 49 0 0
sp_fill_shopping_cart_clo_res 450102 450102 0 0.00 0 25609 13 0 0
sp_get_clo_for_invoice 4874 4874 0 0.00 0 25376 328 0 0
sp_kill_cost_storno 17329 17329 0 0.00 0 24253 54 0 0
sp_kill_qstorno_ret_qs2qd 1150774 1150774 0 0.00 0 123727 31 0 0
sp_kill_qty_storno 606188 606188 0 0.00 0 123728 68 0 0
sp_lock_selected_doc 20489 20489 0 0.00 0 7350 5 0 0
sp_make_cost_storno 27623 27623 0 0.00 0 20479 55 0 0
sp_multiply_rows_for_pdistr 27623 27623 0 0.00 0 25591 27 0 0
sp_multiply_rows_for_qdistr 20290 20290 0 0.00 0 13877 166 0 0
sp_pay_from_customer 6912 6912 0 0.00 4 25602 115 0 0
sp_pay_to_supplier 5933 5933 0 0.00 4 27614 138 0 0
sp_payment_common 12845 12845 0 0.00 1 27575 29 0 0
sp_qd_handle_on_cancel_clo 1719 1719 0 0.00 0 12821 304 0 0
sp_qd_handle_on_invoice_upd_sts 10591 10591 0 0.00 0 19164 335 0 0
sp_qd_handle_on_reserve_upd_sts 9904 9904 0 0.00 0 14627 64 0 0
sp_reserve_write_off 9904 9904 0 0.00 6 14682 179 0 0
sp_supplier_invoice 5395 5395 0 0.00 5 100104 3565 0 0
sp_supplier_order 5406 5406 0 0.00 5 43938 1264 0 0
srv_aggregate_perf_data 96 96 0 0.00 2534 175562 21163 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1423 175774 45680 0 0
srv_find_qd_qs_mism 1056640 1056640 0 0.00 0 15157 6 0 0
srv_make_invnt_saldo 507 507 0 0.00 26 95408 1334 0 0
srv_make_money_saldo 764 764 0 0.00 1 19365 60 0 0
srv_recalc_idx_stat 4 4 0 0.00 147007 520433 284547 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4883 4883 0 0.00 0 11534 177 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 3847 113 0 0
t$perf-norm:sp_cancel_client_order 1719 1719 0 0.00 0 4554 12 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 1851 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 975 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 3486 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 2088 23 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 28888 1540 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 2813 8 0 0
t$perf-norm:sp_client_order 20290 20290 0 0.00 0 6987 17 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 1906 7 0 0
t$perf-norm:sp_pay_from_customer 6912 6912 0 0.00 0 9953 16 0 0
t$perf-norm:sp_pay_to_supplier 5933 5933 0 0.00 0 4655 12 0 0
t$perf-norm:sp_reserve_write_off 9904 9904 0 0.00 0 8450 13 0 0
t$perf-norm:sp_supplier_invoice 5395 5395 0 0.00 0 6371 17 0 0
t$perf-norm:sp_supplier_order 5406 5406 0 0.00 0 3917 16 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 2493 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1942 53 0 0
t$perf-norm:srv_make_invnt_saldo 507 507 0 0.00 0 3113 31 0 0
t$perf-norm:srv_make_money_saldo 764 764 0 0.00 0 7414 31 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 9992 9744 248 2.48 0 5787 18 0 248
v_all_customers 37070 37070 0 0.00 0 24 1 0 0
v_all_suppliers 30106 30106 0 0.00 0 26 1 0 0
v_all_wares 40580 40580 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1503 9 0 0
v_cancel_client_order 3438 3438 0 0.00 0 3309 7 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 6331 11 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 2275 7 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 2070 9 0 0
v_cancel_supplier_invoice 792 780 12 1.51 0 7891 24 0 12
v_cancel_supplier_order 866 866 0 0.00 0 2781 13 0 0
v_cancel_write_off 1960 1960 0 0.00 0 2349 15 0 0
v_max_id_clo_ord 5406 5406 0 0.00 0 10864 5 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 4107 17 0 0
v_max_id_ord_sup 5382 5382 0 0.00 0 25834 11 0 0
v_max_non_paid_invoice 1668 1668 0 0.00 0 178 2 0 0
v_max_non_paid_realizn 3576 3576 0 0.00 0 1396 3 0 0
v_min_id_clo_ord 5406 5406 0 0.00 0 10855 18 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 2008 7 0 0
v_min_id_ord_sup 5382 5382 0 0.00 0 15143 15 0 0
v_min_non_paid_invoice 5933 5933 0 0.00 0 27571 23 0 0
v_min_non_paid_realizn 6912 6912 0 0.00 0 10883 19 0 0
v_our_firm 10182 10182 0 0.00 0 19 1 0 0
v_reserve_write_off 19808 19808 0 0.00 0 5800 7 0 0
x_lock_depdocs_on_canc_invoice 11584 11584 0 0.00 0 23464 120 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 39801 486 0 0
x_make_qstorno_1000_1200 5406 5406 0 0.00 1 43926 1162 0 0
x_make_qstorno_1200_2000 5395 5395 0 0.00 2 100090 3387 0 0
x_make_qstorno_2100_3300 450110 450110 0 0.00 0 127803 172 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 19-07-2022 19:39:35 to 19-07-2022 19:39:40.

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
17:08:33 4 8192000000 8802002288 8814104576 7792032 0.095 2 2 3 0 7933136 66144 74960
17:09:35 22006 8192000000 9903170784 10213273600 983532272 10.719 100 84 168 0 1011411968 6035568 21844128
17:10:59 24290 8192000000 9967583584 10293293056 1045095696 11.314 100 89 178 0 1076227888 8384160 22748032
17:12:28 16907 8192000000 9993518784 10329075712 1076516032 11.614 100 87 174 0 1108304720 9480384 22308304
17:13:47 10284 8192000000 10043277088 10389635072 1130171424 12.123 100 84 168 0 1161079120 10114432 20793264
17:15:03 6639 8192000000 10079775568 10436198400 1164608704 12.446 100 79 156 0 1194025520 11551472 17865344
17:16:12 2245 8192000000 10127407760 10495868928 1213595024 12.902 100 89 172 1 1245710096 12700320 19414752
17:17:16 1399 8192000000 10166504336 10540994560 1252777968 13.264 100 88 175 1 1285793024 13456688 19558368
17:18:19 1241 8192000000 10187765664 10568024064 1272746608 13.447 100 84 167 1 1305141568 14723312 17671648
17:19:22 1324 8192000000 10205106192 10593288192 1297128176 13.669 100 79 158 0 1327812032 13478704 17205152
17:20:25 12189 8192000000 10190762832 10594119680 1297428368 13.672 100 29 56 1 1314096336 10275376 6392592
17:21:38 8321 8192000000 10191521472 10605350912 1307220272 13.761 100 22 44 0 1318979248 5980496 5778480
17:22:51 14566 8192000000 10207179216 10623901696 1321181792 13.887 100 18 35 3 1330401104 5212640 4006672
17:24:08 55505 8192000000 10222377168 10639556608 1328854912 13.957 100 37 74 1 1344670832 7043792 8772128
17:26:04 9381 8192000000 10229070992 10649202688 1336944272 14.030 100 21 43 0 1347248352 5189824 5114256
17:27:14 5742 8192000000 10236831808 10659700736 1343802880 14.092 100 22 46 0 1354951424 6045488 5103056
17:28:23 13698 8192000000 10204954784 10616590336 1273663472 13.455 96 20 40 2 1282285312 4712144 3909696
17:29:37 16482 8192000000 10210512144 10603548672 1265354544 13.379 96 72 145 3 1289034192 6574528 17105120
17:30:54 5906 8192000000 10153146592 10552696832 1251111856 13.248 99 28 57 1 1263469296 5628416 6729024
17:32:04 6645 8192000000 10133539440 10535088128 1244128368 13.184 100 28 58 0 1256744944 5614096 7002480
17:33:12 64320 8192000000 10161250976 10571603968 1266550224 13.390 99 33 66 0 1279187600 4827056 7810320
17:35:19 6803 8192000000 10183987760 10591518720 1287022416 13.577 100 34 69 1 1300717088 6349904 7344768
17:36:26 12277 8192000000 10192093200 10608906240 1304462816 13.736 100 16 32 0 1313488960 5539504 3486640
17:37:40 17342 8192000000 10212093392 10625019904 1314520192 13.827 100 26 52 0 1326119152 5446912 6152048
17:39:02 20416 8192000000 10228919248 10637873152 1328451648 13.953 100 51 102 0 1346416816 6167280 11797888
17:40:26 37506 8192000000 10247883936 10666684416 1347006864 14.121 100 36 72 0 1366595792 11226560 8362368
17:42:12 2569 8192000000 10279315152 10699329536 1374089952 14.364 100 53 105 0 1399743472 14196576 11456944
17:43:17 20190 8192000000 10294779248 10720051200 1387083344 14.480 100 34 68 0 1408531568 13889216 7559008
17:44:41 9960 8192000000 10316196368 10744020992 1401413088 14.608 100 55 110 0 1431669136 16133584 14122464
17:45:58 9961 8192000000 10332911920 10758389760 1411605840 14.698 100 57 115 2 1441491600 16532144 13353616
17:47:16 10402 8192000000 10331029904 10757402624 1408879808 14.674 100 57 114 0 1444573552 22535776 13157968
17:48:29 4410 8192000000 10332250512 10748788736 1398523648 14.582 100 68 136 0 1439537008 24365280 16648080
17:49:40 8138 8192000000 10330154832 10754588672 1395315296 14.553 99 55 107 1 1436986784 27896032 13775456
17:50:54 1818 8192000000 10333780960 10744012800 1388740528 14.495 100 81 162 2 1441389728 33224592 19424608
17:51:59 3264 8192000000 10351652080 10767499264 1405017792 14.640 100 72 144 0 1459199008 36463184 17718032
17:53:05 3326 8192000000 10368015888 10784342016 1415472752 14.733 100 79 155 1 1474152016 39805824 18873440
17:54:12 9782 8192000000 10342370784 10762096640 1403030736 14.622 100 69 138 0 1453538560 33312304 17195520
17:55:27 2573 8192000000 10362258448 10772520960 1405378896 14.643 100 81 159 2 1462409232 37341696 19688640
17:56:35 1418 8192000000 10335045728 10740285440 1380425344 14.420 100 85 168 0 1434883136 33362864 21094928
17:57:43 6553 8192000000 10289498736 10713890816 1355460064 14.197 100 37 74 0 1397199568 31205968 10533536
17:58:59 1971 8192000000 10327270368 10725953536 1365517568 14.287 100 81 159 2 1422592912 37500288 19575056
18:00:08 10323 8192000000 10330300320 10752565248 1387295440 14.482 100 48 96 0 1432532992 32609840 12627712
18:01:22 4450 8192000000 10325842160 10740936704 1377229632 14.392 99 62 121 2 1423813248 30773312 15810304
18:02:32 4659 8192000000 10338805104 10765459456 1394137392 14.543 100 39 76 0 1441182000 36158752 10885856
18:03:38 25251 8192000000 10329300352 10759610368 1367726176 14.307 98 38 77 1 1417057200 38707440 10623584
18:05:12 27157 8192000000 10335408528 10769715200 1376183824 14.382 100 27 54 0 1433184672 48904400 8096448
18:06:40 51780 8192000000 10306335888 10751643648 1376134848 14.382 100 28 56 0 1418987440 35976992 6875600
18:08:51 44713 8192000000 10289385584 10742525952 1361224000 14.248 99 15 30 1 1402036576 37153184 3659392
18:11:05 44062 8192000000 10273871456 10714927104 1347199312 14.122 98 19 40 0 1359141696 8093648 3848736
18:12:50 40082 8192000000 10294420016 10731991040 1374387760 14.366 100 40 79 0 1390733936 7714224 8631952
18:14:33 69075 8192000000 10283179552 10733461504 1377835888 14.397 100 11 22 0 1391331520 11165424 2330208
18:16:59 49108 8192000000 10290785584 10747711488 1381924288 14.434 100 18 32 0 1400736080 14321072 4490720
18:19:04 76284 8192000000 10293333056 10749534208 1395303568 14.553 100 25 50 0 1411459872 10254192 5902112
18:21:24 51749 8192000000 10304848848 10761543680 1406964128 14.657 100 26 52 0 1425630848 12973200 5693520
18:23:20 95225 8192000000 10301363984 10759839744 1391243424 14.517 99 23 46 0 1407333744 10385408 5704912
18:25:55 99660 8192000000 10305591056 10771615744 1409156944 14.676 100 18 34 1 1424960224 12142144 3661136
18:28:35 135046 8192000000 10319168864 10773712896 1367082480 14.301 96 30 60 0 1393743264 19587968 7072816
18:32:18 39990 8192000000 10321427616 10786934784 1409584320 14.680 100 21 42 0 1438846432 24192880 5069232
18:35:43 100349 8192000000 10293504224 10767253504 1360153024 14.239 97 15 30 0 1382648112 18868224 3626864
18:38:27 45630 8192000000 10315167984 10770763776 1385461216 14.465 98 30 60 0 1403252768 11381152 6410400
18:41:02 40252 8192000000 10302349904 10745655296 1371480864 14.340 99 33 65 2 1391854960 13306768 7067328
18:42:43 71897 8192000000 10296304304 10766671872 1374243696 14.365 98 21 42 0 1397041648 18062624 4735328
18:45:17 22952 8192000000 10279899056 10720292864 1350287968 14.150 98 27 54 0 1365079056 9175904 5615184
18:46:40 45127 8192000000 10272527424 10712772608 1311154736 13.797 96 31 61 0 1332588480 14928112 6505632
18:48:33 75333 8192000000 10255694944 10702336000 1292723680 13.629 95 18 36 1 1308165408 11064672 4377056
18:51:08 71488 8192000000 10286237776 10729607168 1338748736 14.046 97 29 58 0 1355464080 10395024 6320320
18:53:46 109882 8192000000 10270676384 10725961728 1318150976 13.860 96 14 26 0 1333686848 12676864 2859008
18:56:45 40428 8192000000 10274475232 10720026624 1366309456 14.294 100 31 61 1 1386078272 13001968 6766848
18:58:34 141014 8192000000 10255046688 10704838656 1295434256 13.654 94 15 30 0 1308226032 9046816 3744960
19:03:06 65305 8192000000 10233551904 10681815040 1335255600 14.015 98 19 38 1 1346326720 6672800 4398320
19:05:14 60275 8192000000 10261871664 10716631040 1364497872 14.278 100 15 30 0 1379699648 12016592 3185184
19:08:54 46051 8192000000 10295882048 10750513152 1379498832 14.412 99 14 28 0 1394907200 12255632 3152736
19:10:43 33746 8192000000 10313640880 10748211200 1388450336 14.492 100 23 46 1 1404662208 10989072 5222800
19:12:19 77793 8192000000 10295400512 10743144448 1377082432 14.390 99 13 27 0 1396662848 16399424 3180992
19:14:39 74102 8192000000 10312659968 10756059136 1369267328 14.320 98 25 49 0 1386668432 12289280 5111824
19:16:56 175747 8192000000 10309010976 10766147584 1370207392 14.329 98 22 44 0 1393005728 18146096 4652240
19:20:53 28383 8192000000 10317354352 10772934656 1386967168 14.479 99 31 62 0 1413074096 19506256 6600672
19:22:25 92854 8192000000 10283386000 10737864704 1354368768 14.187 97 16 28 0 1364909696 7628272 2912656
19:25:02 58714 8192000000 10284753248 10744102912 1366465600 14.295 98 22 44 0 1384470016 12517920 5486496
19:27:01 61450 8192000000 10288100752 10751864832 1391274704 14.517 100 9 18 0 1407030960 14302448 1453808
19:29:03 56667 8192000000 10281723440 10737360896 1351184960 14.158 97 23 46 0 1363497264 6703744 5608560
19:31:02 51594 8192000000 10278469008 10741448704 1362488816 14.260 98 22 44 0 1378689392 10798176 5402400
19:32:54 57237 8192000000 10278357360 10736463872 1337598688 14.036 97 9 18 0 1355228016 15594544 2034784
19:34:54 49325 8192000000 10265594400 10712719360 1298364912 13.680 94 23 45 0 1310425920 6673152 5387856
19:36:44 13567 8192000000 10256815344 10700615680 1337809376 14.038 99 33 64 0 1353416608 8492448 7114784
19:37:58 41989 8192000000 9085985504 9321140224 252206368 2.986 19 4 6 0 255818560 3062976 549216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-07-2022 19:39:41 to 19-07-2022 19:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-07-2022 19:39:57 to 19-07-2022 19:39:59.

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          239508
MON$OLDEST_ACTIVE               239509
MON$OLDEST_SNAPSHOT             239509
MON$NEXT_TRANSACTION            239509
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                       2037088
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

19.07.22 19:40:00. 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-07-19 19:40:02 No severe PSQL-related problems occured
19.07.22 19:40:04. End of report.