Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.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 14921592 kB
MemAvailable 30433532 kB
Buffers 0 kB
Cached 16806376 kB
SwapCached 4460 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-13 10:37:24
DTS_END                         2023-01-13 10:37:24.7990
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/40444
TRN_ID                          215575
ATT_ID                          636
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: 8824.48 27899 1058938
customer order: creation 166.41 448 19969
customer order: refuse 14.95 485 1794
order to supplier: creation 45.99 1304 5519
order to supplier: removal 3.33 206582 399
invoice (draft): creation 44.58 3218 5349
invoice (draft): removal 49.54 1102 5945
invoice accept: apply 40.42 28486 4850
invoice accept: cancel 44.74 1406 5369
customer reserve: creation 3773.30 268 452796
customer reserve: removal 4355.81 123 522697
realization accept: apply 80.30 301 9636
realization accept: cancel 76.76 194 9211
payment from customer: creation 58.60 187 7032
payment from customer: removal 5.84 130 701
payment to supplier: creation 47.91 213 5749
payment to supplier: removal 3.42 156 410
service: total inventory turnovers 4.00 4743 480
service: total monetary turnovers 7.60 421 912
service: aggregate perf. data 0.97 15404 116
service: refresh index statistics 0.03 292809 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-01-2023 10:39:13 to 13-01-2023 10:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9097
TEST_TIME 2 6045
TEST_TIME 3 6166
TEST_TIME 4 5820
TEST_TIME 5 5763
TEST_TIME 6 5689
TEST_TIME 7 6251
TEST_TIME 8 6621
TEST_TIME 9 6659
TEST_TIME 10 6654
TEST_TIME 11 6609
TEST_TIME 12 6692
TEST_TIME 13 6735
TEST_TIME 14 6773
TEST_TIME 15 6929
TEST_TIME 16 7205
TEST_TIME 17 7184
TEST_TIME 18 7107
TEST_TIME 19 7201
TEST_TIME 20 7181
TEST_TIME 21 7226
TEST_TIME 22 7277
TEST_TIME 23 7254
TEST_TIME 24 7370
TEST_TIME 25 7381
TEST_TIME 26 7457
TEST_TIME 27 7769
TEST_TIME 28 7847
TEST_TIME 29 7810
TEST_TIME 30 7785
TEST_TIME 31 7996
TEST_TIME 32 8563
TEST_TIME 33 8645
TEST_TIME 34 8787
TEST_TIME 35 8783
TEST_TIME 36 9123
TEST_TIME 37 9666
TEST_TIME 38 10575
TEST_TIME 39 10813
TEST_TIME 40 10672
TEST_TIME 41 10448
TEST_TIME 42 10348
TEST_TIME 43 10302
TEST_TIME 44 10133
TEST_TIME 45 10215
TEST_TIME 46 10089
TEST_TIME 47 9958
TEST_TIME 48 9815
TEST_TIME 49 9838
TEST_TIME 50 9802
TEST_TIME 51 9725
TEST_TIME 52 9634
TEST_TIME 53 9649
TEST_TIME 54 9640
TEST_TIME 55 9562
TEST_TIME 56 9421
TEST_TIME 57 9458
TEST_TIME 58 9332
TEST_TIME 59 9363
TEST_TIME 60 9350
TEST_TIME 61 9231
TEST_TIME 62 9167
TEST_TIME 63 9133
TEST_TIME 64 9010
TEST_TIME 65 8984
TEST_TIME 66 8961
TEST_TIME 67 8984
TEST_TIME 68 8996
TEST_TIME 69 8982
TEST_TIME 70 9057
TEST_TIME 71 9006
TEST_TIME 72 9116
TEST_TIME 73 9104
TEST_TIME 74 9111
TEST_TIME 75 9097
TEST_TIME 76 9147
TEST_TIME 77 9148
TEST_TIME 78 9168
TEST_TIME 79 9217
TEST_TIME 80 9293
TEST_TIME 81 9205
TEST_TIME 82 9115
TEST_TIME 83 9044
TEST_TIME 84 9038
TEST_TIME 85 8989
TEST_TIME 86 8934
TEST_TIME 87 8916
TEST_TIME 88 8900
TEST_TIME 89 8876
TEST_TIME 90 8849
TEST_TIME 91 8857
TEST_TIME 92 8844
TEST_TIME 93 8809
TEST_TIME 94 8842
TEST_TIME 95 8805
TEST_TIME 96 8797
TEST_TIME 97 8784
TEST_TIME 98 8813
TEST_TIME 99 8810
TEST_TIME 100 8819
TEST_TIME 101 8915
TEST_TIME 102 8836
TEST_TIME 103 9021
TEST_TIME 104 9022
TEST_TIME 105 9017
TEST_TIME 106 8954
TEST_TIME 107 9015
TEST_TIME 108 8971
TEST_TIME 109 8962
TEST_TIME 110 8980
TEST_TIME 111 9038
TEST_TIME 112 9027
TEST_TIME 113 8994
TEST_TIME 114 9097
TEST_TIME 115 9060
TEST_TIME 116 8987
TEST_TIME 117 8939
TEST_TIME 118 8936
TEST_TIME 119 8901
TEST_TIME 120 8847

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-01-2023 10:39:16 to 13-01-2023 10:39:20.

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 1475519 1475519 0 0.00 0 39458 15 0 0
doc_list_biud 1475501 1475501 0 0.00 0 44595 50 0 0
sp_add_invoice_to_stock 4971 4850 121 2.43 45 202698 28487 0 121
sp_cancel_adding_invoice 5369 5369 0 0.00 5 87866 1407 0 0
sp_cancel_client_order 1794 1794 0 0.00 6 12324 485 0 0
sp_cancel_customer_reserve 522697 522697 0 0.00 1 44607 124 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 3098 130 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 4 3045 156 0 0
sp_cancel_supplier_invoice 5951 5945 6 0.10 3 30861 1102 0 6
sp_cancel_supplier_order 399 399 0 0.00 7 1811221 206583 0 0
sp_cancel_write_off 9211 9211 0 0.00 2 12055 195 0 0
sp_client_order 19969 19969 0 0.00 5 29351 448 0 0
sp_customer_reserve 452796 452796 0 0.00 0 42633 269 0 0
sp_fill_shopping_cart 30823 30823 0 0.00 1 8866 87 0 0
sp_fill_shopping_cart_clo_res 452766 452766 0 0.00 0 15298 20 0 0
sp_get_clo_for_invoice 4811 4811 0 0.00 0 33281 291 0 0
sp_kill_cost_storno 15691 15691 0 0.00 0 8622 84 0 0
sp_kill_qstorno_ret_qs2qd 1060949 1060949 0 0.00 0 44578 52 0 0
sp_kill_qty_storno 559858 559858 0 0.00 0 44592 121 0 0
sp_lock_selected_doc 20118 20118 0 0.00 0 6845 7 0 0
sp_make_cost_storno 27228 27228 0 0.00 0 39424 84 0 0
sp_multiply_rows_for_pdistr 27224 27224 0 0.00 0 8973 40 0 0
sp_multiply_rows_for_qdistr 19968 19968 0 0.00 0 14905 164 0 0
sp_pay_from_customer 7032 7032 0 0.00 6 13269 187 0 0
sp_pay_to_supplier 5749 5749 0 0.00 6 39550 214 0 0
sp_payment_common 12779 12779 0 0.00 2 8640 48 0 0
sp_qd_handle_on_cancel_clo 1794 1794 0 0.00 0 12283 409 0 0
sp_qd_handle_on_invoice_upd_sts 10179 10179 0 0.00 0 39202 642 0 0
sp_qd_handle_on_reserve_upd_sts 9633 9633 0 0.00 0 28985 72 0 0
sp_reserve_write_off 9636 9636 0 0.00 7 29015 301 0 0
sp_supplier_invoice 5349 5349 0 0.00 9 81413 3218 0 0
sp_supplier_order 5519 5519 0 0.00 9 29799 1304 0 0
srv_aggregate_perf_data 116 116 0 0.00 2356 141892 15405 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 769 137583 32380 0 0
srv_find_qd_qs_mism 1010753 1010753 0 0.00 0 20756 11 0 0
srv_make_invnt_saldo 480 480 0 0.00 43 110362 4743 0 0
srv_make_money_saldo 912 912 0 0.00 1 32303 422 0 0
srv_recalc_idx_stat 4 4 0 0.00 98848 414797 292810 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4855 4855 0 0.00 1 14976 1022 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 8411 512 0 0
t$perf-norm:sp_cancel_client_order 1794 1794 0 0.00 0 2890 35 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 1279 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 1214 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1434 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 1242 42 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 83781 7989 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 1030 22 0 0
t$perf-norm:sp_client_order 19971 19971 0 0.00 0 7287 39 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 2170 19 0 0
t$perf-norm:sp_pay_from_customer 7032 7032 0 0.00 0 6078 20 0 0
t$perf-norm:sp_pay_to_supplier 5749 5749 0 0.00 0 5403 19 0 0
t$perf-norm:sp_reserve_write_off 9636 9636 0 0.00 0 8815 26 0 0
t$perf-norm:sp_supplier_invoice 5350 5350 0 0.00 0 6101 35 0 0
t$perf-norm:sp_supplier_order 5520 5520 0 0.00 0 8782 39 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 392 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 107 7 0 0
t$perf-norm:srv_make_invnt_saldo 480 480 0 0.00 0 694 12 0 0
t$perf-norm:srv_make_money_saldo 912 912 0 0.00 0 8764 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 17 152 56 0 0
v_add_invoice_to_stock 9844 9602 242 2.45 0 4377 27 0 242
v_all_customers 36830 36830 0 0.00 0 94 4 0 0
v_all_suppliers 30484 30484 0 0.00 0 157 5 0 0
v_all_wares 39926 39926 0 0.00 0 154 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 1567 22 0 0
v_cancel_client_order 3588 3588 0 0.00 0 2698 9 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 1694 12 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 1 2198 16 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 1223 16 0 0
v_cancel_supplier_invoice 742 730 12 1.61 0 2461 31 0 12
v_cancel_supplier_order 798 798 0 0.00 0 2737 18 0 0
v_cancel_write_off 1904 1904 0 0.00 0 3298 29 0 0
v_max_id_clo_ord 5514 5514 0 0.00 0 1738 6 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 793 11 0 0
v_max_id_ord_sup 5342 5342 0 0.00 0 8118 8 0 0
v_max_non_paid_invoice 1360 1360 0 0.00 0 111 9 0 0
v_max_non_paid_realizn 3507 3507 0 0.00 0 2482 9 0 0
v_min_id_clo_ord 5514 5514 0 0.00 0 2434 10 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 1584 12 0 0
v_min_id_ord_sup 5342 5342 0 0.00 0 5579 10 0 0
v_min_non_paid_invoice 5746 5746 0 0.00 0 5210 15 0 0
v_min_non_paid_realizn 7032 7032 0 0.00 0 7064 18 0 0
v_our_firm 10142 10142 0 0.00 0 70 5 0 0
v_reserve_write_off 19266 19266 0 0.00 0 4710 13 0 0
x_lock_depdocs_on_canc_invoice 10949 10949 0 0.00 0 11618 137 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 12630 436 0 0
x_make_qstorno_1000_1200 5519 5519 0 0.00 2 29767 1115 0 0
x_make_qstorno_1200_2000 5349 5349 0 0.00 3 81241 2988 0 0
x_make_qstorno_2100_3300 452795 452795 0 0.00 0 42628 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2023 10:39:21 to 13-01-2023 10:39:25.

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:28 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:29 20733 8192000000 9710393088 10005893120 786113152 8.755 100 90 178 0 815926384 7749680 22063552
08:09:57 23340 8192000000 9755624448 10061430784 828649856 9.186 100 88 176 0 861140240 11467568 21022816
08:11:28 20656 8192000000 9797459440 10116022272 859804112 9.498 100 87 175 0 905810496 25363216 20643168
08:12:53 15129 8192000000 9851838928 10186235904 912222352 10.019 100 74 147 0 964411184 36696672 15492160
08:14:12 3585 8192000000 9920049040 10261626880 961217344 10.501 100 89 178 0 1037441072 57953504 18270224
08:15:19 2390 8192000000 9961168064 10314027008 992202880 10.803 100 83 162 1 1080703616 71691792 16808944
08:16:23 2174 8192000000 9979970864 10342047744 1015636560 11.030 100 89 177 0 1093398336 59601760 18160016
08:17:28 1252 8192000000 9996477568 10361470976 1029257680 11.161 100 82 160 2 1115334720 70832800 15244240
08:18:32 1056 8192000000 10031027056 10404392960 1048988848 11.351 100 96 184 3 1150956272 83922224 18045200
08:19:36 1630 8192000000 10024715040 10401210368 1060934192 11.465 100 90 173 2 1149139072 71028736 17176144
08:20:42 4131 8192000000 10038515616 10414063616 1064316176 11.498 100 89 172 0 1162248528 80362624 17569728
08:21:49 908 8192000000 10050409568 10430197760 1073559840 11.586 100 89 162 1 1170777648 81889152 15328656
08:22:55 2677 8192000000 10035046528 10418339840 1076001248 11.609 100 84 148 0 1157783664 67495168 14287248
08:24:00 1551 8192000000 10047305840 10434969600 1088545616 11.729 100 95 184 0 1170876240 64066816 18263808
08:25:04 1359 8192000000 10063302048 10449620992 1087263104 11.717 100 95 183 2 1180966480 75646752 18056624
08:26:08 1350 8192000000 10058069744 10444181504 1094695440 11.787 100 97 182 2 1178530096 66002240 17832416
08:27:11 1320 8192000000 10072904976 10463703040 1095799088 11.798 100 99 188 1 1200014896 85244688 18971120
08:28:15 939 8192000000 10084558560 10473218048 1093777920 11.779 100 94 175 0 1203307920 92099568 17430432
08:29:17 1231 8192000000 10064029408 10450931712 1095252704 11.793 100 94 182 1 1187555360 74644752 17657904
08:30:21 1226 8192000000 10066393200 10458800128 1093431504 11.775 100 96 181 0 1189826176 76365408 20029264
08:31:25 1155 8192000000 10036140976 10422800384 1079533072 11.643 100 96 176 3 1160181536 61861744 18786720
08:32:27 1911 8192000000 9981457552 10358906880 1040655136 11.271 99 80 149 0 1106233216 51069056 14509024
08:33:31 1293 8192000000 10022110560 10398158848 1052744384 11.387 100 95 186 0 1145268544 73517952 19006208
08:34:33 1156 8192000000 9992546064 10362757120 1031268992 11.181 99 96 184 2 1117309072 68112448 17927632
08:35:35 1563 8192000000 9977072112 10345267200 1021449552 11.086 100 88 165 2 1100420848 62003984 16967312
08:36:38 949 8192000000 9997764304 10375520256 1045043472 11.313 100 86 163 2 1118945488 56838000 17064016
08:37:41 828 8192000000 9976112304 10350563328 1040646704 11.271 100 91 166 4 1100222432 39588560 19987168
08:38:49 11761 8192000000 10003939696 10383704064 1051269792 11.373 99 76 126 2 1123578272 60919360 11389120
08:40:28 1325 8192000000 10036441264 10420699136 1061573680 11.472 100 98 183 1 1157749424 77076832 19098912
08:41:33 1225 8192000000 10049136000 10430439424 1069721408 11.549 99 85 161 1 1170059712 84953328 15384976
08:42:38 3685 8192000000 10083070592 10469105664 1081728624 11.664 100 87 152 0 1197618880 99804192 16086064
08:43:46 828 8192000000 10103651536 10486849536 1096398512 11.803 100 97 183 3 1219838064 104518720 18920832
08:44:51 2674 8192000000 10112123152 10503614464 1099834016 11.836 100 81 140 1 1230649488 116318512 14496960
08:45:56 2937 8192000000 10134711328 10526400512 1112566464 11.957 100 73 127 0 1243850976 117921840 13362672
08:47:08 1892 8192000000 10151327344 10546475008 1128151152 12.104 100 85 155 1 1262974560 118378832 16444576
08:48:14 12080 8192000000 10183407456 10585989120 1145614112 12.268 100 68 130 0 1297902512 138462096 13826304
08:49:28 1302 8192000000 10221746608 10617401344 1161579904 12.418 100 93 181 2 1334241536 153027040 19634592
08:50:33 1394 8192000000 10260417840 10658037760 1163795072 12.439 100 96 186 2 1369108496 183622624 21690800
08:51:38 2797 8192000000 10273862736 10672742400 1164944384 12.450 99 97 179 2 1384493312 198589248 20959680
08:52:44 1810 8192000000 10330468720 10752958464 1184835296 12.635 100 91 172 1 1434814128 230316480 19662352
08:53:49 1717 8192000000 10313070864 10730778624 1181568800 12.605 99 85 163 0 1424445168 224174672 18701696
08:54:55 1770 8192000000 10363758880 10768617472 1197320576 12.751 100 95 187 3 1470714784 251363920 22030288
08:56:02 875 8192000000 10407240480 10819325952 1199005952 12.767 100 96 183 0 1512551520 292477712 21067856
08:57:08 3225 8192000000 10421474768 10833395712 1202181696 12.797 100 82 164 0 1522899168 301530048 19187424
08:58:18 2644 8192000000 10463151792 10882224128 1201172864 12.787 100 84 165 1 1570298528 348405856 20719808
08:59:24 1033 8192000000 10478964464 10888142848 1179569072 12.586 99 90 174 1 1585843152 385601200 20672880
09:00:28 783 8192000000 10521149680 10922070016 1187737152 12.662 99 95 180 2 1623966880 413985920 22243808
09:01:33 1129 8192000000 10538536336 10958090240 1199309280 12.770 100 100 192 0 1639543472 417593184 22641008
09:02:39 757 8192000000 10588957952 11001712640 1211483696 12.883 100 94 181 0 1690665984 456658960 22523328
09:03:46 989 8192000000 10580634496 11040231424 1209565552 12.865 100 95 190 1 1683510576 450817920 23127104
09:04:49 3495 8192000000 10644700368 11069952000 1216191232 12.926 100 94 179 2 1742765968 504850512 21724224
09:05:55 849 8192000000 10648000752 11082215424 1218865856 12.951 100 85 167 0 1751808064 511593632 21348576
09:07:02 1033 8192000000 10702157504 11140759552 1221430240 12.975 100 88 176 1 1800651280 557142768 22078272
09:08:05 2289 8192000000 10715084784 11189563392 1225755552 13.015 100 70 140 1 1819919680 575474832 18689296
09:09:15 3009 8192000000 10687441888 11231420416 1228593856 13.041 100 60 120 0 1788134032 543706496 15833680
09:10:31 16765 8192000000 10677150784 11163967488 1214723552 12.913 100 47 90 1 1775182928 548670848 11788528
09:11:50 35501 8192000000 10663317424 11154821120 1179513264 12.586 100 31 62 0 1757127168 568761472 8852432
09:13:41 20460 8192000000 10493083856 11026001920 1140207984 12.217 99 28 56 0 1646917232 498638064 8071184
09:15:06 29886 8192000000 10166564016 10752229376 1147029392 12.282 99 21 42 0 1381356848 229117680 5209776
09:16:41 52396 8192000000 10080208448 10500575232 1099048064 11.829 96 53 85 1 1148180576 40977616 8154896
09:18:36 109816 8192000000 10144629216 10611716096 1136496064 12.183 100 43 83 0 1260656000 115505744 8654192
09:21:53 37259 8192000000 10079713568 10590400512 1143369152 12.247 100 11 22 0 1204774240 59593584 1811504
09:23:35 59719 8192000000 10116177936 10549874688 1140575776 12.221 99 67 126 0 1189889520 34716880 14596864
09:25:37 43007 8192000000 10094603936 10579095552 1141998352 12.234 100 27 54 0 1235498768 88049504 5450912
09:27:21 71226 8192000000 10083627744 10566123520 1097417728 11.813 96 8 14 0 1184002224 85114080 1470416
09:29:47 31978 8192000000 10102468288 10554740736 1111408544 11.946 97 18 33 0 1188635760 73729472 3497744
09:31:20 66564 8192000000 10078631200 10545078272 1105362208 11.888 97 12 24 0 1162350960 53829664 3159088
09:33:34 76192 8192000000 10086941040 10603581440 1118094432 12.009 98 17 32 0 1200086176 78005856 3985888
09:35:52 82001 8192000000 10076547472 10574204928 1123592288 12.061 99 21 38 1 1179004096 51517280 3894528
09:38:18 58119 8192000000 10055874352 10504990720 1119015280 12.018 99 24 48 1 1148160640 24325584 4819776
09:40:32 53104 8192000000 10073870976 10543595520 1119421792 12.022 98 13 26 0 1185259296 63293824 2543680
09:42:35 85283 8192000000 10082448704 10560057344 1077992816 11.628 95 8 15 0 1156128688 76685872 1450000
09:45:08 29127 8192000000 10061707216 10529079296 1114771808 11.978 99 26 52 0 1158745136 38597376 5375952
09:46:38 29840 8192000000 10077320176 10509852672 1085259136 11.698 97 28 56 0 1153323200 62403504 5660560
09:48:08 18847 8192000000 10103606432 10540109824 1128306960 12.105 100 37 73 1 1188425072 52740752 7377360
09:49:30 29115 8192000000 10072341840 10536337408 1116741824 11.996 99 35 66 1 1157542432 33893968 6906640
09:51:00 10501 8192000000 10075171488 10510585856 1119440272 12.022 99 46 88 1 1175166480 47230992 8495216
09:52:14 23285 8192000000 10103142208 10543710208 1138737072 12.204 100 44 87 0 1200681408 53461584 8482752
09:53:41 48143 8192000000 10079178992 10553950208 1108602432 11.919 98 26 50 0 1171569328 58382400 4584496
09:55:30 36742 8192000000 10131606000 10609246208 1128472352 12.107 100 42 82 1 1208946928 71356736 9117840
09:57:07 57364 8192000000 10081000832 10582863872 1125928192 12.083 99 19 38 0 1220662368 90829200 3904976
09:59:11 38331 8192000000 10069143456 10506612736 1125340704 12.077 99 39 75 0 1169883296 36831648 7710944
10:01:10 34540 8192000000 10082750160 10532229120 1131600608 12.136 100 33 64 1 1198633632 60496864 6536160
10:02:48 68612 8192000000 10080751488 10537574400 1132673584 12.147 100 14 28 0 1172457296 37088432 2695280
10:05:02 61292 8192000000 10043697472 10526564352 1111153376 11.943 98 7 13 0 1151471040 39455120 862544
10:07:04 17532 8192000000 10046780608 10487734272 1098041376 11.819 97 34 67 0 1137437104 32479184 6916544
10:08:28 40461 8192000000 10084211808 10536194048 1106248400 11.897 98 32 64 1 1188565776 75634544 6682832
10:10:16 35283 8192000000 10135355440 10570240000 1104533152 11.881 97 38 69 0 1192925072 80593824 7798096
10:12:08 31715 8192000000 10149936128 10583003136 1125007008 12.074 99 34 68 0 1234431792 101736912 7687872
10:13:42 44794 8192000000 10203391856 10641436672 1120289024 12.030 98 37 74 0 1290292608 161155424 8848160
10:15:34 27975 8192000000 10219153808 10686300160 1129015808 12.112 99 34 68 0 1317231472 180217728 7997936
10:17:07 70097 8192000000 10228312624 10719563776 1119001280 12.018 98 19 39 0 1302339248 179010912 4327056
10:19:26 109113 8192000000 10088897088 10623832064 1114897072 11.979 98 23 46 0 1304991648 184906656 5187920
10:22:15 61410 8192000000 10078545056 10509803520 1114756288 11.977 98 26 52 0 1136727664 16403392 5567984
10:24:19 43322 8192000000 10051192048 10496966656 1124626032 12.071 99 15 30 0 1159868848 32276432 2966384
10:26:03 23991 8192000000 10134220320 10556215296 1118476128 12.013 99 62 124 1 1193612208 62323904 12812176
10:27:29 137575 8192000000 10057452464 10479755264 1080808496 11.655 95 26 49 0 1141037200 55068880 5159824
10:30:48 84201 8192000000 10073981472 10598887424 1104066400 11.876 98 23 45 0 1169088368 60719456 4302512
10:33:12 123381 8192000000 10074814928 10527215616 1083232784 11.678 96 37 73 0 1125896768 35241168 7422816
10:36:36 57710 8192000000 9730308528 10163195904 832295456 9.222 75 6 12 0 868981200 35597072 1088672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 13-01-2023 10:39:27 to 13-01-2023 10:39:44.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-01-2023 10:39:45 to 13-01-2023 10:39:47.

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          216900
MON$OLDEST_ACTIVE               216901
MON$OLDEST_SNAPSHOT             216901
MON$NEXT_TRANSACTION            216901
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                       2083168
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:327: 
:328: mscs-test-161.budzdorov.ru    Fri Jan 13 10:37:41 2023
:329:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:330: 
--- end of diff output ---

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

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