Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.01.2023 07: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 15332916 kB
MemAvailable 30429724 kB
Buffers 0 kB
Cached 16408324 kB
SwapCached 2412 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 2023-01-17 07:37:23
DTS_END                         2023-01-17 07:37:23.4930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42528
TRN_ID                          225452
ATT_ID                          589
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: 9002.06 23282 1080247
customer order: creation 180.28 497 21634
customer order: refuse 15.24 426 1829
order to supplier: creation 46.62 1342 5594
order to supplier: removal 3.09 185861 371
invoice (draft): creation 48.12 2966 5774
invoice (draft): removal 49.04 985 5885
invoice accept: apply 43.05 26560 5166
invoice accept: cancel 44.48 1316 5337
customer reserve: creation 3938.87 258 472664
customer reserve: removal 4331.18 106 519742
realization accept: apply 86.44 283 10373
realization accept: cancel 80.73 161 9688
payment from customer: creation 62.08 163 7450
payment from customer: removal 5.93 126 711
payment to supplier: creation 50.38 197 6045
payment to supplier: removal 3.43 109 412
service: total inventory turnovers 4.22 3620 506
service: total monetary turnovers 7.98 360 957
service: aggregate perf. data 0.88 14481 105
service: refresh index statistics 0.03 225813 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-01-2023 07:40:18 to 17-01-2023 07:40:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6483
TEST_TIME 2 6654
TEST_TIME 3 6627
TEST_TIME 4 6709
TEST_TIME 5 6728
TEST_TIME 6 6650
TEST_TIME 7 6802
TEST_TIME 8 6896
TEST_TIME 9 6813
TEST_TIME 10 6731
TEST_TIME 11 6664
TEST_TIME 12 6836
TEST_TIME 13 7056
TEST_TIME 14 7095
TEST_TIME 15 7140
TEST_TIME 16 7190
TEST_TIME 17 7471
TEST_TIME 18 7513
TEST_TIME 19 7577
TEST_TIME 20 7779
TEST_TIME 21 7828
TEST_TIME 22 8037
TEST_TIME 23 7989
TEST_TIME 24 8245
TEST_TIME 25 8477
TEST_TIME 26 8614
TEST_TIME 27 8598
TEST_TIME 28 8847
TEST_TIME 29 9328
TEST_TIME 30 10024
TEST_TIME 31 10697
TEST_TIME 32 11047
TEST_TIME 33 10866
TEST_TIME 34 10681
TEST_TIME 35 10554
TEST_TIME 36 10308
TEST_TIME 37 10062
TEST_TIME 38 10167
TEST_TIME 39 10050
TEST_TIME 40 10080
TEST_TIME 41 10004
TEST_TIME 42 9907
TEST_TIME 43 9797
TEST_TIME 44 9665
TEST_TIME 45 9603
TEST_TIME 46 9478
TEST_TIME 47 9543
TEST_TIME 48 9515
TEST_TIME 49 9669
TEST_TIME 50 9901
TEST_TIME 51 9850
TEST_TIME 52 9945
TEST_TIME 53 9915
TEST_TIME 54 9943
TEST_TIME 55 9877
TEST_TIME 56 9896
TEST_TIME 57 9919
TEST_TIME 58 9796
TEST_TIME 59 9647
TEST_TIME 60 9683
TEST_TIME 61 9585
TEST_TIME 62 9489
TEST_TIME 63 9487
TEST_TIME 64 9432
TEST_TIME 65 9410
TEST_TIME 66 9367
TEST_TIME 67 9359
TEST_TIME 68 9320
TEST_TIME 69 9409
TEST_TIME 70 9351
TEST_TIME 71 9302
TEST_TIME 72 9406
TEST_TIME 73 9395
TEST_TIME 74 9482
TEST_TIME 75 9514
TEST_TIME 76 9536
TEST_TIME 77 9602
TEST_TIME 78 9485
TEST_TIME 79 9434
TEST_TIME 80 9428
TEST_TIME 81 9377
TEST_TIME 82 9371
TEST_TIME 83 9309
TEST_TIME 84 9238
TEST_TIME 85 9249
TEST_TIME 86 9253
TEST_TIME 87 9228
TEST_TIME 88 9218
TEST_TIME 89 9218
TEST_TIME 90 9193
TEST_TIME 91 9223
TEST_TIME 92 9229
TEST_TIME 93 9189
TEST_TIME 94 9234
TEST_TIME 95 9380
TEST_TIME 96 9360
TEST_TIME 97 9408
TEST_TIME 98 9372
TEST_TIME 99 9302
TEST_TIME 100 9240
TEST_TIME 101 9305
TEST_TIME 102 9240
TEST_TIME 103 9177
TEST_TIME 104 9161
TEST_TIME 105 9175
TEST_TIME 106 9150
TEST_TIME 107 9171
TEST_TIME 108 9138
TEST_TIME 109 9126
TEST_TIME 110 9118
TEST_TIME 111 9168
TEST_TIME 112 9130
TEST_TIME 113 9141
TEST_TIME 114 9150
TEST_TIME 115 9192
TEST_TIME 116 9210
TEST_TIME 117 9163
TEST_TIME 118 9102
TEST_TIME 119 9079
TEST_TIME 120 9021

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-01-2023 07:40:22 to 17-01-2023 07:40:25.

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 1516109 1516109 0 0.00 0 18409 14 0 0
doc_list_biud 1516103 1516103 0 0.00 0 42302 42 0 0
sp_add_invoice_to_stock 5294 5166 128 2.41 44 231375 26560 0 128
sp_cancel_adding_invoice 5337 5337 0 0.00 6 93593 1317 0 0
sp_cancel_client_order 1829 1829 0 0.00 5 28121 426 0 0
sp_cancel_customer_reserve 519742 519742 0 0.00 1 33916 106 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 4 6816 126 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 4 2398 109 0 0
sp_cancel_supplier_invoice 5900 5885 15 0.25 3 35634 985 0 15
sp_cancel_supplier_order 371 371 0 0.00 12 1429115 185861 0 0
sp_cancel_write_off 9688 9688 0 0.00 2 26684 162 0 0
sp_client_order 21634 21634 0 0.00 5 28131 497 0 0
sp_customer_reserve 472664 472664 0 0.00 0 98363 258 0 0
sp_fill_shopping_cart 32996 32996 0 0.00 1 21457 76 0 0
sp_fill_shopping_cart_clo_res 472647 472647 0 0.00 0 26386 22 0 0
sp_get_clo_for_invoice 5131 5131 0 0.00 0 27069 307 0 0
sp_kill_cost_storno 16148 16148 0 0.00 0 6799 66 0 0
sp_kill_qstorno_ret_qs2qd 1055428 1055428 0 0.00 0 35623 46 0 0
sp_kill_qty_storno 558351 558351 0 0.00 0 42301 105 0 0
sp_lock_selected_doc 21375 21375 0 0.00 0 9200 10 0 0
sp_make_cost_storno 28994 28994 0 0.00 0 13103 73 0 0
sp_multiply_rows_for_pdistr 28993 28993 0 0.00 0 7742 40 0 0
sp_multiply_rows_for_qdistr 21634 21634 0 0.00 0 16609 211 0 0
sp_pay_from_customer 7450 7450 0 0.00 5 13236 163 0 0
sp_pay_to_supplier 6045 6045 0 0.00 6 13912 197 0 0
sp_payment_common 13494 13494 0 0.00 2 6239 44 0 0
sp_qd_handle_on_cancel_clo 1829 1829 0 0.00 0 28114 350 0 0
sp_qd_handle_on_invoice_upd_sts 10463 10463 0 0.00 0 42300 500 0 0
sp_qd_handle_on_reserve_upd_sts 10373 10373 0 0.00 0 15558 65 0 0
sp_reserve_write_off 10373 10373 0 0.00 8 15943 283 0 0
sp_supplier_invoice 5774 5774 0 0.00 7 102241 2967 0 0
sp_supplier_order 5594 5594 0 0.00 7 68844 1342 0 0
srv_aggregate_perf_data 105 105 0 0.00 2314 175354 14482 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 833 132895 35066 0 0
srv_find_qd_qs_mism 1029928 1029928 0 0.00 0 18407 11 0 0
srv_make_invnt_saldo 506 506 0 0.00 54 63339 3621 0 0
srv_make_money_saldo 957 957 0 0.00 1 16417 360 0 0
srv_recalc_idx_stat 4 4 0 0.00 81959 406713 225813 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5166 5166 0 0.00 1 10439 883 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 6835 481 0 0
t$perf-norm:sp_cancel_client_order 1829 1829 0 0.00 0 14953 44 0 0
t$perf-norm:sp_cancel_customer_reserve 747 747 0 0.00 0 10180 48 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 2137 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 2450 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 387 26 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 95051 6753 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 1540 18 0 0
t$perf-norm:sp_client_order 21634 21634 0 0.00 0 15921 36 0 0
t$perf-norm:sp_customer_reserve 1105 1105 0 0.00 0 15388 27 0 0
t$perf-norm:sp_pay_from_customer 7450 7450 0 0.00 0 13231 23 0 0
t$perf-norm:sp_pay_to_supplier 6045 6045 0 0.00 0 14632 24 0 0
t$perf-norm:sp_reserve_write_off 10373 10373 0 0.00 0 14629 27 0 0
t$perf-norm:sp_supplier_invoice 5774 5774 0 0.00 0 3679 28 0 0
t$perf-norm:sp_supplier_order 5594 5594 0 0.00 0 5847 34 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 506 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 59 5 0 0
t$perf-norm:srv_make_invnt_saldo 506 506 0 0.00 0 767 11 0 0
t$perf-norm:srv_make_money_saldo 957 957 0 0.00 0 2828 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 116 41 0 0
v_add_invoice_to_stock 10508 10252 256 2.43 0 6393 28 0 256
v_all_customers 39996 39996 0 0.00 0 137 4 0 0
v_all_suppliers 31556 31556 0 0.00 0 70 4 0 0
v_all_wares 43268 43268 0 0.00 0 71 4 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 1800 17 0 0
v_cancel_client_order 3658 3658 0 0.00 0 5813 12 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 634 10 0 0
v_cancel_customer_reserve 1494 1494 0 0.00 1 4516 16 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 426 10 0 0
v_cancel_supplier_invoice 838 808 30 3.57 0 2514 24 0 30
v_cancel_supplier_order 742 742 0 0.00 0 455 11 0 0
v_cancel_write_off 1946 1946 0 0.00 0 2814 23 0 0
v_max_id_clo_ord 5594 5594 0 0.00 0 1131 5 0 0
v_max_id_clo_res 1105 1105 0 0.00 0 3372 20 0 0
v_max_id_ord_sup 5768 5768 0 0.00 0 3396 6 0 0
v_max_non_paid_invoice 1629 1629 0 0.00 0 65 8 0 0
v_max_non_paid_realizn 3790 3790 0 0.00 0 961 7 0 0
v_min_id_clo_ord 5594 5594 0 0.00 0 4332 8 0 0
v_min_id_clo_res 1105 1105 0 0.00 0 2457 15 0 0
v_min_id_ord_sup 5768 5768 0 0.00 0 5553 8 0 0
v_min_non_paid_invoice 6045 6045 0 0.00 0 6234 19 0 0
v_min_non_paid_realizn 7449 7449 0 0.00 0 5613 15 0 0
v_our_firm 10590 10590 0 0.00 0 137 5 0 0
v_reserve_write_off 20746 20746 0 0.00 0 5475 11 0 0
x_lock_depdocs_on_canc_invoice 10818 10818 0 0.00 0 20884 131 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 7860 357 0 0
x_make_qstorno_1000_1200 5594 5594 0 0.00 3 68814 1184 0 0
x_make_qstorno_1200_2000 5774 5774 0 0.00 4 102225 2761 0 0
x_make_qstorno_2100_3300 472664 472664 0 0.00 0 98355 224 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 17-01-2023 07:40:26 to 17-01-2023 07:40:31.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:27 9 8192000000 8808984912 8824152064 11783280 0.143 6 3 2 0 11894032 74976 35776
05:08:28 28699 8192000000 9724116960 10022916096 802272464 8.919 100 93 185 0 832702048 7459008 22970576
05:10:04 21003 8192000000 9759436992 10064461824 831424160 9.214 100 89 178 0 866289360 12929952 21935248
05:11:33 11538 8192000000 9801453984 10123444224 867441904 9.575 100 81 161 1 907886848 21571376 18873568
05:12:48 11552 8192000000 9868688592 10204291072 925778032 10.153 100 74 147 0 978167440 36875744 15513664
05:14:05 3939 8192000000 9922232160 10266763264 968948800 10.576 100 88 176 0 1036824128 49105664 18769664
05:15:12 2047 8192000000 9969281328 10321989632 990639904 10.788 100 85 170 0 1084140880 76406112 17094864
05:16:17 2695 8192000000 9981710816 10343972864 1011582432 10.991 100 85 165 0 1099148416 70651120 16914864
05:17:24 1453 8192000000 10010246080 10380574720 1031802736 11.186 100 89 175 2 1124266816 75685104 16778976
05:18:28 1374 8192000000 10022451376 10392379392 1044805904 11.311 100 87 166 0 1143385248 81606896 16972448
05:19:32 8572 8192000000 10001309168 10377138176 1052570656 11.385 100 80 152 0 1132156896 65339584 14246656
05:20:43 1047 8192000000 10013353648 10393149440 1061684592 11.473 100 96 180 2 1142433664 62542432 18206640
05:21:45 1099 8192000000 10045389440 10428010496 1069682992 11.549 100 95 188 2 1170349536 81004064 19662480
05:22:48 1327 8192000000 10054922864 10441478144 1072473120 11.576 100 78 152 2 1173393104 85667568 15252416
05:23:50 1117 8192000000 10040260944 10426929152 1078513488 11.633 100 89 164 1 1164140304 67616944 18009872
05:24:53 1703 8192000000 10038810400 10433187840 1084620160 11.691 100 80 158 1 1169751024 68734128 16396736
05:25:55 1395 8192000000 10054222048 10439421952 1087895664 11.723 100 97 185 1 1177126432 68935840 20294928
05:26:57 2438 8192000000 10062259648 10447794176 1092464816 11.766 100 83 156 2 1178999664 70788784 15746064
05:28:01 3084 8192000000 10040004240 10431954944 1089011088 11.733 100 61 121 2 1160761552 59705296 12045168
05:29:05 1039 8192000000 10058640096 10446716928 1093075584 11.772 100 94 184 0 1176243312 64672656 18495072
05:30:07 3286 8192000000 10005513376 10391138304 1058133168 11.439 98 68 128 4 1129560368 58599248 12827952
05:31:11 1323 8192000000 10046805840 10429702144 1071923712 11.570 100 93 176 1 1173830592 84252576 17654304
05:32:13 968 8192000000 10012788192 10389762048 1038518592 11.250 98 89 174 1 1137848112 80587536 18741984
05:33:15 1206 8192000000 9965637024 10332270592 1008171216 10.958 99 98 184 2 1083018416 56372784 18474416
05:34:17 1186 8192000000 10004729888 10381979648 1040926480 11.274 100 92 172 1 1128314896 69793872 17594544
05:35:19 1582 8192000000 9997711072 10382589952 1044642896 11.309 100 63 124 0 1119926656 62597328 12686432
05:36:22 3097 8192000000 9977308304 10356748288 1038436384 11.250 100 71 134 0 1105873088 53425472 14011232
05:37:25 10381 8192000000 9979454624 10359848960 1038186688 11.247 99 52 96 0 1103425792 55938016 9301088
05:38:40 8471 8192000000 10024625456 10409062400 1072891824 11.580 100 72 132 1 1156420432 68631504 14897104
05:39:53 990 8192000000 10052823968 10437640192 1091387472 11.756 100 95 172 1 1174359856 66217152 16755232
05:40:59 3496 8192000000 10080306928 10464608256 1097538496 11.814 100 74 139 1 1203503488 91261200 14703792
05:42:11 3774 8192000000 10107076672 10503704576 1118287792 12.011 100 76 140 0 1221608672 88853072 14467808
05:43:20 1437 8192000000 10145231184 10542931968 1129074640 12.113 100 90 175 2 1266738976 118709328 18955008
05:44:25 990 8192000000 10186252976 10580996096 1138111616 12.198 100 93 171 2 1301684656 143766464 19806576
05:45:29 1070 8192000000 10231859712 10627166208 1147445536 12.286 100 96 187 1 1341358880 174601264 19312080
05:46:34 1512 8192000000 10241843680 10642018304 1154944304 12.356 100 89 170 3 1354685056 181478368 18262384
05:47:40 2624 8192000000 10261633408 10660020224 1150910720 12.318 99 79 150 0 1368925520 201000112 17014688
05:48:50 1286 8192000000 10312768400 10711756800 1163059264 12.432 100 91 170 1 1423923072 239773856 21089952
05:49:56 836 8192000000 10328185744 10739671040 1166872064 12.468 100 92 176 0 1434592192 247635856 20084272
05:51:02 1723 8192000000 10345818816 10762227712 1181811248 12.607 100 96 190 1 1459053856 255394144 21848464
05:52:07 1021 8192000000 10380999600 10780635136 1173896576 12.533 100 97 187 0 1488377536 292006832 22474128
05:53:12 4101 8192000000 10416918800 10835886080 1184387568 12.631 100 80 158 0 1529867008 326800032 18679408
05:54:20 827 8192000000 10395206512 10797969408 1184478704 12.632 99 93 177 1 1502909808 297415328 21015776
05:55:26 1443 8192000000 10450848464 10869293056 1201722800 12.792 100 88 174 1 1558243344 336393584 20126960
05:56:33 1134 8192000000 10473945200 10902405120 1201491792 12.790 100 100 198 1 1580201584 355319920 23389872
05:57:38 1072 8192000000 10504921568 10920321024 1203638432 12.810 100 94 186 0 1613127632 387840624 21648576
05:58:41 1932 8192000000 10510422192 10960105472 1197932512 12.757 100 92 171 0 1614992240 396332688 20727040
05:59:44 1375 8192000000 10551302304 10987606016 1207404000 12.845 100 87 167 4 1654079520 426430192 20245328
06:00:50 1399 8192000000 10563590256 11021058048 1206099136 12.833 100 81 160 0 1667369952 441402560 19868256
06:01:56 1758 8192000000 10554318064 11010506752 1208256848 12.853 100 85 153 0 1666373200 438742336 19374016
06:03:01 5015 8192000000 10543676560 11013263360 1207292592 12.844 100 51 102 0 1648456768 428492448 12671728
06:04:10 11064 8192000000 10528969104 10987515904 1185018864 12.637 100 40 76 0 1642020080 446118640 10882576
06:05:59 17738 8192000000 10462738448 10984542208 1163019872 12.432 99 30 60 0 1567958704 396301136 8637696
06:07:34 41434 8192000000 10177392640 10637836288 1091468112 11.757 95 24 46 1 1277668448 180949184 5251152
06:09:17 90448 8192000000 10075112576 10506092544 1108435808 11.918 97 23 44 0 1136643968 23567072 4641088
06:11:52 19917 8192000000 10099759488 10530975744 1130543648 12.126 99 62 118 1 1190530912 45482512 14504752
06:13:16 132893 8192000000 10118388224 10564841472 1140702256 12.222 100 51 101 0 1245722272 93129376 11890640
06:16:30 24899 8192000000 10103367328 10553327616 1130906432 12.130 99 40 79 0 1199858176 61191584 7760160
06:17:59 39950 8192000000 10101588528 10547290112 1123699152 12.062 98 39 78 1 1190706912 58485984 8521776
06:20:15 38979 8192000000 10134430240 10590113792 1124630496 12.071 98 24 48 1 1233620208 103832288 5157424
06:21:55 99375 8192000000 10199442464 10645815296 1121452672 12.041 98 24 49 0 1259132272 132184224 5495376
06:24:36 41468 8192000000 10232307536 10674847744 1142520080 12.239 100 34 65 0 1318857408 169466976 6870352
06:26:18 26803 8192000000 10191280400 10681143296 1149828336 12.308 100 27 54 0 1314743728 158505072 6410320
06:27:46 26715 8192000000 10130969952 10563284992 1146694896 12.278 100 41 81 0 1220969712 65360320 8914496
06:29:18 25064 8192000000 10099215632 10547822592 1111346064 11.945 99 35 68 2 1218564352 100101408 7116880
06:30:44 10673 8192000000 10076263168 10505957376 1119943216 12.026 100 70 131 1 1171852576 38229184 13680176
06:31:56 41979 8192000000 10111774528 10544754688 1110248160 11.935 99 26 52 0 1178461904 63005600 5208144
06:33:39 35133 8192000000 10109122736 10578288640 1122651744 12.052 100 36 72 0 1216691488 86979568 7060176
06:35:14 120987 8192000000 10090991872 10555342848 1114122864 11.971 99 49 95 1 1173880160 49780048 9977248
06:38:20 29795 8192000000 10061816784 10506981376 1116750384 11.996 99 20 36 2 1140740688 20083872 3906432
06:40:03 60932 8192000000 10044606784 10539294720 1111180480 11.944 99 6 12 0 1161267680 49082800 1004400
06:42:05 111941 8192000000 10080923776 10517086208 1047824064 11.340 93 18 36 0 1102512768 51019136 3669568
06:44:58 31547 8192000000 10101977216 10538090496 1090155200 11.744 97 32 62 0 1181602240 84302592 7144448
06:46:34 58783 8192000000 10096101232 10527551488 1091409904 11.756 97 25 45 1 1162135200 64810880 5914416
06:48:34 29142 8192000000 10153199712 10612576256 1129270784 12.114 100 45 90 0 1242344096 103495232 9578080
06:50:08 10217 8192000000 10143796416 10567720960 1122509424 12.051 100 52 104 0 1223895024 90900528 10485072
06:51:23 61303 8192000000 10101009904 10620461056 1095017184 11.790 97 26 52 0 1213987648 113589792 5380672
06:53:26 29816 8192000000 10127238400 10555195392 1085260000 11.698 97 48 95 0 1197294320 102149120 9885200
06:54:58 68755 8192000000 10064433552 10492821504 1123267376 12.058 100 32 62 0 1161143888 30980000 6896512
06:57:14 36075 8192000000 10066234640 10536660992 1118797984 12.016 100 31 60 0 1174403648 48841168 6764496
06:58:52 91019 8192000000 10067351360 10557227008 1125151408 12.076 99 10 20 0 1194083792 66543296 2389088
07:01:24 76885 8192000000 10067433088 10553344000 1105931568 11.894 98 7 14 0 1145004016 37438704 1633744
07:03:47 61831 8192000000 10106163616 10554023936 1114762288 11.977 98 20 38 0 1183333792 64583392 3988112
07:05:51 26160 8192000000 10125653872 10570416128 1125196720 12.076 99 37 74 0 1220280192 86674896 8408576
07:07:30 29133 8192000000 10163835264 10617643008 1120068640 12.028 97 30 60 1 1243945840 116554672 7322528
07:09:01 42880 8192000000 10176886784 10646220800 1131382880 12.134 98 36 68 0 1255638336 116631776 7623680
07:10:52 48932 8192000000 10184818144 10627575808 1126490000 12.088 98 29 56 1 1273232288 140929488 5812800
07:12:45 47130 8192000000 10088547744 10552418304 1120213280 12.029 98 34 63 1 1197452816 70417328 6822208
07:14:32 45048 8192000000 10121140384 10555105280 1103315280 11.869 97 37 71 0 1182458928 72241680 6901968
07:16:19 83299 8192000000 10133834352 10581590016 1124180816 12.066 99 31 58 0 1224585024 94212096 6192112
07:18:43 48778 8192000000 10048513120 10555285504 1093950032 11.780 97 7 13 0 1137783536 42688320 1145184
07:20:58 26569 8192000000 10091607520 10541707264 1129894512 12.120 100 17 31 0 1196067904 62827664 3345728
07:22:33 46994 8192000000 10075644224 10549325824 1096339248 11.803 97 22 42 0 1138073232 37645248 4088736
07:24:22 59785 8192000000 10049318208 10514178048 1107481216 11.909 99 32 65 0 1169992032 54929968 7580848
07:26:33 56250 8192000000 10107421904 10567630848 1108097328 11.914 98 31 62 0 1173386416 59039104 6249984
07:28:29 84999 8192000000 10088151360 10531885056 1087860160 11.722 96 14 28 0 1133515472 42624640 3030672
07:30:55 45511 8192000000 10100474096 10550542336 1109787168 11.930 99 28 56 0 1174506720 59092608 5626944
07:32:41 72093 8192000000 10035658352 10486878208 1054402320 11.403 94 20 39 0 1129858208 71702064 3753824
07:35:00 97525 8192000000 10058829056 10512756736 1117833280 12.007 100 31 62 0 1176649744 51733008 7083456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-01-2023 07:40:33 to 17-01-2023 07:40:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-01-2023 07:40:50 to 17-01-2023 07:40:52.

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          223436
MON$OLDEST_ACTIVE               225562
MON$OLDEST_SNAPSHOT             225562
MON$NEXT_TRANSACTION            225562
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                       2032736
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2890 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2890 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2890 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2890 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 ---
:772: 
:773: mscs-test-161.budzdorov.ru    Tue Jan 17 07:38:19 2023
:774:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:775: 
--- end of diff output ---

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

17.01.23 07:40:53. 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
2023-01-17 07:40:56 No severe PSQL-related problems occured
17.01.23 07:40:57. End of report.