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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15524352 kB
MemAvailable 30418844 kB
Buffers 0 kB
Cached 16165704 kB
SwapCached 3580 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-07 10:37:23
DTS_END                         2023-02-07 10:37:23.8620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55668
TRN_ID                          214890
ATT_ID                          662
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: 8497.84 21863 1019741
customer order: creation 165.67 473 19880
customer order: refuse 13.65 387 1638
order to supplier: creation 43.61 1446 5233
order to supplier: removal 2.98 173996 358
invoice (draft): creation 44.78 3263 5374
invoice (draft): removal 45.57 1045 5468
invoice accept: apply 40.32 26575 4838
invoice accept: cancel 41.25 1186 4950
customer reserve: creation 3758.14 255 450977
customer reserve: removal 4069.01 99 488281
realization accept: apply 80.08 256 9610
realization accept: cancel 70.02 165 8402
payment from customer: creation 55.53 170 6664
payment from customer: removal 5.00 136 600
payment to supplier: creation 46.69 181 5603
payment to supplier: removal 3.34 107 401
service: total inventory turnovers 4.18 3206 501
service: total monetary turnovers 7.03 325 843
service: aggregate perf. data 0.97 14736 116
service: refresh index statistics 0.03 209261 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-02-2023 10:38:28 to 07-02-2023 10:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7027
TEST_TIME 2 5710
TEST_TIME 3 6011
TEST_TIME 4 6364
TEST_TIME 5 5919
TEST_TIME 6 5675
TEST_TIME 7 5775
TEST_TIME 8 6068
TEST_TIME 9 6223
TEST_TIME 10 6276
TEST_TIME 11 6282
TEST_TIME 12 6284
TEST_TIME 13 6355
TEST_TIME 14 6336
TEST_TIME 15 6624
TEST_TIME 16 6634
TEST_TIME 17 6664
TEST_TIME 18 6759
TEST_TIME 19 7083
TEST_TIME 20 7312
TEST_TIME 21 7331
TEST_TIME 22 7489
TEST_TIME 23 7857
TEST_TIME 24 8694
TEST_TIME 25 8730
TEST_TIME 26 9065
TEST_TIME 27 9760
TEST_TIME 28 10361
TEST_TIME 29 10597
TEST_TIME 30 10952
TEST_TIME 31 10906
TEST_TIME 32 10610
TEST_TIME 33 10357
TEST_TIME 34 10314
TEST_TIME 35 10114
TEST_TIME 36 9961
TEST_TIME 37 9923
TEST_TIME 38 9770
TEST_TIME 39 9780
TEST_TIME 40 9703
TEST_TIME 41 9608
TEST_TIME 42 9612
TEST_TIME 43 9600
TEST_TIME 44 9391
TEST_TIME 45 9346
TEST_TIME 46 9357
TEST_TIME 47 9226
TEST_TIME 48 9185
TEST_TIME 49 9009
TEST_TIME 50 9004
TEST_TIME 51 8979
TEST_TIME 52 8875
TEST_TIME 53 8847
TEST_TIME 54 8867
TEST_TIME 55 8917
TEST_TIME 56 9006
TEST_TIME 57 9103
TEST_TIME 58 9098
TEST_TIME 59 9080
TEST_TIME 60 9093
TEST_TIME 61 9053
TEST_TIME 62 9048
TEST_TIME 63 9059
TEST_TIME 64 8947
TEST_TIME 65 8915
TEST_TIME 66 8913
TEST_TIME 67 8857
TEST_TIME 68 8790
TEST_TIME 69 8767
TEST_TIME 70 8740
TEST_TIME 71 8741
TEST_TIME 72 8731
TEST_TIME 73 8692
TEST_TIME 74 8662
TEST_TIME 75 8733
TEST_TIME 76 8716
TEST_TIME 77 8747
TEST_TIME 78 8775
TEST_TIME 79 8740
TEST_TIME 80 8775
TEST_TIME 81 8773
TEST_TIME 82 8881
TEST_TIME 83 8928
TEST_TIME 84 8937
TEST_TIME 85 8851
TEST_TIME 86 8833
TEST_TIME 87 8809
TEST_TIME 88 8756
TEST_TIME 89 8737
TEST_TIME 90 8681
TEST_TIME 91 8641
TEST_TIME 92 8670
TEST_TIME 93 8673
TEST_TIME 94 8677
TEST_TIME 95 8653
TEST_TIME 96 8656
TEST_TIME 97 8721
TEST_TIME 98 8675
TEST_TIME 99 8743
TEST_TIME 100 8734
TEST_TIME 101 8774
TEST_TIME 102 8743
TEST_TIME 103 8760
TEST_TIME 104 8770
TEST_TIME 105 8820
TEST_TIME 106 8745
TEST_TIME 107 8707
TEST_TIME 108 8691
TEST_TIME 109 8660
TEST_TIME 110 8630
TEST_TIME 111 8596
TEST_TIME 112 8537
TEST_TIME 113 8562
TEST_TIME 114 8535
TEST_TIME 115 8526
TEST_TIME 116 8533
TEST_TIME 117 8562
TEST_TIME 118 8565
TEST_TIME 119 8584
TEST_TIME 120 8528

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-02-2023 10:38:32 to 07-02-2023 10:38:35.

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 1434747 1434747 0 0.00 0 25376 14 0 0
doc_list_biud 1434739 1434739 0 0.00 0 32757 39 0 0
sp_add_invoice_to_stock 4916 4838 78 1.58 41 267946 26575 0 78
sp_cancel_adding_invoice 4950 4950 0 0.00 8 75184 1186 0 0
sp_cancel_client_order 1638 1638 0 0.00 6 14862 388 0 0
sp_cancel_customer_reserve 488281 488281 0 0.00 1 32758 99 0 0
sp_cancel_pay_from_customer 600 600 0 0.00 4 7509 136 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 4 4210 108 0 0
sp_cancel_supplier_invoice 5475 5468 7 0.12 4 22496 1046 0 7
sp_cancel_supplier_order 358 358 0 0.00 8 1529478 173996 0 0
sp_cancel_write_off 8402 8402 0 0.00 2 11326 165 0 0
sp_client_order 19880 19880 0 0.00 6 32911 473 0 0
sp_customer_reserve 450977 450977 0 0.00 0 143321 256 0 0
sp_fill_shopping_cart 30476 30476 0 0.00 1 17129 81 0 0
sp_fill_shopping_cart_clo_res 450955 450955 0 0.00 0 17184 19 0 0
sp_get_clo_for_invoice 4804 4804 0 0.00 0 26015 365 0 0
sp_kill_cost_storno 14353 14353 0 0.00 0 23912 71 0 0
sp_kill_qstorno_ret_qs2qd 990790 990790 0 0.00 0 32145 43 0 0
sp_kill_qty_storno 523509 523509 0 0.00 0 32757 99 0 0
sp_lock_selected_doc 19830 19830 0 0.00 0 5034 6 0 0
sp_make_cost_storno 26680 26680 0 0.00 0 19958 75 0 0
sp_multiply_rows_for_pdistr 26679 26679 0 0.00 0 10841 33 0 0
sp_multiply_rows_for_qdistr 19878 19878 0 0.00 0 10337 198 0 0
sp_pay_from_customer 6664 6664 0 0.00 5 15224 170 0 0
sp_pay_to_supplier 5603 5603 0 0.00 5 47639 182 0 0
sp_payment_common 12266 12266 0 0.00 2 47634 44 0 0
sp_qd_handle_on_cancel_clo 1638 1638 0 0.00 0 14854 321 0 0
sp_qd_handle_on_invoice_upd_sts 9752 9752 0 0.00 0 21485 482 0 0
sp_qd_handle_on_reserve_upd_sts 9610 9610 0 0.00 0 23907 64 0 0
sp_reserve_write_off 9610 9610 0 0.00 8 23951 256 0 0
sp_supplier_invoice 5374 5374 0 0.00 10 64566 3264 0 0
sp_supplier_order 5233 5233 0 0.00 8 68810 1447 0 0
srv_aggregate_perf_data 116 116 0 0.00 1999 140789 14737 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 844 104308 35637 0 0
srv_find_qd_qs_mism 972505 972505 0 0.00 0 25373 11 0 0
srv_make_invnt_saldo 501 501 0 0.00 33 61900 3207 0 0
srv_make_money_saldo 843 843 0 0.00 1 11142 325 0 0
srv_recalc_idx_stat 4 4 0 0.00 96910 322518 209261 0 0
t$perf-abend:sp_add_invoice_to_stock 78 78 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 4850 4850 0 0.00 1 12203 799 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 2 5512 417 0 0
t$perf-norm:sp_cancel_client_order 1638 1638 0 0.00 0 3953 28 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 1195 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 600 600 0 0.00 0 1024 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 4581 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 3885 46 0 0
t$perf-norm:sp_cancel_supplier_order 358 358 0 0.00 0 92236 6656 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 2954 22 0 0
t$perf-norm:sp_client_order 19880 19880 0 0.00 0 8109 34 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 2588 18 0 0
t$perf-norm:sp_pay_from_customer 6665 6665 0 0.00 0 13173 19 0 0
t$perf-norm:sp_pay_to_supplier 5603 5603 0 0.00 0 13273 23 0 0
t$perf-norm:sp_reserve_write_off 9610 9610 0 0.00 0 11077 21 0 0
t$perf-norm:sp_supplier_invoice 5374 5374 0 0.00 0 4138 29 0 0
t$perf-norm:sp_supplier_order 5233 5233 0 0.00 0 4312 28 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 279 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 3200 60 0 0
t$perf-norm:srv_make_invnt_saldo 501 501 0 0.00 0 3241 24 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 399 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 129 45 0 0
v_add_invoice_to_stock 9746 9590 156 1.60 0 4146 22 0 156
v_all_customers 36174 36174 0 0.00 0 115 3 0 0
v_all_suppliers 29840 29840 0 0.00 0 116 4 0 0
v_all_wares 39747 39747 0 0.00 0 109 3 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1387 13 0 0
v_cancel_client_order 3276 3276 0 0.00 0 4142 8 0 0
v_cancel_customer_prepayment 1200 1200 0 0.00 0 7496 18 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 1347 11 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 4143 19 0 0
v_cancel_supplier_invoice 774 760 14 1.80 0 3186 27 0 14
v_cancel_supplier_order 716 716 0 0.00 0 3501 16 0 0
v_cancel_write_off 1956 1956 0 0.00 0 2841 23 0 0
v_max_id_clo_ord 5232 5232 0 0.00 0 514 4 0 0
v_max_id_clo_res 990 990 0 0.00 0 5453 20 0 0
v_max_id_ord_sup 5368 5368 0 0.00 0 9050 10 0 0
v_max_non_paid_invoice 1281 1281 0 0.00 0 122 7 0 0
v_max_non_paid_realizn 3437 3437 0 0.00 0 2872 9 0 0
v_min_id_clo_ord 5232 5232 0 0.00 0 2023 7 0 0
v_min_id_clo_res 990 990 0 0.00 0 1091 8 0 0
v_min_id_ord_sup 5368 5368 0 0.00 0 5348 11 0 0
v_min_non_paid_invoice 5602 5602 0 0.00 0 47627 21 0 0
v_min_non_paid_realizn 6662 6662 0 0.00 0 5198 17 0 0
v_our_firm 10020 10020 0 0.00 0 158 4 0 0
v_reserve_write_off 19220 19220 0 0.00 0 4162 10 0 0
x_lock_depdocs_on_canc_invoice 10038 10038 0 0.00 0 34235 152 0 0
x_lock_depdocs_on_canc_sup_ord 358 358 0 0.00 0 6389 399 0 0
x_make_qstorno_1000_1200 5233 5233 0 0.00 2 68790 1306 0 0
x_make_qstorno_1200_2000 5374 5374 0 0.00 4 61720 3009 0 0
x_make_qstorno_2100_3300 450977 450977 0 0.00 0 143292 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-02-2023 10:38:36 to 07-02-2023 10:38:40.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:28 27379 8192000000 9706748720 9998557184 782371568 8.717 100 90 180 0 812406288 7933968 22100752
08:10:03 16095 8192000000 9742041152 10042994688 813702480 9.035 100 87 172 0 847367584 12173680 21491424
08:11:25 11017 8192000000 9787571520 10100801536 856050832 9.461 100 88 176 0 899121376 22213712 20856832
08:12:45 7355 8192000000 9862149152 10192306176 919898080 10.095 100 80 160 0 968506048 30962928 17645040
08:14:02 3408 8192000000 9921119760 10265313280 962613904 10.515 100 83 164 0 1035429136 55166512 17648720
08:15:08 3276 8192000000 9962204272 10316177408 1002404640 10.902 100 90 179 0 1076122336 55167648 18550048
08:16:14 1308 8192000000 9997493680 10359955456 1018797440 11.060 100 93 179 0 1109595584 73049824 17748320
08:17:17 1116 8192000000 10020084224 10389991424 1043361376 11.297 100 94 183 0 1140004736 78292864 18350496
08:18:20 853 8192000000 10038307200 10416840704 1054307632 11.402 100 93 184 2 1160244864 88474960 17462272
08:19:22 1628 8192000000 10036491520 10417709056 1063704480 11.492 100 94 183 1 1162439056 80372304 18362272
08:20:24 6628 8192000000 10025665584 10405867520 1065683120 11.511 100 81 145 2 1149370464 69908896 13778448
08:21:32 1236 8192000000 10059674880 10446741504 1075191296 11.602 100 98 191 0 1185242544 90362320 19688928
08:22:34 1040 8192000000 10055804800 10437976064 1076837088 11.617 100 99 195 2 1178369168 82053504 19478576
08:23:36 1028 8192000000 10057702912 10444537856 1082270288 11.669 100 96 182 2 1181033328 79380512 19382528
08:24:38 1058 8192000000 10055069520 10450714624 1086396016 11.708 100 90 177 2 1180177360 75389552 18391792
08:25:40 1326 8192000000 10054182688 10444025856 1086788416 11.712 100 96 187 1 1177771344 71649296 19333632
08:26:42 1526 8192000000 10033189680 10420490240 1094114240 11.782 100 93 177 3 1158203696 46606416 17483040
08:27:45 1135 8192000000 10056150592 10439598080 1083993952 11.686 100 96 185 1 1175029296 71726624 19308720
08:28:47 6141 8192000000 10011868000 10408927232 1076051344 11.610 100 75 144 2 1141125808 51174304 13900160
08:29:54 1232 8192000000 10005319840 10385780736 1061702080 11.473 100 93 172 1 1130019728 49616784 18700864
08:30:56 1218 8192000000 10004723328 10390732800 1067135472 11.525 100 76 146 3 1125595264 42121344 16338448
08:31:59 4760 8192000000 9974233232 10354794496 1024652880 11.117 97 64 116 2 1086160544 50719728 10787936
08:33:04 1297 8192000000 9992875952 10372050944 1044786752 11.311 99 78 138 1 1112994176 53535424 14672000
08:34:07 1943 8192000000 9953199600 10323279872 1019565120 11.068 97 85 162 3 1072758080 36572624 16620336
08:35:10 1126 8192000000 10002286880 10372820992 1024515696 11.116 100 96 181 1 1117742656 74570816 18656144
08:36:11 1081 8192000000 9984224480 10356445184 1024846592 11.119 99 97 185 1 1108693536 64350480 19496464
08:37:13 3667 8192000000 10002019728 10393505792 1049481152 11.356 100 59 113 0 1135417232 74419424 11516656
08:38:26 10761 8192000000 10006282912 10392612864 1068583120 11.539 100 61 118 0 1125130160 44800096 11746944
08:39:42 1468 8192000000 10044511200 10434441216 1088137968 11.725 100 76 144 2 1168960512 65898480 14924064
08:40:54 27734 8192000000 10113583952 10511605760 1110405072 11.936 100 66 132 0 1236444896 112343232 13696592
08:42:28 12892 8192000000 10139524736 10536529920 1121463232 12.041 100 63 120 0 1253724272 120175568 12085472
08:43:46 4547 8192000000 10185649840 10578931712 1137757216 12.194 100 87 167 2 1303094096 146547568 18789312
08:44:56 3685 8192000000 10231985392 10646319104 1146077920 12.273 100 75 145 1 1340907872 178988304 15841648
08:46:06 1204 8192000000 10245959120 10644189184 1162918112 12.431 100 97 190 0 1362734368 178727184 21089072
08:47:11 831 8192000000 10256381888 10670358528 1171960832 12.515 100 81 158 0 1373348976 182304848 19083296
08:48:16 1142 8192000000 10309108736 10716762112 1188296448 12.668 100 87 168 0 1423180944 215557552 19326944
08:49:19 2979 8192000000 10350018720 10754641920 1187326704 12.658 100 83 156 1 1457776816 253119440 17330672
08:50:26 2476 8192000000 10418082528 10829393920 1201535008 12.791 100 86 170 0 1532611408 310472096 20604304
08:51:32 1119 8192000000 10459783136 10867466240 1205031984 12.823 100 98 186 1 1566202144 338994096 22176064
08:52:36 960 8192000000 10493231216 10914418688 1219624992 12.958 100 94 182 0 1603559664 360769552 23165120
08:53:41 1945 8192000000 10542542096 10966810624 1215941024 12.924 100 90 178 2 1646705056 409247616 21516416
08:54:47 2739 8192000000 10579281056 11000864768 1214365552 12.910 99 92 170 0 1681845360 446636224 20843584
08:55:53 953 8192000000 10588224704 11040395264 1213250352 12.899 100 93 181 1 1692958544 457821184 21887008
08:56:57 1004 8192000000 10582045888 11027898368 1219102928 12.953 100 98 194 3 1690014624 446196128 24715568
08:58:02 1238 8192000000 10606472000 11034103808 1206438480 12.836 100 96 186 1 1710382416 480857424 23086512
08:59:06 976 8192000000 10648964480 11096793088 1202336288 12.798 100 87 169 0 1756333072 532561776 21435008
09:00:11 914 8192000000 10628202720 11079368704 1192954864 12.711 99 83 164 1 1732887712 519467648 20465200
09:01:17 15681 8192000000 10544513824 11079577600 1192405600 12.706 99 50 100 0 1677353536 471191392 13756544
09:02:35 17467 8192000000 10524049936 10998857728 1185154864 12.638 100 43 86 0 1634968400 438056528 11757008
09:03:59 18298 8192000000 10372110528 10887905280 1170679968 12.503 100 35 70 0 1525258368 345275424 9302976
09:05:20 42441 8192000000 10219658816 10690519040 1141213488 12.227 98 16 32 1 1369994624 224673296 4107840
09:07:07 61014 8192000000 10115804304 10635493376 1132670704 12.147 98 9 18 0 1215005312 80555344 1779264
09:09:09 7697 8192000000 10102608752 10537951232 1169573904 12.493 100 69 129 0 1201620944 18637696 13409344
09:10:17 8964 8192000000 10131265760 10568417280 1169660288 12.494 100 79 151 2 1222300160 36726592 15913280
09:11:45 98668 8192000000 10133986816 10611707904 1158595248 12.390 99 20 39 0 1245455568 82940576 3919744
09:14:57 58669 8192000000 10164344496 10642464768 1110555744 11.938 95 16 32 0 1209099136 94698128 3845264
09:18:18 93056 8192000000 10076542512 10533224448 1084951664 11.695 95 19 38 0 1166561664 77654272 3955728
09:21:47 28640 8192000000 10079766672 10522157056 1128710480 12.109 100 44 87 0 1174758448 36212128 9835840
09:23:18 92419 8192000000 10074763920 10547781632 1121560864 12.042 98 15 30 1 1171299152 46633600 3104688
09:27:27 59496 8192000000 10086214672 10579595264 1123431680 12.059 98 23 47 0 1192265472 63662720 5171072
09:29:44 36177 8192000000 10139463968 10586562560 1134745008 12.166 99 19 38 0 1231946032 92982592 4218432
09:31:22 31473 8192000000 10145422896 10618544128 1113640704 11.967 97 30 60 0 1246832528 126312128 6879696
09:32:54 72810 8192000000 10137180512 10608160768 1111466864 11.946 98 23 46 0 1228758096 112360064 4931168
09:35:07 21079 8192000000 10064215040 10501279744 1119675440 12.024 99 33 66 1 1154951200 28709024 6566736
09:36:31 36315 8192000000 10109654160 10538459136 1099953808 11.837 98 44 86 1 1173500704 64906144 8640752
09:38:08 6326 8192000000 10067068400 10482847744 1104963344 11.885 98 59 116 0 1157999920 41275312 11761264
09:39:15 24077 8192000000 10125804368 10556350464 1119192864 12.019 99 65 129 0 1206174048 74316704 12664480
09:40:42 85897 8192000000 10095077184 10546294784 1127384192 12.097 100 52 103 2 1207558320 69473408 10700720
09:43:14 41022 8192000000 10083945504 10544816128 1120199152 12.029 99 39 78 0 1178277872 50138096 7940624
09:45:02 60581 8192000000 10076338912 10529042432 1120550864 12.032 99 13 25 0 1161800704 38622832 2627008
09:47:26 80609 8192000000 10059168880 10534887424 1110994864 11.942 98 5 10 0 1160584832 48550848 1039120
09:49:54 40851 8192000000 10080267008 10536865792 1126836160 12.092 99 18 34 0 1168794400 38799968 3158272
09:51:39 93100 8192000000 10075774464 10519928832 1105156336 11.887 97 26 52 0 1179570336 68659760 5754240
09:54:14 44943 8192000000 10077651488 10521104384 1106764160 11.902 97 20 40 0 1152339232 41323152 4251920
09:56:14 26867 8192000000 10111708672 10568237056 1116445984 11.993 99 33 66 0 1204748768 81978000 6324784
09:57:46 2741 8192000000 10086483296 10502918144 1124278032 12.067 100 78 150 2 1174768736 34200336 16290368
09:58:50 77655 8192000000 10112613488 10616430592 1110202656 11.934 98 21 40 0 1214434640 99974544 4257440
10:01:18 100436 8192000000 10059104576 10519769088 1128346080 12.106 100 18 32 0 1211284848 78525696 4413072
10:04:24 47606 8192000000 10065615072 10524000256 1114806240 11.978 98 16 30 0 1152641376 34225360 3609776
10:06:31 70560 8192000000 10067657600 10558914560 1110556992 11.938 99 16 28 2 1146875968 33488496 2830480
10:08:45 104307 8192000000 10058584272 10523607040 1083305920 11.679 95 17 34 0 1138332496 51809744 3216832
10:11:32 48159 8192000000 10091554256 10535772160 1095765776 11.797 96 29 53 1 1159139328 57850368 5523184
10:13:30 40023 8192000000 10074176256 10578763776 1106767392 11.902 99 26 52 1 1195542640 83405056 5370192
10:15:12 45122 8192000000 10111595824 10543505408 1082218864 11.669 96 41 83 0 1160730880 69546752 8965264
10:16:59 51816 8192000000 10101541232 10534428672 1111679520 11.948 99 34 68 0 1194564144 76145888 6738736
10:18:56 39579 8192000000 10076256080 10505551872 1075956192 11.609 96 46 92 0 1138196272 52461712 9778368
10:20:36 27610 8192000000 10123123968 10568331264 1118579920 12.014 100 53 106 0 1219092528 89361376 11151232
10:22:06 61855 8192000000 10050854496 10562834432 1106737904 11.902 99 11 22 0 1164342944 54995232 2609808
10:24:22 27920 8192000000 10044306240 10486566912 1110573696 11.938 99 31 61 2 1142197184 25311776 6311712
10:25:50 49735 8192000000 10043367280 10496409600 1099100624 11.829 98 7 14 0 1121486896 21240672 1145600
10:27:40 43372 8192000000 10040751904 10494476288 1102400000 11.860 98 7 14 0 1129466928 25710256 1356672
10:29:24 83717 8192000000 10053526256 10554343424 1117321072 12.002 99 12 24 0 1174667488 54566576 2779840
10:31:49 31947 8192000000 10065263456 10513211392 1121087840 12.037 99 19 38 0 1173044208 48116928 3839440
10:33:22 35873 8192000000 10095287024 10534191104 1093212912 11.773 96 33 64 0 1153712112 53178480 7320720
10:35:00 52006 8192000000 10097964336 10548699136 1095838272 11.798 98 30 58 1 1178209168 75966928 6403968
10:36:56 30484 8192000000 9294750720 9625903104 421405744 4.892 38 13 24 1 468785440 44882224 2497472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 07-02-2023 10:38:42 to 07-02-2023 10:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 78 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:02, from 07-02-2023 10:39:00 to 07-02-2023 10:39:02.

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          215737
MON$OLDEST_ACTIVE               215738
MON$OLDEST_SNAPSHOT             215738
MON$NEXT_TRANSACTION            215738
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                       2013120
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

07.02.23 10:39:03. 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-07 10:39:05 No severe PSQL-related problems occured
07.02.23 10:39:07. End of report.