Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.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 15134840 kB
MemAvailable 30530740 kB
Buffers 20 kB
Cached 16543628 kB
SwapCached 2236 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-08 04:37:21
DTS_END                         2022-10-08 04:37:21.4370
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/40756
TRN_ID                          220246
ATT_ID                          647
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: 9165.27 26080 1099832
customer order: creation 169.60 488 20352
customer order: refuse 15.21 445 1825
order to supplier: creation 45.47 1494 5456
order to supplier: removal 3.43 154789 412
invoice (draft): creation 46.78 3280 5614
invoice (draft): removal 49.77 992 5972
invoice accept: apply 43.41 28094 5209
invoice accept: cancel 45.28 1291 5433
customer reserve: creation 4043.49 268 485219
customer reserve: removal 4410.47 94 529256
realization accept: apply 84.05 291 10086
realization accept: cancel 78.20 173 9384
payment from customer: creation 59.33 186 7120
payment from customer: removal 5.61 123 673
payment to supplier: creation 48.49 195 5819
payment to supplier: removal 3.76 130 451
service: total inventory turnovers 4.15 4109 498
service: total monetary turnovers 7.92 351 950
service: aggregate perf. data 0.82 20341 98
service: refresh index statistics 0.04 304461 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-10-2022 04:40:07 to 08-10-2022 04:40:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4264
TEST_TIME 2 4104
TEST_TIME 3 4044
TEST_TIME 4 3903
TEST_TIME 5 4034
TEST_TIME 6 4521
TEST_TIME 7 4617
TEST_TIME 8 5110
TEST_TIME 9 5441
TEST_TIME 10 5871
TEST_TIME 11 6097
TEST_TIME 12 6496
TEST_TIME 13 6659
TEST_TIME 14 6903
TEST_TIME 15 6906
TEST_TIME 16 6916
TEST_TIME 17 6983
TEST_TIME 18 7230
TEST_TIME 19 7254
TEST_TIME 20 7386
TEST_TIME 21 7571
TEST_TIME 22 8067
TEST_TIME 23 8041
TEST_TIME 24 8059
TEST_TIME 25 8197
TEST_TIME 26 8451
TEST_TIME 27 8581
TEST_TIME 28 8968
TEST_TIME 29 8944
TEST_TIME 30 9213
TEST_TIME 31 9730
TEST_TIME 32 9847
TEST_TIME 33 9991
TEST_TIME 34 10044
TEST_TIME 35 10039
TEST_TIME 36 10036
TEST_TIME 37 10169
TEST_TIME 38 10656
TEST_TIME 39 10506
TEST_TIME 40 10358
TEST_TIME 41 10292
TEST_TIME 42 10379
TEST_TIME 43 10211
TEST_TIME 44 10125
TEST_TIME 45 10136
TEST_TIME 46 9945
TEST_TIME 47 9834
TEST_TIME 48 9858
TEST_TIME 49 9804
TEST_TIME 50 9675
TEST_TIME 51 9694
TEST_TIME 52 9640
TEST_TIME 53 9550
TEST_TIME 54 9472
TEST_TIME 55 9511
TEST_TIME 56 9477
TEST_TIME 57 9381
TEST_TIME 58 9451
TEST_TIME 59 9451
TEST_TIME 60 9386
TEST_TIME 61 9374
TEST_TIME 62 9459
TEST_TIME 63 9464
TEST_TIME 64 9383
TEST_TIME 65 9492
TEST_TIME 66 9554
TEST_TIME 67 9539
TEST_TIME 68 9577
TEST_TIME 69 9544
TEST_TIME 70 9506
TEST_TIME 71 9596
TEST_TIME 72 9584
TEST_TIME 73 9610
TEST_TIME 74 9524
TEST_TIME 75 9458
TEST_TIME 76 9467
TEST_TIME 77 9417
TEST_TIME 78 9437
TEST_TIME 79 9381
TEST_TIME 80 9350
TEST_TIME 81 9282
TEST_TIME 82 9245
TEST_TIME 83 9216
TEST_TIME 84 9222
TEST_TIME 85 9266
TEST_TIME 86 9263
TEST_TIME 87 9268
TEST_TIME 88 9250
TEST_TIME 89 9278
TEST_TIME 90 9225
TEST_TIME 91 9218
TEST_TIME 92 9376
TEST_TIME 93 9353
TEST_TIME 94 9412
TEST_TIME 95 9392
TEST_TIME 96 9428
TEST_TIME 97 9340
TEST_TIME 98 9335
TEST_TIME 99 9331
TEST_TIME 100 9274
TEST_TIME 101 9297
TEST_TIME 102 9239
TEST_TIME 103 9248
TEST_TIME 104 9180
TEST_TIME 105 9174
TEST_TIME 106 9157
TEST_TIME 107 9120
TEST_TIME 108 9109
TEST_TIME 109 9081
TEST_TIME 110 9067
TEST_TIME 111 9183
TEST_TIME 112 9192
TEST_TIME 113 9185
TEST_TIME 114 9248
TEST_TIME 115 9206
TEST_TIME 116 9220
TEST_TIME 117 9197
TEST_TIME 118 9219
TEST_TIME 119 9227
TEST_TIME 120 9183

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-10-2022 04:40:10 to 08-10-2022 04:40:14.

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 1544872 1544872 0 0.00 0 14724 15 0 0
doc_list_biud 1544866 1544866 0 0.00 0 52610 39 0 0
sp_add_invoice_to_stock 5354 5209 145 2.70 37 238337 28095 0 145
sp_cancel_adding_invoice 5433 5433 0 0.00 6 84966 1291 0 0
sp_cancel_client_order 1825 1825 0 0.00 6 12903 445 0 0
sp_cancel_customer_reserve 529256 529256 0 0.00 1 25605 95 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 3131 124 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 4 1680 130 0 0
sp_cancel_supplier_invoice 5984 5972 12 0.20 3 52618 993 0 12
sp_cancel_supplier_order 412 412 0 0.00 15 1307912 154790 0 0
sp_cancel_write_off 9384 9384 0 0.00 2 7975 174 0 0
sp_client_order 20352 20352 0 0.00 5 27010 489 0 0
sp_customer_reserve 485219 485219 0 0.00 0 80297 269 0 0
sp_fill_shopping_cart 31400 31400 0 0.00 1 11710 82 0 0
sp_fill_shopping_cart_clo_res 485203 485203 0 0.00 0 14576 18 0 0
sp_get_clo_for_invoice 5180 5180 0 0.00 0 26353 319 0 0
sp_kill_cost_storno 15941 15941 0 0.00 0 6232 76 0 0
sp_kill_qstorno_ret_qs2qd 1074280 1074280 0 0.00 0 52609 42 0 0
sp_kill_qty_storno 567547 567547 0 0.00 0 52610 97 0 0
sp_lock_selected_doc 21126 21126 0 0.00 0 9589 8 0 0
sp_make_cost_storno 28205 28205 0 0.00 0 14714 84 0 0
sp_multiply_rows_for_pdistr 28204 28204 0 0.00 0 7129 39 0 0
sp_multiply_rows_for_qdistr 20348 20348 0 0.00 0 18230 192 0 0
sp_pay_from_customer 7120 7120 0 0.00 5 14314 187 0 0
sp_pay_to_supplier 5819 5819 0 0.00 6 14614 196 0 0
sp_payment_common 12939 12939 0 0.00 2 5534 49 0 0
sp_qd_handle_on_cancel_clo 1825 1825 0 0.00 0 12872 368 0 0
sp_qd_handle_on_invoice_upd_sts 10613 10613 0 0.00 0 20037 536 0 0
sp_qd_handle_on_reserve_upd_sts 10085 10085 0 0.00 0 20896 71 0 0
sp_reserve_write_off 10086 10086 0 0.00 9 21015 291 0 0
sp_supplier_invoice 5614 5614 0 0.00 9 94636 3280 0 0
sp_supplier_order 5456 5456 0 0.00 8 36218 1494 0 0
srv_aggregate_perf_data 98 98 0 0.00 2356 173280 20341 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 800 115751 32491 0 0
srv_find_qd_qs_mism 1049190 1049190 0 0.00 0 8915 12 0 0
srv_make_invnt_saldo 498 498 0 0.00 48 76077 4110 0 0
srv_make_money_saldo 950 950 0 0.00 1 13152 351 0 0
srv_recalc_idx_stat 5 5 0 0.00 159036 440641 304461 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5212 5212 0 0.00 1 14558 955 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 2 7060 479 0 0
t$perf-norm:sp_cancel_client_order 1825 1825 0 0.00 0 7249 35 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 3544 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2750 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 157 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 3221 34 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 105345 7272 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 2812 25 0 0
t$perf-norm:sp_client_order 20353 20353 0 0.00 0 14882 37 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 1375 16 0 0
t$perf-norm:sp_pay_from_customer 7120 7120 0 0.00 0 9263 20 0 0
t$perf-norm:sp_pay_to_supplier 5819 5819 0 0.00 0 5576 20 0 0
t$perf-norm:sp_reserve_write_off 10086 10086 0 0.00 0 6210 23 0 0
t$perf-norm:sp_supplier_invoice 5614 5614 0 0.00 0 9615 33 0 0
t$perf-norm:sp_supplier_order 5456 5456 0 0.00 0 5329 36 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 662 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 326 8 0 0
t$perf-norm:srv_make_invnt_saldo 498 498 0 0.00 0 355 12 0 0
t$perf-norm:srv_make_money_saldo 950 950 0 0.00 0 14856 21 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 494 131 0 0
v_add_invoice_to_stock 10641 10351 290 2.72 0 3057 24 0 290
v_all_customers 37510 37510 0 0.00 0 77 4 0 0
v_all_suppliers 30792 30792 0 0.00 0 191 5 0 0
v_all_wares 40686 40686 0 0.00 0 193 4 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 400 15 0 0
v_cancel_client_order 3650 3650 0 0.00 0 7284 11 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 2076 13 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 2660 13 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 488 14 0 0
v_cancel_supplier_invoice 750 726 24 3.20 0 638 25 0 24
v_cancel_supplier_order 824 824 0 0.00 0 2108 16 0 0
v_cancel_write_off 2044 2044 0 0.00 0 3070 26 0 0
v_max_id_clo_ord 5450 5450 0 0.00 0 6535 6 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 2879 27 0 0
v_max_id_ord_sup 5605 5605 0 0.00 0 5926 7 0 0
v_max_non_paid_invoice 1477 1477 0 0.00 0 79 7 0 0
v_max_non_paid_realizn 3673 3673 0 0.00 0 4172 9 0 0
v_min_id_clo_ord 5450 5450 0 0.00 0 2910 9 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 7207 25 0 0
v_min_id_ord_sup 5605 5605 0 0.00 0 6811 14 0 0
v_min_non_paid_invoice 5818 5818 0 0.00 0 5522 18 0 0
v_min_non_paid_realizn 7119 7119 0 0.00 0 5101 20 0 0
v_our_firm 10064 10064 0 0.00 0 55 5 0 0
v_reserve_write_off 20165 20165 0 0.00 0 2670 11 0 0
x_lock_depdocs_on_canc_invoice 11042 11042 0 0.00 0 16742 129 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 6929 393 0 0
x_make_qstorno_1000_1200 5456 5456 0 0.00 2 36195 1314 0 0
x_make_qstorno_1200_2000 5614 5614 0 0.00 4 94614 3065 0 0
x_make_qstorno_2100_3300 485219 485219 0 0.00 0 80280 238 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-10-2022 04:40:15 to 08-10-2022 04:40:19.

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:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:26 33104 8192000000 9731251600 10035032064 798576304 8.882 100 76 152 0 823404448 6995760 17832384
02:10:02 20278 8192000000 9776996560 10088390656 854131056 9.441 100 89 176 0 887236928 12535504 20570368
02:11:25 13201 8192000000 9803180224 10122330112 876126928 9.661 100 90 180 0 916553280 19985104 20441248
02:12:42 6219 8192000000 9877693872 10210447360 918156608 10.078 100 79 157 0 984394960 49590208 16648144
02:13:52 5527 8192000000 9908489184 10256482304 953949168 10.430 100 83 166 0 1025686224 53388992 18348064
02:15:07 2970 8192000000 9947584288 10300841984 990230240 10.784 100 78 155 0 1062565088 57118480 15216368
02:16:13 1522 8192000000 9973082240 10332880896 1008821984 10.964 100 87 172 0 1084991680 58994224 17175472
02:17:18 821 8192000000 10022888944 10390392832 1027574512 11.145 100 94 181 1 1146391312 101249152 17567648
02:18:21 1185 8192000000 10020867264 10389868544 1045400640 11.317 100 99 195 0 1142839360 77810032 19628688
02:19:23 1367 8192000000 10023841152 10392956928 1052181872 11.382 100 99 196 1 1143122096 71311920 19628304
02:20:27 933 8192000000 10026373248 10408181760 1058327216 11.440 100 93 180 1 1150431488 73287440 18816832
02:21:30 1178 8192000000 10060290992 10438377472 1066458400 11.518 100 99 192 0 1182301024 96662560 19180064
02:22:33 1599 8192000000 10045794928 10431246336 1070586464 11.558 100 82 162 2 1166171696 79571520 16013712
02:23:35 1054 8192000000 10036877520 10422464512 1074986256 11.600 100 87 170 2 1162906336 70670608 17249472
02:24:38 1248 8192000000 10037421376 10422611968 1081083440 11.658 100 95 184 2 1158930752 60848176 16999136
02:25:40 1340 8192000000 10057710432 10443558912 1086672784 11.711 100 97 186 1 1182803296 78234000 17896512
02:26:42 1171 8192000000 10043892352 10432606208 1082728624 11.673 100 90 174 2 1166953776 67072880 17152272
02:27:45 895 8192000000 10041395104 10429235200 1089374960 11.737 100 90 178 2 1169470640 61020448 19075232
02:28:47 1535 8192000000 10022025680 10418896896 1086139712 11.706 100 65 124 0 1145506512 46717680 12649120
02:29:50 2437 8192000000 10009792672 10401161216 1076683856 11.616 100 56 101 3 1130691536 43927568 10080112
02:30:53 1147 8192000000 10023158624 10412974080 1073607296 11.587 100 92 179 1 1151575024 59692976 18274752
02:31:55 1109 8192000000 10017201776 10404167680 1071577664 11.567 100 74 141 0 1138097968 51459008 15061296
02:32:57 1091 8192000000 10012797536 10389291008 1049579056 11.357 99 84 156 5 1135637792 69190448 16868288
02:34:00 1257 8192000000 10009493360 10377515008 1034385568 11.211 100 96 190 1 1131860320 77546320 19928432
02:35:02 5037 8192000000 9975503248 10340745216 996416832 10.844 97 80 150 2 1095620128 85322544 13880752
02:36:08 1010 8192000000 9986824000 10359771136 1036647904 11.232 100 89 170 0 1106423424 53179632 16595888
02:37:09 7972 8192000000 9956256432 10333126656 1016555728 11.039 99 52 97 1 1073192560 46400032 10236800
02:38:25 8225 8192000000 9982468400 10362064896 1042988400 11.293 99 69 134 1 1092376752 36302416 13085936
02:39:38 18315 8192000000 10036352320 10416902144 1060898688 11.465 100 64 125 2 1154641440 80418176 13324576
02:41:40 6523 8192000000 10097548400 10486394880 1079661712 11.644 100 76 141 2 1209304544 114753520 14889312
02:42:55 5802 8192000000 10120151952 10506227712 1088343968 11.727 100 74 145 0 1237042240 132747856 15950416
02:44:06 9740 8192000000 10195872176 10598928384 1095756016 11.797 100 69 137 3 1304693920 194250912 14686992
02:45:21 1009 8192000000 10178308320 10583805952 1099152000 11.830 100 93 180 0 1292633920 175340256 18141664
02:46:29 2836 8192000000 10215351024 10610454528 1117708864 12.005 100 90 169 2 1324398528 187710000 18979664
02:47:36 1048 8192000000 10212156144 10620993536 1134533280 12.164 100 95 184 0 1328026064 172633072 20859712
02:48:41 1186 8192000000 10247796240 10657824768 1152991632 12.338 100 92 180 0 1352231120 178195424 21044064
02:49:46 1181 8192000000 10281534176 10677972992 1167897120 12.477 100 95 182 1 1394837344 206386944 20553280
02:50:49 903 8192000000 10305873744 10729295872 1183422720 12.622 100 94 184 0 1417084752 212774816 20887216
02:51:53 897 8192000000 10335051776 10741133312 1185485488 12.641 100 95 184 1 1443068496 236786608 20796400
02:53:00 823 8192000000 10364184320 10767368192 1194943296 12.729 100 92 174 1 1469373200 255028656 19401248
02:54:04 954 8192000000 10431591392 10839207936 1200402512 12.780 100 94 179 1 1540530080 319246896 20880672
02:55:08 1556 8192000000 10423743424 10860384256 1202167504 12.796 100 97 190 0 1534670480 309557392 22945584
02:56:12 1100 8192000000 10477922672 10889089024 1204960752 12.822 100 92 182 2 1582126112 356433344 20732016
02:57:17 929 8192000000 10468145968 10894876672 1201273728 12.788 99 94 181 3 1578668608 354771008 22623872
02:58:20 953 8192000000 10481358704 10948243456 1199008704 12.767 100 96 188 2 1586218256 363306064 23903488
02:59:23 10626 8192000000 10435474368 10907557888 1187579648 12.661 100 59 114 1 1547554080 345658256 14316176
03:00:43 794 8192000000 10435629552 10869739520 1188032544 12.665 100 87 170 1 1547171632 337199760 21939328
03:01:52 1050 8192000000 10393170480 10872561664 1181946688 12.608 100 87 174 0 1500542880 297427824 21168368
03:02:58 1363 8192000000 10445250192 10889633792 1197976928 12.758 100 96 186 0 1549922272 329973424 21971920
03:04:02 6000 8192000000 10418114480 10887077888 1192403488 12.706 100 56 110 0 1523465984 317154016 13908480
03:05:23 6455 8192000000 10409213936 10873909248 1195057632 12.730 100 45 88 0 1520218864 313885648 11275584
03:06:32 17155 8192000000 10358370976 10838921216 1186624960 12.652 100 28 56 1 1495985200 301891440 7468800
03:08:07 15909 8192000000 10260432576 10742812672 1164730944 12.448 100 32 62 1 1385933760 213262736 7940080
03:09:28 5368 8192000000 10176906464 10652553216 1147323200 12.284 100 49 92 0 1284339344 127032320 9983824
03:10:38 1140 8192000000 10178154208 10602659840 1132686016 12.147 99 94 185 0 1278610240 125179584 20744640
03:11:41 1387 8192000000 10166830368 10592149504 1139717216 12.213 100 69 127 1 1266860064 112912368 14230480
03:12:47 16484 8192000000 10208135136 10653483008 1138968272 12.206 99 26 48 1 1307527344 162125104 6433968
03:14:07 54010 8192000000 10184264912 10650259456 1103664688 11.872 97 17 34 0 1256517376 148879008 3973680
03:16:01 64550 8192000000 10057316464 10485964800 1131258384 12.133 100 38 70 0 1156992832 18600176 7134272
03:18:09 23522 8192000000 10102670128 10544844800 1117552800 12.004 98 28 56 0 1175771200 52106752 6111648
03:19:34 89396 8192000000 10076714288 10509676544 1096587264 11.805 97 19 36 0 1139666560 39813904 3265392
03:22:07 114798 8192000000 10047881280 10478878720 1086924976 11.713 97 21 36 1 1107267392 17014032 3328384
03:25:06 74248 8192000000 10076583328 10517950464 1091412576 11.756 96 32 59 1 1151565488 54032928 6119984
03:27:23 53920 8192000000 10076783248 10545270784 1117843056 12.007 99 20 36 1 1194887808 73149472 3895280
03:29:22 15867 8192000000 10103345584 10531573760 1124123920 12.066 99 65 118 2 1199588864 60460064 15004880
03:31:09 23955 8192000000 10170119344 10595176448 1141696784 12.231 100 62 103 2 1259685552 108150704 9838064
03:32:34 68648 8192000000 10122217936 10606260224 1127426928 12.097 99 12 24 0 1222866672 92600048 2839696
03:34:43 29272 8192000000 10123938880 10596458496 1137791088 12.195 100 23 47 0 1213946576 71113504 5041984
03:36:16 110743 8192000000 10106385744 10583732224 1129356384 12.115 99 24 44 0 1239546336 105417776 4772176
03:39:08 45384 8192000000 10089226000 10555072512 1127351984 12.096 99 26 51 1 1196425952 63799696 5274272
03:40:58 5560 8192000000 10112086448 10537922560 1143023616 12.244 100 71 136 1 1209458752 51591856 14843280
03:42:14 18061 8192000000 10118648240 10550530048 1134077264 12.160 100 41 82 0 1202036128 59845728 8113136
03:43:35 68907 8192000000 10077180560 10526109696 1056566832 11.424 94 18 36 0 1120918448 60832608 3519008
03:45:56 50202 8192000000 10056183536 10473660416 1102108576 11.858 99 45 82 1 1133662096 22708400 8845120
03:47:49 24890 8192000000 10143224352 10574065664 1125953888 12.083 100 45 84 0 1225364128 91193792 8216448
03:49:16 99543 8192000000 10068036384 10528014336 1129043952 12.112 100 17 34 2 1176248432 42504880 4699600
03:51:56 63513 8192000000 10107347328 10545471488 1120277088 12.030 99 44 86 1 1156208000 26732512 9198400
03:54:00 57279 8192000000 10129234944 10570186752 1137714192 12.194 100 49 92 0 1212577856 66373744 8489920
03:55:59 22948 8192000000 10068347040 10511097856 1129193168 12.114 99 40 70 2 1180478192 42575216 8709808
03:57:23 66072 8192000000 10074799264 10539192320 1119736288 12.024 98 15 31 0 1175565328 52762192 3066848
03:59:30 99761 8192000000 10106361216 10573250560 1103064896 11.867 97 21 36 1 1191713920 84548160 4100864
04:02:12 35558 8192000000 10098626896 10535923712 1129317040 12.115 100 22 44 0 1180478256 46243024 4918192
04:04:02 57721 8192000000 10079261840 10502443008 1073657392 11.587 95 31 60 1 1125312704 45675600 5979712
04:06:03 6837 8192000000 10109153664 10531602432 1111357680 11.945 100 49 98 1 1194394960 73569968 9467312
04:07:16 27583 8192000000 10132685984 10554302464 1096544832 11.805 98 48 96 0 1198328000 91814752 9968416
04:08:46 19350 8192000000 10165974640 10645094400 1120844800 12.035 100 46 92 0 1275293536 144951696 9497040
04:10:05 35088 8192000000 10092356432 10523525120 1099948768 11.837 98 24 45 2 1159063440 53820624 5294048
04:11:43 29244 8192000000 10091137472 10558803968 1109045856 11.923 99 34 66 0 1177651136 62147552 6457728
04:13:18 115749 8192000000 10127379840 10570149888 1104046176 11.876 98 23 44 0 1177621728 68208848 5366704
04:16:36 32572 8192000000 10075728176 10532179968 1123574304 12.061 100 31 60 0 1176881456 47392160 5914992
04:18:09 54635 8192000000 10079229824 10574856192 1131118096 12.132 100 11 22 0 1194012880 60943616 1951168
04:20:05 98953 8192000000 10049717232 10499944448 1066499616 11.519 94 16 32 1 1102279168 31479872 4299680
04:22:44 57689 8192000000 10034083056 10507816960 1094438256 11.785 98 8 16 0 1123781728 27794000 1549472
04:24:43 27946 8192000000 10109443344 10556932096 1142499872 12.239 100 34 66 1 1211817216 62569488 6747856
04:26:12 25672 8192000000 10160152736 10591285248 1129038384 12.112 99 38 76 0 1227683408 90456112 8188912
04:27:38 103855 8192000000 10083165232 10578964480 1116546624 11.994 98 25 50 0 1253903984 131822608 5534752
04:30:43 64267 8192000000 10086307360 10574090240 1102854640 11.865 97 24 48 0 1179772928 71982944 4935344
04:32:47 19511 8192000000 10113726272 10551734272 1128854768 12.111 99 51 103 0 1206156816 67079008 10223040
04:34:07 20070 8192000000 10106031536 10541363200 1115445376 11.984 98 45 90 0 1176054224 51962400 8646448
04:35:31 46376 8192000000 10089710832 10531295232 1119144144 12.019 99 48 96 1 1199065184 69810448 10110592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-10-2022 04:40:21 to 08-10-2022 04:40:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

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

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          216922
MON$OLDEST_ACTIVE               220752
MON$OLDEST_SNAPSHOT             220752
MON$NEXT_TRANSACTION            220752
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                       2071904
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2855 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2855 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2855 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2855 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 ---
:165: 
:166: mscs-test-161.budzdorov.ru    Sat Oct  8 04:38:13 2022
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:168: 
--- end of diff output ---

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

08.10.22 04:40:42. 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-08 04:40:44 No severe PSQL-related problems occured
08.10.22 04:40:46. End of report.