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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14853400 kB
MemAvailable 30460496 kB
Buffers 20 kB
Cached 16816548 kB
SwapCached 2108 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 2022-10-23 04:37:23
DTS_END                         2022-10-23 04:37:23.1860
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/44496
TRN_ID                          222078
ATT_ID                          669
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: 8815.09 23770 1057811
customer order: creation 179.55 475 21546
customer order: refuse 15.26 462 1831
order to supplier: creation 48.29 1517 5795
order to supplier: removal 3.28 179360 394
invoice (draft): creation 47.38 3471 5685
invoice (draft): removal 47.25 1215 5670
invoice accept: apply 43.24 27137 5189
invoice accept: cancel 42.22 1531 5066
customer reserve: creation 3976.32 258 477158
customer reserve: removal 4114.16 112 493699
realization accept: apply 86.65 312 10398
realization accept: cancel 76.08 195 9129
payment from customer: creation 61.92 199 7430
payment from customer: removal 5.84 139 701
payment to supplier: creation 50.81 224 6097
payment to supplier: removal 3.64 156 437
service: total inventory turnovers 3.88 4781 465
service: total monetary turnovers 8.30 462 996
service: aggregate perf. data 1.00 16268 120
service: refresh index statistics 0.04 237129 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-10-2022 04:39:30 to 23-10-2022 04:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7701
TEST_TIME 2 8422
TEST_TIME 3 7881
TEST_TIME 4 7418
TEST_TIME 5 7420
TEST_TIME 6 7122
TEST_TIME 7 6682
TEST_TIME 8 6645
TEST_TIME 9 6562
TEST_TIME 10 6417
TEST_TIME 11 6346
TEST_TIME 12 6447
TEST_TIME 13 6697
TEST_TIME 14 6865
TEST_TIME 15 7146
TEST_TIME 16 7475
TEST_TIME 17 7510
TEST_TIME 18 7636
TEST_TIME 19 7605
TEST_TIME 20 7613
TEST_TIME 21 7671
TEST_TIME 22 7765
TEST_TIME 23 7975
TEST_TIME 24 8100
TEST_TIME 25 7944
TEST_TIME 26 8044
TEST_TIME 27 8048
TEST_TIME 28 8083
TEST_TIME 29 8234
TEST_TIME 30 8499
TEST_TIME 31 8659
TEST_TIME 32 8615
TEST_TIME 33 8946
TEST_TIME 34 9077
TEST_TIME 35 9070
TEST_TIME 36 9295
TEST_TIME 37 9712
TEST_TIME 38 9781
TEST_TIME 39 10095
TEST_TIME 40 10077
TEST_TIME 41 10191
TEST_TIME 42 10122
TEST_TIME 43 10066
TEST_TIME 44 10054
TEST_TIME 45 9996
TEST_TIME 46 10079
TEST_TIME 47 9972
TEST_TIME 48 9916
TEST_TIME 49 9895
TEST_TIME 50 9823
TEST_TIME 51 9658
TEST_TIME 52 9670
TEST_TIME 53 9717
TEST_TIME 54 9597
TEST_TIME 55 9515
TEST_TIME 56 9529
TEST_TIME 57 9485
TEST_TIME 58 9445
TEST_TIME 59 9596
TEST_TIME 60 9526
TEST_TIME 61 9478
TEST_TIME 62 9436
TEST_TIME 63 9474
TEST_TIME 64 9371
TEST_TIME 65 9282
TEST_TIME 66 9195
TEST_TIME 67 9182
TEST_TIME 68 9139
TEST_TIME 69 9046
TEST_TIME 70 9008
TEST_TIME 71 8999
TEST_TIME 72 8999
TEST_TIME 73 9077
TEST_TIME 74 9106
TEST_TIME 75 9131
TEST_TIME 76 9236
TEST_TIME 77 9238
TEST_TIME 78 9206
TEST_TIME 79 9255
TEST_TIME 80 9285
TEST_TIME 81 9261
TEST_TIME 82 9354
TEST_TIME 83 9319
TEST_TIME 84 9243
TEST_TIME 85 9218
TEST_TIME 86 9139
TEST_TIME 87 9123
TEST_TIME 88 9111
TEST_TIME 89 9050
TEST_TIME 90 9029
TEST_TIME 91 8999
TEST_TIME 92 8946
TEST_TIME 93 8947
TEST_TIME 94 8915
TEST_TIME 95 8914
TEST_TIME 96 8912
TEST_TIME 97 8890
TEST_TIME 98 8888
TEST_TIME 99 8884
TEST_TIME 100 8873
TEST_TIME 101 8828
TEST_TIME 102 8857
TEST_TIME 103 8852
TEST_TIME 104 8965
TEST_TIME 105 9109
TEST_TIME 106 9136
TEST_TIME 107 9189
TEST_TIME 108 9128
TEST_TIME 109 9073
TEST_TIME 110 9081
TEST_TIME 111 9064
TEST_TIME 112 9051
TEST_TIME 113 9059
TEST_TIME 114 9033
TEST_TIME 115 9006
TEST_TIME 116 8981
TEST_TIME 117 8933
TEST_TIME 118 8906
TEST_TIME 119 8895
TEST_TIME 120 8835

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-10-2022 04:39:34 to 23-10-2022 04:39:37.

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 1496124 1496124 0 0.00 0 26389 14 0 0
doc_list_biud 1496110 1496110 0 0.00 0 31072 44 0 0
sp_add_invoice_to_stock 5274 5189 85 1.61 77 253717 27137 0 85
sp_cancel_adding_invoice 5066 5066 0 0.00 7 104335 1532 0 0
sp_cancel_client_order 1831 1831 0 0.00 6 6948 462 0 0
sp_cancel_customer_reserve 493699 493699 0 0.00 1 29205 112 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 3245 139 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 4 3011 156 0 0
sp_cancel_supplier_invoice 5681 5670 11 0.19 3 31089 1215 0 11
sp_cancel_supplier_order 394 394 0 0.00 18 1286255 179360 0 0
sp_cancel_write_off 9129 9129 0 0.00 2 15621 196 0 0
sp_client_order 21546 21546 0 0.00 5 21658 475 0 0
sp_customer_reserve 477158 477158 0 0.00 1 94841 259 0 0
sp_fill_shopping_cart 33010 33010 0 0.00 1 24440 94 0 0
sp_fill_shopping_cart_clo_res 477132 477132 0 0.00 0 14387 19 0 0
sp_get_clo_for_invoice 5156 5156 0 0.00 0 33677 280 0 0
sp_kill_cost_storno 15333 15333 0 0.00 0 14872 94 0 0
sp_kill_qstorno_ret_qs2qd 1002591 1002591 0 0.00 0 31071 50 0 0
sp_kill_qty_storno 531342 531342 0 0.00 0 31072 115 0 0
sp_lock_selected_doc 21448 21448 0 0.00 0 8153 8 0 0
sp_make_cost_storno 29081 29081 0 0.00 0 9864 89 0 0
sp_multiply_rows_for_pdistr 29080 29080 0 0.00 0 7372 45 0 0
sp_multiply_rows_for_qdistr 21545 21545 0 0.00 0 11538 162 0 0
sp_pay_from_customer 7430 7430 0 0.00 6 8542 200 0 0
sp_pay_to_supplier 6097 6097 0 0.00 6 9187 225 0 0
sp_payment_common 13526 13526 0 0.00 2 7522 54 0 0
sp_qd_handle_on_cancel_clo 1831 1831 0 0.00 0 6909 381 0 0
sp_qd_handle_on_invoice_upd_sts 10222 10222 0 0.00 0 19980 610 0 0
sp_qd_handle_on_reserve_upd_sts 10397 10397 0 0.00 0 19450 70 0 0
sp_reserve_write_off 10398 10398 0 0.00 8 19594 312 0 0
sp_supplier_invoice 5685 5685 0 0.00 11 93840 3472 0 0
sp_supplier_order 5795 5795 0 0.00 6 40545 1518 0 0
srv_aggregate_perf_data 120 120 0 0.00 2069 204372 16268 0 0
srv_fill_mon_cache_memory 77 77 0 0.00 691 134094 28291 0 0
srv_find_qd_qs_mism 1006411 1006411 0 0.00 0 26383 10 0 0
srv_make_invnt_saldo 465 465 0 0.00 74 80583 4782 0 0
srv_make_money_saldo 996 996 0 0.00 1 18074 462 0 0
srv_recalc_idx_stat 5 5 0 0.00 72930 335215 237129 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5191 5191 0 0.00 1 12750 1082 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 1 8360 534 0 0
t$perf-norm:sp_cancel_client_order 1831 1831 0 0.00 0 3713 40 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 726 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 1787 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 440 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 1767 47 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 96227 7685 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 735 20 0 0
t$perf-norm:sp_client_order 21546 21546 0 0.00 0 10438 44 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 6093 28 0 0
t$perf-norm:sp_pay_from_customer 7430 7430 0 0.00 0 6374 23 0 0
t$perf-norm:sp_pay_to_supplier 6097 6097 0 0.00 0 3720 23 0 0
t$perf-norm:sp_reserve_write_off 10398 10398 0 0.00 0 9155 27 0 0
t$perf-norm:sp_supplier_invoice 5685 5685 0 0.00 0 14589 45 0 0
t$perf-norm:sp_supplier_order 5797 5797 0 0.00 0 4330 38 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 430 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 77 77 0 0.00 0 379 13 0 0
t$perf-norm:srv_make_invnt_saldo 465 465 0 0.00 0 3717 31 0 0
t$perf-norm:srv_make_money_saldo 996 996 0 0.00 0 2410 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 68 19 0 0
v_add_invoice_to_stock 10476 10306 170 1.62 0 5368 25 0 170
v_all_customers 39230 39230 0 0.00 0 161 4 0 0
v_all_suppliers 31548 31548 0 0.00 0 70 5 0 0
v_all_wares 43084 43084 0 0.00 0 160 5 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 223 16 0 0
v_cancel_client_order 3662 3662 0 0.00 0 1933 13 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 2303 14 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 1 1318 13 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 2992 18 0 0
v_cancel_supplier_invoice 790 768 22 2.78 0 894 25 0 22
v_cancel_supplier_order 788 788 0 0.00 0 1980 19 0 0
v_cancel_write_off 2012 2012 0 0.00 0 2751 29 0 0
v_max_id_clo_ord 5792 5792 0 0.00 0 426 6 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 3378 14 0 0
v_max_id_ord_sup 5676 5676 0 0.00 0 4333 8 0 0
v_max_non_paid_invoice 1790 1790 0 0.00 0 4850 12 0 0
v_max_non_paid_realizn 3935 3935 0 0.00 0 2857 11 0 0
v_min_id_clo_ord 5792 5792 0 0.00 0 2333 10 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 3204 13 0 0
v_min_id_ord_sup 5676 5676 0 0.00 0 10143 14 0 0
v_min_non_paid_invoice 6096 6096 0 0.00 0 4693 18 0 0
v_min_non_paid_realizn 7430 7430 0 0.00 0 5776 19 0 0
v_our_firm 10844 10844 0 0.00 0 58 5 0 0
v_reserve_write_off 20794 20794 0 0.00 0 3986 12 0 0
x_lock_depdocs_on_canc_invoice 10352 10352 0 0.00 0 24730 124 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 2761 361 0 0
x_make_qstorno_1000_1200 5794 5794 0 0.00 2 40534 1315 0 0
x_make_qstorno_1200_2000 5685 5685 0 0.00 5 85695 3222 0 0
x_make_qstorno_2100_3300 477158 477158 0 0.00 0 94830 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2022 04:39:38 to 23-10-2022 04:39:42.

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
02:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:27 23639 8192000000 9715103344 10011959296 788448720 8.779 100 87 174 0 817487056 7490752 21547584
02:09:55 10970 8192000000 9751374544 10056871936 829799072 9.197 100 81 162 0 860063376 11008096 19256208
02:11:10 15351 8192000000 9788982048 10104639488 862498000 9.525 100 83 166 0 896152656 14635728 19018928
02:12:33 12197 8192000000 9844372656 10174369792 910083312 9.998 100 85 168 0 955982032 27536656 18362064
02:13:48 5137 8192000000 9913889984 10253357056 944672064 10.339 100 86 170 0 1023198448 60885216 17641168
02:14:56 2786 8192000000 9961825424 10308653056 975250400 10.638 100 87 172 0 1083799520 90862240 17686880
02:16:02 1931 8192000000 9974937984 10330361856 1000890592 10.887 100 85 169 0 1097662480 79971136 16800752
02:17:06 1389 8192000000 10007722944 10368978944 1022164704 11.093 100 87 163 2 1119462080 80979424 16317952
02:18:09 1038 8192000000 10007383872 10373414912 1043190480 11.295 100 95 179 0 1128002192 66030464 18781248
02:19:11 1375 8192000000 10020759856 10400301056 1056281984 11.421 100 91 179 0 1143907248 68924144 18701120
02:20:15 1193 8192000000 10017522976 10398142464 1067145920 11.525 100 95 174 1 1139355696 54132592 18077184
02:21:18 1236 8192000000 10053387024 10431062016 1073778432 11.588 100 97 182 0 1176103472 83726192 18598848
02:22:22 933 8192000000 10046541920 10432012288 1081245040 11.659 100 88 164 1 1164925808 66283872 17396896
02:23:24 1072 8192000000 10041723744 10425802752 1088162160 11.725 100 95 186 1 1163529424 56493632 18873632
02:24:26 1091 8192000000 10062120752 10448232448 1091306144 11.755 100 96 185 1 1185260016 75281184 18672688
02:25:28 1048 8192000000 10054084880 10436993024 1100181360 11.839 100 100 197 2 1174174208 54731248 19261600
02:26:30 1218 8192000000 10044112224 10428149760 1092742800 11.769 100 88 166 3 1161770784 52089552 16938432
02:27:33 6971 8192000000 10044894480 10443083776 1096208272 11.802 100 65 124 0 1179527152 70869840 12449040
02:28:40 1195 8192000000 10065389456 10455404544 1096236848 11.802 100 94 182 4 1185255552 71410592 17608112
02:29:43 1219 8192000000 10043420112 10427990016 1101397120 11.851 100 96 181 3 1165000464 45024368 18578976
02:30:46 5684 8192000000 10025514976 10416640000 1086737712 11.712 100 60 112 1 1152833552 55099056 10996784
02:31:53 3305 8192000000 10050400928 10433720320 1076943440 11.618 100 80 148 1 1165483584 74041568 14498576
02:32:57 1272 8192000000 10027403376 10414964736 1078517968 11.633 100 93 174 2 1150959600 54578432 17863200
02:34:06 10992 8192000000 9985372976 10365939712 1050979488 11.370 100 58 102 3 1106469264 44803792 10685984
02:35:18 1967 8192000000 9975006448 10350567424 1023656176 11.107 99 72 135 1 1094633344 55950256 15026912
02:36:20 1342 8192000000 9966483840 10339758080 1037010000 11.236 100 82 157 1 1086639776 33834048 15795728
02:37:23 1501 8192000000 9983161808 10362077184 1042615312 11.290 100 82 147 1 1104070400 45508192 15946896
02:38:36 5152 8192000000 10042762560 10423955456 1064396272 11.499 100 76 143 1 1162530368 84166272 13967824
02:39:46 9605 8192000000 10052808576 10443546624 1082634112 11.673 100 86 172 2 1171666832 71736176 17296544
02:41:12 2449 8192000000 10041178016 10421743616 1088044064 11.724 100 83 152 4 1154973920 52315584 14614272
02:42:21 10176 8192000000 10085932240 10487586816 1107831408 11.912 100 58 114 1 1207696720 88393616 11471696
02:43:38 6604 8192000000 10105877520 10497703936 1130175696 12.123 100 90 164 2 1218814720 72139344 16499680
02:44:49 1280 8192000000 10173391744 10572607488 1130698592 12.128 100 98 185 4 1286211472 135916880 19596000
02:45:53 5323 8192000000 10145825024 10556035072 1127762352 12.100 99 58 114 1 1262610352 122728096 12119904
02:47:01 4722 8192000000 10157486768 10564640768 1143123120 12.245 100 67 132 1 1271962992 114780672 14059200
02:48:13 2205 8192000000 10202687088 10605506560 1152658672 12.334 100 76 151 0 1322909392 154344464 15906256
02:49:21 2914 8192000000 10232984208 10635505664 1158568864 12.390 100 80 158 0 1351859824 175811056 17479904
02:50:26 1944 8192000000 10255089488 10666635264 1161844288 12.421 100 87 169 0 1367816960 187423488 18549184
02:51:32 1258 8192000000 10230659008 10649968640 1163450752 12.436 100 74 140 1 1349225872 170862416 14912704
02:52:37 1679 8192000000 10263384432 10670600192 1170922256 12.505 100 96 185 1 1369626224 178089088 20614880
02:53:41 1428 8192000000 10250164752 10655801344 1172932432 12.524 100 93 174 1 1357153440 164960752 19260256
02:54:47 886 8192000000 10296723072 10700967936 1179637120 12.587 100 98 191 2 1410787280 209543360 21606800
02:55:50 2505 8192000000 10306683856 10729213952 1182444816 12.613 100 81 162 1 1416437056 215396000 18596240
02:56:55 2723 8192000000 10355546032 10756177920 1171370208 12.510 100 88 172 1 1462375280 271320960 19684112
02:58:03 1051 8192000000 10374798304 10791854080 1180691104 12.597 100 89 169 1 1475780976 275735248 19354624
02:59:08 875 8192000000 10386192048 10830286848 1193083856 12.712 100 99 194 1 1493053424 277255184 22714384
03:00:13 1273 8192000000 10415128528 10836746240 1188696592 12.671 100 93 178 0 1521280000 313260752 19322656
03:01:20 2096 8192000000 10445678032 10865008640 1189072224 12.675 100 88 172 0 1552626912 342643136 20911552
03:02:29 1452 8192000000 10434921472 10857668608 1188926240 12.673 100 86 168 1 1537714864 328155696 20632928
03:03:36 2161 8192000000 10454612256 10865111040 1159241776 12.396 99 86 164 1 1561398848 382722752 19434320
03:04:44 6436 8192000000 10424100192 10873712640 1165949280 12.459 99 61 118 0 1538296992 357946528 14401184
03:05:58 4052 8192000000 10447436768 10897707008 1163540560 12.436 99 68 134 0 1563632848 381748656 18343632
03:07:18 5427 8192000000 10439820496 10888777728 1177585984 12.568 100 65 130 0 1547032784 352873568 16573232
03:08:26 1965 8192000000 10443048368 10873831424 1171846464 12.514 100 67 132 2 1546243984 358345664 16051856
03:09:34 1446 8192000000 10428789104 10864828416 1159080416 12.395 100 71 139 0 1533553216 357510416 16962384
03:10:47 14075 8192000000 10382464336 10830409728 1160214112 12.405 99 45 91 0 1510875264 339324976 11336176
03:12:07 12994 8192000000 10369626592 10828505088 1155344416 12.360 100 46 88 3 1463699504 297580272 10774816
03:13:22 12038 8192000000 10310043232 10791022592 1144068928 12.254 100 29 58 0 1413470192 262073680 7327584
03:14:37 14889 8192000000 10244179104 10738356224 1154112864 12.348 100 51 100 3 1364564608 198359488 12092256
03:16:11 11476 8192000000 10206896432 10686226432 1157061040 12.376 100 25 50 0 1310849632 147793184 5995408
03:17:37 54736 8192000000 10133565200 10566942720 1151606960 12.325 100 27 52 0 1242642416 85646720 5388736
03:19:35 75932 8192000000 10108445104 10558169088 1135678528 12.175 98 35 66 1 1207079008 64640064 6760416
03:22:04 56596 8192000000 10118012208 10585759744 1124504800 12.070 98 23 44 0 1196094944 67132896 4457248
03:24:09 42393 8192000000 10095211008 10547032064 1133712416 12.156 99 22 40 0 1178518528 40535696 4270416
03:26:24 77529 8192000000 10120668336 10595205120 1120062352 12.028 98 15 30 0 1193918352 70781584 3074416
03:28:43 24797 8192000000 10093869472 10554159104 1119541328 12.023 98 42 81 2 1189961472 62800384 7619760
03:30:09 18082 8192000000 10115205200 10574974976 1140994736 12.225 100 82 162 5 1228779456 70240416 17544304
03:31:37 48348 8192000000 10097467728 10552111104 1133119216 12.151 99 24 44 2 1187821904 50450448 4252240
03:33:27 42891 8192000000 10132988848 10607849472 1141983264 12.234 100 41 78 0 1246224304 95855792 8385248
03:35:12 21372 8192000000 10135552688 10565517312 1113456032 11.965 98 54 106 0 1230511744 104584368 12471344
03:36:35 47718 8192000000 10070791856 10523258880 1089502576 11.738 96 18 34 0 1140626192 47213760 3909856
03:38:55 134092 8192000000 10045978544 10525446144 1091606096 11.758 96 23 44 0 1149845760 53660848 4578816
03:42:13 54929 8192000000 10035095488 10479218688 1094971424 11.790 97 24 44 1 1123790352 24779792 4039136
03:44:09 48590 8192000000 10047201536 10513690624 1103389392 11.870 98 12 24 0 1157022048 51483888 2148768
03:46:09 42601 8192000000 10082351424 10526773248 1129599696 12.118 100 21 42 0 1183959744 48409616 5950432
03:47:53 33921 8192000000 10167208896 10638643200 1146172336 12.274 100 23 44 1 1255748560 104471392 5104832
03:49:30 13487 8192000000 10189175600 10646278144 1147222960 12.283 100 38 72 0 1288728688 133099200 8406528
03:50:46 40827 8192000000 10161700816 10624778240 1138015952 12.197 100 25 51 0 1269584000 125713520 5854528
03:52:33 23536 8192000000 10174073600 10649427968 1136983984 12.187 100 35 65 2 1290008608 145968672 7055952
03:53:57 51596 8192000000 10099637440 10539044864 1138886336 12.205 100 34 68 0 1207683360 61666368 7130656
03:55:53 21628 8192000000 10140807840 10610073600 1127827680 12.101 100 48 96 1 1258689040 120905760 9955600
03:57:22 36061 8192000000 10091295968 10537136128 1094862992 11.789 98 31 62 1 1169855904 68785232 6207680
03:58:59 35729 8192000000 10133221376 10586947584 1097289248 11.812 98 28 56 0 1213070576 109936800 5844528
04:00:38 67618 8192000000 10089280912 10523037696 1120027376 12.027 100 45 84 1 1167991520 38975024 8989120
04:02:45 41733 8192000000 10062904272 10500845568 1099763936 11.835 98 47 94 1 1141897936 32824128 9309872
04:04:29 48594 8192000000 10079790624 10527195136 1124577520 12.070 100 33 66 0 1192351280 60184320 7589440
04:06:18 38986 8192000000 10064100336 10514714624 1127158064 12.095 100 12 24 0 1169366128 39591152 2616912
04:07:59 97652 8192000000 10064074512 10532352000 1107267008 11.907 98 15 30 0 1165865472 54677392 3921072
04:10:43 689 8192000000 10033511344 10503716864 1119800608 12.025 100 35 69 0 1141367392 14745696 6821088
04:11:44 49234 8192000000 10079685424 10534989824 1093408592 11.775 97 25 50 0 1155106608 56598880 5099136
04:13:34 43701 8192000000 10119343632 10564141056 1123197296 12.057 98 27 52 0 1208891168 79518976 6174896
04:15:18 23401 8192000000 10161727776 10604969984 1152855520 12.336 100 28 56 0 1263448976 104437184 6156272
04:16:49 35615 8192000000 10182203568 10621251584 1137322208 12.190 98 32 63 0 1244579456 100313248 6944000
04:18:26 15635 8192000000 10257013424 10693459968 1169521472 12.492 100 44 88 0 1341704608 162731248 9451888
04:19:43 33813 8192000000 10278409584 10780160000 1174220800 12.536 100 37 72 0 1414557072 232189472 8146800
04:21:33 41871 8192000000 10220760320 10692558848 1140425872 12.220 98 23 46 0 1290801568 144791488 5584208
04:23:28 55723 8192000000 10107103920 10590871552 1115272112 11.982 97 15 30 0 1157401920 38950848 3178960
04:25:33 41133 8192000000 10154071728 10605285376 1151185296 12.321 100 41 82 1 1229984000 70559248 8239456
04:27:15 99615 8192000000 10115870896 10611159040 1138300240 12.200 99 21 41 0 1225031024 82595392 4135392
04:29:56 70584 8192000000 10069912800 10562584576 1143005280 12.244 100 20 40 0 1193699328 46905968 3788080
04:32:26 34239 8192000000 10081886320 10546057216 1124849824 12.073 98 21 42 0 1172378416 43415072 4113520
04:34:06 68631 8192000000 10068340544 10538311680 1121674432 12.043 98 16 32 0 1166359200 41061184 3623584
04:36:17 64249 8192000000 10071252480 10520842240 1056876576 11.427 93 18 34 0 1096192640 35834272 3481792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 23-10-2022 04:39:44 to 23-10-2022 04:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2022 04:40:02 to 23-10-2022 04:40:04.

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          220419
MON$OLDEST_ACTIVE               223447
MON$OLDEST_SNAPSHOT             223447
MON$NEXT_TRANSACTION            223447
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                       2095808
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2861 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2861 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2861 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 ---
:729: 
:730: mscs-test-161.budzdorov.ru    Sun Oct 23 04:37:41 2022
:731:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:732: 
:733: 
:734: mscs-test-161.budzdorov.ru    Sun Oct 23 04:37:42 2022
:735:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:736: 
:737: 
:738: mscs-test-161.budzdorov.ru    Sun Oct 23 04:37:43 2022
:739:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:740: 
:741: 
:742: mscs-test-161.budzdorov.ru    Sun Oct 23 04:37:46 2022
:743:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:744: 
--- end of diff output ---

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

23.10.22 04:40:05. 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
2022-10-23 04:40:08 No severe PSQL-related problems occured
23.10.22 04:40:09. End of report.