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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15231444 kB
MemAvailable 30421896 kB
Buffers 0 kB
Cached 16497820 kB
SwapCached 3144 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-02-26 07:37:24
DTS_END                         2023-02-26 07:37:24.7420
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/35344
TRN_ID                          212348
ATT_ID                          574
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: 8720.42 23641 1046450
customer order: creation 159.50 498 19140
customer order: refuse 13.55 397 1626
order to supplier: creation 41.93 1375 5032
order to supplier: removal 3.24 181366 389
invoice (draft): creation 42.53 3341 5104
invoice (draft): removal 50.10 938 6012
invoice accept: apply 39.25 26907 4710
invoice accept: cancel 45.65 1101 5478
customer reserve: creation 3633.53 258 436023
customer reserve: removal 4411.13 104 529335
realization accept: apply 77.11 288 9253
realization accept: cancel 81.97 158 9836
payment from customer: creation 55.48 183 6658
payment from customer: removal 5.17 98 620
payment to supplier: creation 44.96 198 5395
payment to supplier: removal 3.11 156 373
service: total inventory turnovers 4.32 3348 518
service: total monetary turnovers 6.88 319 825
service: aggregate perf. data 0.98 13972 118
service: refresh index statistics 0.04 237812 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-02-2023 07:39:46 to 26-02-2023 07:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4794
TEST_TIME 2 4928
TEST_TIME 3 5181
TEST_TIME 4 5380
TEST_TIME 5 5537
TEST_TIME 6 5672
TEST_TIME 7 5924
TEST_TIME 8 5782
TEST_TIME 9 5847
TEST_TIME 10 5972
TEST_TIME 11 6208
TEST_TIME 12 6430
TEST_TIME 13 6732
TEST_TIME 14 6895
TEST_TIME 15 6949
TEST_TIME 16 6927
TEST_TIME 17 7046
TEST_TIME 18 6994
TEST_TIME 19 7058
TEST_TIME 20 7193
TEST_TIME 21 7639
TEST_TIME 22 7580
TEST_TIME 23 7833
TEST_TIME 24 8422
TEST_TIME 25 8447
TEST_TIME 26 9005
TEST_TIME 27 9324
TEST_TIME 28 9386
TEST_TIME 29 9874
TEST_TIME 30 10684
TEST_TIME 31 10997
TEST_TIME 32 11295
TEST_TIME 33 11035
TEST_TIME 34 10872
TEST_TIME 35 10858
TEST_TIME 36 10615
TEST_TIME 37 10470
TEST_TIME 38 10360
TEST_TIME 39 10208
TEST_TIME 40 10097
TEST_TIME 41 9977
TEST_TIME 42 9903
TEST_TIME 43 9867
TEST_TIME 44 9827
TEST_TIME 45 9740
TEST_TIME 46 9627
TEST_TIME 47 9653
TEST_TIME 48 9567
TEST_TIME 49 9552
TEST_TIME 50 9496
TEST_TIME 51 9411
TEST_TIME 52 9350
TEST_TIME 53 9213
TEST_TIME 54 9102
TEST_TIME 55 9120
TEST_TIME 56 9178
TEST_TIME 57 9166
TEST_TIME 58 9181
TEST_TIME 59 9196
TEST_TIME 60 9192
TEST_TIME 61 9260
TEST_TIME 62 9261
TEST_TIME 63 9221
TEST_TIME 64 9230
TEST_TIME 65 9266
TEST_TIME 66 9240
TEST_TIME 67 9374
TEST_TIME 68 9328
TEST_TIME 69 9230
TEST_TIME 70 9183
TEST_TIME 71 9110
TEST_TIME 72 9142
TEST_TIME 73 9105
TEST_TIME 74 9090
TEST_TIME 75 9066
TEST_TIME 76 9016
TEST_TIME 77 8948
TEST_TIME 78 8930
TEST_TIME 79 8940
TEST_TIME 80 8898
TEST_TIME 81 8860
TEST_TIME 82 8918
TEST_TIME 83 8903
TEST_TIME 84 9022
TEST_TIME 85 9031
TEST_TIME 86 9008
TEST_TIME 87 9030
TEST_TIME 88 9068
TEST_TIME 89 9056
TEST_TIME 90 9095
TEST_TIME 91 9016
TEST_TIME 92 8981
TEST_TIME 93 8957
TEST_TIME 94 8941
TEST_TIME 95 8902
TEST_TIME 96 8848
TEST_TIME 97 8815
TEST_TIME 98 8825
TEST_TIME 99 8852
TEST_TIME 100 8839
TEST_TIME 101 8817
TEST_TIME 102 8808
TEST_TIME 103 8871
TEST_TIME 104 8834
TEST_TIME 105 8837
TEST_TIME 106 8894
TEST_TIME 107 8920
TEST_TIME 108 8965
TEST_TIME 109 8952
TEST_TIME 110 9005
TEST_TIME 111 8991
TEST_TIME 112 8940
TEST_TIME 113 8917
TEST_TIME 114 8918
TEST_TIME 115 8882
TEST_TIME 116 8877
TEST_TIME 117 8835
TEST_TIME 118 8810
TEST_TIME 119 8810
TEST_TIME 120 8742

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-02-2023 07:39:50 to 26-02-2023 07:39:53.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1448082 1448082 0 0.00 0 14098 13 0 0
doc_list_biud 1448066 1448066 0 0.00 0 32425 43 0 0
sp_add_invoice_to_stock 4881 4710 171 3.50 53 185881 26907 0 171
sp_cancel_adding_invoice 5478 5478 0 0.00 4 80568 1102 0 0
sp_cancel_client_order 1626 1626 0 0.00 5 14893 397 0 0
sp_cancel_customer_reserve 529335 529335 0 0.00 1 18576 104 0 0
sp_cancel_pay_from_customer 620 620 0 0.00 4 3191 99 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 4 6572 157 0 0
sp_cancel_supplier_invoice 6029 6012 17 0.28 2 24932 938 0 17
sp_cancel_supplier_order 389 389 0 0.00 12 1489244 181367 0 0
sp_cancel_write_off 9836 9836 0 0.00 2 6520 159 0 0
sp_client_order 19140 19140 0 0.00 5 32317 499 0 0
sp_customer_reserve 436023 436023 0 0.00 0 60610 259 0 0
sp_fill_shopping_cart 29268 29268 0 0.00 1 14585 77 0 0
sp_fill_shopping_cart_clo_res 435996 435996 0 0.00 0 19455 23 0 0
sp_get_clo_for_invoice 4674 4674 0 0.00 0 27474 311 0 0
sp_kill_cost_storno 16307 16307 0 0.00 0 14089 70 0 0
sp_kill_qstorno_ret_qs2qd 1074907 1074907 0 0.00 0 24917 44 0 0
sp_kill_qty_storno 566595 566595 0 0.00 0 32425 101 0 0
sp_lock_selected_doc 19262 19262 0 0.00 0 10737 8 0 0
sp_make_cost_storno 25979 25979 0 0.00 0 13812 81 0 0
sp_multiply_rows_for_pdistr 25976 25976 0 0.00 0 9160 37 0 0
sp_multiply_rows_for_qdistr 19140 19140 0 0.00 0 10260 194 0 0
sp_pay_from_customer 6658 6658 0 0.00 5 9836 183 0 0
sp_pay_to_supplier 5395 5395 0 0.00 6 12499 198 0 0
sp_payment_common 12050 12050 0 0.00 2 6211 42 0 0
sp_qd_handle_on_cancel_clo 1626 1626 0 0.00 0 14866 322 0 0
sp_qd_handle_on_invoice_upd_sts 10147 10147 0 0.00 0 32423 489 0 0
sp_qd_handle_on_reserve_upd_sts 9249 9249 0 0.00 0 20096 74 0 0
sp_reserve_write_off 9253 9253 0 0.00 8 20300 289 0 0
sp_supplier_invoice 5104 5104 0 0.00 8 80671 3342 0 0
sp_supplier_order 5032 5032 0 0.00 7 85362 1376 0 0
srv_aggregate_perf_data 118 118 0 0.00 2523 196441 13973 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 755 156214 36936 0 0
srv_find_qd_qs_mism 1000820 1000820 0 0.00 0 12678 10 0 0
srv_make_invnt_saldo 518 518 0 0.00 59 62322 3349 0 0
srv_make_money_saldo 825 825 0 0.00 1 9189 319 0 0
srv_recalc_idx_stat 5 5 0 0.00 100163 569191 237813 0 0
t$perf-abend:sp_add_invoice_to_stock 171 171 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4713 4713 0 0.00 1 24858 880 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 1 7621 367 0 0
t$perf-norm:sp_cancel_client_order 1626 1626 0 0.00 0 1540 21 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 1415 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 620 620 0 0.00 0 2080 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 4585 36 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 1679 41 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 111572 6716 0 0
t$perf-norm:sp_cancel_write_off 911 911 0 0.00 0 4432 28 0 0
t$perf-norm:sp_client_order 19140 19140 0 0.00 0 13109 37 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 3301 14 0 0
t$perf-norm:sp_pay_from_customer 6658 6658 0 0.00 0 8687 22 0 0
t$perf-norm:sp_pay_to_supplier 5395 5395 0 0.00 0 16485 23 0 0
t$perf-norm:sp_reserve_write_off 9253 9253 0 0.00 0 9789 27 0 0
t$perf-norm:sp_supplier_invoice 5104 5104 0 0.00 0 21683 36 0 0
t$perf-norm:sp_supplier_order 5032 5032 0 0.00 0 5646 28 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 1060 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 347 10 0 0
t$perf-norm:srv_make_invnt_saldo 518 518 0 0.00 0 3574 22 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 1070 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 199 79 0 0
v_add_invoice_to_stock 9676 9334 342 3.53 0 8619 32 0 342
v_all_customers 35366 35366 0 0.00 0 64 3 0 0
v_all_suppliers 28335 28335 0 0.00 0 68 4 0 0
v_all_wares 38272 38272 0 0.00 0 57 4 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 6400 31 0 0
v_cancel_client_order 3252 3252 0 0.00 0 6507 12 0 0
v_cancel_customer_prepayment 1240 1240 0 0.00 0 1420 10 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 384 9 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 6492 36 0 0
v_cancel_supplier_invoice 784 750 34 4.33 0 12004 45 0 34
v_cancel_supplier_order 778 778 0 0.00 0 8619 32 0 0
v_cancel_write_off 1822 1822 0 0.00 0 4719 30 0 0
v_max_id_clo_ord 5030 5030 0 0.00 0 1083 4 0 0
v_max_id_clo_res 930 930 0 0.00 0 1508 12 0 0
v_max_id_ord_sup 5098 5098 0 0.00 0 2504 6 0 0
v_max_non_paid_invoice 1353 1353 0 0.00 0 61 7 0 0
v_max_non_paid_realizn 3361 3361 0 0.00 0 2061 9 0 0
v_min_id_clo_ord 5030 5030 0 0.00 0 3853 11 0 0
v_min_id_clo_res 930 930 0 0.00 0 5633 26 0 0
v_min_id_ord_sup 5097 5097 0 0.00 0 6046 10 0 0
v_min_non_paid_invoice 5394 5394 0 0.00 0 6008 14 0 0
v_min_non_paid_realizn 6655 6655 0 0.00 0 4393 14 0 0
v_our_firm 9494 9494 0 0.00 0 65 4 0 0
v_reserve_write_off 18494 18494 0 0.00 0 12477 16 0 0
x_lock_depdocs_on_canc_invoice 11115 11115 0 0.00 0 21039 141 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 19706 452 0 0
x_make_qstorno_1000_1200 5032 5032 0 0.00 3 82698 1233 0 0
x_make_qstorno_1200_2000 5104 5104 0 0.00 4 80613 3118 0 0
x_make_qstorno_2100_3300 436022 436022 0 0.00 0 60608 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-02-2023 07:39:54 to 26-02-2023 07:39:58.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:28 5 8192000000 8805441728 8819437568 9948912 0.121 4 2 3 0 10122512 112240 61360
05:08:29 34300 8192000000 9728920736 10027704320 802669184 8.923 100 90 180 0 832492384 7954880 21868320
05:10:08 19081 8192000000 9762805664 10069831680 833318000 9.233 100 92 182 0 869744608 15006512 21420096
05:11:30 18972 8192000000 9809060064 10130276352 869754784 9.598 100 82 163 0 918082272 30242416 18085072
05:12:51 13226 8192000000 9858405552 10191642624 915382848 10.050 100 81 163 1 971968656 38559472 18026336
05:14:09 3045 8192000000 9914107872 10257227776 956947264 10.459 100 86 163 0 1034034944 60263744 16823936
05:15:15 3008 8192000000 9976703168 10329333760 995088464 10.831 100 90 176 0 1091246736 78566912 17591360
05:16:20 6287 8192000000 10002325632 10368974848 1020517392 11.077 100 80 157 1 1119280720 83176224 15587104
05:17:28 853 8192000000 10023286992 10390134784 1045180832 11.314 100 97 184 0 1142572944 79137568 18254544
05:18:31 1020 8192000000 10040557392 10413092864 1054178832 11.401 100 96 187 1 1164459328 91447424 18833072
05:19:34 4096 8192000000 10029178592 10400104448 1063108896 11.486 100 93 178 3 1146448864 66882240 16457728
05:20:39 851 8192000000 10030089200 10410561536 1073562848 11.586 100 96 182 0 1152451840 60197280 18691712
05:21:41 1311 8192000000 10054499328 10434625536 1081967136 11.666 100 96 179 2 1172997632 72695120 18335376
05:22:44 1281 8192000000 10052582688 10437214208 1083940048 11.685 100 99 190 0 1174400880 71558704 18902128
05:23:46 1281 8192000000 10042777936 10428887040 1090952176 11.752 100 98 187 1 1168959280 58797648 19209456
05:24:49 856 8192000000 10052847936 10441027584 1093904976 11.780 100 86 167 0 1175256720 63836336 17515408
05:25:51 1117 8192000000 10056347040 10443714560 1096530160 11.805 100 95 180 1 1178797024 64334960 17931904
05:26:53 1181 8192000000 10087426880 10474340352 1098494112 11.823 100 93 176 2 1210011952 92811760 18706080
05:27:56 942 8192000000 10067897552 10458595328 1099945328 11.837 100 89 171 1 1191526592 72649696 18931568
05:28:58 4358 8192000000 10035248576 10427351040 1091198544 11.754 100 72 138 4 1164426496 59141824 14086128
05:30:03 3004 8192000000 10022565488 10410512384 1082552224 11.672 100 72 130 4 1144890704 48907696 13430784
05:31:07 1258 8192000000 10025918992 10404118528 1072066032 11.572 100 94 176 1 1149521056 59106368 18348656
05:32:09 898 8192000000 10053653648 10434379776 1076292000 11.612 100 90 170 2 1172890480 79761744 16836736
05:33:11 4286 8192000000 9998584096 10383482880 1031640688 11.184 97 58 114 1 1102137632 58825520 11671424
05:34:21 831 8192000000 9979232800 10356641792 1034334992 11.210 99 74 147 0 1102656416 53848576 14472848
05:35:22 1200 8192000000 9995585264 10370109440 1014527280 11.019 99 90 171 0 1110175072 77059408 18588384
05:36:24 1062 8192000000 10025979104 10401849344 1052520032 11.385 100 97 178 3 1147152832 76577536 18055264
05:37:28 8026 8192000000 9995673488 10382311424 1056977712 11.428 100 71 134 0 1116373168 46124464 13270992
05:38:45 5846 8192000000 10049000080 10433421312 1077766784 11.626 100 76 143 2 1163526480 71148352 14611344
05:39:56 1901 8192000000 10067628064 10452213760 1100767136 11.845 100 78 144 2 1184435248 68073264 15594848
05:41:02 3193 8192000000 10104187664 10489167872 1106851760 11.903 100 80 154 0 1220463264 98155456 15456048
05:42:24 9076 8192000000 10191409584 10590998528 1126670096 12.090 100 75 145 1 1312630448 170325456 15634896
05:43:40 980 8192000000 10191098128 10589233152 1132256016 12.143 100 81 150 0 1307206400 158989760 15960624
05:44:46 2311 8192000000 10241341216 10648498176 1145770144 12.270 100 79 155 1 1348738336 185940960 17027232
05:45:52 1523 8192000000 10240705440 10633302016 1146922528 12.281 100 86 165 1 1357432624 192734288 17775808
05:46:57 4144 8192000000 10279351008 10689900544 1166579296 12.465 100 80 160 1 1391648576 207115840 17953440
05:48:07 742 8192000000 10317576816 10716184576 1181909408 12.608 100 90 174 2 1436333936 234132832 20291696
05:49:11 1040 8192000000 10371970048 10774679552 1184857504 12.635 100 98 185 1 1479234592 272862448 21514640
05:50:14 1011 8192000000 10388675008 10813267968 1194416944 12.724 100 95 188 1 1498627408 281707152 22503312
05:51:18 2447 8192000000 10433578480 10851753984 1198631680 12.764 100 100 186 1 1540440272 319326464 22482128
05:52:22 993 8192000000 10463166704 10868314112 1209594592 12.865 100 93 184 0 1572586864 341740464 21251808
05:53:27 861 8192000000 10524255728 10930307072 1216849776 12.933 100 97 189 3 1631101312 391760336 22491200
05:54:31 882 8192000000 10556469360 10968133632 1215213568 12.917 100 100 196 3 1660127280 421686016 23227696
05:55:36 1277 8192000000 10614306416 11025809408 1215086496 12.916 100 93 184 0 1721920832 483998608 22835728
05:56:39 771 8192000000 10639445488 11074674688 1213610032 12.903 100 94 184 0 1746140208 510178944 22351232
05:57:42 751 8192000000 10632357296 11082055680 1218301664 12.946 100 96 190 0 1740999728 498999344 23698720
05:58:44 825 8192000000 10644765264 11112939520 1216587584 12.930 100 96 187 2 1749601952 509768144 23246224
05:59:51 2443 8192000000 10668012464 11109986304 1215984576 12.925 100 78 153 1 1766503104 530738672 19779856
06:00:55 3804 8192000000 10639040752 11147841536 1209801264 12.867 100 64 128 0 1748440560 521150608 17488688
06:02:01 13647 8192000000 10617499168 11074387968 1178476352 12.576 98 54 106 1 1699843888 507367168 14000368
06:03:25 5265 8192000000 10590366928 11130036224 1198136128 12.759 100 47 91 0 1696838912 486670192 12032592
06:04:33 19566 8192000000 10533169008 11016200192 1186208816 12.648 99 38 74 0 1623019408 427040624 9769968
06:05:58 15407 8192000000 10512490928 11004342272 1175433712 12.548 98 31 62 0 1599030800 415264448 8332640
06:07:21 42243 8192000000 10248502112 10750066688 1156068336 12.366 100 21 42 0 1437148064 275270160 5809568
06:09:04 35451 8192000000 10069916672 10503368704 1119697680 12.024 97 25 48 0 1143220864 19630928 3892256
06:10:39 53692 8192000000 10130943872 10568265728 1157289792 12.378 100 48 91 0 1221317104 54801136 9226176
06:12:37 121366 8192000000 10075144400 10535034880 1109884544 11.931 96 16 33 0 1136978256 24022144 3071568
06:15:41 89380 8192000000 10117440496 10565517312 1106117440 11.896 96 17 33 0 1175192976 66072752 3002784
06:18:11 66801 8192000000 10116315760 10584625152 1136937408 12.187 99 26 53 0 1222831200 79989872 5903920
06:20:19 94610 8192000000 10148170480 10667962368 1126125248 12.085 98 17 34 0 1262441840 131931520 4385072
06:22:55 67373 8192000000 10100532784 10535591936 1119003376 12.018 98 37 72 0 1179595712 52589856 8002480
06:25:05 54133 8192000000 10117737040 10592325632 1152600080 12.334 100 25 50 1 1203275536 45220496 5454960
06:28:18 43110 8192000000 10091366624 10545274880 1130246384 12.124 98 16 28 1 1164462672 30532096 3684192
06:30:06 75829 8192000000 10114134784 10568552448 1138835104 12.205 99 17 34 0 1193433920 51154272 3444544
06:32:23 64820 8192000000 10125055792 10625646592 1128580512 12.108 98 22 44 0 1239267504 105350768 5336224
06:34:29 61655 8192000000 10078471712 10559201280 1103238240 11.868 97 29 47 2 1158846448 50298976 5309232
06:37:42 49408 8192000000 10095999760 10569187328 1124948624 12.074 99 18 36 0 1186117872 57637840 3531408
06:39:36 49262 8192000000 10099479904 10529988608 1117233136 12.001 98 34 68 1 1187871680 62772288 7866256
06:41:27 83363 8192000000 10110949552 10600267776 1134004464 12.159 99 22 44 0 1180945984 42893648 4047872
06:44:01 32378 8192000000 10161111632 10648711168 1136240976 12.180 100 40 80 1 1252982944 108749984 7991984
06:45:35 24293 8192000000 10074196256 10501812224 1133268080 12.152 100 38 76 0 1182379472 41412448 7698944
06:47:03 74923 8192000000 10165256624 10633252864 1147497776 12.286 100 51 100 0 1256809456 99039040 10272640
06:49:19 47550 8192000000 10095821024 10541592576 1153888208 12.346 100 30 57 0 1214582944 55123792 5570944
06:51:08 51353 8192000000 10124784608 10613338112 1153915952 12.346 100 11 22 0 1224764608 68635168 2213488
06:53:06 45050 8192000000 10096677312 10550951936 1122010496 12.046 98 20 40 0 1174363536 48040624 4312416
06:54:54 78251 8192000000 10095755696 10591166464 1141210416 12.227 100 12 24 0 1200707792 57123008 2374368
06:57:36 32065 8192000000 10126955328 10586787840 1126985760 12.093 98 19 39 0 1218084160 86918112 4180288
06:59:10 45572 8192000000 10125641216 10574884864 1124246432 12.067 98 21 42 0 1213347488 84672928 4428128
07:01:00 48671 8192000000 10068741808 10570436608 1079596336 11.644 95 19 38 0 1180671120 97220128 3854656
07:02:50 5371 8192000000 10066394672 10487062528 1109778912 11.930 99 64 116 1 1153288800 31458400 12051488
07:03:56 14158 8192000000 10077083072 10540138496 1097054848 11.810 98 36 72 0 1160560528 56339040 7166640
07:05:11 53796 8192000000 10086617760 10546208768 1101782736 11.855 98 27 52 0 1162446576 55768672 4895168
07:07:07 13180 8192000000 10112997056 10545655808 1125158448 12.076 100 38 76 0 1205181952 72073824 7949680
07:08:23 156213 8192000000 10049375136 10496430080 1068385472 11.537 95 33 64 0 1100131552 25183280 6562800
07:12:35 56461 8192000000 10064472096 10520547328 1136662768 12.184 100 12 23 0 1166363888 27335136 2365984
07:14:32 75201 8192000000 10084796064 10550734848 1109090352 11.924 97 13 22 0 1165776560 54289248 2396960
07:16:47 50279 8192000000 10087337728 10531049472 1140677152 12.222 100 30 60 1 1188140320 41522704 5940464
07:18:45 18891 8192000000 10122127728 10569691136 1140125824 12.217 99 33 66 0 1214595664 67557392 6912448
07:20:10 50031 8192000000 10097385600 10526060544 1129028000 12.112 98 24 48 1 1199842672 65581232 5233440
07:22:02 30704 8192000000 10157028096 10612514816 1154641840 12.353 100 50 100 0 1266690128 101397728 10650560
07:23:42 68710 8192000000 10123350608 10594852864 1136294736 12.181 99 13 26 1 1196190848 56719472 3176640
07:25:51 47075 8192000000 10125800000 10601525248 1127656416 12.099 99 32 62 0 1181561488 47987600 5917472
07:27:40 80284 8192000000 10091410080 10536296448 1109692064 11.930 97 35 70 0 1167569680 50493280 7384336
07:30:03 70493 8192000000 10073101584 10515795968 1121453440 12.041 98 32 63 0 1175636400 47708304 6474656
07:32:26 5214 8192000000 10085583152 10528501760 1134544768 12.164 99 50 96 2 1182845568 38266592 10034208
07:33:32 65064 8192000000 10085930336 10532569088 1120322560 12.030 98 10 20 0 1178989488 56425776 2241152
07:35:37 82861 8192000000 10111852320 10602885120 1100450064 11.842 96 12 24 0 1184026432 81238528 2337840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 26-02-2023 07:40:00 to 26-02-2023 07:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 171 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-02-2023 07:40:18 to 26-02-2023 07:40:20.

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          207633
MON$OLDEST_ACTIVE               212641
MON$OLDEST_SNAPSHOT             212641
MON$NEXT_TRANSACTION            212641
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                       2037728
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
:270: 
:271: mscs-test-161.budzdorov.ru    Sun Feb 26 07:37:54 2023
:272:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:273: 
--- end of diff output ---

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

26.02.23 07:40:21. 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-02-26 07:40:23 No severe PSQL-related problems occured
26.02.23 07:40:25. End of report.