Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.10.2021 04: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15809112 kB
MemAvailable 30615612 kB
Buffers 20 kB
Cached 15842908 kB
SwapCached 3716 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.1
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 2021-10-20 04:37:08
DTS_END                         2021-10-20 04:37:08.6140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46710
TRN_ID                          227719
ATT_ID                          633
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: 8638.25 27081 1036590
customer order: creation 160.90 418 19308
customer order: refuse 13.98 342 1677
order to supplier: creation 42.97 1440 5156
order to supplier: removal 3.26 137257 391
invoice (draft): creation 43.96 3874 5275
invoice (draft): removal 49.63 1198 5956
invoice accept: apply 38.24 18340 4589
invoice accept: cancel 44.57 927 5348
customer reserve: creation 3541.93 180 425031
customer reserve: removal 4418.57 78 530228
realization accept: apply 77.98 183 9358
realization accept: cancel 82.69 123 9923
payment from customer: creation 54.36 113 6523
payment from customer: removal 5.33 91 639
payment to supplier: creation 46.28 145 5554
payment to supplier: removal 2.88 74 346
service: total inventory turnovers 3.53 3529 423
service: total monetary turnovers 6.34 430 761
service: aggregate perf. data 0.83 17556 100
service: refresh index statistics 0.03 355320 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2021 04:38:39 to 20-10-2021 04:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3022
TEST_TIME 2 3645
TEST_TIME 3 3981
TEST_TIME 4 4082
TEST_TIME 5 4513
TEST_TIME 6 4965
TEST_TIME 7 4810
TEST_TIME 8 5210
TEST_TIME 9 5482
TEST_TIME 10 5928
TEST_TIME 11 6107
TEST_TIME 12 6311
TEST_TIME 13 7091
TEST_TIME 14 7121
TEST_TIME 15 7360
TEST_TIME 16 7286
TEST_TIME 17 7242
TEST_TIME 18 7433
TEST_TIME 19 8222
TEST_TIME 20 8302
TEST_TIME 21 8702
TEST_TIME 22 8589
TEST_TIME 23 8519
TEST_TIME 24 9565
TEST_TIME 25 10387
TEST_TIME 26 11870
TEST_TIME 27 11568
TEST_TIME 28 11252
TEST_TIME 29 11050
TEST_TIME 30 10839
TEST_TIME 31 10585
TEST_TIME 32 10470
TEST_TIME 33 10240
TEST_TIME 34 10150
TEST_TIME 35 10073
TEST_TIME 36 9911
TEST_TIME 37 9906
TEST_TIME 38 9764
TEST_TIME 39 9651
TEST_TIME 40 9595
TEST_TIME 41 9618
TEST_TIME 42 9523
TEST_TIME 43 9479
TEST_TIME 44 9297
TEST_TIME 45 9218
TEST_TIME 46 9197
TEST_TIME 47 9032
TEST_TIME 48 9044
TEST_TIME 49 8998
TEST_TIME 50 9067
TEST_TIME 51 9050
TEST_TIME 52 9010
TEST_TIME 53 8958
TEST_TIME 54 8951
TEST_TIME 55 9006
TEST_TIME 56 8968
TEST_TIME 57 9026
TEST_TIME 58 8941
TEST_TIME 59 8975
TEST_TIME 60 9050
TEST_TIME 61 9081
TEST_TIME 62 9128
TEST_TIME 63 9024
TEST_TIME 64 8916
TEST_TIME 65 8867
TEST_TIME 66 8773
TEST_TIME 67 8687
TEST_TIME 68 8756
TEST_TIME 69 8767
TEST_TIME 70 8699
TEST_TIME 71 8659
TEST_TIME 72 8604
TEST_TIME 73 8582
TEST_TIME 74 8634
TEST_TIME 75 8598
TEST_TIME 76 8675
TEST_TIME 77 8672
TEST_TIME 78 8643
TEST_TIME 79 8661
TEST_TIME 80 8761
TEST_TIME 81 8773
TEST_TIME 82 8766
TEST_TIME 83 8815
TEST_TIME 84 8788
TEST_TIME 85 8848
TEST_TIME 86 8755
TEST_TIME 87 8698
TEST_TIME 88 8659
TEST_TIME 89 8618
TEST_TIME 90 8587
TEST_TIME 91 8576
TEST_TIME 92 8649
TEST_TIME 93 8667
TEST_TIME 94 8668
TEST_TIME 95 8652
TEST_TIME 96 8694
TEST_TIME 97 8704
TEST_TIME 98 8719
TEST_TIME 99 8691
TEST_TIME 100 8801
TEST_TIME 101 8863
TEST_TIME 102 8832
TEST_TIME 103 8851
TEST_TIME 104 8897
TEST_TIME 105 8818
TEST_TIME 106 8821
TEST_TIME 107 8808
TEST_TIME 108 8802
TEST_TIME 109 8754
TEST_TIME 110 8712
TEST_TIME 111 8668
TEST_TIME 112 8597
TEST_TIME 113 8624
TEST_TIME 114 8668
TEST_TIME 115 8688
TEST_TIME 116 8661
TEST_TIME 117 8723
TEST_TIME 118 8714
TEST_TIME 119 8694
TEST_TIME 120 8640

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-10-2021 04:38:42 to 20-10-2021 04:38:46.

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 1431070 1431070 0 0.00 0 19255 7 0 0
doc_list_biud 1431069 1431069 0 0.00 0 105952 37 0 0
sp_add_invoice_to_stock 4684 4589 95 2.02 35 195389 18341 0 95
sp_cancel_adding_invoice 5348 5348 0 0.00 6 76810 928 0 0
sp_cancel_client_order 1677 1677 0 0.00 5 11758 342 0 0
sp_cancel_customer_reserve 530228 530228 0 0.00 1 27355 79 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 3 13684 92 0 0
sp_cancel_pay_to_supplier 346 346 0 0.00 2 1795 74 0 0
sp_cancel_supplier_invoice 5961 5956 5 0.08 2 105956 1198 0 5
sp_cancel_supplier_order 391 391 0 0.00 10 1344511 137257 0 0
sp_cancel_write_off 9923 9923 0 0.00 1 9042 124 0 0
sp_client_order 19308 19308 0 0.00 4 26650 419 0 0
sp_customer_reserve 425031 425031 0 0.00 0 145869 180 0 0
sp_fill_shopping_cart 29730 29730 0 0.00 1 13558 53 0 0
sp_fill_shopping_cart_clo_res 425025 425025 0 0.00 0 15720 18 0 0
sp_get_clo_for_invoice 4583 4583 0 0.00 0 23822 462 0 0
sp_kill_cost_storno 16256 16256 0 0.00 0 19193 66 0 0
sp_kill_qstorno_ret_qs2qd 1076726 1076726 0 0.00 0 105951 43 0 0
sp_kill_qty_storno 567464 567464 0 0.00 0 105952 91 0 0
sp_lock_selected_doc 19220 19220 0 0.00 0 6119 4 0 0
sp_make_cost_storno 26018 26018 0 0.00 0 17224 58 0 0
sp_multiply_rows_for_pdistr 26018 26018 0 0.00 0 8062 34 0 0
sp_multiply_rows_for_qdistr 19308 19308 0 0.00 0 26635 229 0 0
sp_pay_from_customer 6523 6523 0 0.00 4 11780 114 0 0
sp_pay_to_supplier 5554 5554 0 0.00 5 13895 146 0 0
sp_payment_common 12076 12076 0 0.00 1 10740 24 0 0
sp_qd_handle_on_cancel_clo 1677 1677 0 0.00 0 11751 310 0 0
sp_qd_handle_on_invoice_upd_sts 9931 9931 0 0.00 0 18381 389 0 0
sp_qd_handle_on_reserve_upd_sts 9358 9358 0 0.00 0 9559 53 0 0
sp_reserve_write_off 9358 9358 0 0.00 7 12844 183 0 0
sp_supplier_invoice 5275 5275 0 0.00 6 120077 3874 0 0
sp_supplier_order 5156 5156 0 0.00 6 26639 1440 0 0
srv_aggregate_perf_data 100 100 0 0.00 2990 272136 17557 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1361 168320 46555 0 0
srv_find_qd_qs_mism 992741 992741 0 0.00 0 17939 5 0 0
srv_make_invnt_saldo 423 423 0 0.00 59 101912 3529 0 0
srv_make_money_saldo 761 761 0 0.00 1 11223 430 0 0
srv_recalc_idx_stat 4 4 0 0.00 250308 532561 355320 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4589 4589 0 0.00 0 26065 153 0 0
t$perf-norm:sp_cancel_adding_invoice 339 339 0 0.00 0 2876 97 0 0
t$perf-norm:sp_cancel_client_order 1677 1677 0 0.00 0 4178 11 0 0
t$perf-norm:sp_cancel_customer_reserve 604 604 0 0.00 0 505 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 3379 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 346 346 0 0.00 0 144 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 359 359 0 0.00 0 4097 21 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 19299 1525 0 0
t$perf-norm:sp_cancel_write_off 924 924 0 0.00 0 1546 10 0 0
t$perf-norm:sp_client_order 19308 19308 0 0.00 0 10482 17 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 2502 6 0 0
t$perf-norm:sp_pay_from_customer 6523 6523 0 0.00 0 7691 11 0 0
t$perf-norm:sp_pay_to_supplier 5554 5554 0 0.00 0 16613 12 0 0
t$perf-norm:sp_reserve_write_off 9358 9358 0 0.00 0 6375 10 0 0
t$perf-norm:sp_supplier_invoice 5275 5275 0 0.00 0 13287 20 0 0
t$perf-norm:sp_supplier_order 5156 5156 0 0.00 0 1716 14 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 420 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 20 1 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 1708 12 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 1958 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9356 9166 190 2.03 0 6495 18 0 190
v_all_customers 35200 35200 0 0.00 0 19 1 0 0
v_all_suppliers 29136 29136 0 0.00 0 142 1 0 0
v_all_wares 38616 38616 0 0.00 0 141 1 0 0
v_cancel_adding_invoice 678 678 0 0.00 0 238 6 0 0
v_cancel_client_order 3354 3354 0 0.00 0 4136 7 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 403 4 0 0
v_cancel_customer_reserve 1208 1208 0 0.00 0 2020 6 0 0
v_cancel_payment_to_supplier 692 692 0 0.00 0 1789 13 0 0
v_cancel_supplier_invoice 728 718 10 1.37 0 706 13 0 10
v_cancel_supplier_order 782 782 0 0.00 0 145 3 0 0
v_cancel_write_off 1848 1848 0 0.00 0 3772 18 0 0
v_max_id_clo_ord 5154 5154 0 0.00 0 722 1 0 0
v_max_id_clo_res 971 971 0 0.00 0 4593 20 0 0
v_max_id_ord_sup 5268 5268 0 0.00 0 4271 4 0 0
v_max_non_paid_invoice 1405 1405 0 0.00 0 2128 5 0 0
v_max_non_paid_realizn 3432 3432 0 0.00 0 4039 4 0 0
v_min_id_clo_ord 5154 5154 0 0.00 0 3152 7 0 0
v_min_id_clo_res 971 971 0 0.00 0 6050 19 0 0
v_min_id_ord_sup 5268 5268 0 0.00 0 10992 11 0 0
v_min_non_paid_invoice 5554 5554 0 0.00 0 6627 16 0 0
v_min_non_paid_realizn 6522 6522 0 0.00 0 6626 17 0 0
v_our_firm 9596 9596 0 0.00 0 87 1 0 0
v_reserve_write_off 18716 18716 0 0.00 0 4720 6 0 0
x_lock_depdocs_on_canc_invoice 10945 10945 0 0.00 0 20446 143 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 5368 358 0 0
x_make_qstorno_1000_1200 5156 5156 0 0.00 2 26584 1364 0 0
x_make_qstorno_1200_2000 5275 5275 0 0.00 3 116303 3659 0 0
x_make_qstorno_2100_3300 425031 425031 0 0.00 0 145814 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2021 04:38:47 to 20-10-2021 04:38:51.

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
02:07:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:13 23174 8192000000 9733832624 10031308800 805020176 8.947 100 91 182 0 835052816 7809056 22223584
02:09:49 25236 8192000000 9768597632 10078076928 842411824 9.324 100 89 177 1 876754992 13356256 20986912
02:11:25 21976 8192000000 9816484992 10139451392 879991424 9.700 100 83 167 1 929202352 29323584 19887344
02:12:52 10763 8192000000 9881048256 10216579072 923364944 10.129 100 77 154 0 989089040 49118272 16605824
02:14:08 7287 8192000000 9936931952 10283814912 971753504 10.604 100 78 156 0 1050999280 62949584 16296192
02:15:18 3747 8192000000 9986781232 10339913728 1005669968 10.933 100 86 171 1 1094880368 72272048 16938352
02:16:23 2540 8192000000 9985113600 10350264320 1030822640 11.176 100 92 180 2 1108613584 60312128 17478816
02:17:27 2340 8192000000 10045184352 10415095808 1056370736 11.422 100 86 172 1 1156529264 82832592 17325936
02:18:31 9485 8192000000 10038791040 10409091072 1069848352 11.551 100 81 162 1 1138166416 51994464 16323600
02:19:41 7649 8192000000 10004035312 10381905920 1080624480 11.653 100 46 93 2 1123202176 33516928 9060768
02:20:49 5279 8192000000 9999135136 10389782528 1085780480 11.703 100 31 62 0 1125392272 33046400 6565392
02:21:58 59152 8192000000 10017890112 10407186432 1088956784 11.733 100 55 105 3 1121368928 21406544 11005600
02:23:57 7099 8192000000 10012107232 10425069568 1092181616 11.763 100 23 46 0 1128031968 31385456 4464896
02:25:05 5112 8192000000 10013559520 10408214528 1098675264 11.825 100 37 74 1 1122965488 16852176 7438048
02:26:11 4907 8192000000 10016498480 10437124096 1088094896 11.725 99 24 48 1 1141454416 48543024 4816496
02:27:18 39240 8192000000 10005309520 10420826112 1056536416 11.423 96 38 74 1 1081864384 17373984 7953984
02:28:57 5119 8192000000 9958640096 10344423424 1011063904 10.986 96 40 79 0 1057197008 36860448 9272656
02:30:03 9374 8192000000 9942296688 10323972096 1016681056 11.040 99 31 62 0 1049331264 25891216 6758992
02:31:13 43603 8192000000 9947189904 10337140736 1014516448 11.019 98 26 52 1 1037671968 17859472 5296048
02:32:57 7094 8192000000 9968309728 10360991744 1036318976 11.229 100 57 112 2 1076655632 27746256 12590400
02:34:07 5103 8192000000 9967900768 10346962944 1045702672 11.319 100 49 98 2 1074599680 18127632 10769376
02:35:12 6398 8192000000 9973329696 10373320704 1061082160 11.467 100 46 91 0 1089773168 17642464 11048544
02:36:20 48415 8192000000 9973200704 10377662464 1069008880 11.543 100 19 38 1 1092401280 19622640 3769760
02:38:20 19108 8192000000 10019174848 10417655808 1098752624 11.826 100 38 77 1 1129983488 23273888 7956976
02:40:07 7963 8192000000 10097823408 10493808640 1124852624 12.073 100 61 120 0 1207387952 69279104 13256224
02:41:17 23558 8192000000 10152653840 10557988864 1133522416 12.155 100 44 88 0 1254564464 111486176 9555872
02:42:44 38874 8192000000 10200989680 10625073152 1142122864 12.235 100 50 100 0 1310943760 158466160 10354736
02:44:27 16562 8192000000 10252756864 10672357376 1162755840 12.429 100 42 84 0 1360394496 187988032 9650624
02:45:47 5482 8192000000 10297164784 10712915968 1171243040 12.508 100 73 144 0 1402016880 214802576 15971264
02:47:02 2189 8192000000 10320259808 10729140224 1173149568 12.526 100 77 154 3 1429072112 238262592 17659952
02:48:08 3104 8192000000 10347594416 10754002944 1181866848 12.608 100 80 159 1 1448831600 249192816 17771936
02:49:15 3705 8192000000 10359844416 10769121280 1169016608 12.488 99 80 158 3 1463449632 275758528 18674496
02:50:22 3860 8192000000 10387968016 10800029696 1166132352 12.461 99 71 137 2 1479051968 297069808 15849808
02:51:29 5313 8192000000 10389851280 10810978304 1177233904 12.564 100 53 106 1 1494294000 304565040 12495056
02:52:41 1360 8192000000 10416368880 10825486336 1181185392 12.601 100 85 170 1 1513358784 312205056 19968336
02:53:49 1663 8192000000 10460143904 10866098176 1163052976 12.432 99 76 152 2 1548516400 367504640 17958784
02:54:55 3040 8192000000 10492256192 10912980992 1157482064 12.380 99 82 157 0 1579939536 403115392 19342080
02:56:04 2144 8192000000 10507380176 10951454720 1180336304 12.593 100 74 148 1 1627227376 428806432 18084640
02:57:11 7367 8192000000 10506581584 10927869952 1163396192 12.435 99 51 102 1 1600599552 423904544 13298816
02:58:22 4748 8192000000 10498212848 10928197632 1158283056 12.387 99 45 90 0 1594633392 424473456 11876880
02:59:30 11976 8192000000 10535347680 10982731776 1158797904 12.392 100 39 78 1 1634595440 465203152 10594384
03:00:47 15718 8192000000 10608640896 11061215232 1129123600 12.113 99 36 72 0 1708907424 569073952 10709872
03:02:13 15161 8192000000 10428063216 10897346560 1099248240 11.831 98 30 60 1 1535554544 427251328 9054976
03:03:33 50955 8192000000 10067546768 10517282816 1103920224 11.875 97 15 30 1 1147530560 40748656 2861680
03:05:46 52414 8192000000 10099722912 10543853568 1124832992 12.073 100 22 44 1 1207147200 77423104 4891104
03:07:45 168284 8192000000 10087087472 10514534400 1105638832 11.891 97 31 61 0 1192374464 80086880 6648752
03:11:37 72073 8192000000 10095091440 10537742336 1092483248 11.766 96 18 34 2 1168379216 72741440 3154528
03:13:58 16101 8192000000 10099612352 10546204672 1126581296 12.089 100 28 55 1 1207676208 75476064 5618848
03:15:29 28442 8192000000 10113600336 10547400704 1145380048 12.266 100 37 74 3 1212536576 59247632 7908896
03:17:03 73053 8192000000 10126374448 10596306944 1141957840 12.234 100 19 38 1 1229735472 83553344 4224288
03:19:24 79055 8192000000 10059715392 10536263680 1126449152 12.088 99 10 20 0 1176247712 47756592 2041968
03:21:45 69302 8192000000 10082756768 10546294784 1131774784 12.138 99 16 32 0 1190781904 55743712 3263408
03:25:20 57999 8192000000 10053351168 10552889344 1140382864 12.219 100 20 40 0 1181193392 36638576 4171952
03:27:36 85826 8192000000 10061793696 10536132608 1134205520 12.161 99 8 16 0 1196283488 60156640 1921328
03:30:02 65939 8192000000 10088215952 10526212096 1138622608 12.203 99 13 26 0 1178337632 37095824 2619200
03:32:09 65021 8192000000 10101688512 10550185984 1140341120 12.219 99 24 48 0 1199791536 54831232 4619184
03:34:20 84897 8192000000 10074015584 10505486336 1096684208 11.806 95 19 38 0 1122315024 22357344 3273472
03:36:47 55739 8192000000 10137482208 10589749248 1137567040 12.193 99 26 51 1 1217695680 75375792 4752848
03:38:44 68013 8192000000 10073166688 10557943808 1124931664 12.074 98 9 19 0 1173930864 47221648 1777552
03:40:52 58222 8192000000 10081558816 10517012480 1121484160 12.041 98 27 53 1 1143509664 16354448 5671056
03:42:51 50899 8192000000 10114720688 10576769024 1145939200 12.271 100 6 10 2 1216893552 69818592 1135760
03:44:43 70546 8192000000 10119239216 10610995200 1120801360 12.035 98 13 27 0 1198931520 75196400 2933760
03:47:08 43251 8192000000 10048060752 10483515392 1094888576 11.789 97 13 26 1 1127116336 29143152 3084608
03:48:57 73885 8192000000 10065875712 10519838720 1107280848 11.907 97 17 34 0 1158662720 47830816 3551056
03:51:18 51626 8192000000 10060769440 10530390016 1094701504 11.787 97 19 38 0 1152488208 53495120 4291584
03:53:12 58325 8192000000 10051848848 10490748928 1104507680 11.880 98 19 38 0 1136557456 28145520 3904256
03:55:12 25120 8192000000 10055696608 10487500800 1081021840 11.657 97 25 50 0 1133124896 46377232 5725824
03:56:43 58934 8192000000 10072569824 10512105472 1055381840 11.412 95 23 46 0 1133579264 73182976 5014448
03:58:48 89674 8192000000 10068754368 10494160896 1047984192 11.341 95 23 46 0 1122412736 69678016 4750528
04:01:19 51784 8192000000 10094441040 10516836352 1076483152 11.614 97 25 50 1 1134811424 53418048 4910224
04:03:17 80094 8192000000 10044912080 10469588992 1100541888 11.843 99 31 62 3 1133701536 27150608 6009040
04:05:43 68791 8192000000 10073917264 10539278336 1108018736 11.914 99 12 24 0 1171789680 61087424 2683520
04:07:53 59076 8192000000 10100103632 10560032768 1084856880 11.694 96 16 32 0 1167776816 79485792 3434144
04:09:57 63524 8192000000 10057684400 10504294400 1114427552 11.974 99 12 24 0 1150485504 33864656 2193296
04:12:01 46790 8192000000 10101517552 10535034880 1124537936 12.070 99 18 36 0 1186798816 58814032 3446848
04:13:47 54721 8192000000 10082917216 10535968768 1117685536 12.005 99 21 41 0 1189918416 67684880 4548000
04:15:42 54948 8192000000 10146914176 10583592960 1142900848 12.243 100 34 67 1 1210611632 60532736 7178048
04:17:42 114348 8192000000 10099513648 10559283200 1116973264 11.998 98 19 38 1 1166521008 46086096 3461648
04:20:37 14046 8192000000 10133304752 10561867776 1149473776 12.305 100 48 96 1 1213277808 54313056 9490976
04:21:55 94456 8192000000 10073747136 10542534656 1138142016 12.198 99 24 48 1 1192082032 48533344 5406672
04:24:31 38704 8192000000 10093567776 10548969472 1145744688 12.270 100 28 56 0 1201146448 49470592 5931168
04:26:13 52866 8192000000 10071030752 10533687296 1140278224 12.218 99 9 18 0 1165931920 23226272 2427424
04:28:07 71877 8192000000 10132369664 10587181056 1136171264 12.180 98 15 28 1 1204058320 64739024 3148032
04:30:56 66866 8192000000 10116345648 10612805632 1135717712 12.175 99 12 24 1 1255836928 117109600 3009616
04:33:06 68934 8192000000 10127072560 10574778368 1137279872 12.190 99 18 36 1 1215524768 73961776 4283120
04:35:21 64567 8192000000 10151880288 10598813696 1115566704 11.985 97 17 34 1 1188967440 69815808 3584928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-10-2021 04:38:53 to 20-10-2021 04:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 20-10-2021 04:39:09 to 20-10-2021 04:39:10.

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          220955
MON$OLDEST_ACTIVE               227828
MON$OLDEST_SNAPSHOT             227828
MON$NEXT_TRANSACTION            227828
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                       2036704
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:357: 
:358: mscs-test-161.budzdorov.ru    Wed Oct 20 04:37:26 2021
:359:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:360: 
:361: 
:362: mscs-test-161.budzdorov.ru    Wed Oct 20 04:37:27 2021
:363:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:364: 
:365: 
:366: mscs-test-161.budzdorov.ru    Wed Oct 20 04:37:27 2021
:367:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:368: 
--- end of diff output ---

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

20.10.21 04:39:12. 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
2021-10-20 04:39:14 No severe PSQL-related problems occured
20.10.21 04:39:15. End of report.