Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.03.2023 07:47
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 14809020 kB
MemAvailable 30450224 kB
Buffers 0 kB
Cached 16900584 kB
SwapCached 3032 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.3
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 2023-03-11 07:46:18
DTS_END                         2023-03-11 07:46:18.6000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38088
TRN_ID                          223426
ATT_ID                          649
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: 9016.24 20644 1081949
customer order: creation 179.08 454 21490
customer order: refuse 14.98 451 1797
order to supplier: creation 46.80 1300 5616
order to supplier: removal 3.36 183096 403
invoice (draft): creation 47.73 3068 5728
invoice (draft): removal 48.16 1099 5779
invoice accept: apply 43.70 26852 5244
invoice accept: cancel 42.90 1358 5148
customer reserve: creation 4078.83 252 489460
customer reserve: removal 4213.21 113 505585
realization accept: apply 85.97 287 10316
realization accept: cancel 76.53 202 9183
payment from customer: creation 62.13 175 7455
payment from customer: removal 5.83 125 700
payment to supplier: creation 50.93 191 6111
payment to supplier: removal 3.55 137 426
service: total inventory turnovers 3.97 4335 476
service: total monetary turnovers 7.60 377 912
service: aggregate perf. data 0.96 15053 115
service: refresh index statistics 0.04 173964 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-03-2023 07:47:53 to 11-03-2023 07:47:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11658
TEST_TIME 2 7529
TEST_TIME 3 6901
TEST_TIME 4 6347
TEST_TIME 5 6462
TEST_TIME 6 6632
TEST_TIME 7 6197
TEST_TIME 8 6188
TEST_TIME 9 6424
TEST_TIME 10 6703
TEST_TIME 11 7066
TEST_TIME 12 6942
TEST_TIME 13 7010
TEST_TIME 14 7094
TEST_TIME 15 7030
TEST_TIME 16 7028
TEST_TIME 17 7105
TEST_TIME 18 7368
TEST_TIME 19 7251
TEST_TIME 20 7341
TEST_TIME 21 7751
TEST_TIME 22 7861
TEST_TIME 23 8135
TEST_TIME 24 8171
TEST_TIME 25 8414
TEST_TIME 26 8426
TEST_TIME 27 8434
TEST_TIME 28 8978
TEST_TIME 29 9198
TEST_TIME 30 9489
TEST_TIME 31 9483
TEST_TIME 32 9773
TEST_TIME 33 9992
TEST_TIME 34 10134
TEST_TIME 35 10424
TEST_TIME 36 10478
TEST_TIME 37 10474
TEST_TIME 38 10579
TEST_TIME 39 11019
TEST_TIME 40 10985
TEST_TIME 41 10936
TEST_TIME 42 10824
TEST_TIME 43 10649
TEST_TIME 44 10589
TEST_TIME 45 10553
TEST_TIME 46 10445
TEST_TIME 47 10256
TEST_TIME 48 10302
TEST_TIME 49 10226
TEST_TIME 50 10051
TEST_TIME 51 9976
TEST_TIME 52 9964
TEST_TIME 53 9879
TEST_TIME 54 9921
TEST_TIME 55 9996
TEST_TIME 56 9918
TEST_TIME 57 9848
TEST_TIME 58 9799
TEST_TIME 59 9736
TEST_TIME 60 9730
TEST_TIME 61 9615
TEST_TIME 62 9568
TEST_TIME 63 9801
TEST_TIME 64 9766
TEST_TIME 65 9754
TEST_TIME 66 9752
TEST_TIME 67 9753
TEST_TIME 68 9777
TEST_TIME 69 9778
TEST_TIME 70 9775
TEST_TIME 71 9783
TEST_TIME 72 9888
TEST_TIME 73 9849
TEST_TIME 74 9874
TEST_TIME 75 9908
TEST_TIME 76 9852
TEST_TIME 77 9790
TEST_TIME 78 9708
TEST_TIME 79 9734
TEST_TIME 80 9666
TEST_TIME 81 9654
TEST_TIME 82 9590
TEST_TIME 83 9519
TEST_TIME 84 9483
TEST_TIME 85 9448
TEST_TIME 86 9393
TEST_TIME 87 9307
TEST_TIME 88 9310
TEST_TIME 89 9272
TEST_TIME 90 9307
TEST_TIME 91 9295
TEST_TIME 92 9319
TEST_TIME 93 9296
TEST_TIME 94 9325
TEST_TIME 95 9314
TEST_TIME 96 9353
TEST_TIME 97 9419
TEST_TIME 98 9444
TEST_TIME 99 9468
TEST_TIME 100 9446
TEST_TIME 101 9465
TEST_TIME 102 9457
TEST_TIME 103 9380
TEST_TIME 104 9327
TEST_TIME 105 9343
TEST_TIME 106 9330
TEST_TIME 107 9287
TEST_TIME 108 9238
TEST_TIME 109 9193
TEST_TIME 110 9202
TEST_TIME 111 9165
TEST_TIME 112 9145
TEST_TIME 113 9126
TEST_TIME 114 9111
TEST_TIME 115 9099
TEST_TIME 116 9105
TEST_TIME 117 9114
TEST_TIME 118 9092
TEST_TIME 119 9099
TEST_TIME 120 9050

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-03-2023 07:47:56 to 11-03-2023 07:48:00.

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 1530553 1530553 0 0.00 0 32253 14 0 0
doc_list_biud 1530541 1530541 0 0.00 0 30310 44 0 0
sp_add_invoice_to_stock 5350 5244 106 1.98 50 241485 26852 0 106
sp_cancel_adding_invoice 5148 5148 0 0.00 6 94799 1358 0 0
sp_cancel_client_order 1797 1797 0 0.00 6 8948 451 0 0
sp_cancel_customer_reserve 505585 505585 0 0.00 1 16111 113 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 1871 126 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 4 2693 138 0 0
sp_cancel_supplier_invoice 5783 5779 4 0.06 2 30319 1100 0 4
sp_cancel_supplier_order 403 403 0 0.00 26 1603753 183096 0 0
sp_cancel_write_off 9183 9183 0 0.00 2 7521 202 0 0
sp_client_order 21490 21490 0 0.00 5 19512 455 0 0
sp_customer_reserve 489460 489460 0 0.00 1 116900 252 0 0
sp_fill_shopping_cart 32825 32825 0 0.00 1 32417 87 0 0
sp_fill_shopping_cart_clo_res 489428 489428 0 0.00 0 16537 18 0 0
sp_get_clo_for_invoice 5202 5202 0 0.00 0 25886 255 0 0
sp_kill_cost_storno 15457 15457 0 0.00 0 32242 95 0 0
sp_kill_qstorno_ret_qs2qd 1026535 1026535 0 0.00 0 30309 49 0 0
sp_kill_qty_storno 543410 543410 0 0.00 0 30310 113 0 0
sp_lock_selected_doc 21369 21369 0 0.00 0 11531 8 0 0
sp_make_cost_storno 29084 29084 0 0.00 0 24397 80 0 0
sp_multiply_rows_for_pdistr 29080 29080 0 0.00 0 9540 39 0 0
sp_multiply_rows_for_qdistr 21490 21490 0 0.00 0 7759 167 0 0
sp_pay_from_customer 7455 7455 0 0.00 5 10659 175 0 0
sp_pay_to_supplier 6111 6111 0 0.00 6 11933 192 0 0
sp_payment_common 13563 13563 0 0.00 2 10525 51 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 0 8940 370 0 0
sp_qd_handle_on_invoice_upd_sts 10348 10348 0 0.00 0 14682 575 0 0
sp_qd_handle_on_reserve_upd_sts 10315 10315 0 0.00 0 21047 64 0 0
sp_reserve_write_off 10316 10316 0 0.00 8 21102 288 0 0
sp_supplier_invoice 5728 5728 0 0.00 8 109588 3068 0 0
sp_supplier_order 5616 5616 0 0.00 8 34450 1301 0 0
srv_aggregate_perf_data 115 115 0 0.00 2245 159884 15053 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 799 132718 33754 0 0
srv_find_qd_qs_mism 1029646 1029646 0 0.00 0 16745 11 0 0
srv_make_invnt_saldo 476 476 0 0.00 46 169805 4336 0 0
srv_make_money_saldo 912 912 0 0.00 1 13828 377 0 0
srv_recalc_idx_stat 5 5 0 0.00 37384 378282 173965 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5253 5253 0 0.00 1 20879 1021 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 6563 454 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 2703 31 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 5503 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 620 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 844 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 7661 63 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 102411 9086 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 1768 19 0 0
t$perf-norm:sp_client_order 21490 21490 0 0.00 0 8325 41 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 11108 24 0 0
t$perf-norm:sp_pay_from_customer 7455 7455 0 0.00 0 11520 23 0 0
t$perf-norm:sp_pay_to_supplier 6111 6111 0 0.00 0 4982 20 0 0
t$perf-norm:sp_reserve_write_off 10316 10316 0 0.00 0 29267 27 0 0
t$perf-norm:sp_supplier_invoice 5728 5728 0 0.00 0 5133 33 0 0
t$perf-norm:sp_supplier_order 5616 5616 0 0.00 0 3329 35 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 313 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 1143 18 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 852 18 0 0
t$perf-norm:srv_make_money_saldo 912 912 0 0.00 0 346 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 28 196 99 0 0
v_add_invoice_to_stock 10602 10390 212 1.99 0 9680 26 0 212
v_all_customers 39938 39938 0 0.00 0 110 4 0 0
v_all_suppliers 31594 31594 0 0.00 0 83 5 0 0
v_all_wares 42974 42974 0 0.00 0 137 4 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 1384 17 0 0
v_cancel_client_order 3594 3594 0 0.00 0 2497 14 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 1618 13 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 1674 13 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 583 11 0 0
v_cancel_supplier_invoice 846 838 8 0.94 0 2811 33 0 8
v_cancel_supplier_order 806 806 0 0.00 0 266 12 0 0
v_cancel_write_off 2044 2044 0 0.00 0 1017 26 0 0
v_max_id_clo_ord 5613 5613 0 0.00 0 156 5 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 3109 16 0 0
v_max_id_ord_sup 5723 5723 0 0.00 0 3514 7 0 0
v_max_non_paid_invoice 1649 1649 0 0.00 0 705 8 0 0
v_max_non_paid_realizn 3729 3729 0 0.00 0 8000 10 0 0
v_min_id_clo_ord 5613 5613 0 0.00 0 520 8 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 4456 16 0 0
v_min_id_ord_sup 5723 5723 0 0.00 0 4987 9 0 0
v_min_non_paid_invoice 6110 6110 0 0.00 0 7985 19 0 0
v_min_non_paid_realizn 7453 7453 0 0.00 0 5557 19 0 0
v_our_firm 10484 10484 0 0.00 0 95 5 0 0
v_reserve_write_off 20628 20628 0 0.00 0 2818 10 0 0
x_lock_depdocs_on_canc_invoice 10508 10508 0 0.00 0 17509 146 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 19360 407 0 0
x_make_qstorno_1000_1200 5616 5616 0 0.00 3 34441 1121 0 0
x_make_qstorno_1200_2000 5728 5728 0 0.00 4 103797 2831 0 0
x_make_qstorno_2100_3300 489456 489456 0 0.00 0 116897 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-03-2023 07:48:01 to 11-03-2023 07:48:05.

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
05:16:22 32 8192000000 8820743968 8840409088 17210768 0.209 9 2 2 0 17360800 87808 62224
05:17:23 36177 8192000000 9710039312 10001842176 785319696 8.747 100 89 178 0 814562336 7362208 21880432
05:19:02 23297 8192000000 9742672416 10044030976 812146576 9.019 100 86 170 0 844829616 11714416 20968624
05:20:32 16277 8192000000 9797814816 10116042752 863875104 9.539 100 82 164 0 905561280 22467136 19219040
05:21:55 8727 8192000000 9864267200 10198495232 924247200 10.138 100 81 160 0 979223376 37065904 17910272
05:23:05 4377 8192000000 9909099088 10256564224 966911968 10.557 100 78 154 1 1027219664 44433152 15874544
05:24:15 2839 8192000000 9969263712 10326216704 997903632 10.858 100 85 168 0 1088196400 72824736 17468032
05:25:22 6317 8192000000 9992042688 10359291904 1013726720 11.011 100 73 144 1 1110375632 82761712 13887200
05:26:31 1029 8192000000 10012766320 10381783040 1042170992 11.286 100 98 185 1 1138975536 78403952 18400592
05:27:34 1391 8192000000 10035848864 10410131456 1052725232 11.387 100 94 176 2 1160070528 89302176 18043120
05:28:38 1003 8192000000 10036205456 10410565632 1057223024 11.430 100 97 185 2 1157694528 82375200 18096304
05:29:41 1287 8192000000 10024476800 10403762176 1061238224 11.468 100 82 160 0 1142737216 65662272 15836720
05:30:43 1180 8192000000 10040033776 10419531776 1067373392 11.527 100 95 179 0 1160991472 75168112 18449968
05:31:46 1192 8192000000 10052059392 10436165632 1073450736 11.585 100 95 183 2 1175278272 83421600 18405936
05:32:49 1816 8192000000 10038960000 10424123392 1079008976 11.638 100 73 141 2 1163636480 70908816 13718688
05:33:52 995 8192000000 10026319056 10412802048 1083503696 11.681 100 90 170 3 1149714640 47092960 19117984
05:34:53 5214 8192000000 10031138192 10418036736 1084473456 11.690 100 82 145 3 1151180320 52261344 14445520
05:36:00 1107 8192000000 10057031168 10446995456 1092557536 11.767 100 95 186 0 1185235040 73031568 19645936
05:37:03 2604 8192000000 10046512048 10440413184 1085314784 11.698 100 71 133 3 1169855312 70316032 14224496
05:38:07 1704 8192000000 10042006032 10433294336 1086419536 11.709 100 85 147 3 1161943824 61249152 14275136
05:39:09 1133 8192000000 10060070704 10446942208 1090333152 11.746 100 97 181 2 1183661504 74775968 18552384
05:40:12 1125 8192000000 10045514016 10428350464 1088019424 11.724 100 98 194 3 1166704160 59741296 18943440
05:41:14 1115 8192000000 10049622208 10432581632 1075522160 11.605 100 93 180 1 1166448288 72329408 18596720
05:42:16 928 8192000000 10009637072 10382802944 1043675696 11.300 99 87 172 1 1132607120 70122688 18808736
05:43:18 1301 8192000000 9986715968 10358689792 1030765424 11.176 99 89 168 1 1111687152 63703824 17217904
05:44:20 3776 8192000000 9981384736 10360389632 1034761424 11.214 100 60 111 5 1102333424 56510496 11061504
05:45:25 1440 8192000000 9954127664 10326323200 1027445776 11.144 100 92 176 6 1082281040 34209856 20625408
05:46:30 11200 8192000000 10005362480 10385481728 1053112528 11.391 100 74 138 1 1125960512 59443792 13404192
05:47:47 1748 8192000000 10038687088 10421866496 1073822736 11.589 100 81 152 0 1153245856 65381952 14041168
05:49:01 14719 8192000000 10079829264 10471624704 1089002624 11.733 100 69 135 0 1205104608 101632256 14469728
05:50:20 998 8192000000 10082799680 10455982080 1086503712 11.709 99 90 170 0 1197419680 92086688 18829280
05:51:25 1408 8192000000 10139017248 10531295232 1107927408 11.913 100 94 182 0 1254876512 126220192 20728912
05:52:31 796 8192000000 10188041920 10574999552 1126854880 12.092 100 92 170 0 1302781456 158299328 17627248
05:53:36 3770 8192000000 10226707424 10615947264 1130337600 12.125 100 74 148 0 1337862192 190523232 17001360
05:54:50 831 8192000000 10248698112 10638364672 1135740336 12.175 100 91 176 0 1361944288 206752416 19451536
05:55:53 3593 8192000000 10269100256 10677755904 1148536000 12.296 100 80 158 1 1382411824 216817024 17058800
05:57:01 1841 8192000000 10303786448 10707652608 1165503808 12.455 100 86 167 1 1416126064 231769840 18852416
05:58:07 3773 8192000000 10325325456 10726727680 1169018784 12.488 100 83 158 0 1429283584 243154736 17110064
05:59:13 843 8192000000 10358795120 10772516864 1181263936 12.602 100 98 196 1 1469367344 266135648 21967760
06:00:19 1105 8192000000 10388307264 10794237952 1186180688 12.648 100 96 186 2 1501509440 294305456 21023296
06:01:23 943 8192000000 10448422016 10855579648 1191386816 12.696 100 98 193 0 1558004064 343348624 23268624
06:02:29 2165 8192000000 10446175632 10881486848 1191527056 12.698 100 82 163 0 1556531760 345321728 19682976
06:03:40 1307 8192000000 10480091936 10914017280 1202760752 12.802 100 97 192 1 1589427952 363401632 23265568
06:04:45 3094 8192000000 10477883232 10911158272 1198175584 12.759 100 89 176 2 1584843552 365552464 21115504
06:05:52 1640 8192000000 10501197712 10949824512 1195427680 12.734 100 90 174 0 1604563712 387850976 21285056
06:06:58 1563 8192000000 10526747504 10981916672 1204265056 12.816 100 93 184 2 1628665424 402356432 22043936
06:08:03 857 8192000000 10541135200 10977079296 1215402368 12.919 100 95 184 1 1644890032 407844416 21643248
06:09:07 2806 8192000000 10493440624 10976997376 1205172032 12.824 100 83 161 0 1593853360 369683312 18998016
06:10:18 906 8192000000 10539875520 10976514048 1213066272 12.898 100 93 180 0 1645803632 410446704 22290656
06:11:23 2521 8192000000 10524379232 10979536896 1200000208 12.776 100 72 134 0 1626265792 409425648 16839936
06:12:31 4834 8192000000 10506629312 10930012160 1174060128 12.535 98 67 133 1 1608465296 418149552 16255616
06:13:47 904 8192000000 10511692992 10974117888 1188918688 12.673 100 71 136 0 1613750144 407991760 16839696
06:14:50 5439 8192000000 10453531072 10954760192 1190513552 12.688 100 47 92 0 1558022784 355696944 11812288
06:16:00 17807 8192000000 10382613056 10892324864 1161310912 12.416 100 40 76 0 1497013808 325744288 9958608
06:17:24 31267 8192000000 10372332768 10833649664 1130469552 12.126 98 32 63 0 1469152848 331015216 7668080
06:19:01 936 8192000000 10303875536 10774573056 1156625904 12.372 100 93 181 1 1403599136 228020752 18952480
06:20:03 21023 8192000000 10304750832 10744111104 1140819312 12.223 98 44 88 1 1389679264 238275936 10584016
06:21:25 19299 8192000000 10248463552 10699804672 1153936208 12.346 100 41 82 1 1359230912 195509584 9785120
06:22:53 45725 8192000000 10243420192 10724646912 1131734544 12.138 98 22 42 1 1332257632 195104736 5418352
06:24:41 50201 8192000000 10187041376 10697605120 1114244384 11.973 98 25 46 1 1313514336 193164448 6105504
06:26:31 79345 8192000000 10046807200 10488401920 1123823584 12.063 99 20 40 0 1171073952 43218368 4032000
06:28:59 25657 8192000000 10081821808 10513014784 1136543712 12.183 100 47 90 1 1161872480 15633808 9694960
06:30:45 54688 8192000000 10089666976 10544832512 1109159552 11.924 97 35 63 2 1177863136 62668656 6034928
06:32:41 102192 8192000000 10113445632 10562936832 1112296384 11.954 98 36 68 1 1205090576 85108528 7685664
06:35:58 101667 8192000000 10076337536 10515226624 1114001392 11.970 98 58 109 3 1168712592 42952160 11759040
06:38:41 10027 8192000000 10111792800 10542817280 1140271952 12.218 100 59 99 1 1207613776 58120592 9221232
06:39:56 37295 8192000000 10095907424 10551115776 1119638880 12.024 99 57 105 1 1214346688 83965408 10742400
06:41:47 29138 8192000000 10043456320 10507612160 1112898976 11.960 99 27 48 3 1155372992 36776080 5697936
06:43:17 30566 8192000000 10086345568 10537594880 1115439264 11.984 99 28 56 0 1170144080 48666272 6038544
06:44:49 124181 8192000000 10143344912 10623160320 1140410400 12.219 100 26 52 0 1260758432 114307184 6040848
06:47:56 62648 8192000000 10176766496 10649317376 1146124032 12.273 100 24 48 0 1281310576 130055376 5131168
06:49:59 71208 8192000000 10081540352 10541789184 1125031744 12.075 98 17 34 0 1173236608 44615232 3589632
06:52:53 59120 8192000000 10129273008 10599563264 1128640224 12.109 99 17 34 1 1202957776 70607248 3710304
06:54:54 23226 8192000000 10119026672 10560090112 1139494992 12.211 100 41 82 0 1225690784 77617424 8578368
06:56:20 20739 8192000000 10081668704 10504278016 1098870960 11.827 97 49 93 0 1171770784 63587584 9312240
06:57:42 32209 8192000000 10105646560 10581442560 1108940224 11.922 98 24 46 0 1216694800 102617424 5137152
06:59:16 18396 8192000000 10097140544 10524377088 1120476000 12.031 99 41 82 0 1173887616 44880208 8531408
07:00:38 17019 8192000000 10104168784 10545250304 1090027792 11.743 98 44 88 0 1166515376 68143776 8343808
07:01:58 39943 8192000000 10075399408 10517266432 1108950672 11.922 99 21 40 1 1154388752 40959424 4478656
07:03:39 131730 8192000000 10060973696 10570211328 1107408816 11.908 99 23 46 0 1202588416 90309984 4869616
07:07:06 74108 8192000000 10018320768 10486607872 1060065488 11.457 95 22 39 0 1086129280 22460304 3603488
07:09:20 45035 8192000000 10042662400 10485309440 1104228992 11.878 99 21 38 1 1145874176 37120576 4524608
07:11:07 70990 8192000000 10028596000 10495328256 1085283296 11.698 97 12 22 1 1127882592 40296336 2302960
07:13:35 70420 8192000000 10065203280 10553679872 1118717760 12.015 100 16 32 0 1167647120 45336128 3593232
07:15:46 75900 8192000000 10054150992 10509668352 1112663856 11.958 99 20 40 0 1200810112 83435552 4710704
07:18:02 46560 8192000000 10088843968 10551570432 1102929040 11.865 97 48 92 0 1168304688 55581248 9794400
07:19:50 31211 8192000000 10106345824 10535006208 1115674560 11.986 99 38 74 0 1188802480 65699168 7428752
07:21:31 26135 8192000000 10121918752 10559766528 1124895024 12.073 99 42 83 0 1216996576 83492656 8608896
07:23:11 50576 8192000000 10119254688 10631737344 1141399040 12.229 100 33 66 0 1263220944 114481936 7339968
07:25:02 62038 8192000000 10077386368 10503688192 1104343952 11.879 96 51 90 3 1141760128 28512400 8903776
07:27:08 48733 8192000000 10103410384 10567761920 1105163696 11.887 97 23 46 1 1170330432 60547408 4619328
07:28:59 132718 8192000000 10092335280 10540617728 1116171744 11.991 99 31 60 2 1185013696 62459440 6382512
07:32:13 130587 8192000000 10053498032 10527547392 1098600784 11.824 97 13 26 0 1123170544 21996880 2572880
07:35:24 70293 8192000000 10079343840 10576850944 1136267632 12.180 100 17 34 0 1204793760 65038576 3487552
07:37:55 15690 8192000000 10091613392 10540367872 1139932720 12.215 100 37 73 1 1191978896 43845568 8200608
07:39:17 46812 8192000000 10139307920 10594549760 1132659408 12.146 98 25 50 0 1205859424 67023600 6176416
07:41:13 32987 8192000000 10189995904 10638073856 1138612416 12.202 98 37 72 1 1282455824 135537280 8306128
07:42:46 32957 8192000000 10188776448 10668548096 1154757120 12.354 100 33 65 0 1294595440 131856240 7982080
07:44:20 19825 8192000000 10218427296 10649354240 1146936912 12.281 99 47 94 0 1307269920 149721168 10611840
07:45:51 47210 8192000000 9126850416 9431851008 226342016 2.688 19 11 22 0 332193312 103096160 2755136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 11-03-2023 07:48:07 to 11-03-2023 07:48:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-03-2023 07:48:25 to 11-03-2023 07:48:27.

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          223713
MON$OLDEST_ACTIVE               223714
MON$OLDEST_SNAPSHOT             223714
MON$NEXT_TRANSACTION            223714
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                       2092928
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2908 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2908 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2908 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2908 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Mar 11 07:47:19 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

11.03.23 07:48:28. 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
2023-03-11 07:48:31 No severe PSQL-related problems occured
11.03.23 07:48:32. End of report.