Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.12.2021 13:37
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 16624816 kB
MemAvailable 31432692 kB
Buffers 0 kB
Cached 15037800 kB
SwapCached 0 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-12-13 13:37:09
DTS_END                         2021-12-13 13:37:09.6700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45568
TRN_ID                          229353
ATT_ID                          642
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: 9290.63 25242 1114875
customer order: creation 168.18 451 20181
customer order: refuse 14.46 351 1735
order to supplier: creation 45.65 1622 5478
order to supplier: removal 3.58 134741 429
invoice (draft): creation 44.88 3695 5386
invoice (draft): removal 52.93 1218 6352
invoice accept: apply 40.46 19029 4855
invoice accept: cancel 47.93 1038 5751
customer reserve: creation 3827.53 182 459303
customer reserve: removal 4751.89 78 570227
realization accept: apply 82.44 214 9893
realization accept: cancel 83.49 141 10019
payment from customer: creation 58.95 121 7074
payment from customer: removal 5.37 117 644
payment to supplier: creation 48.61 138 5833
payment to supplier: removal 3.28 65 394
service: total inventory turnovers 3.73 3938 447
service: total monetary turnovers 6.28 590 753
service: aggregate perf. data 0.98 13417 117
service: refresh index statistics 0.03 323697 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-12-2021 13:37:58 to 13-12-2021 13:38:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6808
TEST_TIME 2 5282
TEST_TIME 3 5955
TEST_TIME 4 5994
TEST_TIME 5 5824
TEST_TIME 6 5419
TEST_TIME 7 5271
TEST_TIME 8 5412
TEST_TIME 9 5265
TEST_TIME 10 5437
TEST_TIME 11 5723
TEST_TIME 12 6174
TEST_TIME 13 6225
TEST_TIME 14 6184
TEST_TIME 15 6292
TEST_TIME 16 6433
TEST_TIME 17 6396
TEST_TIME 18 6401
TEST_TIME 19 7006
TEST_TIME 20 7582
TEST_TIME 21 7759
TEST_TIME 22 8029
TEST_TIME 23 8543
TEST_TIME 24 8612
TEST_TIME 25 9187
TEST_TIME 26 9856
TEST_TIME 27 11318
TEST_TIME 28 11267
TEST_TIME 29 11058
TEST_TIME 30 10916
TEST_TIME 31 10600
TEST_TIME 32 10675
TEST_TIME 33 10585
TEST_TIME 34 10432
TEST_TIME 35 10424
TEST_TIME 36 10342
TEST_TIME 37 10269
TEST_TIME 38 10266
TEST_TIME 39 10188
TEST_TIME 40 10076
TEST_TIME 41 10052
TEST_TIME 42 10096
TEST_TIME 43 9955
TEST_TIME 44 10108
TEST_TIME 45 9993
TEST_TIME 46 10078
TEST_TIME 47 10240
TEST_TIME 48 10158
TEST_TIME 49 10212
TEST_TIME 50 10201
TEST_TIME 51 10159
TEST_TIME 52 10182
TEST_TIME 53 10173
TEST_TIME 54 10187
TEST_TIME 55 10170
TEST_TIME 56 10029
TEST_TIME 57 9933
TEST_TIME 58 9805
TEST_TIME 59 9822
TEST_TIME 60 9829
TEST_TIME 61 9755
TEST_TIME 62 9654
TEST_TIME 63 9583
TEST_TIME 64 9583
TEST_TIME 65 9550
TEST_TIME 66 9545
TEST_TIME 67 9573
TEST_TIME 68 9614
TEST_TIME 69 9613
TEST_TIME 70 9614
TEST_TIME 71 9706
TEST_TIME 72 9666
TEST_TIME 73 9688
TEST_TIME 74 9683
TEST_TIME 75 9728
TEST_TIME 76 9699
TEST_TIME 77 9823
TEST_TIME 78 9709
TEST_TIME 79 9658
TEST_TIME 80 9606
TEST_TIME 81 9579
TEST_TIME 82 9534
TEST_TIME 83 9508
TEST_TIME 84 9436
TEST_TIME 85 9375
TEST_TIME 86 9395
TEST_TIME 87 9457
TEST_TIME 88 9419
TEST_TIME 89 9421
TEST_TIME 90 9395
TEST_TIME 91 9402
TEST_TIME 92 9481
TEST_TIME 93 9479
TEST_TIME 94 9547
TEST_TIME 95 9523
TEST_TIME 96 9501
TEST_TIME 97 9565
TEST_TIME 98 9634
TEST_TIME 99 9552
TEST_TIME 100 9508
TEST_TIME 101 9446
TEST_TIME 102 9423
TEST_TIME 103 9385
TEST_TIME 104 9401
TEST_TIME 105 9385
TEST_TIME 106 9335
TEST_TIME 107 9285
TEST_TIME 108 9241
TEST_TIME 109 9249
TEST_TIME 110 9243
TEST_TIME 111 9239
TEST_TIME 112 9251
TEST_TIME 113 9237
TEST_TIME 114 9261
TEST_TIME 115 9298
TEST_TIME 116 9280
TEST_TIME 117 9357
TEST_TIME 118 9328
TEST_TIME 119 9340
TEST_TIME 120 9294

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-12-2021 13:38:01 to 13-12-2021 13:38:05.

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 1539598 1539598 0 0.00 0 31140 8 0 0
doc_list_biud 1539597 1539597 0 0.00 0 52361 37 0 0
sp_add_invoice_to_stock 4987 4855 132 2.64 28 196107 19030 0 132
sp_cancel_adding_invoice 5751 5751 0 0.00 5 106861 1039 0 0
sp_cancel_client_order 1735 1735 0 0.00 4 14512 352 0 0
sp_cancel_customer_reserve 570227 570227 0 0.00 1 52363 79 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 3 8315 117 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 973 65 0 0
sp_cancel_supplier_invoice 6360 6352 8 0.12 2 33806 1218 0 8
sp_cancel_supplier_order 429 429 0 0.00 12 1412928 134742 0 0
sp_cancel_write_off 10019 10019 0 0.00 1 16683 141 0 0
sp_client_order 20181 20181 0 0.00 4 33369 451 0 0
sp_customer_reserve 459303 459303 0 0.00 0 100669 182 0 0
sp_fill_shopping_cart 31025 31025 0 0.00 1 12232 41 0 0
sp_fill_shopping_cart_clo_res 459290 459290 0 0.00 0 12742 16 0 0
sp_get_clo_for_invoice 4841 4841 0 0.00 0 18383 358 0 0
sp_kill_cost_storno 16808 16808 0 0.00 0 8412 74 0 0
sp_kill_qstorno_ret_qs2qd 1157254 1157254 0 0.00 0 52358 43 0 0
sp_kill_qty_storno 609246 609246 0 0.00 0 52361 92 0 0
sp_lock_selected_doc 20384 20384 0 0.00 0 11187 7 0 0
sp_make_cost_storno 27641 27641 0 0.00 0 17585 62 0 0
sp_multiply_rows_for_pdistr 27640 27640 0 0.00 0 11917 32 0 0
sp_multiply_rows_for_qdistr 20181 20181 0 0.00 0 16077 238 0 0
sp_pay_from_customer 7074 7074 0 0.00 4 11407 122 0 0
sp_pay_to_supplier 5833 5833 0 0.00 4 17598 139 0 0
sp_payment_common 12907 12907 0 0.00 1 9097 23 0 0
sp_qd_handle_on_cancel_clo 1735 1735 0 0.00 0 14482 324 0 0
sp_qd_handle_on_invoice_upd_sts 10591 10591 0 0.00 0 31440 404 0 0
sp_qd_handle_on_reserve_upd_sts 9893 9893 0 0.00 0 31306 74 0 0
sp_reserve_write_off 9893 9893 0 0.00 7 31391 214 0 0
sp_supplier_invoice 5386 5386 0 0.00 7 92204 3695 0 0
sp_supplier_order 5478 5478 0 0.00 5 35520 1623 0 0
srv_aggregate_perf_data 117 117 0 0.00 3113 109201 13417 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 776 132918 44719 0 0
srv_find_qd_qs_mism 1067459 1067459 0 0.00 0 31138 7 0 0
srv_make_invnt_saldo 447 447 0 0.00 40 133374 3939 0 0
srv_make_money_saldo 753 753 0 0.00 0 16685 590 0 0
srv_recalc_idx_stat 4 4 0 0.00 155374 448718 323697 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 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4855 4855 0 0.00 0 7668 158 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 26939 199 0 0
t$perf-norm:sp_cancel_client_order 1735 1735 0 0.00 0 1966 9 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 5348 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 498 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 489 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 1250 16 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 24878 1710 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 1050 4 0 0
t$perf-norm:sp_client_order 20182 20182 0 0.00 0 10629 17 0 0
t$perf-norm:sp_customer_reserve 927 927 0 0.00 0 665 4 0 0
t$perf-norm:sp_pay_from_customer 7074 7074 0 0.00 0 10154 10 0 0
t$perf-norm:sp_pay_to_supplier 5833 5833 0 0.00 0 5305 12 0 0
t$perf-norm:sp_reserve_write_off 9893 9893 0 0.00 0 12667 12 0 0
t$perf-norm:sp_supplier_invoice 5386 5386 0 0.00 0 9889 18 0 0
t$perf-norm:sp_supplier_order 5478 5478 0 0.00 0 3799 14 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 327 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 851 28 0 0
t$perf-norm:srv_make_invnt_saldo 447 447 0 0.00 0 891 11 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 4302 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 9944 9680 264 2.65 0 5943 19 0 264
v_all_customers 36846 36846 0 0.00 0 17 1 0 0
v_all_suppliers 30548 30548 0 0.00 0 69 1 0 0
v_all_wares 40362 40362 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 7120 19 0 0
v_cancel_client_order 3470 3470 0 0.00 0 3010 5 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 1456 5 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 3022 9 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 463 4 0 0
v_cancel_supplier_invoice 818 802 16 1.95 0 5819 30 0 16
v_cancel_supplier_order 858 858 0 0.00 0 339 4 0 0
v_cancel_write_off 1918 1918 0 0.00 0 1900 14 0 0
v_max_id_clo_ord 5475 5475 0 0.00 0 4134 2 0 0
v_max_id_clo_res 927 927 0 0.00 0 2906 17 0 0
v_max_id_ord_sup 5369 5369 0 0.00 0 5512 5 0 0
v_max_non_paid_invoice 1403 1403 0 0.00 0 2102 3 0 0
v_max_non_paid_realizn 3651 3651 0 0.00 0 5351 4 0 0
v_min_id_clo_ord 5475 5475 0 0.00 0 3040 5 0 0
v_min_id_clo_res 927 927 0 0.00 0 2040 6 0 0
v_min_id_ord_sup 5369 5369 0 0.00 0 7060 10 0 0
v_min_non_paid_invoice 5833 5833 0 0.00 0 9088 12 0 0
v_min_non_paid_realizn 7074 7074 0 0.00 0 8117 20 0 0
v_our_firm 10362 10362 0 0.00 0 15 1 0 0
v_reserve_write_off 19786 19786 0 0.00 0 7231 7 0 0
x_lock_depdocs_on_canc_invoice 11702 11702 0 0.00 0 23032 138 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 11779 378 0 0
x_make_qstorno_1000_1200 5478 5478 0 0.00 2 35511 1552 0 0
x_make_qstorno_1200_2000 5386 5386 0 0.00 3 92102 3537 0 0
x_make_qstorno_2100_3300 459303 459303 0 0.00 0 100667 163 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 13-12-2021 13:38:05 to 13-12-2021 13:38:10.

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:13 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 33504 8192000000 9726295088 10025955328 808259744 8.980 100 91 181 0 837875712 7967056 21648912
11:09:52 24297 8192000000 9767065120 10074632192 838878832 9.289 100 85 170 1 871839456 12710560 20250064
11:11:22 22332 8192000000 9814148544 10135678976 874174144 9.642 100 84 168 0 922353344 28937392 19241808
11:12:47 13616 8192000000 9877925680 10210758656 924072352 10.136 100 81 162 0 994249824 52259216 17918256
11:14:04 11493 8192000000 9919018368 10266816512 956000432 10.450 100 80 160 0 1037454544 64195424 17258688
11:15:17 7431 8192000000 9963931280 10320318464 1001294000 10.891 100 89 178 0 1081930272 62512800 18123472
11:16:28 2734 8192000000 10014367008 10375720960 1021785920 11.089 100 89 177 0 1125755040 86593888 17375232
11:17:32 2431 8192000000 10034109856 10398482432 1044532992 11.308 100 93 186 0 1135779280 71804096 19442192
11:18:36 1397 8192000000 10053968768 10424680448 1062883376 11.484 100 95 191 0 1168105920 85360144 19862400
11:19:39 27397 8192000000 10031428912 10412126208 1072231088 11.573 100 76 150 0 1143807664 55887552 15689024
11:21:08 3005 8192000000 10026687776 10411294720 1081539088 11.662 100 55 110 0 1142167056 48456944 12171024
11:22:13 14064 8192000000 9983928912 10388811776 1091257312 11.755 100 41 82 0 1136238752 35927344 9054096
11:23:28 5691 8192000000 10013307072 10402107392 1091224608 11.754 100 63 122 4 1118315088 14545568 12544912
11:24:34 6567 8192000000 10004034176 10405441536 1102068144 11.857 100 45 90 2 1125664464 13679552 9916768
11:25:41 5906 8192000000 10003865344 10414366720 1097844480 11.817 100 39 78 0 1121542496 15000240 8697776
11:26:49 31887 8192000000 10006574448 10417233920 1100903984 11.846 100 32 63 0 1134683808 26635536 7144288
11:28:22 5748 8192000000 9999449312 10399309824 1076326768 11.612 99 45 89 3 1106854272 21187008 9340496
11:29:29 8457 8192000000 9980416016 10379636736 1056557472 11.424 98 44 88 1 1079942480 12968672 10416336
11:30:39 7770 8192000000 9944648432 10321383424 971324656 10.600 93 26 52 3 996942240 20230112 5387472
11:31:47 45487 8192000000 9950234160 10343370752 963030480 10.519 93 36 72 0 990578000 20062560 7484960
11:33:33 5816 8192000000 9942782224 10325471232 998686784 10.866 97 38 77 1 1029355088 22465984 8202320
11:34:42 6899 8192000000 9967268096 10349588480 1039240816 11.257 99 30 60 0 1067556896 21649152 6666928
11:35:52 9434 8192000000 9995984432 10397409280 1064184528 11.497 100 30 59 0 1088414832 17049856 7180448
11:37:05 59854 8192000000 9988821024 10386309120 1076207264 11.611 100 47 94 2 1109476592 22965664 10303664
11:39:10 7403 8192000000 10060891264 10452402176 1099273280 11.831 100 61 118 2 1167152736 55994608 11884848
11:40:26 22577 8192000000 10106572400 10504658944 1128988640 12.112 100 60 118 2 1200415696 58740784 12686272
11:41:54 16447 8192000000 10152754592 10557009920 1145473856 12.267 100 52 104 1 1240780688 82870064 12436768
11:43:20 13719 8192000000 10211599360 10617311232 1159901120 12.402 100 55 110 1 1306577664 134299600 12376944
11:44:45 12826 8192000000 10277772560 10688294912 1170692208 12.503 100 52 102 2 1377638176 195293888 11652080
11:46:05 13835 8192000000 10291711696 10699018240 1156008880 12.366 98 53 104 2 1380062992 211348832 12705280
11:47:32 12786 8192000000 10344057936 10758078464 1186202816 12.648 100 61 118 1 1454327872 254243936 13881120
11:48:50 1663 8192000000 10431616304 10838474752 1197407728 12.752 100 78 157 0 1532007488 317001760 17598000
11:49:57 3284 8192000000 10420004800 10841845760 1199771024 12.774 100 73 143 0 1527299952 310934720 16594208
11:51:08 5850 8192000000 10447778544 10863849472 1204549568 12.819 100 67 133 0 1556587216 336527984 15509664
11:52:29 2265 8192000000 10513362480 10918305792 1194808016 12.728 100 91 182 0 1607762592 392331600 20622976
11:53:38 3196 8192000000 10552065040 10966769664 1206739072 12.839 100 75 150 0 1651612672 426506448 18367152
11:54:45 774 8192000000 10615011056 11031490560 1205710800 12.829 100 91 181 1 1717730976 490135792 21884384
11:55:58 4893 8192000000 10630557200 11078369280 1194886848 12.729 100 84 166 4 1735124416 518813072 21424496
11:57:08 4136 8192000000 10603856816 11024113664 1189179904 12.676 100 64 125 0 1705097440 500411776 15505760
11:58:17 3769 8192000000 10523658816 10935754752 1170550304 12.502 100 87 172 2 1626290704 435383424 20356976
11:59:30 6906 8192000000 10528841968 10949709824 1159508368 12.399 100 52 103 0 1625536112 452456192 13571552
12:00:39 7999 8192000000 10498082560 11015102464 1134328912 12.162 100 42 84 0 1600970656 454989408 11652336
12:01:56 10433 8192000000 10495695488 10982854656 1144149984 12.255 100 40 80 0 1619297680 464014656 11133040
12:03:12 13315 8192000000 10387988336 10895179776 1127179008 12.095 100 29 58 0 1520350016 384612288 8558720
12:04:39 94119 8192000000 10035785648 10490978304 1060737504 11.464 94 18 37 0 1109725472 44543616 4444352
12:07:22 132918 8192000000 10047067136 10511392768 1051916960 11.379 93 22 44 0 1107402272 50487456 4997856
12:10:41 74149 8192000000 10103844192 10538196992 1133791808 12.157 100 35 69 0 1208640400 67089488 7759104
12:12:59 70187 8192000000 10108640688 10543177728 1126322912 12.087 98 40 79 3 1188785232 54168464 8293856
12:15:14 53155 8192000000 10161492784 10626338816 1155893264 12.365 100 39 76 0 1257494048 93135888 8464896
12:17:12 123882 8192000000 10130454400 10596204544 1099056192 11.829 96 25 50 1 1222255840 117421520 5778128
12:20:22 56319 8192000000 10169624560 10615607296 1133422640 12.154 100 19 38 0 1247819424 109732176 4664608
12:22:21 41493 8192000000 10140699344 10565230592 1119256384 12.020 98 40 79 1 1217074352 88953168 8864800
12:24:09 44126 8192000000 10066206816 10541137920 1125459792 12.079 100 20 40 0 1163654544 33926256 4268496
12:25:55 29526 8192000000 10082538640 10550747136 1119697168 12.024 99 34 68 0 1191322480 64828336 6796976
12:27:31 63110 8192000000 10065359664 10512166912 1113488528 11.965 98 13 26 1 1144928416 28708496 2731392
12:29:40 125693 8192000000 10092416688 10575351808 1113268720 11.963 98 14 28 0 1153717536 37869520 2579296
12:33:40 93998 8192000000 10113681360 10561716224 1147977424 12.291 100 24 48 0 1201554480 48556448 5020608
12:37:18 48099 8192000000 10072628928 10570641408 1135653184 12.175 99 5 10 0 1172768144 36613216 501744
12:39:22 43942 8192000000 10080060512 10524057600 1147358688 12.285 99 15 31 0 1174154384 23894272 2901424
12:41:20 41986 8192000000 10093971440 10606321664 1142509104 12.239 99 11 21 0 1230437600 85706416 2222080
12:43:06 52043 8192000000 10107833440 10544295936 1122898864 12.054 97 22 45 0 1173802528 45981456 4922208
12:44:58 35541 8192000000 10058054240 10499067904 1087158144 11.716 96 25 50 0 1135453856 42877632 5418080
12:46:47 40428 8192000000 10116793104 10551152640 1115380976 11.983 99 30 60 1 1202469408 80732080 6356352
12:48:29 63762 8192000000 10053088480 10507415552 1100541648 11.843 97 11 22 0 1154616352 51662256 2412448
12:50:34 74138 8192000000 10083757024 10525130752 1080353920 11.651 97 19 38 0 1140224608 56020288 3850400
12:52:52 46180 8192000000 10111638416 10535755776 1093303648 11.774 97 27 54 0 1175987232 77111568 5572016
12:54:48 113491 8192000000 10053102976 10495918080 1099540416 11.833 98 17 34 1 1147693616 44578720 3574480
12:57:49 38086 8192000000 10084551440 10553974784 1111812992 11.950 99 35 70 0 1184111584 65055168 7243424
12:59:31 98243 8192000000 10076341968 10534563840 1106026656 11.895 98 14 28 0 1158376224 49070416 3279152
13:02:12 56267 8192000000 10106988032 10556370944 1109708080 11.930 99 14 28 2 1191892336 78572288 3611968
13:04:58 29334 8192000000 10054556400 10493759488 1110518560 11.937 98 22 41 0 1142467264 27486688 4462016
13:06:34 47215 8192000000 10100399072 10536337408 1115502032 11.984 99 24 49 1 1182806656 61904896 5399728
13:08:22 55690 8192000000 10109711584 10576048128 1143539008 12.249 100 12 24 0 1241837248 96036512 2261728
13:10:18 48009 8192000000 10125348832 10589761536 1124755984 12.072 98 35 70 0 1192202976 60394704 7052288
13:12:06 46056 8192000000 10075649168 10509201408 1128366464 12.106 99 31 60 0 1169850080 34513040 6970576
13:13:56 42406 8192000000 10158968720 10647846912 1138624896 12.203 100 32 64 0 1256802576 111499328 6678352
13:15:41 86592 8192000000 10063223680 10503929856 1141508272 12.230 100 16 32 2 1163624880 18294576 3822032
13:18:21 38432 8192000000 10068731312 10505953280 1137245200 12.190 100 31 62 0 1168221728 24890112 6086416
13:20:00 81279 8192000000 10087427568 10573115392 1134129936 12.160 99 20 40 0 1222156640 82878608 5148096
13:22:31 61061 8192000000 10054240320 10510307328 1140974640 12.225 100 16 31 2 1178970192 34581120 3414432
13:26:21 57460 8192000000 10082826192 10556153856 1147610832 12.287 100 13 26 1 1179467424 28570624 3285968
13:28:18 62731 8192000000 10096382032 10542362624 1119930368 12.026 98 14 28 0 1170052240 47414640 2707232
13:30:53 66420 8192000000 10166606912 10642067456 1140463792 12.220 99 29 58 1 1230670048 84048528 6157728
13:33:13 24392 8192000000 10085745600 10524811264 1118742432 12.015 99 31 62 1 1183004928 58003328 6259168
13:34:37 42495 8192000000 10062956416 10504572928 1096377792 11.803 97 34 68 1 1133984640 30886336 6720512
13:36:22 44897 8192000000 10120207056 10559787008 1121951296 12.045 99 37 74 0 1171806704 42390464 7464944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-12-2021 13:38:11 to 13-12-2021 13:38:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-12-2021 13:38:27 to 13-12-2021 13:38: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          229584
MON$OLDEST_ACTIVE               229585
MON$OLDEST_SNAPSHOT             229585
MON$NEXT_TRANSACTION            229585
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                       2033760
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2685 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2685 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2685 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2685 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Mon Dec 13 13:37:30 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
--- end of diff output ---

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

13.12.21 13:38: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
2021-12-13 13:38:32 No severe PSQL-related problems occured
13.12.21 13:38:33. End of report.