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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15857056 kB
MemAvailable 31054704 kB
Buffers 0 kB
Cached 15879164 kB
SwapCached 2552 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-26 13:37:20
DTS_END                         2022-07-26 13:37:20.3540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56114
TRN_ID                          217552
ATT_ID                          610
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: 9067.55 24910 1088106
customer order: creation 167.76 488 20131
customer order: refuse 14.03 390 1683
order to supplier: creation 44.88 1316 5386
order to supplier: removal 3.23 167194 388
invoice (draft): creation 44.84 3086 5381
invoice (draft): removal 49.27 944 5912
invoice accept: apply 41.49 25426 4979
invoice accept: cancel 45.03 1194 5403
customer reserve: creation 3958.18 236 474982
customer reserve: removal 4412.12 97 529454
realization accept: apply 81.72 291 9806
realization accept: cancel 80.53 166 9663
payment from customer: creation 57.57 187 6908
payment from customer: removal 5.32 109 638
payment to supplier: creation 46.17 198 5540
payment to supplier: removal 3.25 142 390
service: total inventory turnovers 3.92 3747 470
service: total monetary turnovers 7.35 391 882
service: aggregate perf. data 0.88 14760 106
service: refresh index statistics 0.03 277838 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-07-2022 13:38:57 to 26-07-2022 13:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10801
TEST_TIME 2 6972
TEST_TIME 3 7512
TEST_TIME 4 7343
TEST_TIME 5 6806
TEST_TIME 6 6622
TEST_TIME 7 6992
TEST_TIME 8 6889
TEST_TIME 9 7001
TEST_TIME 10 6943
TEST_TIME 11 6901
TEST_TIME 12 6949
TEST_TIME 13 6977
TEST_TIME 14 7064
TEST_TIME 15 7234
TEST_TIME 16 7306
TEST_TIME 17 7419
TEST_TIME 18 7574
TEST_TIME 19 7693
TEST_TIME 20 7911
TEST_TIME 21 7946
TEST_TIME 22 8165
TEST_TIME 23 8209
TEST_TIME 24 8543
TEST_TIME 25 8717
TEST_TIME 26 8505
TEST_TIME 27 8986
TEST_TIME 28 8806
TEST_TIME 29 9368
TEST_TIME 30 9876
TEST_TIME 31 10419
TEST_TIME 32 11095
TEST_TIME 33 11053
TEST_TIME 34 11171
TEST_TIME 35 11060
TEST_TIME 36 10976
TEST_TIME 37 10783
TEST_TIME 38 10569
TEST_TIME 39 10444
TEST_TIME 40 10549
TEST_TIME 41 10352
TEST_TIME 42 10525
TEST_TIME 43 10559
TEST_TIME 44 10600
TEST_TIME 45 10502
TEST_TIME 46 10354
TEST_TIME 47 10322
TEST_TIME 48 10342
TEST_TIME 49 10281
TEST_TIME 50 10296
TEST_TIME 51 10182
TEST_TIME 52 10049
TEST_TIME 53 10017
TEST_TIME 54 9886
TEST_TIME 55 9788
TEST_TIME 56 9814
TEST_TIME 57 9787
TEST_TIME 58 9754
TEST_TIME 59 9802
TEST_TIME 60 9746
TEST_TIME 61 9739
TEST_TIME 62 9708
TEST_TIME 63 9716
TEST_TIME 64 9615
TEST_TIME 65 9611
TEST_TIME 66 9529
TEST_TIME 67 9604
TEST_TIME 68 9593
TEST_TIME 69 9625
TEST_TIME 70 9673
TEST_TIME 71 9712
TEST_TIME 72 9642
TEST_TIME 73 9685
TEST_TIME 74 9677
TEST_TIME 75 9722
TEST_TIME 76 9692
TEST_TIME 77 9649
TEST_TIME 78 9721
TEST_TIME 79 9666
TEST_TIME 80 9623
TEST_TIME 81 9605
TEST_TIME 82 9593
TEST_TIME 83 9545
TEST_TIME 84 9490
TEST_TIME 85 9435
TEST_TIME 86 9369
TEST_TIME 87 9327
TEST_TIME 88 9327
TEST_TIME 89 9356
TEST_TIME 90 9338
TEST_TIME 91 9324
TEST_TIME 92 9374
TEST_TIME 93 9339
TEST_TIME 94 9324
TEST_TIME 95 9410
TEST_TIME 96 9521
TEST_TIME 97 9505
TEST_TIME 98 9495
TEST_TIME 99 9526
TEST_TIME 100 9504
TEST_TIME 101 9526
TEST_TIME 102 9520
TEST_TIME 103 9443
TEST_TIME 104 9449
TEST_TIME 105 9404
TEST_TIME 106 9379
TEST_TIME 107 9369
TEST_TIME 108 9334
TEST_TIME 109 9317
TEST_TIME 110 9268
TEST_TIME 111 9220
TEST_TIME 112 9179
TEST_TIME 113 9150
TEST_TIME 114 9180
TEST_TIME 115 9164
TEST_TIME 116 9145
TEST_TIME 117 9149
TEST_TIME 118 9146
TEST_TIME 119 9114
TEST_TIME 120 9092

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-07-2022 13:39:00 to 26-07-2022 13:39:04.

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 1522849 1522849 0 0.00 0 32722 13 0 0
doc_list_biud 1522840 1522840 0 0.00 0 28250 39 0 0
sp_add_invoice_to_stock 5083 4979 104 2.04 26 196815 25426 0 104
sp_cancel_adding_invoice 5403 5403 0 0.00 5 86505 1194 0 0
sp_cancel_client_order 1683 1683 0 0.00 6 7016 391 0 0
sp_cancel_customer_reserve 529454 529454 0 0.00 1 28266 97 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 4 2120 109 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 10705 142 0 0
sp_cancel_supplier_invoice 5921 5912 9 0.15 3 19341 945 0 9
sp_cancel_supplier_order 388 388 0 0.00 11 1347354 167195 0 0
sp_cancel_write_off 9663 9663 0 0.00 2 15714 167 0 0
sp_client_order 20131 20131 0 0.00 5 36672 488 0 0
sp_customer_reserve 474982 474982 0 0.00 1 106684 236 0 0
sp_fill_shopping_cart 30882 30882 0 0.00 1 14684 81 0 0
sp_fill_shopping_cart_clo_res 474947 474947 0 0.00 0 16353 18 0 0
sp_get_clo_for_invoice 4934 4934 0 0.00 0 20404 326 0 0
sp_kill_cost_storno 16094 16094 0 0.00 0 28619 76 0 0
sp_kill_qstorno_ret_qs2qd 1074871 1074871 0 0.00 0 28224 42 0 0
sp_kill_qty_storno 567242 567242 0 0.00 0 28248 96 0 0
sp_lock_selected_doc 20202 20202 0 0.00 0 10863 9 0 0
sp_make_cost_storno 27187 27187 0 0.00 0 15053 85 0 0
sp_multiply_rows_for_pdistr 27187 27187 0 0.00 0 8722 39 0 0
sp_multiply_rows_for_qdistr 20129 20129 0 0.00 0 14198 179 0 0
sp_pay_from_customer 6908 6908 0 0.00 6 11416 188 0 0
sp_pay_to_supplier 5540 5540 0 0.00 6 16503 199 0 0
sp_payment_common 12447 12447 0 0.00 2 6481 50 0 0
sp_qd_handle_on_cancel_clo 1683 1683 0 0.00 1 7002 323 0 0
sp_qd_handle_on_invoice_upd_sts 10336 10336 0 0.00 0 21493 487 0 0
sp_qd_handle_on_reserve_upd_sts 9806 9806 0 0.00 0 15156 63 0 0
sp_reserve_write_off 9806 9806 0 0.00 8 15172 291 0 0
sp_supplier_invoice 5381 5381 0 0.00 9 92859 3087 0 0
sp_supplier_order 5386 5386 0 0.00 6 32039 1316 0 0
srv_aggregate_perf_data 106 106 0 0.00 2483 131464 14761 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 758 161834 35673 0 0
srv_find_qd_qs_mism 1038322 1038322 0 0.00 0 32721 10 0 0
srv_make_invnt_saldo 470 470 0 0.00 41 70151 3748 0 0
srv_make_money_saldo 882 882 0 0.00 1 14246 391 0 0
srv_recalc_idx_stat 4 4 0 0.00 58697 415306 277838 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4983 4983 0 0.00 0 10549 872 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 6398 485 0 0
t$perf-norm:sp_cancel_client_order 1683 1683 0 0.00 0 2286 28 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 6664 41 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 539 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 250 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 2107 31 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 94692 6546 0 0
t$perf-norm:sp_cancel_write_off 912 912 0 0.00 0 692 16 0 0
t$perf-norm:sp_client_order 20132 20132 0 0.00 0 8007 33 0 0
t$perf-norm:sp_customer_reserve 948 948 0 0.00 0 1484 18 0 0
t$perf-norm:sp_pay_from_customer 6908 6908 0 0.00 0 6212 18 0 0
t$perf-norm:sp_pay_to_supplier 5540 5540 0 0.00 0 4474 19 0 0
t$perf-norm:sp_reserve_write_off 9806 9806 0 0.00 0 5334 21 0 0
t$perf-norm:sp_supplier_invoice 5381 5381 0 0.00 0 6580 30 0 0
t$perf-norm:sp_supplier_order 5387 5387 0 0.00 0 6119 33 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 357 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 135 7 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 1872 16 0 0
t$perf-norm:srv_make_money_saldo 882 882 0 0.00 0 847 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 169 71 0 0
v_add_invoice_to_stock 10058 9850 208 2.06 0 6301 26 0 208
v_all_customers 37334 37334 0 0.00 0 81 4 0 0
v_all_suppliers 29892 29892 0 0.00 0 100 4 0 0
v_all_wares 40250 40250 0 0.00 0 166 4 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 3507 20 0 0
v_cancel_client_order 3366 3366 0 0.00 0 1871 10 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 849 11 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 1 5724 15 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 1608 14 0 0
v_cancel_supplier_invoice 782 764 18 2.30 0 4766 36 0 18
v_cancel_supplier_order 776 776 0 0.00 0 2027 13 0 0
v_cancel_write_off 1824 1824 0 0.00 0 2537 24 0 0
v_max_id_clo_ord 5384 5384 0 0.00 0 611 5 0 0
v_max_id_clo_res 947 947 0 0.00 0 2499 15 0 0
v_max_id_ord_sup 5371 5371 0 0.00 0 4121 8 0 0
v_max_non_paid_invoice 1348 1348 0 0.00 0 233 8 0 0
v_max_non_paid_realizn 3458 3458 0 0.00 0 3101 9 0 0
v_min_id_clo_ord 5384 5384 0 0.00 0 1356 7 0 0
v_min_id_clo_res 947 947 0 0.00 0 1229 10 0 0
v_min_id_ord_sup 5371 5371 0 0.00 0 5422 12 0 0
v_min_non_paid_invoice 5539 5539 0 0.00 0 4746 21 0 0
v_min_non_paid_realizn 6908 6908 0 0.00 0 6476 24 0 0
v_our_firm 9816 9816 0 0.00 0 127 5 0 0
v_reserve_write_off 19612 19612 0 0.00 0 6630 12 0 0
x_lock_depdocs_on_canc_invoice 10933 10933 0 0.00 0 14439 131 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 6881 395 0 0
x_make_qstorno_1000_1200 5386 5386 0 0.00 2 32031 1166 0 0
x_make_qstorno_1200_2000 5381 5381 0 0.00 3 92839 2859 0 0
x_make_qstorno_2100_3300 474980 474980 0 0.00 0 106666 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-07-2022 13:39:05 to 26-07-2022 13:39:09.

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
11:07:24 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:25 26017 8192000000 9714486608 10011648000 792544336 8.821 100 88 173 0 821659552 7533216 21582000
11:09:55 13112 8192000000 9759594160 10067177472 835599920 9.256 100 86 172 0 867503696 11128736 20775040
11:11:11 10500 8192000000 9780890560 10096353280 855331168 9.453 100 88 176 0 895750848 19618000 20801680
11:12:26 9751 8192000000 9849991136 10182053888 912024304 10.017 100 83 160 0 964470640 34141296 18305040
11:13:41 5493 8192000000 9914467152 10258083840 958917248 10.478 100 84 168 0 1027241712 51017424 17307040
11:14:49 2300 8192000000 9967478992 10320916480 995975184 10.839 100 86 170 0 1080090720 66735696 17379840
11:15:55 1696 8192000000 10002084192 10363387904 1023006480 11.101 100 90 174 0 1118970128 79202432 16761216
11:16:59 1180 8192000000 10023748192 10389237760 1042573968 11.289 100 93 184 0 1140286496 79744704 17967824
11:18:03 1112 8192000000 10025885216 10403233792 1055403120 11.412 100 90 170 0 1145690880 73753888 16533872
11:19:05 1155 8192000000 10022758464 10398314496 1064584480 11.500 100 95 186 3 1144187712 61019072 18584160
11:20:07 1534 8192000000 10058068352 10443485184 1069892496 11.551 100 93 179 2 1180601248 91202016 19506736
11:21:11 16754 8192000000 10027231424 10433957888 1075612064 11.606 100 49 98 0 1166968848 81451968 9904816
11:22:29 1058 8192000000 10055522224 10440392704 1082875856 11.675 100 90 176 2 1177244704 77064208 17304640
11:23:33 1126 8192000000 10046338496 10433552384 1091158272 11.754 100 95 180 0 1168309920 58719408 18432240
11:24:37 1220 8192000000 10056268192 10439692288 1091556112 11.757 100 95 182 2 1179730480 68893872 19280496
11:25:39 1178 8192000000 10076290896 10462609408 1097060464 11.810 100 100 192 3 1201856256 86377232 18418560
11:26:42 948 8192000000 10063691472 10452529152 1094769488 11.788 100 93 180 0 1186465520 72297056 19398976
11:27:44 1287 8192000000 10050230016 10440208384 1093459200 11.776 100 92 175 2 1174777120 62908496 18409424
11:28:47 3271 8192000000 10037834112 10426437632 1096609456 11.805 100 81 144 1 1164318112 54267360 13441296
11:29:51 9532 8192000000 10036388144 10432909312 1080022272 11.648 99 60 119 0 1165410672 73708928 11679472
11:31:01 956 8192000000 10029260016 10412441600 1079753168 11.645 100 90 166 2 1152185040 54798592 17633280
11:32:03 1015 8192000000 10018327104 10402385920 1074576912 11.596 100 89 162 3 1139621328 48341344 16703072
11:33:05 1514 8192000000 9994775280 10362527744 1028535200 11.154 100 96 183 3 1112498400 64718400 19244800
11:34:08 3078 8192000000 9986736656 10365620224 1023926160 11.110 99 63 119 1 1111348784 75713184 11709440
11:35:13 1577 8192000000 9989447728 10366775296 1038233664 11.248 100 75 127 3 1120088800 70757200 11097936
11:36:16 1972 8192000000 10006532592 10392588288 1048556672 11.347 100 65 125 2 1136441104 73792736 14091696
11:37:19 1101 8192000000 10000697232 10375524352 1050053360 11.361 100 95 179 4 1123265760 54183040 19029360
11:38:25 1316 8192000000 10027587616 10407047168 1068158384 11.534 100 85 157 1 1143660816 61233152 14269280
11:39:33 10904 8192000000 10048340288 10431016960 1079031888 11.638 100 77 138 1 1171193984 78461664 13700432
11:40:47 13587 8192000000 10085378560 10475253760 1088725616 11.731 100 66 130 0 1201477680 99484864 13267200
11:42:06 1084 8192000000 10098699232 10483773440 1110278592 11.935 100 90 170 1 1211568560 83242480 18047488
11:43:10 5386 8192000000 10140855728 10538332160 1117563024 12.004 100 73 142 2 1260182688 127798864 14820800
11:44:21 1033 8192000000 10155488400 10548412416 1127752688 12.100 100 88 162 0 1266470128 120985216 17732224
11:45:25 2531 8192000000 10178724224 10574913536 1136025232 12.178 100 85 161 1 1297140192 144431616 16683344
11:46:48 1042 8192000000 10236678912 10635792384 1148841456 12.299 100 92 176 0 1348920080 181560368 18518256
11:47:53 1101 8192000000 10244307792 10646519808 1159326784 12.397 100 81 155 0 1355525776 178345840 17853152
11:48:59 1270 8192000000 10283461792 10686439424 1169248928 12.490 100 93 173 0 1397329984 208284688 19796368
11:50:04 3463 8192000000 10303406256 10711478272 1173988608 12.534 100 76 152 1 1409888560 218801104 17098848
11:51:11 1331 8192000000 10350425408 10761658368 1184063808 12.628 100 98 187 1 1455394384 249884656 21445920
11:52:15 1644 8192000000 10381475632 10792796160 1188405680 12.669 100 97 187 0 1487085648 277571200 21108768
11:53:19 2535 8192000000 10380492992 10805657600 1185279712 12.639 100 86 165 0 1488390208 284133232 18977264
11:54:25 1017 8192000000 10436767552 10848055296 1198415536 12.762 100 93 183 1 1549568032 328912896 22239600
11:55:28 1172 8192000000 10442406688 10864558080 1164703536 12.447 99 94 179 1 1545663168 359627888 21331744
11:56:32 868 8192000000 10473137904 10895552512 1190361536 12.687 100 98 191 1 1577773440 364896048 22515856
11:57:36 1086 8192000000 10518004128 10945658880 1198216032 12.760 100 95 186 1 1620607664 400043856 22347776
11:58:39 2112 8192000000 10533119952 10989670400 1197747424 12.755 100 98 192 1 1634928224 412923008 24257792
11:59:44 748 8192000000 10582676688 10991599616 1208109296 12.852 100 98 189 1 1683676832 453082880 22484656
12:00:48 794 8192000000 10561421728 11008790528 1197420352 12.752 100 75 151 0 1670999536 453961344 19617840
12:01:50 5663 8192000000 10548206496 10992816128 1185742848 12.644 100 66 123 1 1649002624 447966144 15293632
12:02:58 15118 8192000000 10572999568 11010400256 1182018144 12.609 100 49 93 1 1675054960 480849664 12187152
12:04:41 25434 8192000000 10560042688 11026870272 1177988992 12.571 100 40 80 0 1675584320 486689824 10905504
12:06:31 11138 8192000000 10488417856 11042770944 1164760608 12.448 100 38 75 0 1604968832 430122080 10086144
12:08:07 41426 8192000000 10293411456 10791763968 1147699952 12.288 100 22 42 0 1456102800 302835856 5566992
12:09:49 56664 8192000000 10200612432 10638954496 1138566144 12.202 100 37 70 0 1263769776 117442752 7760880
12:11:46 22876 8192000000 10068405280 10523316224 1140347136 12.219 100 35 69 2 1167955584 20848800 6759648
12:13:10 34023 8192000000 10075534944 10537197568 1110295648 11.935 100 17 33 0 1149637408 35864096 3477664
12:14:47 115713 8192000000 10101312176 10570158080 1120472512 12.031 100 18 36 0 1243485488 118980416 4032560
12:17:58 55131 8192000000 10155655296 10595561472 1137549728 12.192 100 25 52 0 1231605440 87815504 6240208
12:20:20 127936 8192000000 10054859488 10488573952 1073698512 11.587 95 12 24 0 1156250192 79851920 2699760
12:23:30 7899 8192000000 10046799728 10464567296 1103586144 11.872 99 53 105 0 1130534368 13448496 13499728
12:24:39 16176 8192000000 10067747440 10504818688 1121984032 12.046 100 53 95 3 1153147264 20348096 10815136
12:25:57 161833 8192000000 10077495584 10526814208 1098665200 11.825 97 8 15 0 1181904000 81581792 1657008
12:29:39 70389 8192000000 10050312080 10493087744 1127160720 12.095 100 45 78 0 1166704048 31601888 7941440
12:31:51 133455 8192000000 10067879792 10489569280 1019783872 11.070 90 38 73 1 1091827104 64550160 7493072
12:35:10 52668 8192000000 10098356336 10558111744 1122185568 12.048 100 45 90 0 1229615904 97071920 10358416
12:37:18 63814 8192000000 10116277328 10568232960 1139851248 12.214 100 12 24 0 1220240368 77768512 2620608
12:39:22 42108 8192000000 10112412336 10570801152 1138326528 12.200 100 16 32 0 1208659984 66943744 3389712
12:41:09 46825 8192000000 10074154224 10523062272 1129292944 12.115 99 24 41 0 1156586720 23270128 4023648
12:42:58 32697 8192000000 10078441568 10528354304 1143269008 12.246 100 12 22 2 1173722672 28450768 2002896
12:44:31 40660 8192000000 10111304960 10589855744 1148323088 12.294 99 13 26 0 1206830160 55502960 3004112
12:46:16 28547 8192000000 10132873072 10580758528 1148766368 12.298 100 39 76 1 1227364896 69937040 8661488
12:47:51 24639 8192000000 10121434448 10560249856 1134894176 12.167 98 30 61 0 1180183936 38664928 6624832
12:49:19 66463 8192000000 10136950512 10590973952 1137687600 12.194 99 21 41 0 1221120336 79017008 4415728
12:51:29 15657 8192000000 10101484448 10533748736 1132610240 12.146 99 57 112 1 1195900096 50219152 13070704
12:52:45 40258 8192000000 10082024784 10525179904 1113462416 11.965 98 25 50 0 1155750096 36884384 5403296
12:54:26 35129 8192000000 10136176816 10631725056 1139077744 12.207 100 32 64 0 1249206976 103303968 6825264
12:56:08 110397 8192000000 10131831632 10600521728 1142846224 12.242 100 35 70 0 1201169824 50942048 7381552
13:00:42 42665 8192000000 10035113184 10493489152 1096254976 11.802 98 7 12 0 1126210768 28656848 1298944
13:02:48 44928 8192000000 10097415840 10551410688 1124572736 12.070 100 16 32 0 1186148544 57997504 3578304
13:04:33 81230 8192000000 10103983984 10577047552 1103976672 11.875 98 19 38 0 1192899680 84662272 4260736
13:07:12 42593 8192000000 10079717424 10516955136 1105416608 11.889 98 19 38 0 1146264560 37157680 3690272
13:08:55 48873 8192000000 10081484128 10567028736 1089952800 11.742 96 30 60 0 1154694448 58300912 6440736
13:10:59 40197 8192000000 10133478960 10588848128 1115616400 11.986 98 34 66 0 1216936992 93709648 7610944
13:13:11 59408 8192000000 10074437024 10573074432 1098795792 11.826 98 18 37 0 1158891744 56507440 3588512
13:15:22 14251 8192000000 10125745376 10547781632 1109677296 11.929 99 62 121 0 1203829760 82103808 12048656
13:16:36 30861 8192000000 10078128288 10539057152 1116499776 11.994 99 28 52 0 1176928720 55302032 5126912
13:18:07 51580 8192000000 10051505408 10482941952 1071875360 11.570 97 35 68 0 1133777296 54920224 6981712
13:20:00 135162 8192000000 10041562800 10502852608 1112509776 11.956 100 39 78 1 1178507984 57894640 8103568
13:23:21 46439 8192000000 10067282528 10548994048 1127204752 12.095 100 13 26 0 1194808128 65098512 2504864
13:25:12 60440 8192000000 10049560816 10506625024 1117513920 12.003 99 16 32 1 1172782032 51956960 3311152
13:27:50 54259 8192000000 10078676592 10534707200 1135029856 12.169 100 16 32 0 1180096016 42019808 3046352
13:29:46 73922 8192000000 10097667072 10558480384 1075450688 11.604 95 12 23 0 1137753216 59684048 2618480
13:32:00 22175 8192000000 10112679840 10555432960 1131454912 12.135 100 32 64 0 1211261488 73234128 6572448
13:33:26 16323 8192000000 10170131328 10602921984 1138511392 12.202 100 46 91 1 1249578608 101255488 9811728
13:34:54 36240 8192000000 10166485616 10625081344 1138047440 12.197 99 41 81 1 1266802544 119699040 9056064
13:36:33 37412 8192000000 10224852240 10686279680 1155460432 12.361 100 44 88 0 1338654240 173170240 10023568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-07-2022 13:39:10 to 26-07-2022 13:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-07-2022 13:39:27 to 26-07-2022 13:39:29.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          207558
MON$OLDEST_ACTIVE               217664
MON$OLDEST_SNAPSHOT             217664
MON$NEXT_TRANSACTION            217664
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-30 07:30:08.6030 GMT
MON$PAGES                       2058080
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:48: 
:49: mscs-test-161.budzdorov.ru    Tue Jul 26 13:37:23 2022
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Tue Jul 26 13:37:26 2022
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
--- end of diff output ---

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

26.07.22 13:39:30. There are 3 logs in /var/tmp/logs-oltp40 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-26 13:39:33 No severe PSQL-related problems occured
26.07.22 13:39:34. End of report.