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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14928816 kB
MemAvailable 30392508 kB
Buffers 0 kB
Cached 16738064 kB
SwapCached 3028 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-04 07:46:21
DTS_END                         2023-03-04 07:46:21.2380
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/43194
TRN_ID                          225374
ATT_ID                          609
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: 9257.19 20517 1110863
customer order: creation 178.81 452 21457
customer order: refuse 15.18 373 1821
order to supplier: creation 48.63 1301 5836
order to supplier: removal 3.51 131372 421
invoice (draft): creation 46.82 3108 5618
invoice (draft): removal 51.02 853 6122
invoice accept: apply 43.03 24196 5164
invoice accept: cancel 45.69 1085 5483
customer reserve: creation 3998.03 229 479764
customer reserve: removal 4533.19 79 543983
realization accept: apply 86.36 277 10363
realization accept: cancel 73.47 144 8817
payment from customer: creation 61.03 174 7323
payment from customer: removal 5.85 111 702
payment to supplier: creation 50.90 183 6108
payment to supplier: removal 3.18 169 381
service: total inventory turnovers 3.99 3947 479
service: total monetary turnovers 7.66 320 919
service: aggregate perf. data 0.82 17909 98
service: refresh index statistics 0.03 224060 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-03-2023 07:47:24 to 04-03-2023 07:47:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8282
TEST_TIME 2 6789
TEST_TIME 3 6686
TEST_TIME 4 6102
TEST_TIME 5 5838
TEST_TIME 6 6137
TEST_TIME 7 6642
TEST_TIME 8 6954
TEST_TIME 9 7515
TEST_TIME 10 7795
TEST_TIME 11 8120
TEST_TIME 12 8487
TEST_TIME 13 8884
TEST_TIME 14 8997
TEST_TIME 15 9031
TEST_TIME 16 9081
TEST_TIME 17 8980
TEST_TIME 18 9155
TEST_TIME 19 9196
TEST_TIME 20 9227
TEST_TIME 21 9659
TEST_TIME 22 9876
TEST_TIME 23 9783
TEST_TIME 24 9749
TEST_TIME 25 9752
TEST_TIME 26 10182
TEST_TIME 27 10124
TEST_TIME 28 10646
TEST_TIME 29 11097
TEST_TIME 30 11422
TEST_TIME 31 11283
TEST_TIME 32 11068
TEST_TIME 33 11148
TEST_TIME 34 11443
TEST_TIME 35 11156
TEST_TIME 36 11143
TEST_TIME 37 10985
TEST_TIME 38 10870
TEST_TIME 39 10856
TEST_TIME 40 10736
TEST_TIME 41 10596
TEST_TIME 42 10565
TEST_TIME 43 10677
TEST_TIME 44 10647
TEST_TIME 45 10532
TEST_TIME 46 10619
TEST_TIME 47 10669
TEST_TIME 48 10647
TEST_TIME 49 10476
TEST_TIME 50 10328
TEST_TIME 51 10217
TEST_TIME 52 10221
TEST_TIME 53 10117
TEST_TIME 54 10055
TEST_TIME 55 10075
TEST_TIME 56 9979
TEST_TIME 57 9996
TEST_TIME 58 9941
TEST_TIME 59 9868
TEST_TIME 60 9770
TEST_TIME 61 9723
TEST_TIME 62 9655
TEST_TIME 63 9716
TEST_TIME 64 9790
TEST_TIME 65 9830
TEST_TIME 66 9819
TEST_TIME 67 9858
TEST_TIME 68 9829
TEST_TIME 69 9784
TEST_TIME 70 9865
TEST_TIME 71 9860
TEST_TIME 72 9900
TEST_TIME 73 9914
TEST_TIME 74 9975
TEST_TIME 75 9935
TEST_TIME 76 9937
TEST_TIME 77 9883
TEST_TIME 78 9842
TEST_TIME 79 9750
TEST_TIME 80 9788
TEST_TIME 81 9763
TEST_TIME 82 9707
TEST_TIME 83 9739
TEST_TIME 84 9673
TEST_TIME 85 9627
TEST_TIME 86 9587
TEST_TIME 87 9515
TEST_TIME 88 9453
TEST_TIME 89 9420
TEST_TIME 90 9525
TEST_TIME 91 9509
TEST_TIME 92 9538
TEST_TIME 93 9514
TEST_TIME 94 9480
TEST_TIME 95 9485
TEST_TIME 96 9529
TEST_TIME 97 9575
TEST_TIME 98 9593
TEST_TIME 99 9592
TEST_TIME 100 9586
TEST_TIME 101 9580
TEST_TIME 102 9596
TEST_TIME 103 9604
TEST_TIME 104 9526
TEST_TIME 105 9445
TEST_TIME 106 9461
TEST_TIME 107 9435
TEST_TIME 108 9428
TEST_TIME 109 9400
TEST_TIME 110 9387
TEST_TIME 111 9339
TEST_TIME 112 9360
TEST_TIME 113 9349
TEST_TIME 114 9315
TEST_TIME 115 9306
TEST_TIME 116 9310
TEST_TIME 117 9380
TEST_TIME 118 9345
TEST_TIME 119 9346
TEST_TIME 120 9280

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-03-2023 07:47:28 to 04-03-2023 07:47:31.

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 1553375 1553375 0 0.00 0 18912 12 0 0
doc_list_biud 1553368 1553368 0 0.00 0 52250 33 0 0
sp_add_invoice_to_stock 5391 5164 227 4.21 34 187901 24197 0 227
sp_cancel_adding_invoice 5483 5483 0 0.00 6 75449 1085 0 0
sp_cancel_client_order 1821 1821 0 0.00 6 10576 374 0 0
sp_cancel_customer_reserve 543983 543983 0 0.00 1 39889 79 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 4 5029 111 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 4 6110 170 0 0
sp_cancel_supplier_invoice 6129 6122 7 0.11 2 34495 854 0 7
sp_cancel_supplier_order 421 421 0 0.00 6 1411882 131372 0 0
sp_cancel_write_off 8817 8817 0 0.00 2 11782 145 0 0
sp_client_order 21457 21457 0 0.00 5 28624 452 0 0
sp_customer_reserve 479764 479764 0 0.00 0 97639 230 0 0
sp_fill_shopping_cart 32897 32897 0 0.00 1 22892 84 0 0
sp_fill_shopping_cart_clo_res 479731 479731 0 0.00 0 26686 17 0 0
sp_get_clo_for_invoice 5116 5116 0 0.00 0 24675 269 0 0
sp_kill_cost_storno 15383 15383 0 0.00 0 14338 68 0 0
sp_kill_qstorno_ret_qs2qd 1103326 1103326 0 0.00 0 34630 35 0 0
sp_kill_qty_storno 582124 582124 0 0.00 0 52247 82 0 0
sp_lock_selected_doc 21296 21296 0 0.00 0 7958 8 0 0
sp_make_cost_storno 28908 28908 0 0.00 0 14506 82 0 0
sp_multiply_rows_for_pdistr 28908 28908 0 0.00 0 4952 32 0 0
sp_multiply_rows_for_qdistr 21457 21457 0 0.00 0 28592 170 0 0
sp_pay_from_customer 7323 7323 0 0.00 6 26908 174 0 0
sp_pay_to_supplier 6108 6108 0 0.00 6 11566 184 0 0
sp_payment_common 13430 13430 0 0.00 2 26865 46 0 0
sp_qd_handle_on_cancel_clo 1821 1821 0 0.00 0 10504 298 0 0
sp_qd_handle_on_invoice_upd_sts 10598 10598 0 0.00 0 52245 484 0 0
sp_qd_handle_on_reserve_upd_sts 10362 10362 0 0.00 0 16751 60 0 0
sp_reserve_write_off 10363 10363 0 0.00 8 16867 277 0 0
sp_supplier_invoice 5618 5618 0 0.00 8 120747 3109 0 0
sp_supplier_order 5836 5836 0 0.00 7 30614 1301 0 0
srv_aggregate_perf_data 98 98 0 0.00 3001 178244 17910 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 693 141320 41255 0 0
srv_find_qd_qs_mism 1060507 1060507 0 0.00 0 18867 10 0 0
srv_make_invnt_saldo 479 479 0 0.00 86 87567 3947 0 0
srv_make_money_saldo 919 919 0 0.00 2 13695 320 0 0
srv_recalc_idx_stat 4 4 0 0.00 84376 364765 224061 0 0
t$perf-abend:sp_add_invoice_to_stock 227 227 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5167 5167 0 0.00 1 12456 886 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 1 9174 494 0 0
t$perf-norm:sp_cancel_client_order 1821 1821 0 0.00 0 3918 26 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 1198 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 4813 30 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 339 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 986 31 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 106709 5921 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 1783 18 0 0
t$perf-norm:sp_client_order 21458 21458 0 0.00 0 14089 38 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 311 11 0 0
t$perf-norm:sp_pay_from_customer 7323 7323 0 0.00 0 13694 21 0 0
t$perf-norm:sp_pay_to_supplier 6108 6108 0 0.00 0 7618 18 0 0
t$perf-norm:sp_reserve_write_off 10363 10363 0 0.00 0 9142 26 0 0
t$perf-norm:sp_supplier_invoice 5618 5618 0 0.00 0 5453 30 0 0
t$perf-norm:sp_supplier_order 5837 5837 0 0.00 0 4994 29 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 370 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 71 4 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 6169 42 0 0
t$perf-norm:srv_make_money_saldo 919 919 0 0.00 0 543 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 206 68 0 0
v_add_invoice_to_stock 10678 10224 454 4.25 0 15401 32 0 454
v_all_customers 39372 39372 0 0.00 0 213 4 0 0
v_all_suppliers 32044 32044 0 0.00 0 105 4 0 0
v_all_wares 42914 42914 0 0.00 0 202 4 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 943 17 0 0
v_cancel_client_order 3642 3642 0 0.00 0 8678 13 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 1105 10 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 1 1576 11 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 2317 17 0 0
v_cancel_supplier_invoice 858 844 14 1.63 0 1174 26 0 14
v_cancel_supplier_order 842 842 0 0.00 0 2302 13 0 0
v_cancel_write_off 2000 2000 0 0.00 0 5403 32 0 0
v_max_id_clo_ord 5830 5830 0 0.00 0 761 5 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 1371 8 0 0
v_max_id_ord_sup 5609 5609 0 0.00 0 1966 6 0 0
v_max_non_paid_invoice 1524 1524 0 0.00 0 74 7 0 0
v_max_non_paid_realizn 3829 3829 0 0.00 0 5571 9 0 0
v_min_id_clo_ord 5830 5830 0 0.00 0 1640 9 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 2315 15 0 0
v_min_id_ord_sup 5609 5609 0 0.00 0 2959 8 0 0
v_min_non_paid_invoice 6107 6107 0 0.00 0 4982 16 0 0
v_min_non_paid_realizn 7323 7323 0 0.00 0 5700 16 0 0
v_our_firm 10530 10530 0 0.00 0 106 5 0 0
v_reserve_write_off 20724 20724 0 0.00 0 14088 14 0 0
x_lock_depdocs_on_canc_invoice 11183 11183 0 0.00 0 16349 122 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 8161 360 0 0
x_make_qstorno_1000_1200 5836 5836 0 0.00 2 30128 1131 0 0
x_make_qstorno_1200_2000 5618 5618 0 0.00 2 120708 2885 0 0
x_make_qstorno_2100_3300 479764 479764 0 0.00 0 97634 201 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-03-2023 07:47:32 to 04-03-2023 07:47:37.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:16:25 12 8192000000 8811170128 8826974208 12199488 0.148 6 3 2 0 12348112 112848 35776
05:17:26 36863 8192000000 9713756384 10008690688 787028448 8.765 100 92 184 0 818515296 8489968 22996880
05:19:23 19707 8192000000 9758140016 10067865600 833967824 9.239 100 83 166 0 866961616 13155248 19838544
05:20:45 7121 8192000000 9812098416 10131017728 873085520 9.631 100 85 170 0 920272512 27437840 19749152
05:22:01 6566 8192000000 9878520784 10213384192 930267712 10.197 100 88 176 0 992045888 42553776 19224400
05:23:09 3946 8192000000 9934895632 10277441536 961412032 10.503 100 85 166 1 1043001696 64318784 17270880
05:24:20 2346 8192000000 9975747088 10327678976 999621024 10.875 100 94 185 0 1092248144 73928752 18698368
05:25:25 810 8192000000 10008432720 10368090112 1013674416 11.011 100 97 187 1 1130766576 98737840 18354320
05:26:27 3974 8192000000 10008437216 10374672384 1040087504 11.266 100 85 168 0 1126914576 70089760 16737312
05:27:32 953 8192000000 10059447136 10432696320 1062579104 11.481 100 97 187 3 1178842240 97699792 18563344
05:28:34 25736 8192000000 10042008128 10421817344 1066500976 11.519 100 86 163 1 1170168048 87150624 16516448
05:30:00 2260 8192000000 10050101888 10441359360 1078033168 11.629 100 72 132 2 1174704448 82059248 14612032
05:31:03 1563 8192000000 10039510512 10427600896 1087312176 11.717 100 83 165 0 1160387904 57733184 15342544
05:32:06 1056 8192000000 10068003504 10456813568 1090669360 11.749 100 90 170 1 1189020320 80874688 17476272
05:33:08 976 8192000000 10054371248 10439909376 1092892176 11.770 100 86 169 2 1171854928 61489456 17473296
05:34:10 4431 8192000000 10067325056 10462208000 1096587616 11.805 100 71 139 0 1185783856 75920272 13275968
05:35:16 1407 8192000000 10054567104 10441887744 1093746000 11.778 100 87 162 5 1172236400 62937056 15553344
05:36:18 968 8192000000 10076269024 10468745216 1102575904 11.862 100 88 165 4 1198546992 79119616 16851472
05:37:20 2558 8192000000 10021498928 10419499008 1095690608 11.797 100 69 125 1 1148230448 39674272 12865568
05:38:23 6792 8192000000 10059677376 10460528640 1093320928 11.774 100 68 133 2 1187066288 80561104 13184256
05:39:31 970 8192000000 10033878000 10424692736 1084073280 11.686 100 92 175 0 1157218768 55648912 17496576
05:40:32 1200 8192000000 10042431744 10424745984 1085350816 11.698 100 98 187 1 1159921152 55258208 19312128
05:41:34 1106 8192000000 10049654112 10430537728 1078484496 11.633 100 96 184 0 1169825952 73403040 17938416
05:42:38 1276 8192000000 9979866976 10348453888 1028689728 11.156 99 94 171 5 1102626080 55956064 17980288
05:43:40 1011 8192000000 9992824176 10369961984 1057327824 11.431 100 94 179 0 1117290224 42583920 17378480
05:44:42 1298 8192000000 9977290544 10355249152 1030777904 11.176 100 85 162 2 1098645488 50856592 17010992
05:45:44 1387 8192000000 9980941008 10354544640 1017084048 11.044 99 77 139 3 1097884944 67547520 13253376
05:46:50 17341 8192000000 9999092864 10379911168 1053446080 11.394 100 68 125 1 1115136592 49995472 11695040
05:48:16 1218 8192000000 10056708224 10442248192 1081416272 11.661 100 90 170 3 1175889408 75346640 19126496
05:49:31 2664 8192000000 10107440288 10489135104 1098000272 11.819 100 88 169 2 1217550544 101683872 17866400
05:50:37 10651 8192000000 10122423024 10512986112 1099816720 11.836 99 76 146 1 1234320832 118962672 15541440
05:52:00 1763 8192000000 10163482832 10577739776 1117784928 12.006 100 66 123 2 1283266672 152058320 13423424
05:53:04 2933 8192000000 10173374112 10581352448 1124449408 12.069 100 79 152 2 1293089280 149895360 18744512
05:54:11 819 8192000000 10214694304 10609160192 1141069120 12.226 100 94 179 0 1328892144 167823824 19999200
05:55:14 1656 8192000000 10234536912 10637012992 1146080256 12.273 100 87 167 2 1349909072 186214640 17614176
05:56:18 1076 8192000000 10265062960 10664624128 1154798000 12.355 100 95 180 0 1373968384 200561008 18609376
05:57:21 2194 8192000000 10236132112 10660368384 1162301584 12.425 100 84 153 1 1357731456 175636048 19793824
05:58:28 1188 8192000000 10255418240 10665013248 1171978672 12.515 100 92 180 1 1372631760 180177408 20475680
05:59:31 906 8192000000 10289551296 10710204416 1174140208 12.536 100 95 188 0 1397052128 201979680 20932240
06:00:34 967 8192000000 10305478608 10715922432 1180447664 12.594 100 93 181 0 1409818416 209511520 19859232
06:01:36 2597 8192000000 10299635616 10715000832 1177088048 12.563 100 88 166 3 1407533520 212565728 17879744
06:02:41 948 8192000000 10328927632 10738081792 1179303424 12.584 100 89 171 1 1433865264 235007344 19554496
06:03:46 799 8192000000 10358212912 10778746880 1186901360 12.655 100 93 179 1 1463096480 254058464 22136656
06:04:51 1332 8192000000 10385090304 10801610752 1187451824 12.660 100 96 188 2 1491895392 281648688 22794880
06:05:55 795 8192000000 10384634704 10824089600 1175839712 12.551 100 99 186 0 1492377056 294502704 22034640
06:06:59 830 8192000000 10434996992 10859720704 1182671360 12.615 100 100 193 0 1540268336 334600720 22996256
06:08:01 5208 8192000000 10394629376 10853281792 1175602528 12.549 100 41 82 0 1507765776 321210240 10953008
06:09:21 8334 8192000000 10397144096 10823163904 1157788864 12.383 99 47 94 0 1494775392 325351520 11635008
06:10:37 23443 8192000000 10409963648 10877558784 1172501760 12.520 100 34 68 0 1525994512 345095232 8397520
06:12:05 28097 8192000000 10388558096 10858430464 1172403840 12.519 100 31 62 0 1519169040 339104864 7660336
06:13:36 24775 8192000000 10295900880 10761400320 1107597760 11.910 98 40 76 1 1368532432 251289104 9645568
06:15:02 24045 8192000000 10155402352 10644029440 1106751632 11.902 99 31 60 0 1255610656 141849072 7009952
06:16:30 21569 8192000000 10113344800 10569420800 1126995360 12.093 100 28 48 0 1208086416 76056928 5034128
06:17:59 60111 8192000000 10191964400 10627944448 1128424080 12.107 99 26 52 0 1275351904 141088000 5839824
06:20:00 42833 8192000000 10095161840 10654187520 1136641280 12.184 100 16 32 0 1265894544 125383408 3869856
06:22:32 75924 8192000000 10060034224 10483453952 1097310016 11.812 97 14 26 0 1133663536 33961968 2391552
06:24:51 97795 8192000000 10061581504 10524442624 1126852000 12.092 99 12 24 0 1179307024 50175984 2279040
06:27:31 41816 8192000000 10124000048 10548195328 1141366960 12.228 100 34 66 1 1215882416 67180352 7335104
06:29:20 104693 8192000000 10048352576 10512826368 1071951760 11.571 94 5 6 0 1109315792 36803200 560832
06:32:07 76089 8192000000 10108342192 10615386112 1133292976 12.152 100 13 21 2 1202198048 66692400 2212672
06:34:32 99725 8192000000 10102453696 10597253120 1129584912 12.117 99 20 38 0 1176538352 42433280 4520160
06:37:57 141314 8192000000 10078803408 10510180352 1111781680 11.949 97 35 69 0 1158981280 39625328 7574272
06:41:21 41918 8192000000 10083926544 10527285248 1127291312 12.096 99 19 35 1 1176398960 45322000 3785648
06:43:04 40443 8192000000 10160522416 10614480896 1129002768 12.112 99 39 75 0 1229572880 92611872 7958240
06:45:07 57592 8192000000 10133316896 10604924928 1150714704 12.316 100 11 22 0 1223601424 70424384 2462336
06:47:17 63124 8192000000 10175847088 10653151232 1132733552 12.147 98 17 34 0 1258646000 122128448 3784000
06:49:20 82655 8192000000 10120238352 10660655104 1146034816 12.272 100 16 32 0 1278163648 128719872 3408960
06:52:31 83041 8192000000 10086791792 10543755264 1066937584 11.523 93 13 26 0 1119364432 49706608 2720240
06:55:00 28116 8192000000 10123107360 10559590400 1117391552 12.002 98 39 74 0 1182485840 57667344 7426944
06:56:31 35949 8192000000 10096090416 10540048384 1127461392 12.097 99 24 49 0 1189868176 57795376 4611408
06:58:46 41033 8192000000 10102851744 10571747328 1103313744 11.869 98 29 58 0 1188074288 78345376 6415168
07:01:24 32104 8192000000 10103027936 10536017920 1121543648 12.042 100 44 88 0 1161784672 30986832 9254192
07:02:59 49493 8192000000 10128252624 10556338176 1107757600 11.911 99 34 68 0 1180044528 65286272 7000656
07:04:52 124662 8192000000 10064179536 10554990592 1107231728 11.906 99 29 58 1 1223574256 110010080 6332448
07:08:04 682 8192000000 10078688384 10498043904 1124739488 12.072 100 96 183 1 1178236432 33167360 20329584
07:09:05 68002 8192000000 10032500096 10497134592 1091189184 11.754 98 16 32 0 1143847232 49339984 3318064
07:11:40 69189 8192000000 10074604848 10538590208 1097699872 11.816 98 15 31 0 1153476160 52589936 3186352
07:13:49 106088 8192000000 10134864608 10604060672 1116629296 11.995 99 15 30 0 1226545952 106509872 3406784
07:16:52 73654 8192000000 10089310672 10555371520 1096298208 11.803 97 17 32 0 1153993760 54377984 3317568
07:19:06 21405 8192000000 10064206688 10507640832 1108704272 11.920 98 26 52 0 1140350352 26237008 5409072
07:20:28 36262 8192000000 10156704640 10601689088 1124013840 12.065 100 43 86 1 1212717424 79839648 8863936
07:22:34 90121 8192000000 10089130640 10566225920 1111488160 11.947 98 29 58 0 1175034784 57701808 5844816
07:25:07 45464 8192000000 10083939984 10546733056 1114488512 11.975 99 36 62 2 1148814016 28140432 6185072
07:26:52 22557 8192000000 10110754000 10532868096 1142547344 12.239 100 58 110 1 1203350048 49471200 11331504
07:28:17 52689 8192000000 10109093792 10558955520 1120583856 12.033 99 29 56 0 1167811728 41836976 5390896
07:30:10 129155 8192000000 10137270640 10583080960 1144362480 12.257 100 33 66 0 1237511040 86241520 6907040
07:33:21 64273 8192000000 10095237872 10580684800 1136003712 12.178 100 19 38 0 1224337456 84065680 4268064
07:35:29 70619 8192000000 10073376256 10541682688 1102081776 11.857 97 22 43 0 1165249840 58587888 4580176
07:37:50 56000 8192000000 10067184368 10532999168 1114478304 11.975 98 15 30 0 1171753776 54042912 3232560
07:39:47 56875 8192000000 10103639600 10554130432 1128004560 12.103 99 34 66 1 1179513776 44122944 7386272
07:41:48 83316 8192000000 10127414160 10587619328 1101428336 11.851 97 32 64 0 1205517024 97663872 6424816
07:44:25 38105 8192000000 10083978656 10527551488 1116075632 11.990 99 26 50 0 1164623120 43240896 5306592
07:46:04 29478 8192000000 9229207488 9551732736 355019664 4.153 32 8 16 0 418536320 61927184 1589472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-03-2023 07:47:39 to 04-03-2023 07:47:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 227 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 04-03-2023 07:47:55 to 04-03-2023 07:47:58.

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          225792
MON$OLDEST_ACTIVE               225793
MON$OLDEST_SNAPSHOT             225793
MON$NEXT_TRANSACTION            225793
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                       2069344
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

04.03.23 07:47:59. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-03-04 07:48:01 No severe PSQL-related problems occured
04.03.23 07:48:03. End of report.