Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.12.2021 13:37
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 16385676 kB
MemAvailable 31397264 kB
Buffers 0 kB
Cached 15260704 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-19 13:37:11
DTS_END                         2021-12-19 13:37:11.2230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54740
TRN_ID                          232511
ATT_ID                          617
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: 9086.55 18357 1090386
customer order: creation 169.96 426 20395
customer order: refuse 14.77 399 1772
order to supplier: creation 44.51 1423 5341
order to supplier: removal 3.29 139656 395
invoice (draft): creation 44.07 3896 5288
invoice (draft): removal 51.95 1024 6234
invoice accept: apply 40.40 21175 4848
invoice accept: cancel 47.54 928 5705
customer reserve: creation 3736.05 208 448326
customer reserve: removal 4649.47 77 557936
realization accept: apply 81.09 195 9731
realization accept: cancel 79.87 143 9584
payment from customer: creation 56.36 130 6763
payment from customer: removal 5.75 81 690
payment to supplier: creation 47.13 148 5656
payment to supplier: removal 3.45 78 414
service: total inventory turnovers 3.48 4520 418
service: total monetary turnovers 6.53 672 783
service: aggregate perf. data 0.86 20143 103
service: refresh index statistics 0.03 171819 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-12-2021 13:38:05 to 19-12-2021 13:38:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9576
TEST_TIME 2 6473
TEST_TIME 3 5578
TEST_TIME 4 5167
TEST_TIME 5 5177
TEST_TIME 6 5209
TEST_TIME 7 5858
TEST_TIME 8 5566
TEST_TIME 9 5340
TEST_TIME 10 5888
TEST_TIME 11 5804
TEST_TIME 12 6003
TEST_TIME 13 5837
TEST_TIME 14 6136
TEST_TIME 15 6193
TEST_TIME 16 6753
TEST_TIME 17 6953
TEST_TIME 18 6988
TEST_TIME 19 6981
TEST_TIME 20 7188
TEST_TIME 21 7243
TEST_TIME 22 7603
TEST_TIME 23 7845
TEST_TIME 24 7881
TEST_TIME 25 8528
TEST_TIME 26 8561
TEST_TIME 27 8735
TEST_TIME 28 8696
TEST_TIME 29 9219
TEST_TIME 30 10013
TEST_TIME 31 10473
TEST_TIME 32 10441
TEST_TIME 33 10473
TEST_TIME 34 10318
TEST_TIME 35 10218
TEST_TIME 36 10143
TEST_TIME 37 10137
TEST_TIME 38 10119
TEST_TIME 39 9987
TEST_TIME 40 9923
TEST_TIME 41 9841
TEST_TIME 42 9769
TEST_TIME 43 9667
TEST_TIME 44 9634
TEST_TIME 45 9837
TEST_TIME 46 9751
TEST_TIME 47 9638
TEST_TIME 48 9621
TEST_TIME 49 9560
TEST_TIME 50 9716
TEST_TIME 51 9702
TEST_TIME 52 9624
TEST_TIME 53 9564
TEST_TIME 54 9656
TEST_TIME 55 9643
TEST_TIME 56 9653
TEST_TIME 57 9680
TEST_TIME 58 9631
TEST_TIME 59 9662
TEST_TIME 60 9657
TEST_TIME 61 9650
TEST_TIME 62 9597
TEST_TIME 63 9468
TEST_TIME 64 9363
TEST_TIME 65 9409
TEST_TIME 66 9397
TEST_TIME 67 9413
TEST_TIME 68 9363
TEST_TIME 69 9341
TEST_TIME 70 9265
TEST_TIME 71 9205
TEST_TIME 72 9147
TEST_TIME 73 9198
TEST_TIME 74 9167
TEST_TIME 75 9192
TEST_TIME 76 9137
TEST_TIME 77 9181
TEST_TIME 78 9266
TEST_TIME 79 9227
TEST_TIME 80 9232
TEST_TIME 81 9357
TEST_TIME 82 9359
TEST_TIME 83 9355
TEST_TIME 84 9305
TEST_TIME 85 9223
TEST_TIME 86 9237
TEST_TIME 87 9258
TEST_TIME 88 9214
TEST_TIME 89 9159
TEST_TIME 90 9101
TEST_TIME 91 9086
TEST_TIME 92 9070
TEST_TIME 93 9049
TEST_TIME 94 9071
TEST_TIME 95 9069
TEST_TIME 96 9082
TEST_TIME 97 9140
TEST_TIME 98 9102
TEST_TIME 99 9059
TEST_TIME 100 9242
TEST_TIME 101 9246
TEST_TIME 102 9242
TEST_TIME 103 9238
TEST_TIME 104 9299
TEST_TIME 105 9341
TEST_TIME 106 9258
TEST_TIME 107 9190
TEST_TIME 108 9183
TEST_TIME 109 9146
TEST_TIME 110 9094
TEST_TIME 111 9117
TEST_TIME 112 9126
TEST_TIME 113 9085
TEST_TIME 114 9074
TEST_TIME 115 9099
TEST_TIME 116 9093
TEST_TIME 117 9105
TEST_TIME 118 9141
TEST_TIME 119 9153
TEST_TIME 120 9089

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-12-2021 13:38:09 to 19-12-2021 13:38:12.

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 1505036 1505036 0 0.00 0 16687 8 0 0
doc_list_biud 1505033 1505033 0 0.00 0 29134 36 0 0
sp_add_invoice_to_stock 4987 4848 139 2.78 47 175917 21175 0 139
sp_cancel_adding_invoice 5705 5705 0 0.00 4 62652 929 0 0
sp_cancel_client_order 1772 1772 0 0.00 5 9519 400 0 0
sp_cancel_customer_reserve 557936 557936 0 0.00 1 29136 77 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 2537 82 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 1667 78 0 0
sp_cancel_supplier_invoice 6244 6234 10 0.16 2 24198 1024 0 10
sp_cancel_supplier_order 395 395 0 0.00 7 1227318 139657 0 0
sp_cancel_write_off 9584 9584 0 0.00 1 16663 144 0 0
sp_client_order 20395 20395 0 0.00 4 43029 426 0 0
sp_customer_reserve 448326 448326 0 0.00 0 112767 208 0 0
sp_fill_shopping_cart 31021 31021 0 0.00 1 13049 42 0 0
sp_fill_shopping_cart_clo_res 448323 448323 0 0.00 0 14250 18 0 0
sp_get_clo_for_invoice 4845 4845 0 0.00 0 22282 369 0 0
sp_kill_cost_storno 16393 16393 0 0.00 0 11854 74 0 0
sp_kill_qstorno_ret_qs2qd 1132085 1132085 0 0.00 0 29131 41 0 0
sp_kill_qty_storno 596202 596202 0 0.00 0 29134 89 0 0
sp_lock_selected_doc 20199 20199 0 0.00 0 6146 3 0 0
sp_make_cost_storno 26995 26995 0 0.00 0 13730 58 0 0
sp_multiply_rows_for_pdistr 26995 26995 0 0.00 0 11674 37 0 0
sp_multiply_rows_for_qdistr 20395 20395 0 0.00 0 18300 206 0 0
sp_pay_from_customer 6763 6763 0 0.00 4 15869 131 0 0
sp_pay_to_supplier 5656 5656 0 0.00 5 14663 149 0 0
sp_payment_common 12419 12419 0 0.00 1 12779 25 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 9512 371 0 0
sp_qd_handle_on_invoice_upd_sts 10550 10550 0 0.00 0 23325 425 0 0
sp_qd_handle_on_reserve_upd_sts 9731 9731 0 0.00 0 12145 61 0 0
sp_reserve_write_off 9731 9731 0 0.00 7 13744 195 0 0
sp_supplier_invoice 5288 5288 0 0.00 7 121098 3896 0 0
sp_supplier_order 5341 5341 0 0.00 5 38428 1423 0 0
srv_aggregate_perf_data 103 103 0 0.00 2153 341230 20143 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 925 147528 43231 0 0
srv_find_qd_qs_mism 1043859 1043859 0 0.00 0 16685 6 0 0
srv_make_invnt_saldo 418 418 0 0.00 65 126770 4521 0 0
srv_make_money_saldo 783 783 0 0.00 1 16447 673 0 0
srv_recalc_idx_stat 4 4 0 0.00 111463 253061 171820 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4849 4849 0 0.00 0 29162 203 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 5438 119 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 4299 15 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 5658 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 934 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 644 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 354 354 0 0.00 0 12390 75 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 26177 1825 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 3824 12 0 0
t$perf-norm:sp_client_order 20395 20395 0 0.00 0 13716 20 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 3299 10 0 0
t$perf-norm:sp_pay_from_customer 6763 6763 0 0.00 0 3826 10 0 0
t$perf-norm:sp_pay_to_supplier 5656 5656 0 0.00 0 11691 13 0 0
t$perf-norm:sp_reserve_write_off 9731 9731 0 0.00 0 3273 11 0 0
t$perf-norm:sp_supplier_invoice 5288 5288 0 0.00 0 15401 23 0 0
t$perf-norm:sp_supplier_order 5341 5341 0 0.00 0 15042 22 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 239 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 81 2 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 4493 18 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 4568 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 40 11 0 0
v_add_invoice_to_stock 9968 9690 278 2.78 0 5154 17 0 278
v_all_customers 36502 36502 0 0.00 0 22 1 0 0
v_all_suppliers 29260 29260 0 0.00 0 18 1 0 0
v_all_wares 40790 40790 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1573 9 0 0
v_cancel_client_order 3544 3544 0 0.00 0 3123 6 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 801 5 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 1185 5 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 411 4 0 0
v_cancel_supplier_invoice 728 708 20 2.74 0 407 14 0 20
v_cancel_supplier_order 790 790 0 0.00 0 4624 10 0 0
v_cancel_write_off 1906 1906 0 0.00 0 3051 17 0 0
v_max_id_clo_ord 5341 5341 0 0.00 0 1921 2 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 3859 19 0 0
v_max_id_ord_sup 5285 5285 0 0.00 0 5552 5 0 0
v_max_non_paid_invoice 1652 1652 0 0.00 0 856 3 0 0
v_max_non_paid_realizn 3717 3717 0 0.00 0 1829 4 0 0
v_min_id_clo_ord 5341 5341 0 0.00 0 4791 9 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 6796 10 0 0
v_min_id_ord_sup 5285 5285 0 0.00 0 6270 6 0 0
v_min_non_paid_invoice 5656 5656 0 0.00 0 8950 15 0 0
v_min_non_paid_realizn 6763 6763 0 0.00 0 12773 19 0 0
v_our_firm 10380 10380 0 0.00 0 18 1 0 0
v_reserve_write_off 19462 19462 0 0.00 0 8900 6 0 0
x_lock_depdocs_on_canc_invoice 11585 11585 0 0.00 0 17889 136 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 21513 442 0 0
x_make_qstorno_1000_1200 5341 5341 0 0.00 2 38418 1330 0 0
x_make_qstorno_1200_2000 5288 5288 0 0.00 3 120900 3745 0 0
x_make_qstorno_2100_3300 448326 448326 0 0.00 0 112764 187 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2021 13:38:13 to 19-12-2021 13:38:17.

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
11:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:15 43369 8192000000 9739389712 10040004608 803511792 8.932 100 81 162 0 830947776 8043728 19392256
11:10:04 11001 8192000000 9782266720 10093846528 854137568 9.442 100 91 182 0 891032336 14960560 21934208
11:11:29 20005 8192000000 9812350608 10133872640 882126624 9.721 100 92 184 0 928726944 25288496 21311824
11:12:56 11291 8192000000 9869386720 10202202112 915024656 10.047 100 84 168 1 982955488 49056416 18874416
11:14:13 10004 8192000000 9951224032 10298494976 961792640 10.507 100 80 161 1 1053881344 75182496 16906208
11:15:26 3855 8192000000 9967522048 10320756736 998497040 10.864 100 85 169 2 1078144176 62697376 16949760
11:16:34 1877 8192000000 10006999040 10368638976 1023736896 11.108 100 88 174 1 1119947504 78310224 17900384
11:17:38 1553 8192000000 10031880832 10397261824 1050393504 11.364 100 93 185 1 1139974752 70203040 19378208
11:18:41 14548 8192000000 10035158640 10417127424 1069750736 11.550 100 77 154 0 1154576656 68723744 16102176
11:19:57 2687 8192000000 9992862848 10388226048 1074259536 11.593 100 37 73 0 1111057600 28922896 7875168
11:21:01 48514 8192000000 9999387088 10391121920 1083610496 11.682 100 47 94 4 1106476848 13087168 9779184
11:22:50 9701 8192000000 10008148800 10419077120 1086706144 11.711 100 29 57 0 1130054816 37097856 6250816
11:24:02 8153 8192000000 10009324272 10408013824 1093506352 11.776 100 37 74 1 1128899088 27216944 8175792
11:25:11 5353 8192000000 10011122224 10406211584 1097770192 11.816 100 39 77 1 1121526768 15116512 8640064
11:26:18 21476 8192000000 10010651712 10422845440 1082395664 11.670 99 29 60 1 1112225904 23643232 6187008
11:27:41 5492 8192000000 9997575408 10389876736 1072891552 11.580 98 25 51 1 1101378080 22112736 6373792
11:28:48 3399 8192000000 9958227488 10348294144 1038462544 11.250 98 48 94 2 1073541744 23453136 11626064
11:29:55 69817 8192000000 9930372512 10317721600 973438720 10.620 93 29 59 1 995264304 14785472 7040112
11:32:08 11048 8192000000 9929501984 10322079744 961263408 10.501 93 31 62 0 999645520 32093088 6289024
11:33:19 8278 8192000000 9961794720 10342633472 1030381600 11.172 100 38 73 0 1069852304 31911632 7559072
11:34:30 71353 8192000000 9968264976 10371297280 1060643584 11.463 100 32 64 1 1089660496 21263488 7753424
11:36:43 8204 8192000000 10002753888 10402570240 1072310448 11.574 100 41 82 2 1113337648 33015184 8012016
11:37:57 22478 8192000000 10054199200 10446987264 1098525504 11.824 100 60 119 0 1162719776 52028384 12165888
11:39:23 64736 8192000000 10072173456 10477838336 1113471600 11.965 100 45 91 1 1173837408 50944400 9421408
11:41:43 5532 8192000000 10119453200 10518138880 1132274992 12.143 100 67 133 1 1225457584 79091024 14091568
11:42:53 27396 8192000000 10171456608 10579427328 1141345264 12.228 100 57 112 2 1275410400 121903600 12161536
11:44:29 9626 8192000000 10163339632 10587340800 1151396688 12.323 100 47 94 1 1271551536 109514384 10640464
11:45:45 13217 8192000000 10172711776 10588291072 1153099392 12.339 100 41 81 1 1283539824 120463232 9977200
11:47:12 14044 8192000000 10206708800 10621194240 1160815936 12.411 100 43 84 1 1332202784 161330960 10055888
11:48:29 13340 8192000000 10241966080 10654547968 1170078528 12.498 100 57 114 1 1347333024 164774048 12480448
11:49:51 2770 8192000000 10269711168 10668773376 1170962496 12.506 100 88 166 4 1368743536 179448432 18332608
11:50:59 4169 8192000000 10318032816 10738991104 1186668336 12.652 100 75 151 0 1424404608 221053648 16682624
11:52:09 1788 8192000000 10349482960 10758209536 1185226640 12.639 100 84 166 0 1449333712 245891680 18215392
11:53:15 3482 8192000000 10339901712 10760003584 1171618736 12.512 100 82 164 2 1445158880 255702000 17838144
11:54:27 1609 8192000000 10311651168 10713972736 1149995456 12.309 98 79 158 2 1408822544 241119376 17707712
11:55:35 2666 8192000000 10380578528 10776371200 1170359968 12.500 100 84 165 1 1478399024 288772128 19266928
11:56:43 1835 8192000000 10421938464 10820026368 1155037296 12.357 99 88 176 0 1520306464 343930048 21339120
11:57:50 3233 8192000000 10446891104 10844209152 1165375248 12.454 100 94 188 2 1543886944 356653152 21858544
11:58:59 922 8192000000 10501482848 10895306752 1163429168 12.435 100 98 197 0 1592987424 405903808 23654448
12:00:08 1864 8192000000 10451456448 10851897344 1160798064 12.411 100 89 174 1 1547194368 365404672 20991632
12:01:15 4666 8192000000 10464860416 10872799232 1166196272 12.461 100 69 139 2 1555027504 371423056 17408176
12:02:24 17030 8192000000 10422032704 10903900160 1161847680 12.421 100 38 76 0 1562558608 390190992 10519936
12:03:51 9640 8192000000 10421824640 10891280384 1157313952 12.378 100 38 76 0 1542076752 374898544 9864256
12:05:15 4915 8192000000 10393933520 10824044544 1150088992 12.310 100 33 66 1 1495017360 336120448 8807920
12:06:25 19847 8192000000 10380407296 10899722240 1132052640 12.141 100 26 52 0 1530512832 391219952 7240240
12:08:06 35402 8192000000 10045858176 10543276032 1088225232 11.726 97 12 24 0 1167276848 77022672 2028944
12:10:35 64022 8192000000 10106103344 10534170624 1111166368 11.943 99 24 46 1 1176487936 60555984 4765584
12:12:45 96127 8192000000 10136302992 10625355776 1121422832 12.040 100 33 66 0 1206501840 78053168 7025840
12:15:41 46491 8192000000 10055050992 10475642880 1108438032 11.918 98 30 59 0 1139752160 25159888 6154240
12:17:31 86885 8192000000 10067435504 10489982976 1100232496 11.840 97 40 81 0 1166660224 58577024 7850704
12:20:00 25310 8192000000 10119747392 10549907456 1113942896 11.970 98 18 35 2 1195183728 77336624 3904208
12:21:41 17247 8192000000 10187844384 10623172608 1146549664 12.277 100 62 120 1 1289477264 129636736 13290864
12:23:02 144890 8192000000 10169297984 10627076096 1122117200 12.047 98 20 37 1 1207980608 81757088 4106320
12:26:29 63660 8192000000 10105462400 10608713728 1141709536 12.232 100 23 46 0 1247057440 99701904 5646000
12:28:32 47945 8192000000 10068170592 10536521728 1119202896 12.019 98 44 89 1 1175103104 46259040 9641168
12:30:21 81904 8192000000 10100719152 10606215168 1119270480 12.020 98 24 49 0 1154147408 29613248 5263680
12:32:55 12090 8192000000 10085374960 10508120064 1140257888 12.218 100 59 118 1 1170075792 17502960 12314944
12:34:12 70083 8192000000 10062473520 10507722752 1102539376 11.862 96 16 32 0 1141261616 35598720 3123520
12:36:25 61949 8192000000 10078412784 10545807360 1143470704 12.248 100 20 40 0 1195056656 47518832 4067120
12:38:28 128510 8192000000 10101906288 10543067136 1088377072 11.727 94 27 54 0 1127694480 33825968 5491440
12:42:11 40419 8192000000 10086371888 10585636864 1127344192 12.096 100 14 28 0 1211738832 81468256 2926384
12:43:53 61653 8192000000 10082539840 10562961408 1140543776 12.221 100 15 30 0 1192241760 48562912 3135072
12:46:11 46418 8192000000 10071726016 10539843584 1117516768 12.004 98 25 48 3 1164383232 41834224 5032240
12:47:59 62280 8192000000 10080828608 10552926208 1120139504 12.028 99 14 28 1 1185859616 62673056 3047056
12:50:09 57742 8192000000 10062552528 10519404544 1097510832 11.814 97 10 20 1 1148095696 48615424 1969440
12:52:08 59897 8192000000 10056706080 10504847360 1076617824 11.615 96 22 44 2 1142203680 60792672 4793184
12:54:11 30794 8192000000 10116146992 10567696384 1108959952 11.923 99 32 64 0 1172841504 57458960 6422592
12:55:43 45393 8192000000 10063682864 10481971200 1097682608 11.816 97 24 48 0 1147704496 45428496 4593392
12:57:29 38930 8192000000 10147487232 10595786752 1121097712 12.037 99 34 68 1 1220675056 93011872 6565472
12:59:17 70547 8192000000 10079095424 10540548096 1089880608 11.742 96 11 22 0 1144769168 52312800 2575760
13:01:31 147528 8192000000 10081189872 10547736576 1126399296 12.087 99 39 77 1 1245822464 111057712 8365456
13:05:01 38376 8192000000 10038513248 10495320064 1105945824 11.894 98 9 18 0 1138696192 31149264 1601104
13:06:45 57479 8192000000 10068800320 10527158272 1129676512 12.118 100 11 22 0 1172887600 40615552 2595536
13:09:02 56643 8192000000 10068050720 10520899584 1113156528 11.962 98 14 26 0 1165449936 50065280 2228128
13:11:05 41248 8192000000 10098910784 10540957696 1130425072 12.125 99 23 46 1 1179976064 44975712 4575280
13:12:47 28680 8192000000 10108088464 10530983936 1110999408 11.942 97 44 88 0 1153273840 33035568 9238864
13:14:19 56780 8192000000 10102430208 10542473216 1129175120 12.114 99 24 48 0 1236386304 102019120 5192064
13:16:20 118927 8192000000 10101013728 10582589440 1122267808 12.048 98 12 24 0 1224858512 100007184 2583520
13:19:25 52223 8192000000 10095775504 10531803136 1110018096 11.933 97 11 22 0 1136287856 24177984 2091776
13:21:25 59194 8192000000 10094227744 10603638784 1128130496 12.104 99 28 56 0 1244574480 109988448 6455536
13:23:29 81086 8192000000 10095418208 10515972096 1121119936 12.038 98 29 57 0 1162570416 35568848 5881632
13:25:51 58313 8192000000 10103403312 10562396160 1145712928 12.269 100 14 28 0 1218503840 69827552 2963360
13:28:32 37864 8192000000 10096334640 10564329472 1126682496 12.090 98 15 30 0 1173958032 44206432 3069104
13:30:11 51037 8192000000 10121138928 10555625472 1137342704 12.191 99 22 44 2 1202722416 60943056 4436656
13:32:03 69668 8192000000 10119100624 10576343040 1150183152 12.311 100 17 34 2 1232087904 78329824 3574928
13:34:12 93373 8192000000 10109185456 10561560576 1077488080 11.624 93 22 44 0 1161083392 78938288 4657024
13:36:49 14113 8192000000 10086026480 10511126528 1124522704 12.070 100 35 70 0 1160485904 29414256 6548944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-12-2021 13:38:19 to 19-12-2021 13:38:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2021 13:38:35 to 19-12-2021 13:38:37.

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          230013
MON$OLDEST_ACTIVE               232671
MON$OLDEST_SNAPSHOT             232671
MON$NEXT_TRANSACTION            232671
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                       2058208
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2692 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2692 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2692 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2692 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 ---
:76: 
:77: mscs-test-161.budzdorov.ru    Sun Dec 19 13:37:19 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Sun Dec 19 13:37:22 2021
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
--- end of diff output ---

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

19.12.21 13:38:38. 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-19 13:38:41 No severe PSQL-related problems occured
19.12.21 13:38:42. End of report.