Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.05.2022 22: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 16525044 kB
MemAvailable 31396680 kB
Buffers 0 kB
Cached 15160924 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-19 22:38:36
DTS_END                         2022-05-19 22:38:14.7930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50556
TRN_ID                          242553
ATT_ID                          274
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: 9073.28 19307 1088794
customer order: creation 176.59 371 21191
customer order: refuse 13.98 363 1678
order to supplier: creation 45.59 1263 5471
order to supplier: removal 3.25 96065 390
invoice (draft): creation 45.78 3221 5494
invoice (draft): removal 50.31 771 6037
invoice accept: apply 42.09 17217 5051
invoice accept: cancel 45.18 811 5422
customer reserve: creation 3907.21 170 468865
customer reserve: removal 4450.08 55 534009
realization accept: apply 83.69 180 10043
realization accept: cancel 77.27 111 9272
payment from customer: creation 59.94 109 7193
payment from customer: removal 5.94 98 713
payment to supplier: creation 50.63 128 6075
payment to supplier: removal 3.61 80 433
service: total inventory turnovers 4.73 1047 568
service: total monetary turnovers 6.62 23 794
service: aggregate perf. data 0.76 24923 91
service: refresh index statistics 0.03 239124 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-05-2022 22:39:26 to 19-05-2022 22:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4202
TEST_TIME 2 4109
TEST_TIME 3 4170
TEST_TIME 4 4226
TEST_TIME 5 4262
TEST_TIME 6 5204
TEST_TIME 7 5447
TEST_TIME 8 5458
TEST_TIME 9 5507
TEST_TIME 10 5507
TEST_TIME 11 5810
TEST_TIME 12 5822
TEST_TIME 13 6052
TEST_TIME 14 6773
TEST_TIME 15 6910
TEST_TIME 16 7150
TEST_TIME 17 7603
TEST_TIME 18 7777
TEST_TIME 19 7739
TEST_TIME 20 8565
TEST_TIME 21 8451
TEST_TIME 22 8590
TEST_TIME 23 8483
TEST_TIME 24 8931
TEST_TIME 25 9075
TEST_TIME 26 9051
TEST_TIME 27 9554
TEST_TIME 28 9500
TEST_TIME 29 10113
TEST_TIME 30 10664
TEST_TIME 31 10384
TEST_TIME 32 10253
TEST_TIME 33 10086
TEST_TIME 34 9945
TEST_TIME 35 9849
TEST_TIME 36 10209
TEST_TIME 37 10045
TEST_TIME 38 9919
TEST_TIME 39 9738
TEST_TIME 40 9741
TEST_TIME 41 9742
TEST_TIME 42 9687
TEST_TIME 43 9546
TEST_TIME 44 9524
TEST_TIME 45 9491
TEST_TIME 46 9373
TEST_TIME 47 9357
TEST_TIME 48 9357
TEST_TIME 49 9249
TEST_TIME 50 9197
TEST_TIME 51 9305
TEST_TIME 52 9348
TEST_TIME 53 9352
TEST_TIME 54 9298
TEST_TIME 55 9391
TEST_TIME 56 9428
TEST_TIME 57 9439
TEST_TIME 58 9402
TEST_TIME 59 9384
TEST_TIME 60 9572
TEST_TIME 61 9667
TEST_TIME 62 9627
TEST_TIME 63 9549
TEST_TIME 64 9437
TEST_TIME 65 9366
TEST_TIME 66 9432
TEST_TIME 67 9362
TEST_TIME 68 9314
TEST_TIME 69 9294
TEST_TIME 70 9241
TEST_TIME 71 9182
TEST_TIME 72 9135
TEST_TIME 73 9095
TEST_TIME 74 9155
TEST_TIME 75 9156
TEST_TIME 76 9234
TEST_TIME 77 9235
TEST_TIME 78 9303
TEST_TIME 79 9282
TEST_TIME 80 9237
TEST_TIME 81 9243
TEST_TIME 82 9355
TEST_TIME 83 9324
TEST_TIME 84 9317
TEST_TIME 85 9391
TEST_TIME 86 9296
TEST_TIME 87 9282
TEST_TIME 88 9230
TEST_TIME 89 9236
TEST_TIME 90 9194
TEST_TIME 91 9145
TEST_TIME 92 9109
TEST_TIME 93 9113
TEST_TIME 94 9114
TEST_TIME 95 9104
TEST_TIME 96 9165
TEST_TIME 97 9175
TEST_TIME 98 9206
TEST_TIME 99 9154
TEST_TIME 100 9249
TEST_TIME 101 9249
TEST_TIME 102 9250
TEST_TIME 103 9239
TEST_TIME 104 9275
TEST_TIME 105 9367
TEST_TIME 106 9390
TEST_TIME 107 9388
TEST_TIME 108 9335
TEST_TIME 109 9277
TEST_TIME 110 9264
TEST_TIME 111 9244
TEST_TIME 112 9221
TEST_TIME 113 9199
TEST_TIME 114 9179
TEST_TIME 115 9132
TEST_TIME 116 9116
TEST_TIME 117 9103
TEST_TIME 118 9139
TEST_TIME 119 9125
TEST_TIME 120 9076

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-05-2022 22:39:29 to 19-05-2022 22:39:33.

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 1522662 1522662 0 0.00 0 16467 6 0 0
doc_list_biud 1522660 1522660 0 0.00 0 31807 25 0 0
sp_add_invoice_to_stock 5216 5051 165 3.16 25 203468 17217 0 165
sp_cancel_adding_invoice 5422 5422 0 0.00 4 77260 811 0 0
sp_cancel_client_order 1678 1678 0 0.00 4 13996 364 0 0
sp_cancel_customer_reserve 534009 534009 0 0.00 1 28476 56 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 2 7345 99 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 2967 80 0 0
sp_cancel_supplier_invoice 6052 6037 15 0.24 2 12315 771 0 15
sp_cancel_supplier_order 390 390 0 0.00 6 1059685 96065 0 0
sp_cancel_write_off 9272 9272 0 0.00 1 14383 111 0 0
sp_client_order 21191 21191 0 0.00 3 33290 372 0 0
sp_customer_reserve 468865 468865 0 0.00 0 58320 170 0 0
sp_fill_shopping_cart 32151 32151 0 0.00 1 9548 35 0 0
sp_fill_shopping_cart_clo_res 468861 468861 0 0.00 0 20029 15 0 0
sp_get_clo_for_invoice 5047 5047 0 0.00 0 23686 321 0 0
sp_kill_cost_storno 15840 15840 0 0.00 0 7657 54 0 0
sp_kill_qstorno_ret_qs2qd 1083717 1083717 0 0.00 0 23298 30 0 0
sp_kill_qty_storno 571898 571898 0 0.00 0 31804 66 0 0
sp_lock_selected_doc 20839 20839 0 0.00 0 6799 4 0 0
sp_make_cost_storno 28358 28358 0 0.00 0 16457 50 0 0
sp_multiply_rows_for_pdistr 28358 28358 0 0.00 0 8772 29 0 0
sp_multiply_rows_for_qdistr 21191 21191 0 0.00 0 14097 163 0 0
sp_pay_from_customer 7193 7193 0 0.00 4 14087 109 0 0
sp_pay_to_supplier 6075 6075 0 0.00 4 16827 129 0 0
sp_payment_common 13268 13268 0 0.00 1 7975 25 0 0
sp_qd_handle_on_cancel_clo 1678 1678 0 0.00 0 13990 335 0 0
sp_qd_handle_on_invoice_upd_sts 10469 10469 0 0.00 0 31802 316 0 0
sp_qd_handle_on_reserve_upd_sts 10043 10043 0 0.00 0 17829 69 0 0
sp_reserve_write_off 10043 10043 0 0.00 6 18029 181 0 0
sp_supplier_invoice 5494 5494 0 0.00 6 75373 3221 0 0
sp_supplier_order 5471 5471 0 0.00 5 25385 1264 0 0
srv_aggregate_perf_data 91 91 0 0.00 2274 555532 24924 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1315 143317 48170 0 0
srv_find_qd_qs_mism 1040076 1040076 0 0.00 0 15155 5 0 0
srv_make_invnt_saldo 568 568 0 0.00 38 59900 1047 0 0
srv_make_money_saldo 794 794 0 0.00 1 1260 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 215591 271045 239124 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5051 5051 0 0.00 0 19474 150 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 5959 120 0 0
t$perf-norm:sp_cancel_client_order 1678 1678 0 0.00 0 8356 21 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 1267 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 2714 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 605 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 443 443 0 0.00 0 2057 18 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 29754 1634 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 4092 10 0 0
t$perf-norm:sp_client_order 21191 21191 0 0.00 0 7784 17 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 3215 8 0 0
t$perf-norm:sp_pay_from_customer 7193 7193 0 0.00 0 4958 8 0 0
t$perf-norm:sp_pay_to_supplier 6075 6075 0 0.00 0 4638 7 0 0
t$perf-norm:sp_reserve_write_off 10043 10043 0 0.00 0 5055 9 0 0
t$perf-norm:sp_supplier_invoice 5494 5494 0 0.00 0 9239 17 0 0
t$perf-norm:sp_supplier_order 5471 5471 0 0.00 0 8036 17 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 76 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 617 11 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 1301 19 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 3336 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 19 10 0 0
v_add_invoice_to_stock 10424 10094 330 3.16 0 11068 19 0 330
v_all_customers 38320 38320 0 0.00 0 20 1 0 0
v_all_suppliers 30558 30558 0 0.00 0 24 1 0 0
v_all_wares 42382 42382 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 1530 9 0 0
v_cancel_client_order 3356 3356 0 0.00 0 2649 5 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 5263 11 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 2948 8 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 2948 14 0 0
v_cancel_supplier_invoice 916 886 30 3.27 0 1672 13 0 30
v_cancel_supplier_order 780 780 0 0.00 0 2216 8 0 0
v_cancel_write_off 2072 2072 0 0.00 0 1300 13 0 0
v_max_id_clo_ord 5470 5470 0 0.00 0 516 1 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 1969 16 0 0
v_max_id_ord_sup 5490 5490 0 0.00 0 2849 4 0 0
v_max_non_paid_invoice 1756 1756 0 0.00 0 2363 4 0 0
v_max_non_paid_realizn 3976 3976 0 0.00 0 2090 4 0 0
v_min_id_clo_ord 5470 5470 0 0.00 0 2288 5 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 8298 25 0 0
v_min_id_ord_sup 5490 5490 0 0.00 0 5910 9 0 0
v_min_non_paid_invoice 6075 6075 0 0.00 0 7969 17 0 0
v_min_non_paid_realizn 7193 7193 0 0.00 0 7478 19 0 0
v_our_firm 10496 10496 0 0.00 0 20 1 0 0
v_reserve_write_off 20086 20086 0 0.00 0 4830 6 0 0
x_lock_depdocs_on_canc_invoice 11016 11016 0 0.00 0 17137 121 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 9287 407 0 0
x_make_qstorno_1000_1200 5471 5471 0 0.00 2 25368 1192 0 0
x_make_qstorno_1200_2000 5494 5494 0 0.00 3 75362 3097 0 0
x_make_qstorno_2100_3300 468865 468865 0 0.00 0 58317 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-05-2022 22:39:34 to 19-05-2022 22:39:38.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:18 67 8192000000 8830808800 8853364736 21276592 0.259 6 5 6 0 21519408 93520 149296
20:09:19 37341 8192000000 9917256720 10229080064 998985872 10.869 100 92 184 0 1029123680 6147984 23989824
20:11:00 26466 8192000000 9960728832 10284912640 1043196848 11.295 100 85 168 1 1072290352 8015616 21077888
20:12:28 12498 8192000000 9987530704 10325229568 1074221872 11.592 100 79 158 0 1102900512 9046400 19632240
20:13:44 13229 8192000000 10065228592 10418278400 1149010544 12.300 100 84 169 1 1179379968 10889024 19480400
20:15:00 11812 8192000000 10108939312 10474115072 1197441376 12.753 100 80 160 0 1228026032 12487488 18097168
20:16:16 5057 8192000000 10152984112 10527764480 1235887952 13.108 100 90 176 3 1269169648 13622448 19659248
20:17:23 3679 8192000000 10166846416 10544648192 1256404736 13.297 100 80 159 0 1288469728 15034096 17030896
20:18:28 2076 8192000000 10201121072 10584416256 1285001200 13.559 100 89 180 2 1318061824 13305664 19754960
20:19:32 3041 8192000000 10219372656 10612244480 1304007280 13.732 100 77 155 1 1334364656 12930416 17426960
20:20:36 20993 8192000000 10221867552 10618523648 1311251648 13.797 100 62 125 4 1332402672 6346064 14804960
20:21:58 9821 8192000000 10212340768 10623578112 1319234176 13.870 100 22 44 0 1331132432 7015824 4882432
20:23:10 12288 8192000000 10212203632 10637045760 1328978976 13.958 100 15 30 0 1338036480 5836608 3220896
20:24:22 11428 8192000000 10212793744 10636034048 1319574464 13.873 99 13 26 0 1327894080 5497328 2822288
20:25:41 25169 8192000000 10255528096 10657665024 1336917072 14.030 100 69 138 1 1361041456 6666512 17457872
20:27:06 8981 8192000000 10225456480 10649546752 1325117552 13.923 99 27 54 1 1338340992 6116464 7106976
20:28:17 8626 8192000000 10208806064 10625474560 1317652192 13.855 100 22 46 1 1328431600 5501344 5278064
20:29:29 52015 8192000000 10188976816 10608750592 1282505104 13.536 98 33 66 1 1296550112 7075312 6969696
20:31:23 6387 8192000000 10132871424 10537066496 1203800048 12.812 96 14 28 2 1211728352 4887744 3040560
20:32:29 9129 8192000000 10158927744 10556915712 1249125568 13.230 99 33 66 0 1262978400 5759904 8092928
20:33:39 15700 8192000000 10164003552 10565439488 1261740704 13.346 99 29 58 1 1275164576 5084592 8339280
20:34:55 23559 8192000000 10162530816 10575093760 1277343344 13.489 100 16 32 0 1286283232 5742464 3197424
20:36:18 7197 8192000000 10179621696 10596564992 1295079072 13.650 100 16 32 0 1304279344 5426720 3773552
20:37:26 11760 8192000000 10198361984 10613800960 1306987968 13.759 100 19 38 2 1316050336 4762160 4300208
20:38:43 19021 8192000000 10253541344 10655477760 1335066880 14.013 100 58 117 0 1358247984 9002496 14178608
20:40:07 15110 8192000000 10254034512 10673102848 1348596096 14.135 100 40 80 1 1368769696 10810528 9363072
20:41:29 10273 8192000000 10265403104 10681696256 1359826768 14.236 100 55 110 0 1385793184 12842736 13123680
20:42:48 15377 8192000000 10287263520 10708901888 1375634720 14.378 100 42 84 0 1399924304 14928416 9361168
20:44:26 35448 8192000000 10297617696 10727075840 1392304928 14.526 100 42 84 0 1416427088 14109920 10012240
20:46:11 7224 8192000000 10315715744 10737131520 1398881808 14.585 100 62 124 3 1429448944 15353296 15213840
20:47:24 10864 8192000000 10320663056 10743324672 1407097008 14.658 100 50 100 1 1437477600 17994256 12386336
20:48:39 5174 8192000000 10331505248 10757496832 1416225520 14.739 100 56 112 0 1448359056 19173968 12959568
20:49:53 3158 8192000000 10341152448 10764029952 1408915856 14.674 99 55 110 0 1444452096 21711008 13825232
20:51:00 6838 8192000000 10358115376 10774036480 1419429760 14.768 100 76 151 1 1466315232 27722112 19163360
20:52:13 2361 8192000000 10345453072 10753253376 1395955216 14.559 98 83 163 2 1447350032 30427152 20967664
20:53:19 5186 8192000000 10350671168 10766397440 1406268272 14.651 100 70 139 1 1451187120 28271360 16647488
20:54:46 2550 8192000000 10364440944 10774831104 1410839696 14.691 100 79 156 2 1462924688 33443520 18641472
20:55:52 1599 8192000000 10344950464 10750464000 1379028896 14.408 99 93 184 1 1435483360 34148640 22305824
20:56:58 1314 8192000000 10337082688 10745954304 1377021008 14.390 100 87 173 0 1436908032 37416464 22470560
20:58:05 3447 8192000000 10351865616 10766622720 1376091280 14.382 99 75 148 3 1442780896 47729472 18960144
20:59:13 24830 8192000000 10319744688 10742247424 1368586976 14.314 99 45 90 4 1415744928 35226688 11931264
21:00:49 15248 8192000000 10330012720 10762313728 1384979408 14.461 100 28 56 2 1431553136 39193440 7380288
21:02:11 13155 8192000000 10323228432 10750935040 1367724048 14.307 100 35 70 3 1431936656 54233408 9979200
21:03:50 12597 8192000000 10296636832 10725273600 1347776432 14.127 99 26 52 2 1392813744 38351264 6686048
21:05:04 17943 8192000000 10294090992 10725097472 1342131232 14.077 99 23 45 0 1399605056 51130640 6343184
21:07:06 20997 8192000000 10291202640 10729402368 1330790240 13.974 99 17 34 1 1387838592 51836576 5211776
21:08:48 87954 8192000000 10262328336 10706071552 1347978736 14.129 99 42 81 3 1364387728 7298688 9110304
21:11:18 35365 8192000000 10298526816 10732572672 1376009472 14.381 100 32 64 1 1396973840 13776320 7188048
21:12:54 103740 8192000000 10284007472 10748416000 1339064160 14.049 97 25 50 1 1366177920 21221024 5892736
21:15:44 28802 8192000000 10281638480 10719711232 1367810688 14.307 100 13 27 1 1383126496 11897472 3418336
21:17:15 98504 8192000000 10284510416 10734231552 1356607280 14.207 98 15 30 0 1370181552 9860448 3713824
21:20:03 95519 8192000000 10286067280 10746134528 1384622336 14.458 100 19 38 0 1399365520 10582992 4160192
21:24:03 40871 8192000000 10294660368 10754437120 1380278688 14.419 99 9 18 1 1399177344 16585440 2313216
21:25:46 39866 8192000000 10293381024 10759811072 1395167344 14.552 100 11 22 0 1410262624 12167040 2928240
21:27:26 68292 8192000000 10300530368 10761891840 1393073376 14.533 99 13 26 1 1408961776 12686096 3202304
21:29:50 118899 8192000000 10306088528 10773741568 1390292128 14.508 99 5 10 0 1408893712 17687664 913920
21:32:55 19975 8192000000 10311732096 10757689344 1385358832 14.464 99 58 116 3 1412452560 13985232 13108496
21:34:22 113447 8192000000 10297134384 10757255168 1338429568 14.043 96 9 18 0 1358932432 18747968 1754896
21:37:16 65236 8192000000 10316203952 10769793024 1402220160 14.615 100 26 52 1 1424827792 16406864 6200768
21:39:35 109471 8192000000 10279139120 10724143104 1281420544 13.526 92 11 22 0 1290565184 7304512 1840128
21:42:28 79514 8192000000 10304694352 10750652416 1392804592 14.531 100 36 71 0 1412966464 11487264 8674608
21:44:54 45724 8192000000 10282810368 10746683392 1372615664 14.350 99 9 18 0 1387752992 12900144 2237184
21:46:42 93075 8192000000 10267063984 10735476736 1346837712 14.119 97 8 16 0 1356231536 7487392 1906432
21:49:16 73366 8192000000 10266401312 10730754048 1352465056 14.170 98 10 20 0 1360909824 6650224 1794544
21:51:29 32465 8192000000 10287875200 10738700288 1384581040 14.457 100 32 64 2 1404379456 12577200 7221216
21:53:09 88616 8192000000 10260970192 10715103232 1311959040 13.804 95 22 43 1 1330229392 13097600 5172752
21:56:13 70009 8192000000 10256746272 10710413312 1291427984 13.617 95 20 40 2 1311009808 15350496 4231328
21:58:27 35147 8192000000 10236628304 10666004480 1267686272 13.400 95 28 56 1 1286547712 12767648 6093792
22:00:04 63859 8192000000 10258084112 10719621120 1343784080 14.092 99 24 48 1 1365861616 16725456 5352080
22:02:14 92386 8192000000 10256947376 10709770240 1311601696 13.801 97 14 29 0 1330499024 15880512 3016816
22:04:49 120420 8192000000 10246585168 10700550144 1316708576 13.847 97 24 48 1 1330198304 8270752 5218976
22:07:50 38444 8192000000 10246744208 10699649024 1329012320 13.958 98 10 20 1 1338152016 7390080 1749616
22:09:39 36455 8192000000 10250571968 10709618688 1363362816 14.268 100 16 32 0 1372598512 5984288 3251408
22:11:26 64440 8192000000 10264703168 10721759232 1366117936 14.292 100 7 13 0 1375693216 8454160 1121120
22:13:30 36785 8192000000 10289334336 10732343296 1373080480 14.355 100 23 46 0 1387237568 8629248 5527840
22:15:08 54329 8192000000 10282321808 10740011008 1365771152 14.289 99 19 37 1 1383834624 13654576 4408896
22:17:12 35136 8192000000 10302361248 10746036224 1362475216 14.260 98 35 70 1 1379738608 9045584 8217808
22:19:07 49062 8192000000 10303467280 10759151616 1375432864 14.376 99 26 51 1 1394177472 12605760 6138848
22:21:03 94838 8192000000 10319919008 10773397504 1375604080 14.377 99 25 51 2 1406478160 25430624 5443456
22:23:38 143317 8192000000 10294730912 10763591680 1351268560 14.159 97 8 16 0 1373303344 20196864 1837920
22:27:01 71874 8192000000 10277059488 10743332864 1378710080 14.405 99 19 38 1 1391038864 7688272 4640512
22:29:19 52205 8192000000 10268560976 10730831872 1364185808 14.275 99 15 30 1 1378084976 10911424 2987744
22:32:18 67240 8192000000 10251177104 10712207360 1340459456 14.062 97 9 20 2 1351962512 9367904 2135152
22:34:26 60547 8192000000 10276142704 10740486144 1355820720 14.200 98 11 22 0 1365905168 7630672 2453776
22:36:29 63287 8192000000 10271241328 10728370176 1328980768 13.958 97 16 32 0 1346968688 14834352 3153568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 19-05-2022 22:39:40 to 19-05-2022 22:39:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 165 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

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

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          254890
MON$OLDEST_ACTIVE               254891
MON$OLDEST_SNAPSHOT             254891
MON$NEXT_TRANSACTION            254891
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                       2025248
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.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 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.05.22 22:39:58. 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-19 22:40:01 No severe PSQL-related problems occured
19.05.22 22:40:02. End of report.