Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2023 07: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 15428176 kB
MemAvailable 30380232 kB
Buffers 0 kB
Cached 16261472 kB
SwapCached 4596 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-01-12 07:37:22
DTS_END                         2023-01-12 07:37:22.3270
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/51410
TRN_ID                          218979
ATT_ID                          594
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: 8596.29 28585 1031555
customer order: creation 164.99 486 19799
customer order: refuse 14.13 395 1696
order to supplier: creation 44.33 1375 5320
order to supplier: removal 3.37 156130 404
invoice (draft): creation 44.65 3402 5358
invoice (draft): removal 48.33 1047 5799
invoice accept: apply 40.92 26632 4911
invoice accept: cancel 42.67 1092 5120
customer reserve: creation 3716.16 261 445939
customer reserve: removal 4207.03 97 504844
realization accept: apply 80.58 270 9669
realization accept: cancel 64.24 160 7709
payment from customer: creation 56.33 172 6760
payment from customer: removal 5.88 127 705
payment to supplier: creation 47.27 187 5672
payment to supplier: removal 3.23 134 387
service: total inventory turnovers 3.78 3913 453
service: total monetary turnovers 7.44 317 893
service: aggregate perf. data 0.94 14452 113
service: refresh index statistics 0.03 361057 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-01-2023 07:38:35 to 12-01-2023 07:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11904
TEST_TIME 2 8740
TEST_TIME 3 7872
TEST_TIME 4 6970
TEST_TIME 5 6751
TEST_TIME 6 6682
TEST_TIME 7 6320
TEST_TIME 8 6475
TEST_TIME 9 6532
TEST_TIME 10 6876
TEST_TIME 11 7063
TEST_TIME 12 7106
TEST_TIME 13 7179
TEST_TIME 14 7202
TEST_TIME 15 7154
TEST_TIME 16 7263
TEST_TIME 17 7287
TEST_TIME 18 7317
TEST_TIME 19 7644
TEST_TIME 20 8015
TEST_TIME 21 8376
TEST_TIME 22 8508
TEST_TIME 23 8523
TEST_TIME 24 8592
TEST_TIME 25 8846
TEST_TIME 26 8788
TEST_TIME 27 9381
TEST_TIME 28 9949
TEST_TIME 29 10608
TEST_TIME 30 10953
TEST_TIME 31 10761
TEST_TIME 32 10480
TEST_TIME 33 10616
TEST_TIME 34 10429
TEST_TIME 35 10354
TEST_TIME 36 10298
TEST_TIME 37 10168
TEST_TIME 38 10031
TEST_TIME 39 9872
TEST_TIME 40 9824
TEST_TIME 41 9665
TEST_TIME 42 9470
TEST_TIME 43 9482
TEST_TIME 44 9481
TEST_TIME 45 9349
TEST_TIME 46 9245
TEST_TIME 47 9163
TEST_TIME 48 9137
TEST_TIME 49 9224
TEST_TIME 50 9142
TEST_TIME 51 9064
TEST_TIME 52 8917
TEST_TIME 53 9019
TEST_TIME 54 9061
TEST_TIME 55 9042
TEST_TIME 56 9030
TEST_TIME 57 9036
TEST_TIME 58 8996
TEST_TIME 59 9052
TEST_TIME 60 9086
TEST_TIME 61 9043
TEST_TIME 62 9049
TEST_TIME 63 9098
TEST_TIME 64 9100
TEST_TIME 65 9187
TEST_TIME 66 9159
TEST_TIME 67 9199
TEST_TIME 68 9083
TEST_TIME 69 9020
TEST_TIME 70 8947
TEST_TIME 71 8917
TEST_TIME 72 8946
TEST_TIME 73 8885
TEST_TIME 74 8800
TEST_TIME 75 8770
TEST_TIME 76 8733
TEST_TIME 77 8780
TEST_TIME 78 8782
TEST_TIME 79 8767
TEST_TIME 80 8832
TEST_TIME 81 8799
TEST_TIME 82 8800
TEST_TIME 83 8859
TEST_TIME 84 8850
TEST_TIME 85 8875
TEST_TIME 86 8847
TEST_TIME 87 8852
TEST_TIME 88 8927
TEST_TIME 89 8850
TEST_TIME 90 8788
TEST_TIME 91 8787
TEST_TIME 92 8757
TEST_TIME 93 8697
TEST_TIME 94 8691
TEST_TIME 95 8729
TEST_TIME 96 8688
TEST_TIME 97 8696
TEST_TIME 98 8669
TEST_TIME 99 8645
TEST_TIME 100 8653
TEST_TIME 101 8714
TEST_TIME 102 8778
TEST_TIME 103 8817
TEST_TIME 104 8812
TEST_TIME 105 8824
TEST_TIME 106 8801
TEST_TIME 107 8874
TEST_TIME 108 8854
TEST_TIME 109 8811
TEST_TIME 110 8789
TEST_TIME 111 8732
TEST_TIME 112 8722
TEST_TIME 113 8688
TEST_TIME 114 8666
TEST_TIME 115 8654
TEST_TIME 116 8611
TEST_TIME 117 8569
TEST_TIME 118 8542
TEST_TIME 119 8675
TEST_TIME 120 8615

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-01-2023 07:38:39 to 12-01-2023 07:38:42.

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 1442300 1442300 0 0.00 0 25142 13 0 0
doc_list_biud 1442290 1442290 0 0.00 0 61296 40 0 0
sp_add_invoice_to_stock 5043 4911 132 2.61 69 180987 26632 0 132
sp_cancel_adding_invoice 5120 5120 0 0.00 5 68892 1093 0 0
sp_cancel_client_order 1696 1696 0 0.00 7 13713 396 0 0
sp_cancel_customer_reserve 504844 504844 0 0.00 1 27068 98 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 4 6755 128 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 2982 134 0 0
sp_cancel_supplier_invoice 5805 5799 6 0.10 3 61302 1048 0 6
sp_cancel_supplier_order 404 404 0 0.00 14 1443469 156131 0 0
sp_cancel_write_off 7709 7709 0 0.00 2 12870 161 0 0
sp_client_order 19799 19799 0 0.00 5 25475 486 0 0
sp_customer_reserve 445939 445939 0 0.00 0 61566 261 0 0
sp_fill_shopping_cart 30466 30466 0 0.00 1 18840 88 0 0
sp_fill_shopping_cart_clo_res 445904 445904 0 0.00 0 14209 22 0 0
sp_get_clo_for_invoice 4867 4867 0 0.00 0 51158 401 0 0
sp_kill_cost_storno 13921 13921 0 0.00 0 7023 66 0 0
sp_kill_qstorno_ret_qs2qd 1023600 1023600 0 0.00 0 61295 44 0 0
sp_kill_qty_storno 540108 540108 0 0.00 0 61296 100 0 0
sp_lock_selected_doc 20141 20141 0 0.00 0 7322 8 0 0
sp_make_cost_storno 26968 26968 0 0.00 0 25106 74 0 0
sp_multiply_rows_for_pdistr 26968 26968 0 0.00 0 23842 36 0 0
sp_multiply_rows_for_qdistr 19798 19798 0 0.00 0 25008 198 0 0
sp_pay_from_customer 6760 6760 0 0.00 5 23960 172 0 0
sp_pay_to_supplier 5672 5672 0 0.00 6 17646 187 0 0
sp_payment_common 12432 12432 0 0.00 2 14038 44 0 0
sp_qd_handle_on_cancel_clo 1696 1696 0 0.00 1 13705 319 0 0
sp_qd_handle_on_invoice_upd_sts 9987 9987 0 0.00 0 46278 496 0 0
sp_qd_handle_on_reserve_upd_sts 9669 9669 0 0.00 0 27376 72 0 0
sp_reserve_write_off 9669 9669 0 0.00 8 27486 271 0 0
sp_supplier_invoice 5358 5358 0 0.00 11 87451 3402 0 0
sp_supplier_order 5320 5320 0 0.00 8 39654 1375 0 0
srv_aggregate_perf_data 113 113 0 0.00 2492 158936 14453 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 927 141488 39285 0 0
srv_find_qd_qs_mism 984447 984447 0 0.00 0 24894 11 0 0
srv_make_invnt_saldo 453 453 0 0.00 90 116602 3913 0 0
srv_make_money_saldo 893 893 0 0.00 1 9676 318 0 0
srv_recalc_idx_stat 4 4 0 0.00 281809 476797 361058 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4915 4915 0 0.00 0 23087 828 0 0
t$perf-norm:sp_cancel_adding_invoice 367 367 0 0.00 1 6931 339 0 0
t$perf-norm:sp_cancel_client_order 1696 1696 0 0.00 0 7011 28 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1545 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 7068 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 1031 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 1135 38 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 70651 5902 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 5959 25 0 0
t$perf-norm:sp_client_order 19800 19800 0 0.00 0 29678 38 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 608 12 0 0
t$perf-norm:sp_pay_from_customer 6760 6760 0 0.00 0 7390 23 0 0
t$perf-norm:sp_pay_to_supplier 5672 5672 0 0.00 0 5497 20 0 0
t$perf-norm:sp_reserve_write_off 9669 9669 0 0.00 0 10076 21 0 0
t$perf-norm:sp_supplier_invoice 5359 5359 0 0.00 0 6959 34 0 0
t$perf-norm:sp_supplier_order 5320 5320 0 0.00 0 5796 30 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 783 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 195 8 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 721 11 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 1343 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 175 75 0 0
v_add_invoice_to_stock 9978 9714 264 2.64 0 9426 23 0 264
v_all_customers 35492 35492 0 0.00 0 199 3 0 0
v_all_suppliers 29624 29624 0 0.00 0 119 4 0 0
v_all_wares 39594 39594 0 0.00 0 220 4 0 0
v_cancel_adding_invoice 734 734 0 0.00 0 4096 20 0 0
v_cancel_client_order 3392 3392 0 0.00 0 2417 13 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 4928 15 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 236 10 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 2954 18 0 0
v_cancel_supplier_invoice 798 786 12 1.50 0 458 20 0 12
v_cancel_supplier_order 808 808 0 0.00 0 1123 15 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2344 24 0 0
v_max_id_clo_ord 5318 5318 0 0.00 0 108 4 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 3324 14 0 0
v_max_id_ord_sup 5350 5350 0 0.00 0 7061 12 0 0
v_max_non_paid_invoice 1527 1527 0 0.00 0 57 7 0 0
v_max_non_paid_realizn 3919 3919 0 0.00 0 5634 9 0 0
v_min_id_clo_ord 5318 5318 0 0.00 0 1128 7 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 1590 9 0 0
v_min_id_ord_sup 5350 5350 0 0.00 0 8891 17 0 0
v_min_non_paid_invoice 5671 5671 0 0.00 0 7458 17 0 0
v_min_non_paid_realizn 6760 6760 0 0.00 0 13973 17 0 0
v_our_firm 9784 9784 0 0.00 0 153 4 0 0
v_reserve_write_off 19338 19338 0 0.00 0 5241 11 0 0
x_lock_depdocs_on_canc_invoice 10526 10526 0 0.00 0 8212 121 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 6823 350 0 0
x_make_qstorno_1000_1200 5320 5320 0 0.00 2 39576 1222 0 0
x_make_qstorno_1200_2000 5358 5358 0 0.00 4 81902 3130 0 0
x_make_qstorno_2100_3300 445935 445935 0 0.00 0 61540 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2023 07:38:44 to 12-01-2023 07:38:48.

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:07:26 32 8192000000 8810986688 8827101184 9864640 0.120 5 2 3 0 10035248 109232 61376
05:08:27 9781 8192000000 9710956944 10006016000 792691792 8.822 100 91 182 0 823376352 7645968 23038592
05:09:44 21162 8192000000 9758879616 10065485824 830341184 9.203 100 90 180 1 863161136 10652192 22167760
05:11:12 15182 8192000000 9786677296 10106519552 858878176 9.489 100 83 166 0 897322432 18872000 19572256
05:12:38 6639 8192000000 9838967152 10171736064 899259360 9.891 100 77 151 0 950381040 34530576 16591104
05:13:54 4584 8192000000 9905013088 10246467584 958198880 10.471 100 85 166 1 1013180464 37352048 17629536
05:15:00 2367 8192000000 9970639136 10324750336 997181600 10.851 100 90 180 0 1079743872 63731392 18830880
05:16:05 2645 8192000000 10007535520 10375553024 1017197248 11.045 100 84 167 0 1119369904 85841472 16331184
05:17:09 1412 8192000000 10021320768 10391875584 1041888496 11.283 100 94 175 1 1138417088 79766512 16762080
05:18:12 847 8192000000 10035394560 10410491904 1055531488 11.414 100 97 192 0 1156698816 81943040 19224288
05:19:15 10272 8192000000 10022718944 10404450304 1061876464 11.474 100 81 152 2 1145957664 69133008 14948192
05:20:28 2082 8192000000 10029755456 10405490688 1068064656 11.534 100 91 170 1 1150359664 66086576 16208432
05:21:30 1097 8192000000 10044238176 10425483264 1075383680 11.603 100 96 180 1 1165401568 72135776 17882112
05:22:32 1470 8192000000 10040949664 10422448128 1075074128 11.601 100 94 182 0 1159276880 65296880 18905872
05:23:34 1163 8192000000 10031664288 10418102272 1077994208 11.628 100 90 167 1 1153762624 57725200 18043216
05:24:37 1156 8192000000 10054787904 10443030528 1088008080 11.724 100 96 188 1 1181508240 74428576 19071584
05:25:41 1254 8192000000 10065834304 10449027072 1092437504 11.766 100 99 190 1 1182259600 70057952 19764144
05:26:44 7704 8192000000 10043798128 10447331328 1090877008 11.751 100 54 105 0 1180056112 78514384 10664720
05:27:53 4187 8192000000 10047675840 10448756736 1093364272 11.775 100 61 117 1 1160356768 55508832 11483664
05:28:58 1046 8192000000 10063006144 10447532032 1089311744 11.736 100 97 188 1 1182932080 74812368 18807968
05:30:01 3047 8192000000 10031599232 10415898624 1071578032 11.567 100 68 129 0 1148893344 64389360 12925952
05:31:05 5045 8192000000 9999426528 10381131776 1042554976 11.289 98 56 112 1 1102155904 47996032 11604896
05:32:11 9424 8192000000 10001111344 10386280448 1058350032 11.441 100 57 110 0 1127166336 57925984 10890320
05:33:22 1336 8192000000 10012117584 10391064576 1057005744 11.428 100 89 163 2 1133447456 59421536 17020176
05:34:25 1184 8192000000 10006328640 10375860224 1018196064 11.055 100 91 175 4 1128322096 93486480 16639552
05:35:27 837 8192000000 10015587984 10397970432 1047933696 11.341 100 82 157 0 1138573024 73764384 16874944
05:36:29 4298 8192000000 9983732512 10365902848 1047614144 11.338 99 62 120 2 1110186096 50101520 12470432
05:37:34 1284 8192000000 9992658288 10363322368 1040599744 11.270 100 91 168 2 1113470656 55896272 16974640
05:38:42 10401 8192000000 9995301328 10386284544 1067151968 11.525 100 54 104 1 1114115088 35669744 11293376
05:39:56 8279 8192000000 10056852288 10440290304 1082001824 11.667 100 80 154 5 1173516688 75747296 15767568
05:41:13 7022 8192000000 10062797552 10452905984 1092346752 11.765 100 70 136 0 1179595360 73476816 13771792
05:42:27 7964 8192000000 10111316528 10512023552 1107817664 11.912 100 62 115 0 1235616608 114857744 12941200
05:43:38 4347 8192000000 10146462128 10551173120 1118623776 12.014 100 71 134 0 1265780320 131760384 15396160
05:44:46 916 8192000000 10188951328 10578653184 1137977776 12.197 100 97 187 2 1305391216 146390704 21022736
05:45:50 1267 8192000000 10222451664 10613985280 1145014560 12.263 100 98 189 0 1339721008 172994800 21711648
05:46:55 1851 8192000000 10253873472 10668236800 1167741776 12.476 100 85 162 2 1367389760 181311808 18336176
05:48:01 1064 8192000000 10287546944 10693967872 1174610160 12.540 100 93 180 0 1398315808 204001616 19704032
05:49:09 1836 8192000000 10325772288 10726195200 1175651696 12.550 100 92 177 0 1424574992 229067296 19856000
05:50:14 1315 8192000000 10337885856 10753646592 1188949248 12.674 100 86 168 1 1445067872 238056400 18062224
05:51:23 1018 8192000000 10382656976 10785173504 1195711024 12.736 100 93 182 1 1491020080 274191184 21117872
05:52:26 1649 8192000000 10427901904 10838740992 1202917792 12.803 100 93 179 1 1538231808 313490064 21823952
05:53:31 1460 8192000000 10466886672 10884370432 1209640096 12.866 100 97 187 0 1573687888 342340608 21707184
05:54:37 1154 8192000000 10505592896 10919211008 1203479040 12.809 100 99 188 0 1610103424 385153472 21470912
05:55:41 1193 8192000000 10549911568 10966548480 1201587008 12.791 100 93 180 0 1656464048 433720976 21156064
05:56:44 1023 8192000000 10556919616 11000987648 1207451488 12.845 100 96 186 1 1661598768 431679216 22468064
05:57:48 1265 8192000000 10505583952 10955472896 1193508144 12.716 100 89 179 0 1608919792 394347584 21064064
05:58:52 4593 8192000000 10469172656 10904862720 1172344688 12.519 99 64 126 0 1591357536 403443200 15569648
05:59:59 11654 8192000000 10476902880 10914349056 1173212336 12.527 99 54 105 0 1577603392 392153184 12237872
06:01:13 3919 8192000000 10551226000 10980442112 1192218288 12.704 100 59 114 2 1649495568 443375904 13901376
06:02:21 12600 8192000000 10531986144 11032821760 1180938640 12.599 100 41 82 0 1640765968 448725904 11101424
06:03:35 22528 8192000000 10495554992 10966867968 1144028368 12.253 99 35 67 1 1595456752 441737232 9691152
06:05:11 13852 8192000000 10327602912 10861301760 1143244960 12.246 100 28 53 0 1437834096 287699072 6890064
06:06:27 17664 8192000000 10190259344 10683002880 1137217648 12.189 100 26 47 0 1311690720 168710496 5762576
06:07:53 21364 8192000000 10125231168 10558107648 1129208784 12.114 99 36 68 0 1208730032 72343248 7178000
06:09:16 31538 8192000000 10156935360 10593390592 1134970848 12.168 99 61 115 0 1234119776 86521184 12627744
06:11:10 99014 8192000000 10080819520 10535743488 1109773856 11.930 97 19 36 1 1179144880 65621136 3749888
06:13:50 141488 8192000000 10072019792 10521649152 1083150096 11.677 95 26 49 0 1120253760 31510976 5592688
06:17:30 138473 8192000000 10092489888 10530717696 1100182656 11.839 97 28 53 1 1129357808 23550672 5624480
06:20:49 65681 8192000000 10065133952 10515218432 1113989856 11.970 98 24 48 0 1199984080 80940064 5054160
06:23:21 3215 8192000000 10099687120 10529632256 1135349776 12.172 100 67 132 1 1208532560 58792048 14390736
06:24:27 30553 8192000000 10124951216 10557726720 1125235776 12.076 99 45 90 0 1218824208 82792480 10795952
06:26:00 35481 8192000000 10133278576 10590179328 1109580288 11.928 98 21 38 0 1221785232 107479216 4725728
06:27:43 69155 8192000000 10090607104 10561126400 1114364192 11.974 99 8 16 0 1181197712 64850352 1983168
06:30:21 110697 8192000000 10051713760 10501591040 1082525488 11.672 96 19 36 1 1146218848 60079936 3613424
06:33:12 62367 8192000000 10085603552 10531938304 1099844016 11.836 98 28 56 0 1155198624 49057904 6296704
06:35:16 75430 8192000000 10119983024 10565230592 1132586864 12.146 100 22 43 0 1209658416 72785088 4286464
06:37:36 83028 8192000000 10092450080 10532884480 1090550784 11.748 96 24 48 1 1145418768 49775056 5092928
06:40:02 84932 8192000000 10101698240 10583728128 1112683872 11.958 98 23 46 0 1194381392 76744624 4952896
06:42:33 66775 8192000000 10092321472 10550353920 1111937248 11.951 98 24 38 1 1162249232 45750080 4561904
06:44:43 120431 8192000000 10052415920 10485260288 1067424208 11.527 94 31 59 1 1103833232 30340112 6068912
06:47:45 10592 8192000000 10069844080 10485231616 1117420112 12.003 100 65 129 1 1171700304 41103632 13176560
06:49:00 37545 8192000000 10048623408 10524303360 1122774592 12.053 100 16 30 0 1179280768 53726608 2779568
06:50:52 80670 8192000000 10095033680 10539515904 1122604192 12.052 99 19 38 0 1171626656 45154144 3868320
06:53:34 86345 8192000000 10078277216 10534510592 1140357744 12.219 100 22 44 0 1225617680 80592080 4667856
06:56:01 61516 8192000000 10081363792 10522640384 1061943968 11.475 93 20 40 0 1128339072 61834576 4560528
06:58:03 31605 8192000000 10065738784 10489597952 1106571168 11.900 98 31 60 0 1148825472 35557424 6696880
06:59:36 17497 8192000000 10122729168 10551930880 1132837392 12.148 100 48 92 1 1209726928 68043920 8845616
07:00:54 67730 8192000000 10106728624 10544152576 1134341136 12.162 99 31 62 1 1180023024 39134528 6547360
07:03:04 11454 8192000000 10100153024 10517282816 1139427952 12.210 100 56 113 0 1196143392 44563872 12151568
07:04:17 37323 8192000000 10131457824 10579804160 1129714272 12.119 99 39 78 0 1203382960 66391904 7276784
07:06:59 71824 8192000000 10062257072 10532024320 1105946640 11.894 97 46 71 1 1168636640 55936176 6753824
07:09:19 85170 8192000000 10075881136 10536538112 1092163968 11.763 97 11 22 0 1151996048 57583152 2248928
07:11:50 67254 8192000000 10070421744 10562330624 1118546080 12.013 99 20 38 0 1178867072 55528864 4792128
07:14:00 43945 8192000000 10083512784 10551570432 1111402912 11.946 98 20 40 0 1160655456 44601968 4650576
07:15:59 41329 8192000000 10103009952 10540519424 1092704480 11.768 97 31 62 0 1186174832 86671952 6798400
07:17:43 66014 8192000000 10146122256 10650591232 1134721072 12.166 100 39 76 0 1259876928 115835680 9320176
07:20:13 57190 8192000000 10083421744 10540138496 1131311440 12.134 99 16 33 1 1165592432 30673696 3607296
07:22:13 16520 8192000000 10096593264 10514665472 1135611328 12.174 100 51 103 1 1194128784 47901376 10616080
07:23:29 35670 8192000000 10142586608 10599309312 1131774400 12.138 99 26 53 0 1223041696 85742928 5524368
07:25:12 16844 8192000000 10102796336 10521243648 1123438832 12.059 99 39 78 0 1171164208 40120704 7604672
07:26:29 124259 8192000000 10048974944 10485084160 1091257296 11.755 96 40 78 1 1130873136 31398144 8217696
07:29:41 55273 8192000000 10099578528 10562396160 1118848528 12.016 99 25 50 0 1200537088 77007152 4681408
07:32:25 74582 8192000000 10119269712 10565079040 1080329584 11.651 95 22 44 0 1169930528 84793040 4807904
07:34:41 84677 8192000000 10153067872 10620690432 1118595648 12.014 99 24 48 0 1259064096 135152608 5315840
07:37:20 5596 8192000000 8889357632 9070149632 61386752 0.743 6 1 2 0 66200256 4778096 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-01-2023 07:38:50 to 12-01-2023 07:39:06.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2023 07:39:07 to 12-01-2023 07:39:09.

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          219828
MON$OLDEST_ACTIVE               219829
MON$OLDEST_SNAPSHOT             219829
MON$NEXT_TRANSACTION            219829
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                       2019840
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
:238: 
:239: mscs-test-161.budzdorov.ru    Thu Jan 12 07:37:27 2023
:240:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:241: 
--- end of diff output ---

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

12.01.23 07:39:10. 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-01-12 07:39:12 No severe PSQL-related problems occured
12.01.23 07:39:14. End of report.