Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.10.2022 04: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 15175012 kB
MemAvailable 30531464 kB
Buffers 20 kB
Cached 16495680 kB
SwapCached 2088 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-07 04:37:20
DTS_END                         2022-10-07 04:37:20.4660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53296
TRN_ID                          221180
ATT_ID                          620
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: 9094.14 19933 1091297
customer order: creation 169.84 481 20381
customer order: refuse 14.58 373 1750
order to supplier: creation 44.63 1311 5355
order to supplier: removal 3.49 159390 419
invoice (draft): creation 43.92 3085 5270
invoice (draft): removal 52.62 913 6314
invoice accept: apply 40.95 26791 4914
invoice accept: cancel 46.88 1136 5625
customer reserve: creation 3802.55 257 456306
customer reserve: removal 4581.73 96 549807
realization accept: apply 80.58 261 9669
realization accept: cancel 85.66 166 10279
payment from customer: creation 57.14 169 6857
payment from customer: removal 5.34 121 641
payment to supplier: creation 48.62 168 5834
payment to supplier: removal 3.23 122 387
service: total inventory turnovers 3.93 3712 472
service: total monetary turnovers 7.56 374 907
service: aggregate perf. data 0.88 15244 105
service: refresh index statistics 0.04 184496 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-10-2022 04:38:43 to 07-10-2022 04:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7261
TEST_TIME 2 6036
TEST_TIME 3 5389
TEST_TIME 4 5696
TEST_TIME 5 5346
TEST_TIME 6 5395
TEST_TIME 7 5616
TEST_TIME 8 5701
TEST_TIME 9 5792
TEST_TIME 10 5925
TEST_TIME 11 6044
TEST_TIME 12 6147
TEST_TIME 13 6642
TEST_TIME 14 6843
TEST_TIME 15 7360
TEST_TIME 16 7400
TEST_TIME 17 7547
TEST_TIME 18 7863
TEST_TIME 19 8076
TEST_TIME 20 8140
TEST_TIME 21 8438
TEST_TIME 22 8314
TEST_TIME 23 8541
TEST_TIME 24 8585
TEST_TIME 25 8588
TEST_TIME 26 8803
TEST_TIME 27 9154
TEST_TIME 28 9416
TEST_TIME 29 9414
TEST_TIME 30 9801
TEST_TIME 31 10268
TEST_TIME 32 10905
TEST_TIME 33 11142
TEST_TIME 34 11508
TEST_TIME 35 11368
TEST_TIME 36 11173
TEST_TIME 37 11105
TEST_TIME 38 11150
TEST_TIME 39 11054
TEST_TIME 40 10808
TEST_TIME 41 10728
TEST_TIME 42 10689
TEST_TIME 43 10615
TEST_TIME 44 10432
TEST_TIME 45 10336
TEST_TIME 46 10265
TEST_TIME 47 10256
TEST_TIME 48 10198
TEST_TIME 49 10219
TEST_TIME 50 10191
TEST_TIME 51 10213
TEST_TIME 52 10139
TEST_TIME 53 10134
TEST_TIME 54 10025
TEST_TIME 55 9958
TEST_TIME 56 9933
TEST_TIME 57 9934
TEST_TIME 58 9874
TEST_TIME 59 9795
TEST_TIME 60 9856
TEST_TIME 61 9897
TEST_TIME 62 9853
TEST_TIME 63 9862
TEST_TIME 64 9889
TEST_TIME 65 9841
TEST_TIME 66 9939
TEST_TIME 67 9871
TEST_TIME 68 9893
TEST_TIME 69 9888
TEST_TIME 70 9859
TEST_TIME 71 9876
TEST_TIME 72 9778
TEST_TIME 73 9750
TEST_TIME 74 9691
TEST_TIME 75 9648
TEST_TIME 76 9605
TEST_TIME 77 9604
TEST_TIME 78 9515
TEST_TIME 79 9442
TEST_TIME 80 9369
TEST_TIME 81 9403
TEST_TIME 82 9380
TEST_TIME 83 9487
TEST_TIME 84 9474
TEST_TIME 85 9425
TEST_TIME 86 9417
TEST_TIME 87 9403
TEST_TIME 88 9453
TEST_TIME 89 9504
TEST_TIME 90 9470
TEST_TIME 91 9620
TEST_TIME 92 9634
TEST_TIME 93 9572
TEST_TIME 94 9530
TEST_TIME 95 9464
TEST_TIME 96 9454
TEST_TIME 97 9388
TEST_TIME 98 9355
TEST_TIME 99 9302
TEST_TIME 100 9262
TEST_TIME 101 9228
TEST_TIME 102 9269
TEST_TIME 103 9304
TEST_TIME 104 9284
TEST_TIME 105 9256
TEST_TIME 106 9281
TEST_TIME 107 9320
TEST_TIME 108 9336
TEST_TIME 109 9341
TEST_TIME 110 9361
TEST_TIME 111 9349
TEST_TIME 112 9408
TEST_TIME 113 9343
TEST_TIME 114 9328
TEST_TIME 115 9274
TEST_TIME 116 9246
TEST_TIME 117 9226
TEST_TIME 118 9202
TEST_TIME 119 9181
TEST_TIME 120 9116

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-10-2022 04:38:47 to 07-10-2022 04:38:50.

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 1512346 1512346 0 0.00 0 16537 14 0 0
doc_list_biud 1512346 1512346 0 0.00 0 76754 40 0 0
sp_add_invoice_to_stock 5031 4914 117 2.32 53 274022 26792 0 117
sp_cancel_adding_invoice 5625 5625 0 0.00 6 74555 1137 0 0
sp_cancel_client_order 1750 1750 0 0.00 6 10223 373 0 0
sp_cancel_customer_reserve 549807 549807 0 0.00 1 60590 96 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 4 6931 121 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 4745 122 0 0
sp_cancel_supplier_invoice 6320 6314 6 0.09 3 76806 914 0 6
sp_cancel_supplier_order 419 419 0 0.00 7 1406217 159390 0 0
sp_cancel_write_off 10279 10279 0 0.00 1 10110 166 0 0
sp_client_order 20381 20381 0 0.00 5 45044 481 0 0
sp_customer_reserve 456306 456306 0 0.00 0 112908 257 0 0
sp_fill_shopping_cart 31003 31003 0 0.00 1 19505 75 0 0
sp_fill_shopping_cart_clo_res 456292 456292 0 0.00 0 14419 19 0 0
sp_get_clo_for_invoice 4876 4876 0 0.00 0 31221 276 0 0
sp_kill_cost_storno 16932 16932 0 0.00 0 7382 67 0 0
sp_kill_qstorno_ret_qs2qd 1116626 1116626 0 0.00 0 76753 42 0 0
sp_kill_qty_storno 588737 588737 0 0.00 0 76753 96 0 0
sp_lock_selected_doc 20201 20201 0 0.00 0 7907 7 0 0
sp_make_cost_storno 27234 27234 0 0.00 0 12572 71 0 0
sp_multiply_rows_for_pdistr 27234 27234 0 0.00 0 7054 36 0 0
sp_multiply_rows_for_qdistr 20381 20381 0 0.00 0 21627 192 0 0
sp_pay_from_customer 6857 6857 0 0.00 5 10920 170 0 0
sp_pay_to_supplier 5834 5834 0 0.00 6 6802 168 0 0
sp_payment_common 12691 12691 0 0.00 2 4916 44 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 10216 300 0 0
sp_qd_handle_on_invoice_upd_sts 10499 10499 0 0.00 0 13721 481 0 0
sp_qd_handle_on_reserve_upd_sts 9669 9669 0 0.00 0 16288 61 0 0
sp_reserve_write_off 9669 9669 0 0.00 8 16335 262 0 0
sp_supplier_invoice 5270 5270 0 0.00 10 118372 3086 0 0
sp_supplier_order 5355 5355 0 0.00 7 77404 1312 0 0
srv_aggregate_perf_data 105 105 0 0.00 2254 120412 15245 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 791 127954 37409 0 0
srv_find_qd_qs_mism 1043689 1043689 0 0.00 0 16508 11 0 0
srv_make_invnt_saldo 472 472 0 0.00 41 76398 3713 0 0
srv_make_money_saldo 907 907 0 0.00 1 20420 374 0 0
srv_recalc_idx_stat 5 5 0 0.00 106489 239757 184497 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4914 4914 0 0.00 1 10578 874 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 1 5697 441 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 2095 26 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 694 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 909 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 380 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 725 27 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 103216 6208 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 695 17 0 0
t$perf-norm:sp_client_order 20381 20381 0 0.00 0 11188 36 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 1599 16 0 0
t$perf-norm:sp_pay_from_customer 6857 6857 0 0.00 0 4484 19 0 0
t$perf-norm:sp_pay_to_supplier 5834 5834 0 0.00 0 3834 18 0 0
t$perf-norm:sp_reserve_write_off 9669 9669 0 0.00 0 4348 22 0 0
t$perf-norm:sp_supplier_invoice 5270 5270 0 0.00 0 6277 30 0 0
t$perf-norm:sp_supplier_order 5355 5355 0 0.00 0 11193 31 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 671 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 2519 41 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 1599 16 0 0
t$perf-norm:srv_make_money_saldo 907 907 0 0.00 0 1572 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 486 139 0 0
v_add_invoice_to_stock 9982 9748 234 2.34 0 6115 26 0 234
v_all_customers 37286 37286 0 0.00 0 137 4 0 0
v_all_suppliers 30022 30022 0 0.00 0 76 4 0 0
v_all_wares 40762 40762 0 0.00 0 56 4 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 3021 21 0 0
v_cancel_client_order 3500 3500 0 0.00 0 3583 12 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 1724 11 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 1 1195 11 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 4732 19 0 0
v_cancel_supplier_invoice 806 794 12 1.48 0 454 21 0 12
v_cancel_supplier_order 838 838 0 0.00 0 3516 16 0 0
v_cancel_write_off 1984 1984 0 0.00 0 3219 27 0 0
v_max_id_clo_ord 5355 5355 0 0.00 0 2070 5 0 0
v_max_id_clo_res 972 972 0 0.00 0 4393 18 0 0
v_max_id_ord_sup 5267 5267 0 0.00 0 2542 5 0 0
v_max_non_paid_invoice 1445 1445 0 0.00 0 811 8 0 0
v_max_non_paid_realizn 3552 3552 0 0.00 0 1319 7 0 0
v_min_id_clo_ord 5355 5355 0 0.00 0 2204 7 0 0
v_min_id_clo_res 972 972 0 0.00 0 2811 18 0 0
v_min_id_ord_sup 5267 5267 0 0.00 0 5146 9 0 0
v_min_non_paid_invoice 5834 5834 0 0.00 0 4809 16 0 0
v_min_non_paid_realizn 6857 6857 0 0.00 0 4825 15 0 0
v_our_firm 10086 10086 0 0.00 0 50 4 0 0
v_reserve_write_off 19338 19338 0 0.00 0 6201 12 0 0
x_lock_depdocs_on_canc_invoice 11542 11542 0 0.00 0 13079 128 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 9196 362 0 0
x_make_qstorno_1000_1200 5355 5355 0 0.00 2 77303 1160 0 0
x_make_qstorno_1200_2000 5270 5270 0 0.00 4 115075 2883 0 0
x_make_qstorno_2100_3300 456305 456305 0 0.00 0 112904 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-10-2022 04:38:51 to 07-10-2022 04:38:55.

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:24 2 8192000000 8797573968 8808550400 3901600 0.047 2 1 2 0 4000912 63536 35776
02:08:25 23322 8192000000 9718253840 10016280576 790655344 8.802 100 89 178 0 820669264 7925024 22088896
02:09:57 15128 8192000000 9754222960 10058838016 819965408 9.098 100 90 180 0 853970240 12643008 21361824
02:11:15 17620 8192000000 9788088320 10104762368 844088016 9.341 100 82 164 0 886478784 22925440 19465328
02:12:38 7195 8192000000 9852607856 10184888320 897697232 9.875 100 78 150 1 958775136 44569632 16508272
02:13:48 3053 8192000000 9899556560 10242965504 945090960 10.343 100 75 145 0 1011816208 51665856 15059392
02:14:54 1931 8192000000 9967645856 10320531456 986672608 10.749 100 87 171 0 1076872416 72598288 17601520
02:15:58 1453 8192000000 9981468688 10343391232 1011486400 10.990 100 90 175 1 1091511376 62134624 17890352
02:17:02 1181 8192000000 9997189088 10367729664 1031542784 11.183 100 91 170 0 1106327840 57010784 17774272
02:18:05 1199 8192000000 10025972480 10400055296 1048067216 11.342 100 93 174 0 1133957024 67299696 18590112
02:19:07 1161 8192000000 10028368448 10405650432 1055277936 11.411 100 90 174 1 1141854432 68929568 17646928
02:20:10 1178 8192000000 10054182128 10434015232 1059699184 11.454 100 93 179 2 1160708384 83794896 17214304
02:21:12 1225 8192000000 10056158976 10437664768 1066094704 11.515 100 95 186 0 1161824208 77926688 17802816
02:22:14 1099 8192000000 10041528192 10428887040 1072256144 11.574 100 92 181 1 1154702432 63658016 18788272
02:23:16 4352 8192000000 10025405872 10412814336 1077773344 11.626 100 69 120 2 1139828592 49530000 12525248
02:24:21 1108 8192000000 10033485984 10418610176 1080541264 11.653 100 89 167 0 1145026576 47199200 17286112
02:25:23 7678 8192000000 10065587824 10459742208 1083629984 11.682 100 60 114 1 1171510272 75852656 12027632
02:26:32 1583 8192000000 10068358976 10467733504 1084463744 11.690 100 86 169 1 1183759616 82306512 16989360
02:27:35 1332 8192000000 10039347008 10424463360 1079160560 11.639 100 94 175 0 1146286240 49440832 17684848
02:28:37 3457 8192000000 10027994480 10416783360 1068987936 11.542 99 74 141 1 1140267312 55572848 15706528
02:29:41 1005 8192000000 10035952112 10420011008 1061830624 11.474 100 87 167 1 1155114864 75820224 17464016
02:30:43 1192 8192000000 9993096336 10369888256 1043082960 11.294 99 96 172 4 1103696704 42199776 18413968
02:31:46 1243 8192000000 10015076464 10388250624 1024511680 11.116 98 90 178 1 1120792032 77749328 18531024
02:32:47 1144 8192000000 9978091280 10352754688 1031018640 11.178 100 87 169 3 1090004992 41661248 17325104
02:33:49 1037 8192000000 9970033856 10341978112 1008633520 10.962 99 86 161 3 1079721376 53015712 18072144
02:34:51 1776 8192000000 9964247776 10334855168 1008970240 10.965 99 80 144 3 1096789392 72166848 15652304
02:35:53 915 8192000000 9976465264 10353577984 1038984736 11.255 100 85 165 2 1097847792 41520896 17342160
02:36:55 1003 8192000000 9984439152 10361712640 1046774944 11.330 100 94 173 5 1103676496 39252352 17649200
02:38:02 19407 8192000000 10000573392 10392064000 1064984416 11.504 100 62 120 0 1118040208 41069344 11986448
02:39:27 18216 8192000000 10042468880 10429247488 1086006192 11.705 100 80 147 1 1165911216 65337728 14567296
02:40:48 6611 8192000000 10089222352 10481967104 1100695552 11.844 100 63 123 1 1205332768 92189472 12447744
02:41:58 2967 8192000000 10148427696 10536624128 1117428144 12.003 100 78 145 0 1254937952 122047744 15462064
02:43:03 2634 8192000000 10169289936 10561421312 1115630416 11.986 100 78 148 1 1291330080 159802256 15897408
02:44:12 1267 8192000000 10227550240 10620932096 1134308704 12.162 100 89 171 2 1341776064 188020128 19447232
02:45:19 893 8192000000 10222930064 10617069568 1147751488 12.288 100 85 161 2 1337241184 172285264 17204432
02:46:22 1973 8192000000 10266908320 10661515264 1156700768 12.372 100 86 171 0 1373243184 197774096 18768320
02:47:28 1207 8192000000 10302568880 10706370560 1169461936 12.492 100 91 178 0 1419150176 228899984 20788256
02:48:33 1346 8192000000 10334536368 10737410048 1168171808 12.480 100 94 179 3 1444739808 256962832 19605168
02:49:37 2041 8192000000 10364320832 10791563264 1171066224 12.507 100 87 169 1 1468863152 278624480 19172448
02:50:43 868 8192000000 10403998960 10821926912 1186725504 12.653 100 97 184 1 1510732416 302221248 21785664
02:51:47 1077 8192000000 10365383728 10796679168 1180943872 12.599 99 96 186 0 1479820928 277441440 21435616
02:52:51 2462 8192000000 10386278560 10803965952 1181478464 12.604 100 82 154 2 1499223344 300239952 17504928
02:53:58 1318 8192000000 10416306992 10830798848 1180184400 12.592 100 86 164 3 1523707216 324449440 19073376
02:55:02 780 8192000000 10404010448 10835210240 1158558592 12.390 99 91 179 1 1514215488 333247920 22408976
02:56:06 1267 8192000000 10410846928 10838999040 1176481776 12.557 100 88 172 1 1518922672 321501776 20939120
02:57:09 1321 8192000000 10412734896 10856226816 1180740720 12.597 100 93 181 0 1518548576 316079568 21728288
02:58:12 865 8192000000 10435303248 10851799040 1182730240 12.616 100 97 190 0 1544738960 338911328 23097392
02:59:16 2648 8192000000 10479594720 10882772992 1180903088 12.599 100 89 163 0 1576454704 376579888 18971728
03:00:22 1393 8192000000 10478405824 10937217024 1188479808 12.669 100 73 146 0 1583227824 376888032 17859984
03:01:32 10790 8192000000 10520660704 10961465344 1187781680 12.663 100 54 107 0 1633523872 431642768 14099424
03:02:55 14275 8192000000 10566322480 11009093632 1185384320 12.640 100 52 102 0 1656534192 457185440 13964432
03:04:11 21387 8192000000 10556301312 11003723776 1178463984 12.576 100 37 74 0 1662532256 473657056 10411216
03:05:35 28860 8192000000 10475584704 10930528256 1156613968 12.372 99 35 69 1 1566463216 400731872 9117376
03:07:20 17028 8192000000 10417506432 10937438208 1141903008 12.233 99 36 72 0 1523552352 372192336 9457008
03:09:03 17619 8192000000 10212838560 10685300736 1136275344 12.180 100 39 77 1 1357450944 212447264 8728336
03:10:26 42938 8192000000 10112426400 10627989504 1129794912 12.119 99 11 22 0 1281182768 148900160 2487696
03:12:09 82821 8192000000 10110844208 10550468608 1121059936 12.037 98 29 51 1 1172844112 45258432 6525744
03:14:43 81630 8192000000 10072814288 10533625856 1121323392 12.039 98 22 42 1 1186976976 61211072 4442512
03:17:33 105435 8192000000 10102398000 10565877760 1114375952 11.974 97 27 48 1 1169959392 51086368 4497072
03:20:27 92965 8192000000 10092482928 10527047680 1122600512 12.052 98 56 102 2 1170760624 35824016 12336096
03:23:01 22435 8192000000 10162394352 10616705024 1164651568 12.447 100 33 66 0 1258991936 86171744 8168624
03:24:31 28319 8192000000 10204955936 10679087104 1151192224 12.321 99 39 78 0 1301640560 141692528 8755808
03:26:11 52536 8192000000 10142968448 10636050432 1128587568 12.108 98 17 34 0 1252263952 119547808 4128576
03:28:14 35036 8192000000 10117925328 10657947648 1132795040 12.148 99 9 18 0 1237604640 102578480 2231120
03:29:52 22211 8192000000 10077471968 10526830592 1137918208 12.196 100 21 43 0 1179200976 36687552 4595216
03:31:14 116748 8192000000 10100503344 10623016960 1104713456 11.882 97 9 18 0 1155384240 48789728 1881056
03:34:16 62223 8192000000 10059802160 10540027904 1122369968 12.049 99 17 34 0 1162491568 36530464 3591136
03:36:26 51993 8192000000 10144792560 10599260160 1134133712 12.160 99 27 54 0 1208424720 68116112 6174896
03:38:40 36775 8192000000 10083529216 10530070528 1122570368 12.051 98 18 36 0 1154774336 28480848 3723120
03:40:59 42844 8192000000 10082222064 10517512192 1087179440 11.716 95 42 78 1 1128821984 33318416 8324128
03:42:46 44652 8192000000 10132653488 10564501504 1098301712 11.822 97 36 73 0 1207498672 101457712 7739248
03:44:32 14328 8192000000 10096841952 10513924096 1108153792 11.915 98 62 118 0 1173767440 53737232 11876416
03:45:49 34559 8192000000 10134509504 10575204352 1112954224 11.960 99 53 106 0 1195384928 71754576 10676128
03:47:23 114851 8192000000 10074004432 10533195776 1111902496 11.950 98 27 55 0 1183292848 64746192 6644160
03:50:20 30555 8192000000 10059096224 10551455744 1108206528 11.915 98 16 32 2 1158174352 46421968 3545856
03:51:56 45635 8192000000 10084762000 10548011008 1136815008 12.186 100 14 28 0 1192855872 53051360 2989504
03:53:51 64252 8192000000 10058678544 10542063616 1104714688 11.882 98 19 38 0 1173585184 64786736 4083760
03:55:57 60482 8192000000 10086437920 10537529344 1108688544 11.920 98 22 44 0 1173553104 60054176 4810384
03:58:03 112135 8192000000 10125573216 10590961664 1138620800 12.203 100 12 24 1 1251399552 109973296 2805456
04:00:56 59144 8192000000 10094233840 10558418944 1140481360 12.220 100 24 48 0 1193686032 48546304 4658368
04:03:02 28906 8192000000 10175198944 10640392192 1158497104 12.389 100 49 96 0 1262488800 94300528 9691168
04:04:35 39520 8192000000 10169655856 10609561600 1154795248 12.354 100 33 66 0 1270689584 108706240 7188096
04:06:15 16251 8192000000 10105350672 10566045696 1139425744 12.210 99 38 76 0 1221126016 73714752 7985520
04:07:34 26149 8192000000 10201214688 10648539136 1150057648 12.310 100 42 82 0 1289862768 131134352 8670768
04:09:01 39145 8192000000 10109867952 10569588736 1148910416 12.299 100 25 50 0 1222364512 68599312 4854784
04:10:41 118199 8192000000 10076250112 10542678016 1152156176 12.330 100 21 42 0 1211513584 55183536 4173872
04:13:41 79829 8192000000 10098186800 10558902272 1145605824 12.268 99 15 30 0 1199406080 50467152 3333104
04:16:01 79050 8192000000 10129800128 10612211712 1141574688 12.230 98 18 36 0 1219511664 74071408 3865568
04:18:27 64725 8192000000 10112317488 10578853888 1100253232 11.840 96 15 30 0 1170056912 66466736 3336944
04:20:33 31584 8192000000 10065962880 10516201472 1113577824 11.966 98 26 52 0 1170428672 51657472 5193376
04:22:05 28100 8192000000 10114104800 10561863680 1139449040 12.210 99 28 55 0 1198080784 52918976 5712768
04:23:33 27791 8192000000 10133093808 10606067712 1148854224 12.299 100 48 93 1 1257154832 98573216 9727392
04:25:04 54405 8192000000 10119000352 10589671424 1124518640 12.070 98 35 70 1 1201447488 70146608 6782240
04:27:02 30751 8192000000 10112654096 10575667200 1121988688 12.046 99 42 82 0 1217860864 87623488 8248688
04:28:34 33577 8192000000 10160084832 10600878080 1122086272 12.047 99 45 90 0 1236298832 105078912 9133648
04:30:10 127954 8192000000 10043121664 10528030720 1106424816 11.899 98 23 46 0 1158635696 47479568 4731312
04:33:21 75134 8192000000 10034861040 10497695744 1070876304 11.560 95 17 32 1 1101697312 27009504 3811504
04:35:37 77272 8192000000 10048863808 10504728576 1099061280 11.829 99 30 58 0 1134869424 29729984 6078160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 07-10-2022 04:38:57 to 07-10-2022 04:39:14.

Exceptions occured during test was in run

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

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

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          218680
MON$OLDEST_ACTIVE               221320
MON$OLDEST_SNAPSHOT             221320
MON$NEXT_TRANSACTION            221320
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                       2066080
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             778
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 ---
:105: 
:106: mscs-test-161.budzdorov.ru    Fri Oct  7 04:37:21 2022
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:108: 
--- end of diff output ---

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

07.10.22 04:39:18. 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-07 04:39:20 No severe PSQL-related problems occured
07.10.22 04:39:22. End of report.