Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2022 07: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 15100248 kB
MemAvailable 30478936 kB
Buffers 0 kB
Cached 16594304 kB
SwapCached 2056 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-18 07:37:22
DTS_END                         2022-10-18 07:37:22.0030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54866
TRN_ID                          226173
ATT_ID                          596
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: 8945.39 21169 1073447
customer order: creation 178.76 443 21451
customer order: refuse 15.22 379 1826
order to supplier: creation 47.07 1324 5648
order to supplier: removal 3.39 151002 407
invoice (draft): creation 47.89 3051 5747
invoice (draft): removal 49.08 948 5889
invoice accept: apply 43.38 24742 5206
invoice accept: cancel 44.33 1312 5319
customer reserve: creation 3916.91 239 470029
customer reserve: removal 4297.03 94 515644
realization accept: apply 85.26 296 10231
realization accept: cancel 82.06 170 9847
payment from customer: creation 61.66 183 7399
payment from customer: removal 5.72 146 686
payment to supplier: creation 51.12 203 6134
payment to supplier: removal 3.44 138 413
service: total inventory turnovers 4.15 4199 498
service: total monetary turnovers 7.97 331 956
service: aggregate perf. data 0.94 14624 113
service: refresh index statistics 0.03 219557 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-10-2022 07:38:41 to 18-10-2022 07:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5795
TEST_TIME 2 4759
TEST_TIME 3 4943
TEST_TIME 4 4894
TEST_TIME 5 5095
TEST_TIME 6 5702
TEST_TIME 7 5657
TEST_TIME 8 6387
TEST_TIME 9 6924
TEST_TIME 10 7068
TEST_TIME 11 7162
TEST_TIME 12 7265
TEST_TIME 13 7362
TEST_TIME 14 7403
TEST_TIME 15 7386
TEST_TIME 16 7421
TEST_TIME 17 7525
TEST_TIME 18 8078
TEST_TIME 19 8070
TEST_TIME 20 8133
TEST_TIME 21 8282
TEST_TIME 22 8443
TEST_TIME 23 8936
TEST_TIME 24 9017
TEST_TIME 25 9029
TEST_TIME 26 9243
TEST_TIME 27 9761
TEST_TIME 28 9985
TEST_TIME 29 10268
TEST_TIME 30 10190
TEST_TIME 31 10488
TEST_TIME 32 10744
TEST_TIME 33 10582
TEST_TIME 34 10460
TEST_TIME 35 10630
TEST_TIME 36 11081
TEST_TIME 37 10937
TEST_TIME 38 10806
TEST_TIME 39 10663
TEST_TIME 40 10497
TEST_TIME 41 10555
TEST_TIME 42 10499
TEST_TIME 43 10381
TEST_TIME 44 10360
TEST_TIME 45 10357
TEST_TIME 46 10274
TEST_TIME 47 10316
TEST_TIME 48 10177
TEST_TIME 49 10009
TEST_TIME 50 9936
TEST_TIME 51 9949
TEST_TIME 52 9903
TEST_TIME 53 9815
TEST_TIME 54 9760
TEST_TIME 55 9708
TEST_TIME 56 9709
TEST_TIME 57 9745
TEST_TIME 58 9704
TEST_TIME 59 9597
TEST_TIME 60 9520
TEST_TIME 61 9487
TEST_TIME 62 9380
TEST_TIME 63 9364
TEST_TIME 64 9555
TEST_TIME 65 9496
TEST_TIME 66 9481
TEST_TIME 67 9486
TEST_TIME 68 9574
TEST_TIME 69 9546
TEST_TIME 70 9536
TEST_TIME 71 9545
TEST_TIME 72 9675
TEST_TIME 73 9713
TEST_TIME 74 9639
TEST_TIME 75 9645
TEST_TIME 76 9624
TEST_TIME 77 9537
TEST_TIME 78 9482
TEST_TIME 79 9404
TEST_TIME 80 9370
TEST_TIME 81 9401
TEST_TIME 82 9351
TEST_TIME 83 9330
TEST_TIME 84 9331
TEST_TIME 85 9269
TEST_TIME 86 9212
TEST_TIME 87 9194
TEST_TIME 88 9182
TEST_TIME 89 9148
TEST_TIME 90 9136
TEST_TIME 91 9144
TEST_TIME 92 9147
TEST_TIME 93 9113
TEST_TIME 94 9126
TEST_TIME 95 9264
TEST_TIME 96 9228
TEST_TIME 97 9248
TEST_TIME 98 9215
TEST_TIME 99 9223
TEST_TIME 100 9201
TEST_TIME 101 9222
TEST_TIME 102 9219
TEST_TIME 103 9218
TEST_TIME 104 9199
TEST_TIME 105 9142
TEST_TIME 106 9134
TEST_TIME 107 9105
TEST_TIME 108 9092
TEST_TIME 109 9040
TEST_TIME 110 9030
TEST_TIME 111 9015
TEST_TIME 112 9023
TEST_TIME 113 9021
TEST_TIME 114 8987
TEST_TIME 115 9014
TEST_TIME 116 8991
TEST_TIME 117 8984
TEST_TIME 118 8974
TEST_TIME 119 8993
TEST_TIME 120 8973

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-10-2022 07:38:45 to 18-10-2022 07:38:48.

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 1508974 1508974 0 0.00 0 11929 13 0 0
doc_list_biud 1508974 1508974 0 0.00 0 29136 38 0 0
sp_add_invoice_to_stock 5297 5206 91 1.71 63 224202 24742 0 91
sp_cancel_adding_invoice 5319 5319 0 0.00 4 111527 1313 0 0
sp_cancel_client_order 1826 1826 0 0.00 5 7740 380 0 0
sp_cancel_customer_reserve 515644 515644 0 0.00 1 29146 95 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 4 10074 147 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 4677 139 0 0
sp_cancel_supplier_invoice 5893 5889 4 0.06 2 16087 949 0 4
sp_cancel_supplier_order 407 407 0 0.00 7 1274166 151002 0 0
sp_cancel_write_off 9847 9847 0 0.00 2 9927 171 0 0
sp_client_order 21451 21451 0 0.00 6 29303 444 0 0
sp_customer_reserve 470029 470029 0 0.00 0 108011 240 0 0
sp_fill_shopping_cart 32842 32842 0 0.00 1 13944 86 0 0
sp_fill_shopping_cart_clo_res 469976 469976 0 0.00 0 17413 19 0 0
sp_get_clo_for_invoice 5152 5152 0 0.00 0 36585 294 0 0
sp_kill_cost_storno 16265 16265 0 0.00 0 10068 72 0 0
sp_kill_qstorno_ret_qs2qd 1047431 1047431 0 0.00 0 29133 41 0 0
sp_kill_qty_storno 554315 554315 0 0.00 0 29134 95 0 0
sp_lock_selected_doc 21404 21404 0 0.00 0 8985 9 0 0
sp_make_cost_storno 28916 28916 0 0.00 0 10788 86 0 0
sp_multiply_rows_for_pdistr 28916 28916 0 0.00 0 9992 40 0 0
sp_multiply_rows_for_qdistr 21451 21451 0 0.00 0 11851 165 0 0
sp_pay_from_customer 7399 7399 0 0.00 5 11281 183 0 0
sp_pay_to_supplier 6134 6134 0 0.00 5 10876 204 0 0
sp_payment_common 13533 13533 0 0.00 2 7760 52 0 0
sp_qd_handle_on_cancel_clo 1826 1826 0 0.00 1 7708 302 0 0
sp_qd_handle_on_invoice_upd_sts 10471 10471 0 0.00 0 23731 524 0 0
sp_qd_handle_on_reserve_upd_sts 10231 10231 0 0.00 0 13174 63 0 0
sp_reserve_write_off 10231 10231 0 0.00 8 13739 297 0 0
sp_supplier_invoice 5747 5747 0 0.00 7 92824 3051 0 0
sp_supplier_order 5648 5648 0 0.00 6 43499 1324 0 0
srv_aggregate_perf_data 113 113 0 0.00 2440 142913 14624 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 670 205112 39644 0 0
srv_find_qd_qs_mism 1024356 1024356 0 0.00 0 11536 10 0 0
srv_make_invnt_saldo 498 498 0 0.00 64 121302 4200 0 0
srv_make_money_saldo 956 956 0 0.00 1 8797 332 0 0
srv_recalc_idx_stat 4 4 0 0.00 91132 380153 219558 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5206 5206 0 0.00 0 10493 949 0 0
t$perf-norm:sp_cancel_adding_invoice 457 457 0 0.00 1 6806 510 0 0
t$perf-norm:sp_cancel_client_order 1826 1826 0 0.00 0 1549 28 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 587 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 3032 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 2451 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 589 39 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 110856 6741 0 0
t$perf-norm:sp_cancel_write_off 1128 1128 0 0.00 0 1412 21 0 0
t$perf-norm:sp_client_order 21451 21451 0 0.00 0 9061 37 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 3530 20 0 0
t$perf-norm:sp_pay_from_customer 7399 7399 0 0.00 0 6105 20 0 0
t$perf-norm:sp_pay_to_supplier 6134 6134 0 0.00 0 6701 23 0 0
t$perf-norm:sp_reserve_write_off 10231 10231 0 0.00 0 4622 22 0 0
t$perf-norm:sp_supplier_invoice 5747 5747 0 0.00 0 7833 31 0 0
t$perf-norm:sp_supplier_order 5648 5648 0 0.00 0 4181 31 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 2172 43 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 37 3 0 0
t$perf-norm:srv_make_invnt_saldo 498 498 0 0.00 0 689 11 0 0
t$perf-norm:srv_make_money_saldo 956 956 0 0.00 0 1007 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 40 179 96 0 0
v_add_invoice_to_stock 10486 10304 182 1.73 0 4512 25 0 182
v_all_customers 39470 39470 0 0.00 0 71 4 0 0
v_all_suppliers 31768 31768 0 0.00 0 185 5 0 0
v_all_wares 42902 42902 0 0.00 0 60 4 0 0
v_cancel_adding_invoice 914 914 0 0.00 0 1054 17 0 0
v_cancel_client_order 3652 3652 0 0.00 0 1579 9 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 8051 17 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 512 12 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 4636 17 0 0
v_cancel_supplier_invoice 836 828 8 0.95 0 1496 24 0 8
v_cancel_supplier_order 814 814 0 0.00 0 1787 13 0 0
v_cancel_write_off 2256 2256 0 0.00 0 3763 26 0 0
v_max_id_clo_ord 5648 5648 0 0.00 0 574 5 0 0
v_max_id_clo_res 998 998 0 0.00 0 1540 15 0 0
v_max_id_ord_sup 5743 5743 0 0.00 0 3615 8 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 63 8 0 0
v_max_non_paid_realizn 3700 3700 0 0.00 0 2065 9 0 0
v_min_id_clo_ord 5648 5648 0 0.00 0 3439 9 0 0
v_min_id_clo_res 998 998 0 0.00 0 6024 19 0 0
v_min_id_ord_sup 5743 5743 0 0.00 0 8203 12 0 0
v_min_non_paid_invoice 6134 6134 0 0.00 0 7751 19 0 0
v_min_non_paid_realizn 7399 7399 0 0.00 0 4786 23 0 0
v_our_firm 10830 10830 0 0.00 0 83 5 0 0
v_reserve_write_off 20462 20462 0 0.00 0 6963 11 0 0
x_lock_depdocs_on_canc_invoice 10794 10794 0 0.00 0 26579 133 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 11780 407 0 0
x_make_qstorno_1000_1200 5648 5648 0 0.00 2 43487 1151 0 0
x_make_qstorno_1200_2000 5747 5747 0 0.00 3 84295 2814 0 0
x_make_qstorno_2100_3300 470029 470029 0 0.00 0 108004 208 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 18-10-2022 07:38:49 to 18-10-2022 07:38:54.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:26 35167 8192000000 9732143920 10031386624 812386768 9.022 100 87 174 0 841300480 7596000 21317712
05:10:11 26305 8192000000 9783472416 10093817856 850396384 9.404 100 91 182 0 884027056 12611744 21018928
05:11:51 14140 8192000000 9831444192 10156290048 894272064 9.842 100 83 166 0 943511024 30309760 18929200
05:13:14 5409 8192000000 9894331328 10227818496 945155280 10.344 100 92 184 1 1008086400 42743952 20187168
05:14:24 5347 8192000000 9914396288 10260901888 972007712 10.606 100 82 162 0 1032049808 42997056 17045040
05:15:33 1793 8192000000 9976688864 10333233152 1002022336 10.898 100 81 160 1 1095277600 77504768 15750496
05:16:37 1659 8192000000 9989754864 10352652288 1019968640 11.072 100 88 172 1 1114399920 77011648 17419632
05:17:40 1075 8192000000 9999196304 10374356992 1040792688 11.272 100 92 174 0 1117492480 58668752 18031040
05:18:42 1226 8192000000 10020535856 10395910144 1051645840 11.376 100 97 183 1 1144482560 74674336 18162384
05:19:45 6370 8192000000 10024037360 10397634560 1061979200 11.475 100 95 185 0 1144816128 65509680 17327248
05:20:53 1015 8192000000 10027111552 10404286464 1067372384 11.527 100 94 174 2 1142545120 58484864 16687872
05:21:56 1234 8192000000 10049352768 10432569344 1068089296 11.534 100 98 189 0 1171317392 83944928 19283168
05:22:58 1070 8192000000 10038779808 10424745984 1074501008 11.595 100 91 170 2 1162851056 70373008 17977040
05:24:00 1147 8192000000 10048521968 10432655360 1077391728 11.623 100 97 189 6 1168884992 72404192 19089072
05:25:03 1023 8192000000 10071432352 10460192768 1081282448 11.660 100 87 164 1 1187125488 90566528 15276512
05:26:05 1152 8192000000 10070856944 10460725248 1083596944 11.682 100 92 179 0 1193169024 91225920 18346160
05:27:07 1283 8192000000 10052150992 10438385664 1089423856 11.737 100 94 177 4 1175795696 67994432 18377408
05:28:10 982 8192000000 10046087936 10433097728 1084302944 11.688 100 83 156 3 1162885136 61797600 16784592
05:29:13 6560 8192000000 10032626528 10424791040 1090029216 11.743 100 66 126 4 1159594448 56813184 12752048
05:30:20 1511 8192000000 10049135456 10438148096 1083420144 11.680 100 83 158 3 1170606864 70967264 16219456
05:31:23 1152 8192000000 10013909264 10401792000 1066716112 11.521 99 66 115 1 1136688864 59664848 10307904
05:32:26 1182 8192000000 10034465360 10414997504 1068663024 11.539 100 96 181 0 1155715360 69120592 17931744
05:33:28 1241 8192000000 9994712752 10366582784 1034297600 11.210 99 95 175 2 1118701568 66746704 17657264
05:34:30 2762 8192000000 9985930720 10366021632 1032806608 11.195 100 89 169 2 1111925312 61854720 17263984
05:35:34 1005 8192000000 10008187840 10386710528 1024710576 11.117 100 83 163 1 1134573360 94066160 15796624
05:36:37 1064 8192000000 9981900336 10352713728 1025235616 11.123 100 88 163 1 1101957824 60290512 16431696
05:37:44 28562 8192000000 9976689584 10362875904 1031317792 11.181 99 52 93 1 1090086240 49731552 9036896
05:39:20 1452 8192000000 10009440480 10387554304 1062648528 11.482 100 79 151 0 1128186208 49474416 16063264
05:40:37 4412 8192000000 10068642592 10449985536 1090618976 11.749 100 83 150 2 1182361312 78666848 13075488
05:41:45 11892 8192000000 10122706880 10512588800 1106946624 11.904 100 73 143 1 1236315104 115227088 14141392
05:43:02 5708 8192000000 10161654224 10560589824 1118088864 12.009 100 82 150 2 1277243872 141481792 17673216
05:44:32 4447 8192000000 10164288432 10561507328 1129866912 12.120 100 77 143 0 1282681136 138065920 14748304
05:45:39 1125 8192000000 10173657216 10580471808 1129858544 12.120 99 92 177 3 1287912768 138992128 19062096
05:46:42 1290 8192000000 10197391008 10609651712 1147107456 12.282 100 82 160 0 1313453888 149023184 17323248
05:47:46 951 8192000000 10203778896 10621181952 1154092432 12.348 100 93 174 5 1317147712 142488144 20567136
05:48:51 1280 8192000000 10250991632 10649673728 1166062480 12.460 100 89 165 0 1358901152 175185552 17653120
05:49:55 1390 8192000000 10294997760 10699571200 1181594992 12.605 100 96 186 0 1407028400 204437632 20995776
05:50:59 3895 8192000000 10323195632 10730758144 1181404048 12.603 100 88 173 2 1432197216 230499136 20294032
05:52:05 1531 8192000000 10364438368 10769309696 1187189056 12.657 100 96 187 1 1468895552 259871360 21835136
05:53:11 1349 8192000000 10427346384 10833752064 1193164752 12.713 100 98 193 0 1532898944 316396960 23337232
05:54:14 1772 8192000000 10409140608 10855915520 1184071552 12.628 100 91 179 1 1518147568 313050544 21025472
05:55:19 2260 8192000000 10413327120 10860396544 1191351712 12.696 100 93 176 1 1523806928 311787664 20667552
05:56:25 826 8192000000 10416357072 10824527872 1181852672 12.607 100 96 186 1 1521314880 317487456 21974752
05:57:29 1111 8192000000 10458645104 10885275648 1184966032 12.636 100 95 180 1 1563945408 356225728 22753648
05:58:32 659 8192000000 10474010128 10902462464 1199333728 12.770 100 89 171 2 1579513376 359878240 20301408
05:59:34 833 8192000000 10453959520 10911117312 1181474144 12.604 100 98 194 2 1557958560 353795808 22688608
06:00:38 2355 8192000000 10472113488 10883624960 1188714576 12.671 100 86 167 1 1576434912 367138144 20582192
06:01:42 14621 8192000000 10445285168 10931732480 1167629952 12.475 99 42 82 0 1556883392 378414096 10839344
06:03:35 7855 8192000000 10413871232 10893279232 1162591856 12.428 100 49 98 0 1528272672 353771376 11909440
06:04:56 27026 8192000000 10276019312 10722979840 1159213168 12.396 100 22 44 1 1389475344 224774096 5488080
06:06:45 8173 8192000000 10237722368 10712498176 1162062720 12.423 100 58 114 3 1354461936 180009936 12389280
06:07:57 921 8192000000 10294729216 10747056128 1180373616 12.594 100 99 199 1 1388006640 186621344 21011680
06:08:59 3356 8192000000 10228861440 10701176832 1174093456 12.535 100 83 164 1 1329046208 136486848 18465904
06:10:04 21034 8192000000 10244008320 10686062592 1171206800 12.508 100 43 86 0 1334138880 152197152 10734928
06:11:26 19995 8192000000 10277583328 10732392448 1178838448 12.579 100 41 83 1 1371245808 183038832 9368528
06:12:48 29651 8192000000 10224953136 10773524480 1157904896 12.384 100 12 24 0 1353241440 192318288 3018256
06:14:19 86571 8192000000 10095869280 10558312448 1125761792 12.081 97 29 48 2 1173144096 42172880 5209424
06:16:46 74202 8192000000 10107788272 10547798016 1130536736 12.126 98 32 62 0 1181066768 43849808 6680224
06:19:01 144417 8192000000 10116954416 10576625664 1114073712 11.971 97 6 12 0 1148960960 33896352 990896
06:22:29 205110 8192000000 10085271296 10527617024 1116351264 11.993 97 20 40 2 1160775808 40129408 4295136
06:26:54 138878 8192000000 10096259584 10578841600 1140727232 12.222 99 32 59 1 1218256640 71481792 6047616
06:30:20 64528 8192000000 10109261552 10535428096 1133834752 12.157 98 78 142 2 1187858960 39389360 14634848
06:32:30 96995 8192000000 10081878672 10541625344 1103770768 11.873 97 37 74 1 1162601360 50707936 8122656
06:35:13 41909 8192000000 10098754688 10551472128 1142879792 12.243 100 23 46 0 1203170240 55665088 4625360
06:36:56 48351 8192000000 10125022656 10564907008 1119116464 12.019 97 22 44 0 1201575728 77491312 4967952
06:38:45 99226 8192000000 10153924144 10637209600 1125139680 12.076 98 14 28 0 1261474336 133041280 3293376
06:41:26 35801 8192000000 10062968288 10566799360 1108125440 11.915 98 26 48 0 1144947344 32059280 4762624
06:43:03 34430 8192000000 10094485680 10534191104 1117618384 12.004 99 19 37 0 1184771360 63395872 3757104
06:44:38 49213 8192000000 10122525216 10608570368 1136295776 12.181 100 19 38 0 1240745504 100216592 4233136
06:46:35 37371 8192000000 10149900208 10591531008 1139150192 12.208 100 32 64 0 1228843792 82966784 6726816
06:48:16 31106 8192000000 10159011760 10600136704 1117498784 12.003 98 28 54 1 1241775232 118301600 5974848
06:49:48 105918 8192000000 10079092384 10530770944 1093619440 11.777 96 15 28 1 1162312832 65094064 3599328
06:52:35 25408 8192000000 10111367328 10570022912 1134144528 12.160 100 35 70 0 1194773472 53708672 6920272
06:54:01 140414 8192000000 10111029936 10570989568 1120734896 12.034 98 40 78 0 1180863536 51992784 8135856
06:57:39 21878 8192000000 10116718592 10598592512 1137657840 12.193 100 13 26 0 1219739984 79150448 2931696
06:59:11 63873 8192000000 10096396496 10533429248 1131869136 12.139 99 35 68 0 1192696224 53394192 7432896
07:01:23 57556 8192000000 10053875840 10506768384 1097597520 11.815 96 9 18 1 1144578480 45230096 1750864
07:03:23 84636 8192000000 10063044112 10530443264 1115134656 11.981 98 12 23 0 1149651216 32297856 2218704
07:05:48 51280 8192000000 10041082080 10484924416 1087969552 11.723 96 19 28 1 1116055808 24519600 3566656
07:07:43 55688 8192000000 10100094784 10563219456 1127083152 12.094 99 25 50 0 1194168896 62154656 4931088
07:09:39 38030 8192000000 10097149472 10545606656 1093285968 11.774 95 19 36 0 1144784464 47795648 3702848
07:11:26 56589 8192000000 10136302160 10630021120 1119549152 12.023 99 31 61 0 1237039088 110840880 6649056
07:13:23 57522 8192000000 10087203376 10542616576 1117280784 12.001 98 17 34 0 1161855472 41158000 3416688
07:15:23 61059 8192000000 10116914272 10556739584 1115808032 11.987 99 40 81 1 1191588800 67488176 8292592
07:17:29 6254 8192000000 10087201808 10519289856 1130941216 12.130 99 49 92 1 1172898272 32746672 9210384
07:18:39 30338 8192000000 10113728976 10571014144 1132226240 12.142 99 50 99 0 1221057248 78758976 10072032
07:20:11 28039 8192000000 10147070144 10590007296 1125368688 12.078 98 35 70 1 1214929056 82681376 6878992
07:21:39 101204 8192000000 10099695264 10547863552 1072849808 11.579 94 21 42 0 1128736752 51617824 4269120
07:24:23 48650 8192000000 10098224672 10592174080 1111857392 11.950 97 30 60 0 1201652656 83545648 6249616
07:26:33 66890 8192000000 10103471744 10579546112 1136543280 12.183 100 17 34 0 1227967424 87329008 4095136
07:28:47 54771 8192000000 10084999920 10583666688 1135982512 12.178 100 9 18 0 1189565808 51257568 2325728
07:30:46 69435 8192000000 10107714496 10562818048 1103006016 11.866 97 23 46 0 1161912128 53573616 5332496
07:32:56 38137 8192000000 10119391936 10564730880 1128412576 12.106 99 25 50 0 1214932000 80416464 6102960
07:34:34 48067 8192000000 10181643392 10619826176 1143221600 12.246 99 31 63 0 1258125808 108009360 6894848
07:36:30 23610 8192000000 10172272512 10602115072 1137738848 12.194 99 41 82 0 1252490048 105757968 8993232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-10-2022 07:38:56 to 18-10-2022 07:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2022 07:39:13 to 18-10-2022 07:39:15.

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          220355
MON$OLDEST_ACTIVE               226287
MON$OLDEST_SNAPSHOT             226287
MON$NEXT_TRANSACTION            226287
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                       2074208
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2860 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2860 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2860 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2860 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 ---
:66: 
:67: mscs-test-161.budzdorov.ru    Tue Oct 18 07:37:25 2022
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
:70: 
:71: mscs-test-161.budzdorov.ru    Tue Oct 18 07:37:25 2022
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Tue Oct 18 07:37:26 2022
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:77: 
:78: 
:79: mscs-test-161.budzdorov.ru    Tue Oct 18 07:37:27 2022
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:81: 
:82: 
:83: mscs-test-161.budzdorov.ru    Tue Oct 18 07:37:30 2022
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:85: 
--- end of diff output ---

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

18.10.22 07:39:16. 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-18 07:39:19 No severe PSQL-related problems occured
18.10.22 07:39:20. End of report.