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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16755852 kB
MemAvailable 30373524 kB
Buffers 0 kB
Cached 15034140 kB
SwapCached 10424 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 2022-12-29 10:37:21
DTS_END                         2022-12-29 10:37:21.6600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35044
TRN_ID                          193107
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: 6570.68 60456 788482
customer order: creation 123.68 654 14841
customer order: refuse 11.29 639 1355
order to supplier: creation 36.45 2283 4374
order to supplier: removal 2.50 285190 300
invoice (draft): creation 36.05 5652 4326
invoice (draft): removal 35.76 2193 4291
invoice accept: apply 31.34 53020 3761
invoice accept: cancel 32.27 2364 3872
customer reserve: creation 2906.06 514 348727
customer reserve: removal 3134.81 173 376177
realization accept: apply 62.72 447 7526
realization accept: cancel 57.08 286 6850
payment from customer: creation 45.42 245 5450
payment from customer: removal 4.71 206 565
payment to supplier: creation 38.21 283 4585
payment to supplier: removal 2.98 247 357
service: total inventory turnovers 2.03 17964 244
service: total monetary turnovers 6.61 1473 793
service: aggregate perf. data 0.70 19582 84
service: refresh index statistics 0.03 815699 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-12-2022 10:39:49 to 29-12-2022 10:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4621
TEST_TIME 2 3912
TEST_TIME 3 4457
TEST_TIME 4 4555
TEST_TIME 5 4421
TEST_TIME 6 4249
TEST_TIME 7 4684
TEST_TIME 8 4817
TEST_TIME 9 5380
TEST_TIME 10 5510
TEST_TIME 11 5709
TEST_TIME 12 5903
TEST_TIME 13 6364
TEST_TIME 14 6299
TEST_TIME 15 6531
TEST_TIME 16 6775
TEST_TIME 17 7116
TEST_TIME 18 7123
TEST_TIME 19 7050
TEST_TIME 20 7098
TEST_TIME 21 7029
TEST_TIME 22 7096
TEST_TIME 23 7299
TEST_TIME 24 7297
TEST_TIME 25 7691
TEST_TIME 26 8007
TEST_TIME 27 7999
TEST_TIME 28 7878
TEST_TIME 29 8533
TEST_TIME 30 9067
TEST_TIME 31 9520
TEST_TIME 32 9696
TEST_TIME 33 9913
TEST_TIME 34 10090
TEST_TIME 35 10203
TEST_TIME 36 10249
TEST_TIME 37 10489
TEST_TIME 38 10512
TEST_TIME 39 10445
TEST_TIME 40 10663
TEST_TIME 41 10538
TEST_TIME 42 10436
TEST_TIME 43 10355
TEST_TIME 44 10323
TEST_TIME 45 10456
TEST_TIME 46 10320
TEST_TIME 47 10187
TEST_TIME 48 10127
TEST_TIME 49 10072
TEST_TIME 50 10078
TEST_TIME 51 9976
TEST_TIME 52 9956
TEST_TIME 53 9901
TEST_TIME 54 9810
TEST_TIME 55 9744
TEST_TIME 56 9651
TEST_TIME 57 9545
TEST_TIME 58 9428
TEST_TIME 59 9301
TEST_TIME 60 9184
TEST_TIME 61 9071
TEST_TIME 62 8951
TEST_TIME 63 8852
TEST_TIME 64 8787
TEST_TIME 65 8686
TEST_TIME 66 8622
TEST_TIME 67 8509
TEST_TIME 68 8442
TEST_TIME 69 8378
TEST_TIME 70 8260
TEST_TIME 71 8151
TEST_TIME 72 8109
TEST_TIME 73 8076
TEST_TIME 74 8031
TEST_TIME 75 7953
TEST_TIME 76 7909
TEST_TIME 77 7822
TEST_TIME 78 7740
TEST_TIME 79 7687
TEST_TIME 80 7616
TEST_TIME 81 7535
TEST_TIME 82 7539
TEST_TIME 83 7463
TEST_TIME 84 7394
TEST_TIME 85 7323
TEST_TIME 86 7252
TEST_TIME 87 7197
TEST_TIME 88 7157
TEST_TIME 89 7124
TEST_TIME 90 7094
TEST_TIME 91 7082
TEST_TIME 92 7027
TEST_TIME 93 6981
TEST_TIME 94 6926
TEST_TIME 95 6890
TEST_TIME 96 6857
TEST_TIME 97 6824
TEST_TIME 98 6775
TEST_TIME 99 6737
TEST_TIME 100 6698
TEST_TIME 101 6677
TEST_TIME 102 6678
TEST_TIME 103 6647
TEST_TIME 104 6616
TEST_TIME 105 6592
TEST_TIME 106 6588
TEST_TIME 107 6555
TEST_TIME 108 6555
TEST_TIME 109 6540
TEST_TIME 110 6568
TEST_TIME 111 6549
TEST_TIME 112 6558
TEST_TIME 113 6547
TEST_TIME 114 6531
TEST_TIME 115 6537
TEST_TIME 116 6561
TEST_TIME 117 6574
TEST_TIME 118 6557
TEST_TIME 119 6619
TEST_TIME 120 6593

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-12-2022 10:39:53 to 29-12-2022 10:39:56.

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 1107951 1107951 0 0.00 0 38244 26 0 0
doc_list_biud 1107935 1107935 0 0.00 0 40499 71 0 0
sp_add_invoice_to_stock 3842 3761 81 2.10 84 715310 53021 0 81
sp_cancel_adding_invoice 3872 3872 0 0.00 7 141214 2365 0 0
sp_cancel_client_order 1355 1355 0 0.00 9 14081 640 0 0
sp_cancel_customer_reserve 376177 376177 0 0.00 1 37627 174 0 0
sp_cancel_pay_from_customer 565 565 0 0.00 4 2149 207 0 0
sp_cancel_pay_to_supplier 357 357 0 0.00 4 14366 248 0 0
sp_cancel_supplier_invoice 4299 4291 8 0.18 2 40506 2194 0 8
sp_cancel_supplier_order 300 300 0 0.00 37 1436296 285191 0 0
sp_cancel_write_off 6850 6850 0 0.00 1 10093 286 0 0
sp_client_order 14841 14841 0 0.00 6 45661 655 0 0
sp_customer_reserve 348727 348727 0 0.00 0 121181 514 0 0
sp_fill_shopping_cart 23531 23531 0 0.00 1 20036 123 0 0
sp_fill_shopping_cart_clo_res 348699 348699 0 0.00 0 25401 32 0 0
sp_get_clo_for_invoice 3726 3726 0 0.00 0 12961 459 0 0
sp_kill_cost_storno 11644 11644 0 0.00 0 38236 160 0 0
sp_kill_qstorno_ret_qs2qd 763795 763795 0 0.00 0 40496 80 0 0
sp_kill_qty_storno 404095 404095 0 0.00 0 40498 183 0 0
sp_lock_selected_doc 15848 15848 0 0.00 0 8765 8 0 0
sp_make_cost_storno 21287 21287 0 0.00 0 14046 132 0 0
sp_multiply_rows_for_pdistr 21285 21285 0 0.00 0 7750 58 0 0
sp_multiply_rows_for_qdistr 14840 14840 0 0.00 0 45393 282 0 0
sp_pay_from_customer 5450 5450 0 0.00 5 15725 246 0 0
sp_pay_to_supplier 4585 4585 0 0.00 6 14081 284 0 0
sp_payment_common 10032 10032 0 0.00 2 14862 57 0 0
sp_qd_handle_on_cancel_clo 1355 1355 0 0.00 1 14074 543 0 0
sp_qd_handle_on_invoice_upd_sts 7598 7598 0 0.00 1 32442 994 0 0
sp_qd_handle_on_reserve_upd_sts 7524 7524 0 0.00 0 16165 114 0 0
sp_reserve_write_off 7526 7526 0 0.00 9 22947 447 0 0
sp_supplier_invoice 4326 4326 0 0.00 14 128016 5653 0 0
sp_supplier_order 4374 4374 0 0.00 6 46568 2284 0 0
srv_aggregate_perf_data 84 84 0 0.00 4036 187777 19582 0 0
srv_fill_mon_cache_memory 80 80 0 0.00 685 180010 23490 0 0
srv_find_qd_qs_mism 750555 750555 0 0.00 0 16240 23 0 0
srv_make_invnt_saldo 244 244 0 0.00 116 182196 17964 0 0
srv_make_money_saldo 793 793 0 0.00 1 35097 1473 0 0
srv_recalc_idx_stat 4 4 0 0.00 388677 1264924 815700 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 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 3761 3761 0 0.00 1 22147 1359 0 0
t$perf-norm:sp_cancel_adding_invoice 356 356 0 0.00 2 7286 640 0 0
t$perf-norm:sp_cancel_client_order 1355 1355 0 0.00 0 1209 39 0 0
t$perf-norm:sp_cancel_customer_reserve 543 543 0 0.00 0 777 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 565 565 0 0.00 0 6405 41 0 0
t$perf-norm:sp_cancel_pay_to_supplier 357 357 0 0.00 0 1788 30 0 0
t$perf-norm:sp_cancel_supplier_invoice 332 332 0 0.00 0 1633 87 0 0
t$perf-norm:sp_cancel_supplier_order 300 300 0 0.00 0 105482 12856 0 0
t$perf-norm:sp_cancel_write_off 793 793 0 0.00 0 4726 35 0 0
t$perf-norm:sp_client_order 14841 14841 0 0.00 0 6425 59 0 0
t$perf-norm:sp_customer_reserve 802 802 0 0.00 0 3432 28 0 0
t$perf-norm:sp_pay_from_customer 5450 5450 0 0.00 0 4556 25 0 0
t$perf-norm:sp_pay_to_supplier 4586 4586 0 0.00 0 9930 27 0 0
t$perf-norm:sp_reserve_write_off 7526 7526 0 0.00 0 5964 32 0 0
t$perf-norm:sp_supplier_invoice 4326 4326 0 0.00 0 4078 53 0 0
t$perf-norm:sp_supplier_order 4374 4374 0 0.00 0 3360 55 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 639 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 80 80 0 0.00 0 880 29 0 0
t$perf-norm:srv_make_invnt_saldo 244 244 0 0.00 0 912 35 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 735 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 291 129 0 0
v_add_invoice_to_stock 7612 7450 162 2.12 0 15470 37 0 162
v_all_customers 27649 27649 0 0.00 0 117 5 0 0
v_all_suppliers 24161 24161 0 0.00 0 86 6 0 0
v_all_wares 29675 29675 0 0.00 0 77 5 0 0
v_cancel_adding_invoice 712 712 0 0.00 0 349 20 0 0
v_cancel_client_order 2710 2710 0 0.00 0 1848 14 0 0
v_cancel_customer_prepayment 1130 1130 0 0.00 0 1812 20 0 0
v_cancel_customer_reserve 1086 1086 0 0.00 1 684 14 0 0
v_cancel_payment_to_supplier 714 714 0 0.00 0 12149 36 0 0
v_cancel_supplier_invoice 680 664 16 2.35 0 1832 33 0 16
v_cancel_supplier_order 600 600 0 0.00 0 1554 24 0 0
v_cancel_write_off 1586 1586 0 0.00 0 1235 31 0 0
v_max_id_clo_ord 4371 4371 0 0.00 0 1399 7 0 0
v_max_id_clo_res 802 802 0 0.00 0 6351 27 0 0
v_max_id_ord_sup 4319 4319 0 0.00 0 5070 8 0 0
v_max_non_paid_invoice 1193 1193 0 0.00 0 70 9 0 0
v_max_non_paid_realizn 2785 2785 0 0.00 0 1133 10 0 0
v_min_id_clo_ord 4371 4371 0 0.00 0 1868 12 0 0
v_min_id_clo_res 802 802 0 0.00 0 195 10 0 0
v_min_id_ord_sup 4318 4318 0 0.00 0 7501 12 0 0
v_min_non_paid_invoice 4583 4583 0 0.00 0 5383 18 0 0
v_min_non_paid_realizn 5446 5446 0 0.00 0 4606 20 0 0
v_our_firm 7344 7344 0 0.00 0 59 7 0 0
v_reserve_write_off 15044 15044 0 0.00 0 13224 17 0 0
x_lock_depdocs_on_canc_invoice 7831 7831 0 0.00 0 6551 278 0 0
x_lock_depdocs_on_canc_sup_ord 300 300 0 0.00 0 10908 768 0 0
x_make_qstorno_1000_1200 4374 4374 0 0.00 2 46544 2037 0 0
x_make_qstorno_1200_2000 4326 4326 0 0.00 8 127916 5296 0 0
x_make_qstorno_2100_3300 348725 348725 0 0.00 0 121163 460 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2022 10:39:57 to 29-12-2022 10:40:01.

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:24 932 8192000000 8917081696 8971636736 3900160 0.047 2 1 2 0 3997776 61840 35776
08:08:26 34027 8192000000 9723835392 10025459712 799174192 8.888 100 85 170 0 828078752 7991984 20912576
08:10:04 14360 8192000000 9758082288 10068234240 834847712 9.248 100 87 174 0 868853728 13477184 20528832
08:11:28 19532 8192000000 9795248048 10114994176 866441392 9.565 100 81 161 0 908660384 23608752 18610240
08:12:53 4241 8192000000 9858230192 10191835136 909877520 9.996 100 78 153 0 969537728 42408608 17251600
08:14:00 4149 8192000000 9927629680 10270285824 951826352 10.409 100 90 180 1 1041377472 69915712 19635408
08:15:09 1860 8192000000 9956379200 10304385024 985466512 10.737 100 88 169 0 1077006528 74051056 17488960
08:16:15 2397 8192000000 9987175984 10349318144 1008320240 10.959 100 88 171 0 1104074624 78091008 17663376
08:17:20 4262 8192000000 10018221072 10385104896 1032910736 11.196 100 87 167 1 1123358384 74313664 16133984
08:18:25 1013 8192000000 10007543344 10380337152 1055799248 11.416 100 96 182 1 1127584096 54518192 17266656
08:19:27 1633 8192000000 10016521232 10392244224 1065903232 11.513 100 92 181 1 1141081552 56389856 18788464
08:20:30 1337 8192000000 10057955344 10438041600 1075002992 11.600 100 93 185 0 1179928656 86350272 18575392
08:21:32 936 8192000000 10044733952 10434260992 1078226976 11.631 100 75 146 0 1166768672 73422272 15119424
08:22:34 4054 8192000000 10052833152 10443472896 1084945104 11.695 100 68 135 0 1177240912 79006112 13289696
08:23:38 1137 8192000000 10056481152 10438840320 1094588032 11.786 100 99 192 2 1174012288 60240864 19183392
08:24:41 1281 8192000000 10055261712 10441797632 1095688032 11.797 100 94 177 2 1178981056 64584096 18708928
08:25:45 1225 8192000000 10045621408 10433323008 1098869200 11.827 100 95 182 1 1169220352 51173728 19177424
08:26:47 5516 8192000000 10049975440 10445385728 1095366160 11.794 100 69 133 4 1172200400 63747776 13086464
08:27:57 881 8192000000 10083078640 10475143168 1097023392 11.809 100 86 171 1 1202790960 88402304 17365264
08:28:58 1047 8192000000 10059133648 10449301504 1094785104 11.788 100 97 186 1 1182123952 68990000 18348848
08:30:01 2618 8192000000 10075199632 10464264192 1094253152 11.783 100 78 142 2 1193375952 84243328 14879472
08:31:05 1436 8192000000 10010597008 10388869120 1041753920 11.282 98 85 156 1 1127722896 69004272 16964704
08:32:07 1167 8192000000 10015839424 10400157696 1082897120 11.675 100 93 173 1 1138970304 37800528 18272656
08:33:09 932 8192000000 10025053120 10410397696 1064420064 11.499 100 83 154 2 1150223120 66975264 18827792
08:34:11 1118 8192000000 9990987728 10362494976 1037301760 11.239 100 97 186 0 1114373296 57630512 19441024
08:35:13 1482 8192000000 9980888832 10355937280 1023030128 11.101 99 73 129 2 1107772976 70781280 13961568
08:36:15 3520 8192000000 10001005280 10381324288 1031924496 11.187 100 78 140 3 1129074656 81624784 15525376
08:37:20 1134 8192000000 9994304368 10374643712 1041521504 11.279 100 62 115 2 1122530384 69052480 11956400
08:38:29 29464 8192000000 10011695040 10399625216 1061416528 11.470 100 62 115 1 1124835616 52082304 11336784
08:40:06 1361 8192000000 10031382624 10412904448 1066165952 11.515 100 75 137 1 1149931232 70474656 13290624
08:41:15 6701 8192000000 10044973472 10434596864 1067577040 11.529 100 58 110 1 1168138528 89205248 11356240
08:42:24 3556 8192000000 10087474960 10465689600 1083178608 11.678 100 83 151 0 1202086672 102087808 16820256
08:43:33 19180 8192000000 10143002112 10537345024 1095438384 11.794 100 53 101 0 1245814176 139614752 10761040
08:44:56 2357 8192000000 10160297264 10558050304 1109578176 11.928 100 71 128 1 1275422560 151393424 14450960
08:46:04 2343 8192000000 10210041808 10618314752 1128786720 12.110 100 98 192 2 1327796576 177695568 21314288
08:47:09 1029 8192000000 10210209392 10605699072 1148033616 12.291 100 94 179 0 1322370672 154000944 20336112
08:48:14 878 8192000000 10265820256 10655973376 1158611424 12.390 100 95 187 0 1378946080 198835856 21498800
08:49:19 1426 8192000000 10316638464 10716393472 1167221360 12.471 100 96 188 2 1424712336 235745312 21745664
08:50:23 1479 8192000000 10311929184 10728148992 1179312816 12.584 100 97 186 1 1419543232 220171520 20058896
08:51:29 952 8192000000 10351717600 10758823936 1186723696 12.653 100 97 189 1 1461693168 253160928 21808544
08:52:35 1261 8192000000 10369227104 10782756864 1193461200 12.716 100 92 179 0 1479391232 265212480 20717552
08:53:40 675 8192000000 10380879536 10801307648 1192047952 12.702 100 96 179 1 1488624176 276278544 20297680
08:54:45 718 8192000000 10413062864 10836090880 1198373120 12.761 100 91 180 0 1522583072 303091008 21118944
08:55:48 4792 8192000000 10459557200 10873462784 1201979040 12.795 100 73 144 1 1564372592 345831088 16562464
08:57:03 932 8192000000 10517314832 10929692672 1207827184 12.849 100 94 186 2 1622282800 392114288 22341328
08:58:08 1711 8192000000 10538724352 10957783040 1208873248 12.859 100 91 177 0 1652710480 422384112 21453120
08:59:13 943 8192000000 10565797584 11007762432 1207606336 12.847 100 92 182 1 1673599952 442852720 23140896
09:00:18 2275 8192000000 10609669072 11034923008 1203204352 12.806 100 95 187 0 1713469696 487454352 22810992
09:01:28 3408 8192000000 10594523104 11048755200 1191546960 12.698 100 86 169 2 1699149392 485966336 21636096
09:02:36 1005 8192000000 10573272688 11047239680 1194676736 12.727 100 92 178 1 1674343984 458564800 21102448
09:03:41 6477 8192000000 10557904624 11006357504 1191919984 12.701 100 60 119 0 1657498512 450580160 14998368
09:04:50 5067 8192000000 10582414032 11010134016 1171409104 12.510 100 63 119 0 1682361552 494933952 16018496
09:06:01 5983 8192000000 10500918592 11052552192 1166671888 12.466 100 67 130 1 1604629792 420598048 17359856
09:07:09 5370 8192000000 10418825200 10934870016 1166294736 12.462 100 55 110 1 1527513344 347434720 13783888
09:08:27 23007 8192000000 10341995856 10824654848 1160272304 12.406 100 26 52 0 1449477872 282369984 6835584
09:09:55 19563 8192000000 10229816416 10680205312 1115914752 11.988 99 27 52 0 1332702880 209703232 7084896
09:11:28 25252 8192000000 10198205200 10647322624 1123160912 12.057 100 19 36 0 1308262880 180732592 4369376
09:12:59 26886 8192000000 10133096288 10567180288 1061565392 11.471 94 25 51 0 1200676784 133511824 5599568
09:14:27 13043 8192000000 10089412720 10570280960 1107104768 11.905 99 12 22 0 1198386736 88690496 2591472
09:15:42 34310 8192000000 10103241008 10525921280 1128018832 12.103 100 42 78 1 1185387680 48754864 8613984
09:17:18 132341 8192000000 10060479952 10512183296 1050701088 11.367 92 6 12 0 1108080768 56314688 1064992
09:20:39 8969 8192000000 10077567600 10490855424 1126156032 12.085 99 51 95 1 1162278480 26812848 9309600
09:21:53 111680 8192000000 10078645488 10523168768 1118584000 12.014 98 18 34 2 1189955984 67669600 3702384
09:24:48 179998 8192000000 10086005792 10578145280 1131530032 12.136 99 23 46 0 1227381792 90335424 5516336
09:28:49 14962 8192000000 10138164272 10563448832 1145284080 12.265 100 60 111 0 1226520272 69926448 11309744
09:30:06 89500 8192000000 10089830352 10526318592 1146188800 12.274 100 30 59 0 1226586848 74710928 5687120
09:32:37 11554 8192000000 10131364176 10563452928 1157590032 12.381 100 50 94 0 1229754560 62394720 9769808
09:33:50 10449 8192000000 10150187344 10573561856 1142617296 12.240 99 62 121 0 1238771008 82968720 13184992
09:35:11 58455 8192000000 10147101136 10590285824 1143974096 12.253 99 42 83 0 1245194448 92782032 8438320
09:37:18 36932 8192000000 10171158672 10606895104 1152801216 12.336 100 40 79 0 1262966112 101836736 8328160
09:39:00 51732 8192000000 10184411008 10622853120 1156881120 12.374 100 41 75 0 1282969616 117514112 8574384
09:40:58 96958 8192000000 10176712320 10638950400 1147651344 12.287 99 30 60 0 1275701680 121363328 6687008
09:43:52 175798 8192000000 10173129616 10647982080 1121752544 12.044 97 28 54 1 1256151104 127588864 6809696
09:47:54 82052 8192000000 10185089344 10630094848 1147482416 12.286 100 47 86 0 1279534272 122907568 9144288
09:50:22 60194 8192000000 10141254080 10618802176 1141328432 12.228 100 36 71 1 1244440112 96130256 6981424
09:52:26 67315 8192000000 10111141632 10565443584 1134069008 12.160 99 47 92 0 1204240208 60333328 9837872
09:54:42 36926 8192000000 10116115984 10539266048 1136282016 12.181 100 44 86 0 1209079904 64258176 8539712
09:56:27 32637 8192000000 10121281152 10559578112 1125848736 12.082 99 51 102 0 1220974784 84647424 10478624
09:58:06 27824 8192000000 10136931920 10558722048 1129688048 12.118 99 60 115 0 1213111952 71966112 11457792
09:59:41 18315 8192000000 10107726544 10533830656 1135792304 12.176 100 52 100 2 1203846944 58413104 9641536
10:01:04 38348 8192000000 10113958368 10535645184 1144981072 12.262 100 56 109 1 1213377184 57715696 10680416
10:02:48 44886 8192000000 10149107504 10571419648 1134030704 12.159 99 51 102 0 1227977216 84005152 9941360
10:05:19 39979 8192000000 10148117136 10590035968 1128077824 12.103 99 51 96 1 1235201712 97612912 9510976
10:07:03 15141 8192000000 10119513552 10544852992 1118901104 12.017 99 46 88 0 1216961232 89389120 8671008
10:08:32 19476 8192000000 10096068928 10525396992 1129692224 12.118 100 37 72 0 1199718512 62428720 7597568
10:10:00 12812 8192000000 10104026256 10523365376 1137619104 12.193 100 51 97 1 1193431952 46134800 9678048
10:11:49 20748 8192000000 10130625936 10550366208 1134996304 12.168 99 61 122 0 1220464240 73507712 11960224
10:13:20 8924 8192000000 10129265424 10560036864 1133466704 12.154 99 56 109 0 1215872880 71636432 10769744
10:14:45 32841 8192000000 10128637024 10557030400 1139098192 12.207 100 46 88 0 1219274496 70295264 9881040
10:16:22 9945 8192000000 10136167040 10554081280 1121354096 12.040 99 51 98 3 1221288112 88823344 11110672
10:17:37 3442 8192000000 10162994640 10576056320 1133318896 12.153 100 72 139 1 1254370224 105726832 15324496
10:18:44 4908 8192000000 10171524880 10584559616 1140520816 12.220 100 70 138 0 1266149408 111587568 14041024
10:19:53 3297 8192000000 10181244192 10598662144 1145163024 12.264 100 70 135 0 1274683920 115109216 14411680
10:21:02 6637 8192000000 10190367472 10606829568 1149187936 12.302 100 75 142 2 1286467328 123050432 14228960
10:22:12 3512 8192000000 10175625456 10583592960 1119541872 12.023 99 72 136 0 1254323664 121134464 13647328
10:23:24 3139 8192000000 10223974992 10628071424 1140536256 12.221 100 78 149 1 1303841280 147809024 15496000
10:24:31 16461 8192000000 10244271104 10666356736 1137915568 12.196 100 60 119 0 1320995168 170701584 12378016
10:26:12 2437 8192000000 10230713216 10642661376 1145943856 12.271 100 82 153 0 1322864784 160641904 16279024
10:27:22 8321 8192000000 10214389520 10644365312 1126096192 12.085 99 70 140 1 1296435072 155566288 14772592
10:28:41 6732 8192000000 10209470112 10637873152 1127513312 12.098 100 73 144 0 1297056048 153658096 15884640
10:30:06 2800 8192000000 10215798016 10627518464 1140687696 12.222 100 76 140 4 1310048928 154016912 15344320
10:31:12 4947 8192000000 10272693056 10678497280 1149688464 12.307 100 89 165 2 1359322528 191859344 17774720
10:32:22 816 8192000000 10277462288 10692771840 1137702832 12.194 99 87 159 1 1364105920 209210336 17192752
10:33:27 4283 8192000000 10268623760 10693136384 1139446784 12.210 100 74 146 0 1355223264 200077296 15699184
10:34:36 1992 8192000000 10284506016 10690871296 1138141904 12.198 99 87 165 1 1365290992 208637632 18511456
10:35:43 1585 8192000000 10260417840 10692026368 1147132592 12.283 100 84 158 4 1347917504 182701680 18083232
10:36:53 1327 8192000000 10264025440 10668679168 1154350432 12.350 100 81 160 0 1343699984 171104384 18245168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 29-12-2022 10:40:03 to 29-12-2022 10:40:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 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 29-12-2022 10:40:21 to 29-12-2022 10:40:23.

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          175216
MON$OLDEST_ACTIVE               193413
MON$OLDEST_SNAPSHOT             193413
MON$NEXT_TRANSACTION            193413
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                       2139712
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             732
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
--- end of diff output ---

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

29.12.22 10:40:24. 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
2022-12-29 10:40:27 No severe PSQL-related problems occured
29.12.22 10:40:28. End of report.