Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.09.2021 10: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 16104308 kB
MemAvailable 30848616 kB
Buffers 0 kB
Cached 15577052 kB
SwapCached 392 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-09-11 10:37:07
DTS_END                         2021-09-11 10:37:07.3680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35990
TRN_ID                          233404
ATT_ID                          618
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: 8830.89 20921 1059707
customer order: creation 166.60 463 19992
customer order: refuse 14.37 339 1724
order to supplier: creation 44.07 1512 5288
order to supplier: removal 3.27 112252 392
invoice (draft): creation 44.45 3381 5334
invoice (draft): removal 48.98 933 5878
invoice accept: apply 40.53 18840 4863
invoice accept: cancel 44.83 923 5379
customer reserve: creation 3737.69 184 448523
customer reserve: removal 4401.18 66 528141
realization accept: apply 81.35 209 9762
realization accept: cancel 79.03 122 9484
payment from customer: creation 56.83 138 6819
payment from customer: removal 5.57 104 668
payment to supplier: creation 47.77 156 5732
payment to supplier: removal 3.33 77 400
service: total inventory turnovers 4.03 3185 483
service: total monetary turnovers 6.19 451 743
service: aggregate perf. data 0.82 14742 98
service: refresh index statistics 0.03 260340 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-09-2021 10:38:23 to 11-09-2021 10:38:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5469
TEST_TIME 2 5688
TEST_TIME 3 5640
TEST_TIME 4 5084
TEST_TIME 5 4750
TEST_TIME 6 4686
TEST_TIME 7 4897
TEST_TIME 8 5119
TEST_TIME 9 5578
TEST_TIME 10 5589
TEST_TIME 11 6453
TEST_TIME 12 6590
TEST_TIME 13 6610
TEST_TIME 14 7100
TEST_TIME 15 7340
TEST_TIME 16 7495
TEST_TIME 17 7744
TEST_TIME 18 8233
TEST_TIME 19 8614
TEST_TIME 20 9067
TEST_TIME 21 9750
TEST_TIME 22 9962
TEST_TIME 23 9946
TEST_TIME 24 10378
TEST_TIME 25 10942
TEST_TIME 26 11356
TEST_TIME 27 11029
TEST_TIME 28 10758
TEST_TIME 29 10594
TEST_TIME 30 10502
TEST_TIME 31 10281
TEST_TIME 32 10239
TEST_TIME 33 10119
TEST_TIME 34 9955
TEST_TIME 35 9854
TEST_TIME 36 9616
TEST_TIME 37 9735
TEST_TIME 38 9655
TEST_TIME 39 9635
TEST_TIME 40 9522
TEST_TIME 41 9476
TEST_TIME 42 9490
TEST_TIME 43 9533
TEST_TIME 44 9515
TEST_TIME 45 9463
TEST_TIME 46 9660
TEST_TIME 47 9712
TEST_TIME 48 9583
TEST_TIME 49 9756
TEST_TIME 50 9865
TEST_TIME 51 9853
TEST_TIME 52 9828
TEST_TIME 53 9660
TEST_TIME 54 9565
TEST_TIME 55 9485
TEST_TIME 56 9466
TEST_TIME 57 9482
TEST_TIME 58 9398
TEST_TIME 59 9380
TEST_TIME 60 9338
TEST_TIME 61 9235
TEST_TIME 62 9221
TEST_TIME 63 9186
TEST_TIME 64 9204
TEST_TIME 65 9148
TEST_TIME 66 9146
TEST_TIME 67 9275
TEST_TIME 68 9236
TEST_TIME 69 9345
TEST_TIME 70 9345
TEST_TIME 71 9372
TEST_TIME 72 9291
TEST_TIME 73 9309
TEST_TIME 74 9433
TEST_TIME 75 9397
TEST_TIME 76 9301
TEST_TIME 77 9282
TEST_TIME 78 9226
TEST_TIME 79 9215
TEST_TIME 80 9168
TEST_TIME 81 9136
TEST_TIME 82 9105
TEST_TIME 83 9070
TEST_TIME 84 9047
TEST_TIME 85 9026
TEST_TIME 86 9103
TEST_TIME 87 9094
TEST_TIME 88 9186
TEST_TIME 89 9202
TEST_TIME 90 9163
TEST_TIME 91 9213
TEST_TIME 92 9256
TEST_TIME 93 9254
TEST_TIME 94 9218
TEST_TIME 95 9146
TEST_TIME 96 9138
TEST_TIME 97 9074
TEST_TIME 98 9045
TEST_TIME 99 9001
TEST_TIME 100 8977
TEST_TIME 101 9011
TEST_TIME 102 8974
TEST_TIME 103 8954
TEST_TIME 104 8974
TEST_TIME 105 9014
TEST_TIME 106 8980
TEST_TIME 107 9001
TEST_TIME 108 9097
TEST_TIME 109 9072
TEST_TIME 110 9111
TEST_TIME 111 9075
TEST_TIME 112 9015
TEST_TIME 113 8965
TEST_TIME 114 9030
TEST_TIME 115 8985
TEST_TIME 116 8964
TEST_TIME 117 8917
TEST_TIME 118 8931
TEST_TIME 119 8911
TEST_TIME 120 8840

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-09-2021 10:38:27 to 11-09-2021 10:38:30.

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 1474338 1474338 0 0.00 0 29868 7 0 0
doc_list_biud 1474336 1474336 0 0.00 0 27946 30 0 0
sp_add_invoice_to_stock 4995 4863 132 2.64 38 197299 18841 0 132
sp_cancel_adding_invoice 5379 5379 0 0.00 4 89105 924 0 0
sp_cancel_client_order 1724 1724 0 0.00 5 17870 340 0 0
sp_cancel_customer_reserve 528141 528141 0 0.00 1 26038 66 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 6722 105 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 2448 77 0 0
sp_cancel_supplier_invoice 5887 5878 9 0.15 1 28003 934 0 9
sp_cancel_supplier_order 392 392 0 0.00 7 1194581 112253 0 0
sp_cancel_write_off 9484 9484 0 0.00 1 17303 123 0 0
sp_client_order 19992 19992 0 0.00 4 60151 463 0 0
sp_customer_reserve 448523 448523 0 0.00 0 49419 185 0 0
sp_fill_shopping_cart 30596 30596 0 0.00 1 18563 45 0 0
sp_fill_shopping_cart_clo_res 448508 448508 0 0.00 0 14623 17 0 0
sp_get_clo_for_invoice 4846 4846 0 0.00 0 20595 421 0 0
sp_kill_cost_storno 15931 15931 0 0.00 0 8280 61 0 0
sp_kill_qstorno_ret_qs2qd 1072036 1072036 0 0.00 0 27943 36 0 0
sp_kill_qty_storno 565604 565604 0 0.00 0 27945 77 0 0
sp_lock_selected_doc 20184 20184 0 0.00 0 7443 6 0 0
sp_make_cost_storno 27159 27159 0 0.00 0 23217 70 0 0
sp_multiply_rows_for_pdistr 27157 27157 0 0.00 0 12056 36 0 0
sp_multiply_rows_for_qdistr 19992 19992 0 0.00 0 60141 239 0 0
sp_pay_from_customer 6819 6819 0 0.00 4 15822 138 0 0
sp_pay_to_supplier 5732 5732 0 0.00 5 29965 157 0 0
sp_payment_common 12551 12551 0 0.00 1 12957 26 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 17853 306 0 0
sp_qd_handle_on_invoice_upd_sts 10224 10224 0 0.00 0 22207 368 0 0
sp_qd_handle_on_reserve_upd_sts 9761 9761 0 0.00 0 27401 65 0 0
sp_reserve_write_off 9762 9762 0 0.00 6 27414 210 0 0
sp_supplier_invoice 5334 5334 0 0.00 6 111461 3382 0 0
sp_supplier_order 5288 5288 0 0.00 6 63386 1513 0 0
srv_aggregate_perf_data 98 98 0 0.00 2689 202354 14742 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1500 142745 48800 0 0
srv_find_qd_qs_mism 1013170 1013170 0 0.00 0 27857 6 0 0
srv_make_invnt_saldo 483 483 0 0.00 70 90048 3186 0 0
srv_make_money_saldo 743 743 0 0.00 1 15330 452 0 0
srv_recalc_idx_stat 4 4 0 0.00 175174 334254 260341 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 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 4865 4865 0 0.00 0 31620 193 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 6277 119 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 8730 16 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 6856 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 703 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 6916 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 320 320 0 0.00 0 2259 23 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 17722 1388 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 6885 10 0 0
t$perf-norm:sp_client_order 19992 19992 0 0.00 0 17063 20 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 4297 13 0 0
t$perf-norm:sp_pay_from_customer 6819 6819 0 0.00 0 15735 12 0 0
t$perf-norm:sp_pay_to_supplier 5732 5732 0 0.00 0 7699 13 0 0
t$perf-norm:sp_reserve_write_off 9762 9762 0 0.00 0 4852 10 0 0
t$perf-norm:sp_supplier_invoice 5334 5334 0 0.00 0 6268 19 0 0
t$perf-norm:sp_supplier_order 5288 5288 0 0.00 0 7934 22 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 1333 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 393 17 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 485 9 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 16324 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9954 9690 264 2.65 0 6159 17 0 264
v_all_customers 36490 36490 0 0.00 0 147 1 0 0
v_all_suppliers 29630 29630 0 0.00 0 23 1 0 0
v_all_wares 39984 39984 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 1117 8 0 0
v_cancel_client_order 3448 3448 0 0.00 0 3895 7 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 3915 16 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 2932 8 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 1003 6 0 0
v_cancel_supplier_invoice 658 640 18 2.73 0 2896 21 0 18
v_cancel_supplier_order 784 784 0 0.00 0 2173 8 0 0
v_cancel_write_off 1996 1996 0 0.00 0 5599 19 0 0
v_max_id_clo_ord 5286 5286 0 0.00 0 404 1 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 2325 13 0 0
v_max_id_ord_sup 5317 5317 0 0.00 0 3550 3 0 0
v_max_non_paid_invoice 1520 1520 0 0.00 0 360 2 0 0
v_max_non_paid_realizn 3589 3589 0 0.00 0 2566 4 0 0
v_min_id_clo_ord 5286 5286 0 0.00 0 2860 6 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 4761 20 0 0
v_min_id_ord_sup 5317 5317 0 0.00 0 8984 8 0 0
v_min_non_paid_invoice 5732 5732 0 0.00 0 12951 18 0 0
v_min_non_paid_realizn 6819 6819 0 0.00 0 9942 19 0 0
v_our_firm 9954 9954 0 0.00 0 14 1 0 0
v_reserve_write_off 19522 19522 0 0.00 0 5692 11 0 0
x_lock_depdocs_on_canc_invoice 10937 10937 0 0.00 0 16629 110 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 5787 306 0 0
x_make_qstorno_1000_1200 5288 5288 0 0.00 2 63370 1434 0 0
x_make_qstorno_1200_2000 5334 5334 0 0.00 2 111424 3208 0 0
x_make_qstorno_2100_3300 448523 448523 0 0.00 0 49419 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-09-2021 10:38:31 to 11-09-2021 10:38:35.

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
08:07:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:11 42256 8192000000 9742016864 10043330560 807775824 8.975 100 90 180 2 837691360 8344080 21571456
08:10:00 26848 8192000000 9778898160 10089246720 847890656 9.379 100 86 173 0 882801440 14840352 20070432
08:11:33 15694 8192000000 9821067552 10146975744 878074592 9.681 100 79 157 2 932645856 36927136 17644128
08:12:51 11934 8192000000 9900148928 10235744256 932773232 10.222 100 82 160 1 1000638080 50806352 17058496
08:14:08 6284 8192000000 9947298816 10294431744 972757120 10.614 100 80 160 0 1055723104 66844448 16121536
08:15:16 4570 8192000000 9989463376 10346119168 1001708320 10.895 100 75 148 1 1095149520 78701280 14739920
08:16:23 2325 8192000000 10007986992 10370863104 1042952352 11.293 100 79 159 0 1117813056 58620112 16240592
08:17:28 1847 8192000000 10031684000 10403553280 1067864880 11.532 100 91 184 3 1143549280 57382016 18302384
08:18:31 1643 8192000000 10060602864 10437828608 1078213680 11.630 100 84 167 0 1175120656 79200768 17706208
08:19:33 4936 8192000000 10026365888 10414997504 1091036096 11.753 100 47 95 3 1133129280 31825056 10268128
08:20:39 60034 8192000000 10010074016 10410541056 1102124672 11.858 100 43 86 2 1131428656 18968944 10335040
08:22:40 5740 8192000000 10022878400 10424786944 1104583248 11.881 100 31 62 3 1134671312 23362528 6725536
08:23:47 9087 8192000000 10022221776 10436792320 1106705952 11.901 100 30 60 0 1142610400 29365824 6538624
08:24:56 2701 8192000000 10022481216 10426093568 1109052144 11.923 100 36 72 1 1131192912 14107424 8033344
08:25:59 36700 8192000000 10014106576 10439774208 1096844032 11.808 99 33 66 1 1125200704 20496768 7859904
08:27:45 11444 8192000000 10002506096 10403713024 1061198496 11.468 98 45 90 0 1097808992 27137600 9472896
08:29:00 7221 8192000000 9972280544 10364248064 1017468080 11.048 96 51 99 3 1050800352 22855872 10476400
08:30:09 69418 8192000000 9897371776 10285363200 913357136 10.030 89 32 64 1 935643088 15663280 6622672
08:32:19 10157 8192000000 9951437088 10345259008 1033859744 11.206 100 42 83 1 1057511024 13840912 9810368
08:33:35 7058 8192000000 9976919504 10368888832 1056012944 11.418 100 49 96 1 1091003008 23894432 11095632
08:34:45 11733 8192000000 9980505584 10391863296 1066941600 11.523 100 22 44 0 1099590048 27600320 5048128
08:36:02 29752 8192000000 9986400832 10401050624 1058438736 11.442 99 14 28 0 1097957760 36826000 2693024
08:37:37 45093 8192000000 10026336032 10423463936 1097919488 11.818 100 26 52 1 1124569232 21515744 5134000
08:39:25 15614 8192000000 10092361280 10495320064 1124643984 12.071 100 60 120 0 1205107664 68248464 12215216
08:40:43 23962 8192000000 10130596880 10540785664 1142537408 12.239 100 46 92 0 1244480496 91697840 10245248
08:42:16 47342 8192000000 10177027328 10601951232 1147901088 12.290 100 37 73 1 1289439824 133458048 8080688
08:44:19 2372 8192000000 10197424320 10608619520 1156857408 12.374 100 65 126 2 1305329456 134006240 14465808
08:45:28 6548 8192000000 10231466912 10654085120 1165082624 12.451 100 41 82 1 1346523168 172306304 9134240
08:46:38 3015 8192000000 10258514656 10685100032 1172338016 12.519 100 64 128 1 1378456480 191851136 14267328
08:47:45 3410 8192000000 10304338560 10716282880 1176255136 12.555 100 77 152 3 1394455376 200467504 17732736
08:48:52 1500 8192000000 10283907360 10689478656 1172837872 12.523 99 80 160 0 1383297168 192011280 18448016
08:49:57 6877 8192000000 10324445760 10738552832 1173112032 12.526 100 74 141 2 1423160128 233998912 16049184
08:51:09 3394 8192000000 10335752768 10762752000 1175706576 12.550 100 74 148 0 1433951872 241587408 16657888
08:52:16 6023 8192000000 10367895440 10789715968 1149090688 12.301 99 56 111 0 1463587584 301585440 12911456
08:53:28 2985 8192000000 10407088304 10813857792 1147092432 12.282 100 73 144 2 1510343808 345892864 17358512
08:54:35 12898 8192000000 10421330976 10850029568 1150444016 12.314 100 42 84 1 1529409552 367629168 11336368
08:55:51 2184 8192000000 10417462528 10857811968 1152111824 12.329 100 82 165 0 1507784096 336452432 19219840
08:56:57 13712 8192000000 10336119952 10829234176 1139809584 12.214 100 41 82 0 1496492608 346352720 10330304
08:58:15 17382 8192000000 10293469824 10775588864 1104587280 11.881 97 36 72 1 1430427184 316704880 9135024
08:59:35 21365 8192000000 10277408016 10718842880 1119541024 12.023 100 20 40 1 1364236960 238828064 5867872
09:00:58 22603 8192000000 10269137264 10699489280 1083063984 11.677 97 21 42 0 1344959072 256547616 5347472
09:02:26 34114 8192000000 10154133936 10643324928 1105859232 11.893 99 10 20 1 1273738448 165286000 2593216
09:04:00 72331 8192000000 10048008192 10472611840 1098679584 11.825 98 39 77 0 1128893616 22079360 8134672
09:06:15 27213 8192000000 10050822304 10479144960 1117308640 12.002 100 43 86 0 1150039424 23123616 9607168
09:07:50 45103 8192000000 10060698880 10515021824 1112633344 11.957 99 11 22 0 1156966240 42058608 2274288
09:09:51 56659 8192000000 10075711456 10527371264 1115435856 11.984 98 13 26 1 1164304528 46344064 2524608
09:11:48 71036 8192000000 10103051680 10575183872 1124093648 12.066 99 21 42 2 1214563280 85438640 5030992
09:14:12 69355 8192000000 10045885904 10508029952 1093155184 11.773 97 17 34 0 1137976384 41573216 3247984
09:16:25 67446 8192000000 10055104416 10532229120 1102470640 11.861 98 11 22 0 1133932816 29243488 2218688
09:18:34 54617 8192000000 10125535584 10570371072 1126208896 12.086 99 34 68 0 1190983104 57050240 7723968
09:20:37 80206 8192000000 10120610608 10586542080 1123042800 12.056 98 23 46 0 1193882592 65970672 4869120
09:23:11 82860 8192000000 10070714672 10561634304 1131082192 12.132 99 10 20 0 1195049696 62027968 1939536
09:25:34 23033 8192000000 10171766496 10602946560 1155623456 12.362 100 46 92 2 1238614464 73537904 9453104
09:27:23 76928 8192000000 10096164416 10569953280 1139129712 12.207 99 24 48 0 1188861664 45077408 4654544
09:29:41 142745 8192000000 10127205184 10573529088 1148049376 12.291 99 41 82 1 1206474544 50130224 8294944
09:33:19 34206 8192000000 10117257376 10582441984 1135814304 12.176 98 26 52 0 1213361456 72221424 5325728
09:34:53 34768 8192000000 10079898464 10531168256 1133618928 12.155 99 7 14 0 1191767328 56803216 1345184
09:36:54 90603 8192000000 10054624992 10542231552 1129991312 12.121 98 11 22 0 1158340624 26454128 1895184
09:39:26 37379 8192000000 10045302096 10519040000 1129800176 12.119 99 8 16 1 1168655360 37376672 1478512
09:41:04 47064 8192000000 10052759760 10511028224 1104414128 11.880 96 19 38 1 1163276304 53978592 4883584
09:42:53 34223 8192000000 10147735392 10586202112 1120706208 12.034 97 39 78 1 1213644912 84855312 8083392
09:45:09 90298 8192000000 10109972288 10584940544 1085898800 11.704 96 13 26 1 1167919888 79444272 2576816
09:47:40 81666 8192000000 10079019136 10527911936 1079613760 11.644 96 12 24 0 1162600176 79982608 3003808
09:50:02 30887 8192000000 10141463232 10600763392 1123002304 12.055 100 36 72 0 1237897216 107487856 7407056
09:51:38 54900 8192000000 10056941424 10491875328 1096052960 11.800 97 20 40 0 1147067904 47350992 3663952
09:53:36 91997 8192000000 10076083104 10544259072 1114161088 11.972 100 43 86 3 1215453760 92434832 8857840
09:56:19 71205 8192000000 10029890416 10531737600 1107661008 11.910 99 8 16 0 1153998528 44727328 1610192
09:59:28 42622 8192000000 10050746960 10491424768 1103745856 11.873 99 11 22 1 1121329776 15518752 2065168
10:01:19 50710 8192000000 10112803376 10550439936 1130476384 12.126 100 25 50 3 1207753072 71641072 5635616
10:03:16 115350 8192000000 10100913776 10634072064 1140752736 12.223 100 19 38 1 1272971008 127619296 4598976
10:06:17 60346 8192000000 10078601552 10523521024 1102923280 11.865 97 16 32 1 1131581456 25434672 3223504
10:08:18 54822 8192000000 10101025072 10580316160 1114068480 11.971 97 16 32 0 1187590048 70376320 3145248
10:10:20 43270 8192000000 10111979904 10576650240 1149995360 12.309 100 35 70 1 1196075328 39342688 6737280
10:12:18 70431 8192000000 10108403584 10573475840 1129292192 12.115 98 30 60 2 1206214432 70489104 6433136
10:14:36 35555 8192000000 10066454288 10554953728 1132674656 12.147 99 7 14 0 1191032784 57004928 1353200
10:16:21 66407 8192000000 10105054560 10574430208 1137549440 12.192 99 17 34 1 1198144288 56745712 3849136
10:18:28 74625 8192000000 10077142752 10545766400 1121325824 12.040 98 11 22 0 1166143664 42333072 2484768
10:20:44 51671 8192000000 10106423408 10580377600 1149737168 12.307 100 25 49 2 1234760848 79610880 5412800
10:22:41 124743 8192000000 10158320032 10636144640 1117702000 12.005 97 15 30 1 1155019536 34168768 3148768
10:25:46 40403 8192000000 10082156112 10545487872 1134643920 12.165 99 13 26 1 1187742656 50693840 2404896
10:27:27 75429 8192000000 10072214112 10499145728 1084332736 11.689 95 25 50 0 1150888864 61310800 5245328
10:29:43 121758 8192000000 10043036192 10544295936 1097160768 11.811 97 21 43 0 1186489520 84793648 4535104
10:32:48 77711 8192000000 10055196944 10518335488 1092714128 11.768 96 17 34 0 1147579168 51016688 3848352
10:35:06 58556 8192000000 10055728192 10492518400 1058388480 11.441 93 20 40 1 1102001328 39296352 4316496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-09-2021 10:38:37 to 11-09-2021 10:38:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 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 11-09-2021 10:38:52 to 11-09-2021 10:38:54.

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          230412
MON$OLDEST_ACTIVE               233515
MON$OLDEST_SNAPSHOT             233515
MON$NEXT_TRANSACTION            233515
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                       2037728
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             797
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2588 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2588 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2588 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2588 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Sat Sep 11 10:37:08 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

11.09.21 10:38:55. 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-09-11 10:38:58 No severe PSQL-related problems occured
11.09.21 10:38:59. End of report.