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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16525684 kB
MemAvailable 31435596 kB
Buffers 0 kB
Cached 15193580 kB
SwapCached 444 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-23 10:37:07
DTS_END                         2021-12-23 10:37:07.5660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58198
TRN_ID                          235912
ATT_ID                          593
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: 9151.90 24850 1098228
customer order: creation 172.01 399 20641
customer order: refuse 14.44 318 1733
order to supplier: creation 44.68 1411 5361
order to supplier: removal 3.24 108467 389
invoice (draft): creation 44.13 3524 5295
invoice (draft): removal 50.65 893 6078
invoice accept: apply 41.26 17663 4951
invoice accept: cancel 46.55 816 5586
customer reserve: creation 3807.24 173 456869
customer reserve: removal 4635.68 59 556281
realization accept: apply 83.91 172 10069
realization accept: cancel 81.13 121 9735
payment from customer: creation 58.92 126 7070
payment from customer: removal 5.74 83 689
payment to supplier: creation 48.53 130 5823
payment to supplier: removal 3.25 86 390
service: total inventory turnovers 3.63 3804 435
service: total monetary turnovers 6.13 551 735
service: aggregate perf. data 0.78 20127 94
service: refresh index statistics 0.03 338068 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-12-2021 10:39:45 to 23-12-2021 10:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2489
TEST_TIME 2 4345
TEST_TIME 3 5133
TEST_TIME 4 5903
TEST_TIME 5 5718
TEST_TIME 6 5805
TEST_TIME 7 6097
TEST_TIME 8 6404
TEST_TIME 9 6344
TEST_TIME 10 6408
TEST_TIME 11 6865
TEST_TIME 12 6783
TEST_TIME 13 6909
TEST_TIME 14 7202
TEST_TIME 15 7689
TEST_TIME 16 8409
TEST_TIME 17 8206
TEST_TIME 18 8780
TEST_TIME 19 9078
TEST_TIME 20 9069
TEST_TIME 21 9643
TEST_TIME 22 9623
TEST_TIME 23 9785
TEST_TIME 24 10030
TEST_TIME 25 10086
TEST_TIME 26 10839
TEST_TIME 27 11452
TEST_TIME 28 11123
TEST_TIME 29 10822
TEST_TIME 30 10616
TEST_TIME 31 10466
TEST_TIME 32 10352
TEST_TIME 33 10144
TEST_TIME 34 10195
TEST_TIME 35 10107
TEST_TIME 36 9944
TEST_TIME 37 9701
TEST_TIME 38 9720
TEST_TIME 39 9729
TEST_TIME 40 9634
TEST_TIME 41 9561
TEST_TIME 42 9520
TEST_TIME 43 9656
TEST_TIME 44 9622
TEST_TIME 45 9666
TEST_TIME 46 9546
TEST_TIME 47 9570
TEST_TIME 48 9617
TEST_TIME 49 9647
TEST_TIME 50 9605
TEST_TIME 51 9680
TEST_TIME 52 9621
TEST_TIME 53 9456
TEST_TIME 54 9396
TEST_TIME 55 9461
TEST_TIME 56 9534
TEST_TIME 57 9469
TEST_TIME 58 9386
TEST_TIME 59 9331
TEST_TIME 60 9244
TEST_TIME 61 9175
TEST_TIME 62 9107
TEST_TIME 63 9047
TEST_TIME 64 9175
TEST_TIME 65 9166
TEST_TIME 66 9142
TEST_TIME 67 9164
TEST_TIME 68 9179
TEST_TIME 69 9291
TEST_TIME 70 9320
TEST_TIME 71 9280
TEST_TIME 72 9251
TEST_TIME 73 9291
TEST_TIME 74 9399
TEST_TIME 75 9425
TEST_TIME 76 9540
TEST_TIME 77 9430
TEST_TIME 78 9437
TEST_TIME 79 9402
TEST_TIME 80 9346
TEST_TIME 81 9319
TEST_TIME 82 9259
TEST_TIME 83 9248
TEST_TIME 84 9222
TEST_TIME 85 9240
TEST_TIME 86 9254
TEST_TIME 87 9225
TEST_TIME 88 9228
TEST_TIME 89 9235
TEST_TIME 90 9264
TEST_TIME 91 9296
TEST_TIME 92 9347
TEST_TIME 93 9395
TEST_TIME 94 9418
TEST_TIME 95 9492
TEST_TIME 96 9462
TEST_TIME 97 9483
TEST_TIME 98 9429
TEST_TIME 99 9392
TEST_TIME 100 9411
TEST_TIME 101 9392
TEST_TIME 102 9328
TEST_TIME 103 9285
TEST_TIME 104 9256
TEST_TIME 105 9255
TEST_TIME 106 9264
TEST_TIME 107 9244
TEST_TIME 108 9229
TEST_TIME 109 9217
TEST_TIME 110 9222
TEST_TIME 111 9200
TEST_TIME 112 9193
TEST_TIME 113 9281
TEST_TIME 114 9300
TEST_TIME 115 9343
TEST_TIME 116 9331
TEST_TIME 117 9322
TEST_TIME 118 9303
TEST_TIME 119 9229
TEST_TIME 120 9152

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-12-2021 10:39:49 to 23-12-2021 10:39:53.

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 1521717 1521717 0 0.00 0 18066 6 0 0
doc_list_biud 1521717 1521717 0 0.00 0 52349 28 0 0
sp_add_invoice_to_stock 5074 4951 123 2.42 54 183404 17664 0 123
sp_cancel_adding_invoice 5586 5586 0 0.00 6 74846 816 0 0
sp_cancel_client_order 1733 1733 0 0.00 5 8169 318 0 0
sp_cancel_customer_reserve 556281 556281 0 0.00 1 27941 59 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 5466 84 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 2 4899 86 0 0
sp_cancel_supplier_invoice 6091 6078 13 0.21 2 37252 893 0 13
sp_cancel_supplier_order 389 389 0 0.00 9 932268 108468 0 0
sp_cancel_write_off 9735 9735 0 0.00 1 35886 122 0 0
sp_client_order 20641 20641 0 0.00 4 28736 399 0 0
sp_customer_reserve 456869 456869 0 0.00 0 64338 174 0 0
sp_fill_shopping_cart 31280 31280 0 0.00 1 15428 46 0 0
sp_fill_shopping_cart_clo_res 456868 456868 0 0.00 0 32807 15 0 0
sp_get_clo_for_invoice 4950 4950 0 0.00 0 36342 393 0 0
sp_kill_cost_storno 16400 16400 0 0.00 0 7650 53 0 0
sp_kill_qstorno_ret_qs2qd 1128764 1128764 0 0.00 0 37245 33 0 0
sp_kill_qty_storno 594821 594821 0 0.00 0 52348 70 0 0
sp_lock_selected_doc 20697 20697 0 0.00 0 8705 5 0 0
sp_make_cost_storno 27912 27912 0 0.00 0 13024 51 0 0
sp_multiply_rows_for_pdistr 27912 27912 0 0.00 0 15689 34 0 0
sp_multiply_rows_for_qdistr 20641 20641 0 0.00 0 28530 206 0 0
sp_pay_from_customer 7070 7070 0 0.00 4 15702 127 0 0
sp_pay_to_supplier 5823 5823 0 0.00 5 12699 131 0 0
sp_payment_common 12893 12893 0 0.00 1 8095 25 0 0
sp_qd_handle_on_cancel_clo 1733 1733 0 0.00 0 8164 290 0 0
sp_qd_handle_on_invoice_upd_sts 10536 10536 0 0.00 0 52346 346 0 0
sp_qd_handle_on_reserve_upd_sts 10069 10069 0 0.00 0 34419 57 0 0
sp_reserve_write_off 10069 10069 0 0.00 7 34462 172 0 0
sp_supplier_invoice 5295 5295 0 0.00 7 98739 3524 0 0
sp_supplier_order 5361 5361 0 0.00 5 81034 1412 0 0
srv_aggregate_perf_data 94 94 0 0.00 2362 209705 20127 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1351 193693 47684 0 0
srv_find_qd_qs_mism 1051292 1051292 0 0.00 0 18065 5 0 0
srv_make_invnt_saldo 435 435 0 0.00 18 137861 3805 0 0
srv_make_money_saldo 735 735 0 0.00 1 16158 551 0 0
srv_recalc_idx_stat 4 4 0 0.00 217668 587139 338069 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4951 4951 0 0.00 0 23252 168 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 8506 108 0 0
t$perf-norm:sp_cancel_client_order 1733 1733 0 0.00 0 4639 18 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 5260 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 1163 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 350 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 3416 31 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 26443 1498 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 4228 12 0 0
t$perf-norm:sp_client_order 20641 20641 0 0.00 0 19644 18 0 0
t$perf-norm:sp_customer_reserve 921 921 0 0.00 0 1878 7 0 0
t$perf-norm:sp_pay_from_customer 7070 7070 0 0.00 0 19154 11 0 0
t$perf-norm:sp_pay_to_supplier 5823 5823 0 0.00 0 4741 10 0 0
t$perf-norm:sp_reserve_write_off 10069 10069 0 0.00 0 5572 11 0 0
t$perf-norm:sp_supplier_invoice 5295 5295 0 0.00 0 10489 20 0 0
t$perf-norm:sp_supplier_order 5361 5361 0 0.00 0 6028 15 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 387 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 398 11 0 0
t$perf-norm:srv_make_invnt_saldo 435 435 0 0.00 0 537 7 0 0
t$perf-norm:srv_make_money_saldo 735 735 0 0.00 0 874 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 18 7 0 0
v_add_invoice_to_stock 10146 9900 246 2.42 0 5569 18 0 246
v_all_customers 37338 37338 0 0.00 0 27 1 0 0
v_all_suppliers 29598 29598 0 0.00 0 122 1 0 0
v_all_wares 41282 41282 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 3235 9 0 0
v_cancel_client_order 3466 3466 0 0.00 0 4349 6 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 3220 9 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 444 3 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 3662 12 0 0
v_cancel_supplier_invoice 794 768 26 3.27 0 3996 25 0 26
v_cancel_supplier_order 778 778 0 0.00 0 2007 8 0 0
v_cancel_write_off 2014 2014 0 0.00 0 3117 17 0 0
v_max_id_clo_ord 5361 5361 0 0.00 0 3391 2 0 0
v_max_id_clo_res 921 921 0 0.00 0 5337 25 0 0
v_max_id_ord_sup 5278 5278 0 0.00 0 2469 3 0 0
v_max_non_paid_invoice 1663 1663 0 0.00 0 207 2 0 0
v_max_non_paid_realizn 3845 3845 0 0.00 0 3679 4 0 0
v_min_id_clo_ord 5361 5361 0 0.00 0 4104 6 0 0
v_min_id_clo_res 921 921 0 0.00 0 240 2 0 0
v_min_id_ord_sup 5278 5278 0 0.00 0 7014 13 0 0
v_min_non_paid_invoice 5823 5823 0 0.00 0 6816 19 0 0
v_min_non_paid_realizn 7070 7070 0 0.00 0 8085 16 0 0
v_our_firm 10394 10394 0 0.00 0 36 1 0 0
v_reserve_write_off 20138 20138 0 0.00 0 4450 8 0 0
x_lock_depdocs_on_canc_invoice 11280 11280 0 0.00 0 13833 108 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 17087 356 0 0
x_make_qstorno_1000_1200 5361 5361 0 0.00 2 80960 1336 0 0
x_make_qstorno_1200_2000 5295 5295 0 0.00 3 98728 3330 0 0
x_make_qstorno_2100_3300 456869 456869 0 0.00 0 64337 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2021 10:39:54 to 23-12-2021 10:39:58.

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 24 8192000000 8803771792 8817143808 7421248 0.090 3 1 2 0 7520672 63648 35776
08:08:12 32846 8192000000 9720218176 10013818880 791870128 8.814 100 96 191 0 824058528 8167936 24020464
08:09:52 26202 8192000000 9759769312 10065354752 833185776 9.231 100 88 176 1 866584992 12516848 20882368
08:11:24 17902 8192000000 9798928032 10115817472 864127520 9.541 100 86 172 0 905685440 21459792 20098128
08:12:47 14972 8192000000 9866577296 10196738048 912629664 10.023 100 76 152 0 971325680 42212528 16483488
08:14:05 8287 8192000000 9923510528 10266320896 956035360 10.450 100 87 174 0 1032713568 58639744 18038464
08:15:15 4294 8192000000 9977594704 10331856896 1005508224 10.932 100 85 170 2 1092463280 69624928 17330128
08:16:21 2944 8192000000 10024925488 10388353024 1034130544 11.208 100 85 169 0 1140753840 89925936 16697360
08:17:26 1478 8192000000 10040689824 10406862848 1053718592 11.396 100 99 198 0 1150555056 76862112 19974352
08:18:28 30075 8192000000 9986218704 10364620800 1064307504 11.498 100 48 96 5 1111890432 37364448 10218480
08:19:59 4599 8192000000 9993122576 10385817600 1070328272 11.555 100 53 109 2 1110645120 28423024 11893824
08:21:05 7727 8192000000 10019862080 10414743552 1077606816 11.625 100 53 106 0 1131420496 42034512 11779168
08:22:13 4834 8192000000 9986842160 10389065728 1082596624 11.672 100 21 42 2 1112072176 25315872 4159680
08:23:20 37017 8192000000 10019849536 10410131456 1093989104 11.781 100 70 142 2 1124282240 15729472 14563664
08:24:58 5130 8192000000 10018603968 10418118656 1097668592 11.816 100 51 102 0 1135351008 26608528 11073888
08:26:05 7009 8192000000 10022459168 10421805056 1101190480 11.849 100 28 57 1 1134858832 27915456 5752896
08:27:13 4817 8192000000 10000106576 10392326144 1065048640 11.505 98 40 78 2 1093432592 19714624 8669328
08:28:19 13896 8192000000 9979524352 10382471168 1039169616 11.257 96 62 123 1 1075042128 22267168 13605344
08:29:38 8576 8192000000 9960887504 10351693824 991860240 10.800 95 43 84 2 1022319456 21452288 9006928
08:30:48 6967 8192000000 9935371632 10305433600 1003195200 10.909 97 32 64 2 1030741936 20406544 7140192
08:31:55 66646 8192000000 9930607584 10323652608 977298496 10.658 95 29 58 0 1004252992 21137888 5816608
08:34:05 7021 8192000000 9967493664 10360733696 1050792320 11.368 100 37 72 2 1078297696 19907136 7598240
08:35:14 6490 8192000000 9991516256 10378772480 1066342464 11.517 100 47 92 0 1092102384 15546480 10213440
08:36:24 9374 8192000000 9984056000 10389630976 1073672704 11.587 100 18 36 1 1095621312 18477040 3471568
08:37:35 78476 8192000000 10026402144 10423554048 1092349008 11.765 100 43 86 1 1134714928 33571664 8794256
08:39:57 3553 8192000000 10083991360 10477821952 1119683168 12.024 100 58 117 0 1188690080 57519696 11487216
08:41:03 8236 8192000000 10126899840 10532364288 1134912240 12.168 100 61 118 2 1232974384 86209504 11852640
08:42:14 2515 8192000000 10137667472 10540335104 1140169328 12.217 100 57 112 1 1249395056 96796752 12428976
08:43:24 6213 8192000000 10199151984 10598289408 1143132480 12.245 99 67 135 0 1294691136 137450288 14108368
08:44:35 4729 8192000000 10214740240 10624950272 1155567520 12.362 100 61 121 1 1322259120 152411216 14280384
08:45:43 14323 8192000000 10219626112 10652786688 1162676848 12.428 100 43 86 3 1325265696 152228720 10360128
08:47:00 9863 8192000000 10265042784 10681307136 1174362240 12.538 100 69 139 1 1356325616 166836912 15126464
08:48:13 4386 8192000000 10300838432 10707288064 1168706208 12.485 100 72 145 0 1400147584 215396640 16044736
08:49:22 1347 8192000000 10298669984 10705285120 1176171584 12.554 100 86 171 0 1407607648 212263424 19172640
08:50:29 2359 8192000000 10336535504 10741035008 1160821040 12.411 99 78 155 0 1433507232 255327312 17358880
08:51:38 1389 8192000000 10347241536 10757165056 1159004896 12.394 100 80 158 3 1445284160 267111776 19167488
08:52:46 4395 8192000000 10315861856 10713817088 1143804448 12.251 100 79 154 1 1415945040 254510544 17630048
08:53:52 12285 8192000000 10247093136 10666631168 1122572016 12.051 99 38 75 0 1336718944 204638624 9508304
08:55:08 2744 8192000000 10335566064 10736443392 1140246864 12.218 100 84 165 0 1428414640 268842416 19325360
08:56:17 15323 8192000000 10284467616 10745614336 1142867488 12.242 99 38 75 2 1416711104 264560608 9283008
08:57:42 4503 8192000000 10311270096 10726694912 1137170000 12.189 100 47 92 2 1414014944 265012128 11832816
08:58:53 14847 8192000000 10283141808 10727657472 1147383056 12.285 100 32 64 1 1389493664 234112672 7997936
09:00:10 37294 8192000000 10292822704 10722004992 1127717008 12.100 99 32 64 1 1413418784 277397808 8303968
09:01:56 37036 8192000000 10312672992 10747342848 1114429776 11.974 99 23 46 0 1375978416 255176544 6372096
09:03:39 39700 8192000000 10125915696 10622758912 1082458944 11.671 97 14 28 1 1283170160 197056528 3654688
09:05:31 78589 8192000000 10046048096 10473611264 1111090608 11.943 100 43 84 2 1141120144 20398800 9630736
09:07:50 45906 8192000000 10084875040 10531225600 1117176640 12.000 99 13 26 0 1188460960 68803968 2480352
09:10:32 45875 8192000000 10125280864 10568466432 1135529808 12.173 100 12 24 0 1218885536 80831392 2524336
09:12:20 54630 8192000000 10076032432 10526330880 1105420000 11.889 97 15 30 0 1168472784 59910672 3142112
09:14:15 122533 8192000000 10089196528 10574577664 1118257040 12.011 98 23 46 0 1185385792 62366112 4762640
09:17:25 28133 8192000000 10074439264 10516938752 1129662816 12.118 99 26 52 0 1153941584 18927040 5351728
09:19:01 36989 8192000000 10140609760 10578776064 1149873520 12.308 100 42 82 2 1215173616 56593120 8706976
09:20:48 107506 8192000000 10092090128 10559938560 1131348928 12.134 98 14 29 0 1162894432 28591872 2953632
09:23:57 55223 8192000000 10116965792 10571014144 1153674464 12.344 100 36 70 0 1245608464 83778608 8155392
09:25:54 73001 8192000000 10105214288 10563203072 1125763648 12.081 98 15 30 0 1170701488 42146752 2791088
09:28:13 51284 8192000000 10112163552 10557009920 1144179248 12.255 99 24 48 0 1215503872 66603664 4720960
09:30:09 90264 8192000000 10143027952 10595069952 1136203072 12.180 99 29 58 0 1223065712 80680976 6181664
09:33:44 85446 8192000000 10062894096 10542432256 1119582704 12.023 98 11 22 0 1156962464 35181568 2198192
09:36:16 97485 8192000000 10082953856 10534436864 1111421376 11.946 97 19 38 0 1159609200 44705088 3482736
09:38:53 52493 8192000000 10104357232 10588286976 1149962416 12.309 100 10 20 0 1190242960 38335408 1945136
09:40:46 53500 8192000000 10051629968 10497171456 1115501968 11.984 98 15 30 0 1186396528 67917280 2977280
09:42:43 33109 8192000000 10068811136 10493214720 1078874400 11.637 96 27 54 0 1133323936 48244480 6205056
09:44:20 61918 8192000000 10089185472 10555617280 1081305200 11.660 96 25 50 1 1170174672 83138464 5731008
09:46:24 50839 8192000000 10040400416 10468438016 1070803328 11.560 96 6 12 0 1111890480 39863696 1223456
09:48:36 16700 8192000000 10039240816 10455470080 1069267968 11.545 97 35 70 0 1116817344 40414496 7134880
09:49:58 25851 8192000000 10074101792 10524831744 1078463696 11.633 98 36 72 2 1152635488 66939536 7232256
09:51:24 41749 8192000000 10103214000 10542489600 1097764048 11.816 99 27 54 0 1202375104 98675824 5935232
09:53:14 193692 8192000000 10057996640 10534563840 1068198352 11.535 96 16 32 0 1131801024 59891200 3711472
09:57:27 53405 8192000000 10059569312 10502676480 1105099280 11.886 99 16 31 0 1147717616 39537136 3081200
09:59:27 60823 8192000000 10089717312 10543640576 1122744528 12.053 100 16 32 0 1194127920 67701040 3682352
10:02:20 39876 8192000000 10086497968 10586222592 1135225936 12.171 100 30 61 1 1212424976 70073904 7125136
10:04:09 52413 8192000000 10098327952 10543026176 1093263712 11.774 96 20 40 0 1148905728 51479792 4162224
10:06:10 57630 8192000000 10142112704 10605039616 1121676480 12.043 98 24 48 1 1199230736 72385056 5169200
10:08:16 79375 8192000000 10094354976 10584109056 1124974272 12.074 98 19 38 0 1168710336 40159712 3576352
10:10:39 91571 8192000000 10093818768 10539229184 1134512416 12.164 99 15 30 0 1198519008 60951840 3054752
10:13:15 79573 8192000000 10130284672 10598375424 1145097216 12.263 99 23 46 0 1185612464 35828784 4686464
10:15:40 101264 8192000000 10110279904 10595119104 1130768144 12.129 98 27 54 0 1204485920 68287824 5429952
10:18:37 53319 8192000000 10079837440 10564886528 1113445600 11.965 97 10 20 0 1157848480 42225984 2176896
10:20:37 50642 8192000000 10118296112 10574163968 1125797504 12.082 99 16 32 0 1206431680 77363392 3270784
10:22:28 101578 8192000000 10068416672 10537951232 1107415936 11.908 97 11 22 0 1185948112 75856976 2675200
10:25:10 54380 8192000000 10097555424 10541395968 1137041920 12.188 99 15 30 0 1178632560 38551376 3039264
10:27:13 67734 8192000000 10122153136 10597916672 1132130768 12.141 98 27 55 1 1221085520 83374944 5579808
10:29:25 75074 8192000000 10116721664 10627989504 1116269264 11.992 98 17 34 0 1244337744 123858656 4209824
10:31:56 27563 8192000000 10112305952 10542424064 1112937744 11.960 99 39 77 0 1163593312 43354432 7301136
10:33:26 25323 8192000000 10108603520 10542751744 1122827040 12.054 99 32 64 2 1173566752 44313488 6426224
10:34:56 76505 8192000000 10148656000 10617372672 1103021504 11.866 98 25 50 0 1242981072 134556944 5402624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-12-2021 10:39:59 to 23-12-2021 10:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-12-2021 10:40:15 to 23-12-2021 10:40:17.

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          233049
MON$OLDEST_ACTIVE               236240
MON$OLDEST_SNAPSHOT             236240
MON$NEXT_TRANSACTION            236240
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                       2046304
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             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2696 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2696 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2696 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Thu Dec 23 10:37:34 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Dec 23 10:37:34 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Dec 23 10:37:34 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

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

23.12.21 10:40:18. 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-23 10:40:21 No severe PSQL-related problems occured
23.12.21 10:40:22. End of report.