Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.12.2021 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 16202880 kB
MemAvailable 31456488 kB
Buffers 20 kB
Cached 15450092 kB
SwapCached 0 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.1
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 2021-12-14 04:37:10
DTS_END                         2021-12-14 04:37:10.1980
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55138
TRN_ID                          226682
ATT_ID                          601
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: 8733.32 24981 1047998
customer order: creation 161.98 400 19437
customer order: refuse 13.76 364 1651
order to supplier: creation 42.92 1467 5150
order to supplier: removal 3.08 139797 369
invoice (draft): creation 43.13 3701 5176
invoice (draft): removal 49.23 1077 5908
invoice accept: apply 40.54 21671 4865
invoice accept: cancel 45.33 923 5440
customer reserve: creation 3666.60 218 439992
customer reserve: removal 4377.23 76 525268
realization accept: apply 79.78 219 9573
realization accept: cancel 89.39 128 10727
payment from customer: creation 55.14 115 6617
payment from customer: removal 5.64 147 677
payment to supplier: creation 46.74 139 5609
payment to supplier: removal 3.18 114 382
service: total inventory turnovers 3.33 4577 400
service: total monetary turnovers 5.62 736 674
service: aggregate perf. data 0.66 26782 79
service: refresh index statistics 0.03 296959 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-12-2021 04:39:30 to 14-12-2021 04:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3580
TEST_TIME 2 5835
TEST_TIME 3 6217
TEST_TIME 4 6558
TEST_TIME 5 6213
TEST_TIME 6 6045
TEST_TIME 7 6784
TEST_TIME 8 6650
TEST_TIME 9 6912
TEST_TIME 10 7097
TEST_TIME 11 7086
TEST_TIME 12 7450
TEST_TIME 13 7491
TEST_TIME 14 7930
TEST_TIME 15 8336
TEST_TIME 16 8462
TEST_TIME 17 8347
TEST_TIME 18 8531
TEST_TIME 19 8586
TEST_TIME 20 8833
TEST_TIME 21 9216
TEST_TIME 22 9283
TEST_TIME 23 9580
TEST_TIME 24 9442
TEST_TIME 25 9579
TEST_TIME 26 9444
TEST_TIME 27 9541
TEST_TIME 28 9647
TEST_TIME 29 9839
TEST_TIME 30 10471
TEST_TIME 31 10992
TEST_TIME 32 11657
TEST_TIME 33 11417
TEST_TIME 34 11440
TEST_TIME 35 11189
TEST_TIME 36 11061
TEST_TIME 37 10921
TEST_TIME 38 10744
TEST_TIME 39 10535
TEST_TIME 40 10452
TEST_TIME 41 10357
TEST_TIME 42 10257
TEST_TIME 43 10096
TEST_TIME 44 9970
TEST_TIME 45 9940
TEST_TIME 46 9980
TEST_TIME 47 10172
TEST_TIME 48 10034
TEST_TIME 49 9837
TEST_TIME 50 9756
TEST_TIME 51 9631
TEST_TIME 52 9510
TEST_TIME 53 9469
TEST_TIME 54 9381
TEST_TIME 55 9361
TEST_TIME 56 9274
TEST_TIME 57 9168
TEST_TIME 58 9064
TEST_TIME 59 8968
TEST_TIME 60 9029
TEST_TIME 61 9026
TEST_TIME 62 9129
TEST_TIME 63 9087
TEST_TIME 64 9138
TEST_TIME 65 9209
TEST_TIME 66 9225
TEST_TIME 67 9170
TEST_TIME 68 9168
TEST_TIME 69 9225
TEST_TIME 70 9310
TEST_TIME 71 9243
TEST_TIME 72 9263
TEST_TIME 73 9265
TEST_TIME 74 9252
TEST_TIME 75 9177
TEST_TIME 76 9133
TEST_TIME 77 9154
TEST_TIME 78 9119
TEST_TIME 79 9054
TEST_TIME 80 8992
TEST_TIME 81 8954
TEST_TIME 82 8977
TEST_TIME 83 8919
TEST_TIME 84 8896
TEST_TIME 85 9008
TEST_TIME 86 8993
TEST_TIME 87 9027
TEST_TIME 88 9009
TEST_TIME 89 9029
TEST_TIME 90 9086
TEST_TIME 91 9094
TEST_TIME 92 9064
TEST_TIME 93 9092
TEST_TIME 94 9084
TEST_TIME 95 9067
TEST_TIME 96 9004
TEST_TIME 97 8972
TEST_TIME 98 8965
TEST_TIME 99 8971
TEST_TIME 100 8918
TEST_TIME 101 8876
TEST_TIME 102 8837
TEST_TIME 103 8780
TEST_TIME 104 8775
TEST_TIME 105 8810
TEST_TIME 106 8792
TEST_TIME 107 8793
TEST_TIME 108 8816
TEST_TIME 109 8828
TEST_TIME 110 8866
TEST_TIME 111 8895
TEST_TIME 112 8918
TEST_TIME 113 8884
TEST_TIME 114 8897
TEST_TIME 115 8929
TEST_TIME 116 8903
TEST_TIME 117 8853
TEST_TIME 118 8823
TEST_TIME 119 8803
TEST_TIME 120 8738

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-12-2021 04:39:34 to 14-12-2021 04:39:37.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1454741 1454741 0 0.00 0 29748 8 0 0
doc_list_biud 1454735 1454735 0 0.00 0 64794 35 0 0
sp_add_invoice_to_stock 4953 4865 88 1.77 16 263394 21671 0 88
sp_cancel_adding_invoice 5440 5440 0 0.00 6 61701 923 0 0
sp_cancel_client_order 1651 1651 0 0.00 4 7820 365 0 0
sp_cancel_customer_reserve 525268 525268 0 0.00 1 35121 77 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 11771 148 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 7670 114 0 0
sp_cancel_supplier_invoice 5916 5908 8 0.13 2 64798 1078 0 8
sp_cancel_supplier_order 369 369 0 0.00 6 940060 139798 0 0
sp_cancel_write_off 10727 10727 0 0.00 1 10790 128 0 0
sp_client_order 19437 19437 0 0.00 4 38824 401 0 0
sp_customer_reserve 439992 439992 0 0.00 0 54805 219 0 0
sp_fill_shopping_cart 29758 29758 0 0.00 1 26475 49 0 0
sp_fill_shopping_cart_clo_res 439983 439983 0 0.00 0 16707 17 0 0
sp_get_clo_for_invoice 4856 4856 0 0.00 0 28588 418 0 0
sp_kill_cost_storno 17226 17226 0 0.00 0 28479 71 0 0
sp_kill_qstorno_ret_qs2qd 1067540 1067540 0 0.00 0 64793 41 0 0
sp_kill_qty_storno 563791 563791 0 0.00 0 64793 89 0 0
sp_lock_selected_doc 19760 19760 0 0.00 0 7092 6 0 0
sp_make_cost_storno 26655 26655 0 0.00 0 29745 59 0 0
sp_multiply_rows_for_pdistr 26654 26654 0 0.00 0 28229 37 0 0
sp_multiply_rows_for_qdistr 19437 19437 0 0.00 0 38815 185 0 0
sp_pay_from_customer 6617 6617 0 0.00 4 14430 115 0 0
sp_pay_to_supplier 5609 5609 0 0.00 5 28269 139 0 0
sp_payment_common 12226 12226 0 0.00 1 10591 29 0 0
sp_qd_handle_on_cancel_clo 1651 1651 0 0.00 0 7795 338 0 0
sp_qd_handle_on_invoice_upd_sts 10295 10295 0 0.00 0 34248 410 0 0
sp_qd_handle_on_reserve_upd_sts 9573 9573 0 0.00 0 17749 75 0 0
sp_reserve_write_off 9573 9573 0 0.00 7 29766 219 0 0
sp_supplier_invoice 5176 5176 0 0.00 7 62122 3701 0 0
sp_supplier_order 5150 5150 0 0.00 5 62750 1467 0 0
srv_aggregate_perf_data 79 79 0 0.00 2763 309344 26783 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1956 270330 47254 0 0
srv_find_qd_qs_mism 1002628 1002628 0 0.00 0 13920 7 0 0
srv_make_invnt_saldo 400 400 0 0.00 55 97283 4577 0 0
srv_make_money_saldo 674 674 0 0.00 1 17131 736 0 0
srv_recalc_idx_stat 4 4 0 0.00 180241 498079 296960 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4865 4865 0 0.00 0 17393 193 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 9516 119 0 0
t$perf-norm:sp_cancel_client_order 1651 1651 0 0.00 0 2732 19 0 0
t$perf-norm:sp_cancel_customer_reserve 587 587 0 0.00 0 9750 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 1688 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 596 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 2721 16 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 0 31595 1825 0 0
t$perf-norm:sp_cancel_write_off 908 908 0 0.00 0 1090 6 0 0
t$perf-norm:sp_client_order 19437 19437 0 0.00 0 15948 23 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 2767 6 0 0
t$perf-norm:sp_pay_from_customer 6617 6617 0 0.00 0 9753 19 0 0
t$perf-norm:sp_pay_to_supplier 5609 5609 0 0.00 0 16631 22 0 0
t$perf-norm:sp_reserve_write_off 9573 9573 0 0.00 0 4668 12 0 0
t$perf-norm:sp_supplier_invoice 5176 5176 0 0.00 0 9505 23 0 0
t$perf-norm:sp_supplier_order 5150 5150 0 0.00 0 11217 22 0 0
t$perf-norm:srv_aggregate_perf_data 79 79 0 0.00 0 131 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 144 7 0 0
t$perf-norm:srv_make_invnt_saldo 400 400 0 0.00 0 5542 24 0 0
t$perf-norm:srv_make_money_saldo 674 674 0 0.00 0 6974 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9886 9710 176 1.78 0 6296 16 0 176
v_all_customers 35376 35376 0 0.00 0 68 1 0 0
v_all_suppliers 28628 28628 0 0.00 0 24 1 0 0
v_all_wares 38874 38874 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 2550 13 0 0
v_cancel_client_order 3302 3302 0 0.00 0 832 3 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 6734 16 0 0
v_cancel_customer_reserve 1174 1174 0 0.00 0 663 5 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 2551 7 0 0
v_cancel_supplier_invoice 736 720 16 2.17 0 2550 19 0 16
v_cancel_supplier_order 738 738 0 0.00 0 519 5 0 0
v_cancel_write_off 1816 1816 0 0.00 0 1431 14 0 0
v_max_id_clo_ord 5149 5149 0 0.00 0 3213 2 0 0
v_max_id_clo_res 947 947 0 0.00 0 4200 16 0 0
v_max_id_ord_sup 5172 5172 0 0.00 0 9350 5 0 0
v_max_non_paid_invoice 1616 1616 0 0.00 0 99 2 0 0
v_max_non_paid_realizn 3498 3498 0 0.00 0 639 2 0 0
v_min_id_clo_ord 5149 5149 0 0.00 0 3483 7 0 0
v_min_id_clo_res 947 947 0 0.00 0 495 2 0 0
v_min_id_ord_sup 5172 5172 0 0.00 0 3044 5 0 0
v_min_non_paid_invoice 5609 5609 0 0.00 0 7410 20 0 0
v_min_non_paid_realizn 6617 6617 0 0.00 0 10585 23 0 0
v_our_firm 9736 9736 0 0.00 0 18 1 0 0
v_reserve_write_off 19146 19146 0 0.00 0 1966 4 0 0
x_lock_depdocs_on_canc_invoice 10988 10988 0 0.00 0 11948 132 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 10702 421 0 0
x_make_qstorno_1000_1200 5150 5150 0 0.00 2 62734 1381 0 0
x_make_qstorno_1200_2000 5176 5176 0 0.00 3 60323 3517 0 0
x_make_qstorno_2100_3300 439991 439991 0 0.00 0 44217 199 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-12-2021 04:39:38 to 14-12-2021 04:39:42.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:14 5 8192000000 8799251808 8810582016 5260816 0.064 2 1 2 0 5360240 63648 35776
02:08:14 45679 8192000000 9729577072 10028109824 803157840 8.928 100 90 180 0 833020368 8012128 21850400
02:10:05 27044 8192000000 9777373520 10083680256 842878832 9.329 100 83 166 0 875458864 12927312 19652720
02:11:43 13446 8192000000 9814236320 10135711744 871345488 9.613 100 80 160 0 918642400 28880240 18416672
02:13:03 7657 8192000000 9880158832 10223472640 938244384 10.276 100 75 150 0 993853728 39410400 16198944
02:14:15 6215 8192000000 9953416288 10306469888 985751200 10.740 100 76 152 2 1060270688 58789024 15730464
02:15:29 8685 8192000000 9974872160 10338656256 1011246624 10.987 100 76 152 1 1097633744 72007520 14379600
02:16:40 2963 8192000000 10009582272 10376634368 1035059648 11.217 100 83 166 2 1125097904 72977552 17060704
02:17:45 2086 8192000000 10023687904 10391052288 1053089184 11.390 100 88 175 1 1141992496 71143456 17759856
02:18:49 16137 8192000000 10024523472 10404433920 1065762704 11.512 100 64 127 1 1142850096 63593088 13494304
02:20:06 9298 8192000000 9989394160 10382675968 1075634256 11.606 100 41 82 0 1122473056 37748256 9090544
02:21:18 6173 8192000000 10001067520 10407542784 1079639248 11.644 100 30 58 0 1129627120 44120960 5866912
02:22:26 59184 8192000000 9981199072 10388193280 1091060144 11.753 100 38 74 1 1111625312 12143360 8421808
02:24:27 5613 8192000000 10022848704 10427015168 1097544480 11.814 100 35 70 0 1134382656 29907312 6930864
02:25:33 6240 8192000000 10028418272 10434220032 1100822912 11.845 100 41 82 0 1136484112 27118160 8543040
02:26:41 57610 8192000000 10010862208 10412892160 1081861104 11.665 98 36 72 0 1110738384 20208400 8668880
02:28:39 3359 8192000000 9988173328 10376323072 1061172272 11.468 99 56 111 3 1089507296 16751280 11583744
02:29:45 6376 8192000000 9973365840 10363047936 1036823040 11.234 99 55 110 0 1076134976 27199392 12112544
02:30:52 3544 8192000000 9924467648 10302889984 993688368 10.817 97 46 91 2 1024029888 19222848 11118672
02:31:58 23322 8192000000 9928275152 10314571776 983323376 10.717 96 33 65 0 1000959072 10228048 7407648
02:33:25 4349 8192000000 9950232512 10326974464 1022095824 11.092 99 41 80 2 1053226592 22892800 8237968
02:34:30 8990 8192000000 9975634896 10380902400 1047162848 11.333 100 33 66 0 1083004416 28871952 6969616
02:35:44 4471 8192000000 9974208720 10363224064 1062997424 11.485 100 49 95 1 1082978608 8612160 11369024
02:36:54 13719 8192000000 9982891040 10381185024 1070123904 11.553 100 32 64 1 1091119072 14036624 6958544
02:38:19 8476 8192000000 10025559504 10417561600 1099459104 11.833 100 55 108 1 1135594560 24858224 11277232
02:39:31 9105 8192000000 10082564256 10482978816 1110807328 11.940 100 49 98 1 1196696080 75993264 9895488
02:40:43 9618 8192000000 10093830336 10498760704 1119313408 12.021 100 61 122 0 1208638880 76377632 12947840
02:42:04 7938 8192000000 10133095040 10532675584 1130865616 12.130 100 60 120 2 1240790144 97492512 12432016
02:43:16 7807 8192000000 10187827232 10582761472 1135904608 12.177 100 77 154 0 1294308816 142258160 16146048
02:44:29 1956 8192000000 10169104736 10574385152 1143530544 12.249 100 78 152 1 1276541088 116816064 16194480
02:45:35 3352 8192000000 10183199168 10586042368 1155598400 12.362 100 78 154 1 1286439568 114529536 16311632
02:46:42 5438 8192000000 10221077664 10618130432 1164374448 12.444 100 69 137 1 1322242912 143086048 14782416
02:47:52 2990 8192000000 10252977200 10663612416 1174659104 12.540 100 58 115 0 1360920400 173084048 13177248
02:49:02 4734 8192000000 10250853776 10651561984 1117551024 12.004 96 63 125 2 1326699872 195255344 13893504
02:50:13 5392 8192000000 10280781344 10682392576 1142929104 12.243 100 74 148 1 1371672032 212177376 16565552
02:51:22 2985 8192000000 10304858640 10702807040 1147542208 12.286 100 73 146 0 1405509520 241446048 16521264
02:52:29 2663 8192000000 10286764864 10698895360 1156733648 12.373 100 71 140 3 1385808624 213478080 15596896
02:53:35 4501 8192000000 10287806208 10717536256 1146160688 12.273 99 53 106 1 1395114096 237036880 11916528
02:54:48 4350 8192000000 10346452816 10748370944 1161496512 12.417 100 69 136 1 1439930832 262466832 15967488
02:55:57 6649 8192000000 10313787280 10722476032 1126238144 12.086 99 59 115 2 1407301264 268122672 12940448
02:57:11 7329 8192000000 10335430704 10746986496 1140812768 12.223 100 52 102 2 1437270928 283145984 13312176
02:58:26 3701 8192000000 10289650544 10685665280 1137274016 12.190 100 80 161 0 1397397936 241452992 18670928
02:59:35 14930 8192000000 10322056736 10743054336 1140454352 12.220 99 37 74 0 1405934480 255910656 9569472
03:00:53 8376 8192000000 10330062192 10751729664 1160250544 12.406 100 37 73 1 1428589232 259219760 9118928
03:02:03 4754 8192000000 10403552272 10818211840 1163346016 12.435 100 42 84 0 1501358080 327486992 10525072
03:03:13 12872 8192000000 10423509104 10836385792 1150258848 12.312 98 43 85 0 1507017360 345973616 10784896
03:04:34 17090 8192000000 10490849040 10926624768 1156219600 12.368 100 42 84 1 1596851696 429532608 11099488
03:06:29 17077 8192000000 10479289504 10970230784 1137128608 12.189 100 36 72 0 1593512528 447312272 9071648
03:07:56 15204 8192000000 10346856960 10907492352 1112988992 11.961 100 23 46 0 1480335376 361348272 5998112
03:09:31 69062 8192000000 10096119392 10540224512 1130935792 12.130 100 19 38 0 1218477904 83522304 4019808
03:11:40 90404 8192000000 10081630832 10534133760 1110119696 11.934 98 12 24 0 1153116608 40922384 2074528
03:14:20 54970 8192000000 10060354544 10493943808 1106683520 11.901 98 13 26 0 1137178368 28302080 2192768
03:16:20 48921 8192000000 10079300288 10517463040 1100230720 11.840 98 24 48 0 1164712304 58635088 5846496
03:18:11 56814 8192000000 10129878960 10555293696 1130654496 12.128 100 36 70 4 1225361216 86630608 8076112
03:20:08 86012 8192000000 10194691504 10623696896 1140713824 12.222 99 34 68 0 1260601536 112376400 7511312
03:22:39 93025 8192000000 10191018480 10751098880 1129996320 12.121 99 17 34 1 1322666864 188268240 4402304
03:25:21 270329 8192000000 10068859760 10512961536 1146960016 12.281 100 14 28 0 1189931488 40319248 2652224
03:30:52 79937 8192000000 10063891920 10529263616 1148817840 12.298 100 11 22 0 1192357552 41352624 2187088
03:33:15 63561 8192000000 10090546576 10536800256 1124538016 12.070 98 14 27 2 1172234960 44865168 2831776
03:35:18 83100 8192000000 10145074304 10606198784 1121906704 12.045 98 26 52 2 1210851712 82664064 6280944
03:37:50 74926 8192000000 10155786400 10652237824 1143749472 12.251 100 16 32 0 1278914576 131871968 3293136
03:40:05 80712 8192000000 10116130928 10578309120 1104738352 11.883 96 21 42 0 1161081216 52356208 3986656
03:43:39 91909 8192000000 10101434448 10549661696 1087104784 11.715 94 15 29 2 1105161552 14983056 3073712
03:46:15 110472 8192000000 10098430000 10608033792 1084859792 11.694 94 10 20 0 1155790768 68679536 2251440
03:49:05 31073 8192000000 10101713728 10533302272 1098757776 11.826 96 48 96 0 1136955664 28909104 9288784
03:50:38 11872 8192000000 10113563216 10546102272 1128225968 12.105 100 51 102 0 1209554528 71152528 10176032
03:51:54 93934 8192000000 10096409312 10542161920 1090436288 11.747 97 43 84 0 1187722576 88523104 8763184
03:54:40 50701 8192000000 10074930848 10565431296 1123172384 12.057 100 34 68 2 1179366144 49174944 7018816
03:56:31 73724 8192000000 10087674688 10536792064 1078505344 11.633 95 10 20 1 1131776640 51314832 1956464
03:58:55 82584 8192000000 10114663488 10611552256 1108117648 11.915 99 11 22 0 1220396784 109494128 2785008
04:01:19 61618 8192000000 10102093808 10589642752 1119221568 12.020 99 18 36 0 1236791040 113141936 4427536
04:03:25 75887 8192000000 10024621056 10461196288 1042594160 11.290 93 12 24 1 1080363488 35360288 2409040
04:05:46 38086 8192000000 10084660272 10518106112 1104702800 11.882 99 22 44 0 1162894048 53880496 4310752
04:07:25 73087 8192000000 10076352560 10537435136 1091979056 11.761 97 17 34 1 1158651200 63279920 3392224
04:09:39 19333 8192000000 10076629872 10502098944 1121330192 12.040 99 37 74 0 1159150416 30633376 7186848
04:11:00 29583 8192000000 10065924480 10486444032 1093970832 11.780 97 40 80 0 1141669344 39931456 7767056
04:12:30 128483 8192000000 10072819376 10526220288 1114853584 11.978 99 32 64 0 1168715456 47456736 6405136
04:15:42 55191 8192000000 10055696256 10507571200 1108057232 11.914 98 21 42 1 1165106048 52261072 4787744
04:17:46 50377 8192000000 10050050384 10509434880 1115845632 11.988 99 12 22 1 1146075072 27845712 2383728
04:19:41 87479 8192000000 10101094400 10569285632 1119320176 12.021 99 15 30 0 1177195600 54640592 3234832
04:22:09 74240 8192000000 10043819904 10513018880 1126323232 12.087 100 17 34 0 1175307168 44864832 4119104
04:24:23 54180 8192000000 10116161440 10572509184 1134562944 12.164 99 23 46 0 1205065584 65186304 5316336
04:26:21 64958 8192000000 10088460512 10557071360 1100375600 11.841 97 20 40 2 1188365360 83800848 4188912
04:28:27 75491 8192000000 10087563888 10573926400 1116620544 11.995 98 16 32 0 1174168096 54399728 3147824
04:30:49 64234 8192000000 10089618272 10522529792 1131352880 12.134 99 41 82 0 1202635232 62965392 8316960
04:32:53 73344 8192000000 10093032576 10534928384 1080319648 11.651 95 34 69 2 1125437088 38741824 6375616
04:35:11 63990 8192000000 10047961168 10494103552 1098624832 11.825 97 19 38 0 1142551616 39965968 3960816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-12-2021 04:39:44 to 14-12-2021 04:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-12-2021 04:40:00 to 14-12-2021 04:40:02.

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          225179
MON$OLDEST_ACTIVE               226821
MON$OLDEST_SNAPSHOT             226821
MON$NEXT_TRANSACTION            226821
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                       2086112
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2685 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2685 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2685 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2685 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Tue Dec 14 04:37:41 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

14.12.21 04:40:03. 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
2021-12-14 04:40:05 No severe PSQL-related problems occured
14.12.21 04:40:06. End of report.