Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.02.2023 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 14669608 kB
MemAvailable 30385440 kB
Buffers 20 kB
Cached 16937944 kB
SwapCached 3156 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-02-27 04:38:47
DTS_END                         2023-02-27 04:37:28.2810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47662
TRN_ID                          214730
ATT_ID                          259
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: 8461.00 28916 1015320
customer order: creation 164.44 485 19733
customer order: refuse 14.13 475 1695
order to supplier: creation 44.84 1394 5381
order to supplier: removal 3.18 176191 382
invoice (draft): creation 44.27 3563 5312
invoice (draft): removal 45.18 1134 5422
invoice accept: apply 40.98 29056 4917
invoice accept: cancel 41.30 1595 4956
customer reserve: creation 3767.93 278 452152
customer reserve: removal 4007.41 109 480889
realization accept: apply 80.42 301 9650
realization accept: cancel 81.98 180 9837
payment from customer: creation 57.54 194 6905
payment from customer: removal 5.42 135 650
payment to supplier: creation 46.63 201 5596
payment to supplier: removal 3.39 142 407
service: total inventory turnovers 3.51 4903 421
service: total monetary turnovers 7.58 463 909
service: aggregate perf. data 0.84 17707 101
service: refresh index statistics 0.04 339818 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-02-2023 04:39:57 to 27-02-2023 04:39:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5074
TEST_TIME 2 6461
TEST_TIME 3 5680
TEST_TIME 4 6590
TEST_TIME 5 6759
TEST_TIME 6 7299
TEST_TIME 7 7409
TEST_TIME 8 7284
TEST_TIME 9 7266
TEST_TIME 10 7157
TEST_TIME 11 7083
TEST_TIME 12 6990
TEST_TIME 13 7054
TEST_TIME 14 7212
TEST_TIME 15 7350
TEST_TIME 16 7398
TEST_TIME 17 7398
TEST_TIME 18 7475
TEST_TIME 19 7420
TEST_TIME 20 7526
TEST_TIME 21 7645
TEST_TIME 22 7604
TEST_TIME 23 7629
TEST_TIME 24 7860
TEST_TIME 25 8293
TEST_TIME 26 8478
TEST_TIME 27 8846
TEST_TIME 28 8954
TEST_TIME 29 9013
TEST_TIME 30 8926
TEST_TIME 31 8994
TEST_TIME 32 9264
TEST_TIME 33 9407
TEST_TIME 34 9565
TEST_TIME 35 9965
TEST_TIME 36 10265
TEST_TIME 37 10219
TEST_TIME 38 10333
TEST_TIME 39 10291
TEST_TIME 40 10095
TEST_TIME 41 10132
TEST_TIME 42 10377
TEST_TIME 43 10151
TEST_TIME 44 10055
TEST_TIME 45 10195
TEST_TIME 46 10035
TEST_TIME 47 10031
TEST_TIME 48 9910
TEST_TIME 49 9891
TEST_TIME 50 9776
TEST_TIME 51 9727
TEST_TIME 52 9646
TEST_TIME 53 9573
TEST_TIME 54 9508
TEST_TIME 55 9475
TEST_TIME 56 9344
TEST_TIME 57 9231
TEST_TIME 58 9335
TEST_TIME 59 9297
TEST_TIME 60 9221
TEST_TIME 61 9115
TEST_TIME 62 9070
TEST_TIME 63 8999
TEST_TIME 64 8967
TEST_TIME 65 8899
TEST_TIME 66 9021
TEST_TIME 67 8956
TEST_TIME 68 8856
TEST_TIME 69 8830
TEST_TIME 70 8871
TEST_TIME 71 8847
TEST_TIME 72 8821
TEST_TIME 73 8835
TEST_TIME 74 8879
TEST_TIME 75 8825
TEST_TIME 76 8917
TEST_TIME 77 8907
TEST_TIME 78 8921
TEST_TIME 79 8912
TEST_TIME 80 8913
TEST_TIME 81 8935
TEST_TIME 82 8932
TEST_TIME 83 8899
TEST_TIME 84 8894
TEST_TIME 85 8811
TEST_TIME 86 8810
TEST_TIME 87 8802
TEST_TIME 88 8823
TEST_TIME 89 8774
TEST_TIME 90 8741
TEST_TIME 91 8712
TEST_TIME 92 8725
TEST_TIME 93 8661
TEST_TIME 94 8620
TEST_TIME 95 8593
TEST_TIME 96 8585
TEST_TIME 97 8580
TEST_TIME 98 8549
TEST_TIME 99 8559
TEST_TIME 100 8629
TEST_TIME 101 8612
TEST_TIME 102 8640
TEST_TIME 103 8665
TEST_TIME 104 8695
TEST_TIME 105 8664
TEST_TIME 106 8671
TEST_TIME 107 8759
TEST_TIME 108 8740
TEST_TIME 109 8756
TEST_TIME 110 8789
TEST_TIME 111 8784
TEST_TIME 112 8733
TEST_TIME 113 8718
TEST_TIME 114 8664
TEST_TIME 115 8655
TEST_TIME 116 8617
TEST_TIME 117 8587
TEST_TIME 118 8557
TEST_TIME 119 8508
TEST_TIME 120 8477

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-02-2023 04:40:01 to 27-02-2023 04:40:04.

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 1431569 1431569 0 0.00 0 32879 15 0 0
doc_list_biud 1431561 1431561 0 0.00 0 55128 44 0 0
sp_add_invoice_to_stock 5028 4917 111 2.20 31 177742 29056 0 111
sp_cancel_adding_invoice 4956 4956 0 0.00 6 96677 1595 0 0
sp_cancel_client_order 1695 1695 0 0.00 6 17515 476 0 0
sp_cancel_customer_reserve 480889 480889 0 0.00 1 55209 109 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 2187 135 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 4 8809 142 0 0
sp_cancel_supplier_invoice 5431 5422 9 0.16 3 54592 1135 0 9
sp_cancel_supplier_order 382 382 0 0.00 10 1458127 176191 0 0
sp_cancel_write_off 9837 9837 0 0.00 2 14441 180 0 0
sp_client_order 19733 19733 0 0.00 5 48316 485 0 0
sp_customer_reserve 452152 452152 0 0.00 1 41770 279 0 0
sp_fill_shopping_cart 30415 30415 0 0.00 1 17888 96 0 0
sp_fill_shopping_cart_clo_res 452132 452132 0 0.00 0 13633 20 0 0
sp_get_clo_for_invoice 4885 4885 0 0.00 0 23445 321 0 0
sp_kill_cost_storno 15850 15850 0 0.00 0 32865 88 0 0
sp_kill_qstorno_ret_qs2qd 977419 977419 0 0.00 0 54581 47 0 0
sp_kill_qty_storno 517713 517713 0 0.00 0 55127 111 0 0
sp_lock_selected_doc 20042 20042 0 0.00 0 6901 7 0 0
sp_make_cost_storno 27036 27036 0 0.00 0 10373 84 0 0
sp_multiply_rows_for_pdistr 27033 27033 0 0.00 0 11687 44 0 0
sp_multiply_rows_for_qdistr 19732 19732 0 0.00 0 48286 166 0 0
sp_pay_from_customer 6905 6905 0 0.00 6 17284 195 0 0
sp_pay_to_supplier 5596 5596 0 0.00 7 10770 201 0 0
sp_payment_common 12500 12500 0 0.00 2 9612 49 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 7827 375 0 0
sp_qd_handle_on_invoice_upd_sts 9839 9839 0 0.00 0 19342 644 0 0
sp_qd_handle_on_reserve_upd_sts 9649 9649 0 0.00 0 10428 70 0 0
sp_reserve_write_off 9650 9650 0 0.00 8 14194 301 0 0
sp_supplier_invoice 5312 5312 0 0.00 12 111719 3564 0 0
sp_supplier_order 5381 5381 0 0.00 7 57638 1394 0 0
srv_aggregate_perf_data 101 101 0 0.00 2683 150820 17707 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 716 106095 32614 0 0
srv_find_qd_qs_mism 967869 967869 0 0.00 0 12167 12 0 0
srv_make_invnt_saldo 421 421 0 0.00 52 55853 4904 0 0
srv_make_money_saldo 909 909 0 0.00 1 19290 463 0 0
srv_recalc_idx_stat 5 5 0 0.00 134734 568464 339818 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4917 4917 0 0.00 1 19044 1084 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 1 7032 655 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 3335 30 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 3465 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 3887 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 846 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 2687 39 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 117551 9068 0 0
t$perf-norm:sp_cancel_write_off 939 939 0 0.00 0 1823 24 0 0
t$perf-norm:sp_client_order 19735 19735 0 0.00 0 32911 45 0 0
t$perf-norm:sp_customer_reserve 883 883 0 0.00 0 558 16 0 0
t$perf-norm:sp_pay_from_customer 6905 6905 0 0.00 0 8070 23 0 0
t$perf-norm:sp_pay_to_supplier 5596 5596 0 0.00 0 5139 23 0 0
t$perf-norm:sp_reserve_write_off 9650 9650 0 0.00 0 10591 29 0 0
t$perf-norm:sp_supplier_invoice 5312 5312 0 0.00 0 2118 35 0 0
t$perf-norm:sp_supplier_order 5381 5381 0 0.00 0 7144 37 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 729 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 20471 451 0 0
t$perf-norm:srv_make_invnt_saldo 421 421 0 0.00 0 3752 28 0 0
t$perf-norm:srv_make_money_saldo 909 909 0 0.00 0 1259 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 170 71 0 0
v_add_invoice_to_stock 9969 9747 222 2.22 0 16895 31 0 222
v_all_customers 36118 36118 0 0.00 0 202 4 0 0
v_all_suppliers 29580 29580 0 0.00 0 203 5 0 0
v_all_wares 39446 39446 0 0.00 0 67 4 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 5418 25 0 0
v_cancel_client_order 3390 3390 0 0.00 0 16908 23 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 1477 15 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 11329 26 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 2635 16 0 0
v_cancel_supplier_invoice 744 726 18 2.41 0 596 25 0 18
v_cancel_supplier_order 764 764 0 0.00 0 5589 20 0 0
v_cancel_write_off 1878 1878 0 0.00 0 1910 28 0 0
v_max_id_clo_ord 5379 5379 0 0.00 0 363 6 0 0
v_max_id_clo_res 883 883 0 0.00 0 2225 11 0 0
v_max_id_ord_sup 5308 5308 0 0.00 0 3857 8 0 0
v_max_non_paid_invoice 1492 1492 0 0.00 0 3118 11 0 0
v_max_non_paid_realizn 3675 3675 0 0.00 0 5094 10 0 0
v_min_id_clo_ord 5379 5379 0 0.00 0 3344 12 0 0
v_min_id_clo_res 883 883 0 0.00 0 8758 29 0 0
v_min_id_ord_sup 5308 5308 0 0.00 0 5290 11 0 0
v_min_non_paid_invoice 5595 5595 0 0.00 0 6485 17 0 0
v_min_non_paid_realizn 6903 6903 0 0.00 0 6958 19 0 0
v_our_firm 9785 9785 0 0.00 0 64 5 0 0
v_reserve_write_off 19298 19298 0 0.00 0 12196 13 0 0
x_lock_depdocs_on_canc_invoice 10015 10015 0 0.00 0 16762 161 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 19937 481 0 0
x_make_qstorno_1000_1200 5381 5381 0 0.00 2 57610 1187 0 0
x_make_qstorno_1200_2000 5312 5312 0 0.00 5 107165 3306 0 0
x_make_qstorno_2100_3300 452152 452152 0 0.00 0 41767 244 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-02-2023 04:40:05 to 27-02-2023 04:40:09.

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:31 925 8192000000 8932289792 8989212672 29701552 0.361 6 5 8 0 30780496 417728 661216
02:08:35 28838 8192000000 9718904720 10017452032 798876144 8.885 100 85 170 0 827778672 7607104 21295424
02:10:09 28378 8192000000 9771929472 10079264768 845149824 9.351 100 90 180 0 879438176 12703600 21584752
02:11:44 14201 8192000000 9803210208 10122657792 867393120 9.574 100 84 168 0 913432864 26621616 19418128
02:13:06 5014 8192000000 9855777648 10184622080 909766784 9.995 100 81 162 0 962960528 35325712 17868032
02:14:15 5365 8192000000 9920071440 10264199168 955553040 10.445 100 88 176 0 1034273568 59326624 19393904
02:15:24 2569 8192000000 9974226800 10325831680 990879296 10.790 100 92 181 0 1083998192 74898336 18220560
02:16:29 1713 8192000000 10000397024 10361655296 1014884928 11.023 100 91 178 0 1120488704 87447040 18156736
02:17:33 1838 8192000000 10012239616 10378686464 1026271376 11.133 100 84 161 1 1119940784 77695904 15973504
02:18:37 975 8192000000 10011433568 10387615744 1052441184 11.384 100 89 170 1 1132554544 62192080 17921280
02:19:40 1213 8192000000 10023347360 10407075840 1064551488 11.500 100 93 179 2 1142725888 60248064 17926336
02:20:43 4314 8192000000 10029645184 10407370752 1068037024 11.533 100 90 165 1 1146030448 60868080 17125344
02:21:49 1393 8192000000 10047568624 10424365056 1073169536 11.582 100 97 187 2 1167377552 75117472 19090544
02:22:51 1478 8192000000 10056921024 10445643776 1078588048 11.634 100 77 150 0 1181897728 88053856 15255824
02:23:54 1128 8192000000 10039780560 10427387904 1083247728 11.678 100 93 168 2 1164359744 64098736 17013280
02:24:56 1553 8192000000 10047500368 10441011200 1088838992 11.732 100 68 131 0 1169541888 67711648 12991248
02:25:58 942 8192000000 10068869744 10458267648 1089538528 11.738 100 94 178 3 1191870864 83663280 18669056
02:27:01 1783 8192000000 10080498096 10471505920 1092454416 11.766 100 85 168 0 1201471264 92021744 16995104
02:28:07 1035 8192000000 10062594512 10452922368 1096666912 11.806 100 95 172 1 1184863968 68923792 19273264
02:29:09 1133 8192000000 10061953968 10452455424 1096571968 11.805 100 90 173 3 1189252768 74379680 18301120
02:30:11 1261 8192000000 10037810016 10429014016 1093229040 11.773 100 88 165 0 1164037296 52355744 18452512
02:31:15 1355 8192000000 10062030400 10446716928 1081938624 11.666 100 96 184 0 1183839312 83076096 18824592
02:32:17 1151 8192000000 10045782368 10430287872 1079147760 11.639 100 96 177 0 1169905840 72078272 18679808
02:33:19 1256 8192000000 10038567664 10414575616 1060265872 11.459 100 94 174 4 1155844144 76428912 19149360
02:34:22 1160 8192000000 9996164832 10371395584 1039012448 11.255 100 81 149 0 1120491280 68144800 13334032
02:35:29 1257 8192000000 10030230720 10407411712 1048586512 11.347 100 93 175 1 1153196416 87892656 16717248
02:36:31 1226 8192000000 9986713616 10366046208 1041353520 11.278 100 75 134 4 1109607344 54073424 14180400
02:37:34 7051 8192000000 9959771680 10340204544 1035189088 11.218 100 75 133 2 1081551328 33893408 12468832
02:38:48 1108 8192000000 10035533856 10413350912 1056280160 11.421 100 93 179 0 1155767056 80513200 18973696
02:40:00 5482 8192000000 10025588592 10405511168 1055700672 11.415 100 69 135 4 1145824848 76407216 13716960
02:41:13 875 8192000000 10048745024 10422546432 1059338976 11.450 100 86 161 0 1166509264 90965056 16205232
02:42:19 2419 8192000000 10092634048 10474762240 1083625184 11.682 100 95 182 4 1206474592 104394736 18454672
02:43:24 1692 8192000000 10111728832 10503503872 1093527776 11.776 100 92 177 0 1232790800 120988832 18274192
02:44:29 4136 8192000000 10120361632 10512605184 1109958528 11.932 100 85 161 1 1233861840 106627040 17276272
02:45:37 989 8192000000 10144963408 10536775680 1120045792 12.027 100 83 154 2 1255333952 119063408 16224752
02:46:41 1021 8192000000 10171708448 10567933952 1127492448 12.098 100 89 167 0 1285988704 140383712 18112544
02:47:47 3622 8192000000 10206612656 10603261952 1143486592 12.248 100 76 147 1 1323289104 162472000 17330512
02:48:57 766 8192000000 10244730240 10639577088 1156037040 12.366 100 86 164 2 1358423856 183753568 18633248
02:50:02 1968 8192000000 10312997376 10712719360 1169567344 12.493 100 96 182 1 1421236800 231486320 20183136
02:51:07 939 8192000000 10328656256 10730160128 1171626800 12.512 100 93 182 0 1438709856 247117552 19965504
02:52:13 787 8192000000 10373823280 10776092672 1179417056 12.585 100 92 180 0 1477633184 278858912 19357216
02:53:19 1124 8192000000 10395584352 10801393664 1190381152 12.687 100 96 186 3 1506354720 294433840 21539728
02:54:24 1218 8192000000 10380516944 10791976960 1187867104 12.664 100 88 162 3 1486080880 279193488 19020288
02:55:31 1797 8192000000 10422641952 10824642560 1193552640 12.716 100 96 180 1 1521419104 305693200 22173264
02:56:37 1080 8192000000 10438655936 10863722496 1207004256 12.841 100 96 192 1 1545009648 316049232 21956160
02:57:41 1858 8192000000 10486729120 10895732736 1206204160 12.834 100 93 171 1 1592531424 366493888 19833376
02:58:49 2327 8192000000 10473061888 10904391680 1187018544 12.656 99 78 150 1 1581913696 376230992 18664160
02:59:54 2229 8192000000 10505699664 10924331008 1185341856 12.640 100 93 183 1 1610653392 402225552 23085984
03:01:00 1604 8192000000 10498538992 10970763264 1190647360 12.689 99 95 189 2 1601779552 387374864 23757328
03:02:03 1242 8192000000 10458910736 10928463872 1188825664 12.672 100 97 175 0 1563814592 352973584 22015344
03:03:07 708 8192000000 10479435088 10931851264 1207756400 12.848 100 93 183 1 1581179984 351540128 21883456
03:04:10 1044 8192000000 10482636592 10949197824 1208652400 12.857 100 70 139 1 1587401168 362060048 16688720
03:05:14 11847 8192000000 10469599680 10918969344 1196320320 12.742 100 37 75 0 1572412720 366232544 9859856
03:06:27 14608 8192000000 10458112256 10903769088 1179882064 12.589 99 39 75 0 1544641088 355799456 8959568
03:07:43 22025 8192000000 10454929312 10932621312 1164543488 12.446 99 30 58 0 1559107360 386977552 7586320
03:09:07 32252 8192000000 10385384320 10873294848 1159648304 12.400 100 25 50 0 1506747344 340691760 6407280
03:10:42 5610 8192000000 10354296192 10826141696 1164406912 12.445 100 68 129 0 1468982384 289461072 15114400
03:11:53 1159 8192000000 10242314560 10788032512 1163982704 12.441 100 83 152 1 1338956896 156005776 18968416
03:12:55 3405 8192000000 10246375440 10730987520 1161525472 12.418 100 66 130 1 1343122384 166143040 15453872
03:14:00 14209 8192000000 10240359232 10692927488 1162975568 12.431 100 36 71 0 1361749936 190259872 8514496
03:15:16 36299 8192000000 10240021728 10740023296 1132164144 12.142 97 18 34 0 1303244576 166592992 4487440
03:17:01 21484 8192000000 10229713136 10660081664 1159030160 12.394 100 40 73 0 1322156496 155314192 7812144
03:18:25 33823 8192000000 10263567552 10734747648 1152455600 12.333 100 14 28 0 1351762944 195903872 3403472
03:20:19 106094 8192000000 10171588112 10614075392 1135837184 12.176 98 45 92 1 1260239952 113707296 10695472
03:23:45 70444 8192000000 10113191040 10591678464 1131025840 12.131 99 27 54 0 1189993088 53576544 5390704
03:26:05 33859 8192000000 10065643040 10543640576 1095703152 11.797 96 11 23 0 1153719808 55630928 2385728
03:27:45 52299 8192000000 10060005280 10526380032 1112113888 11.952 98 24 46 1 1161689632 43485376 6090368
03:29:51 44880 8192000000 10076193120 10525335552 1109478880 11.927 98 25 48 0 1169054160 54357392 5217888
03:31:36 87218 8192000000 10120047504 10601545728 1141896912 12.233 100 20 40 0 1227534944 80817744 4820288
03:34:04 92281 8192000000 10086025920 10582298624 1091774944 11.760 96 38 74 0 1162540576 62288192 8477440
03:36:45 57293 8192000000 10061872496 10530324480 1134437760 12.163 100 14 28 0 1172815248 35127664 3249824
03:39:01 25858 8192000000 10081338528 10527690752 1124128960 12.066 98 32 62 0 1171908880 41393808 6386112
03:40:40 33791 8192000000 10138956512 10575675392 1154400832 12.351 100 56 110 0 1237020928 69913600 12706496
03:42:27 56841 8192000000 10124749472 10633162752 1152197808 12.330 100 16 32 0 1269255904 113167600 3890496
03:44:30 62332 8192000000 10095577920 10612584448 1138217024 12.199 100 12 24 0 1214121968 73531936 2373008
03:46:39 96118 8192000000 10065940880 10551980032 1083456768 11.680 95 7 14 0 1127768544 43197104 1114672
03:49:16 99856 8192000000 10093373760 10565615616 1098833632 11.827 96 15 28 0 1149695840 48309536 2552672
03:51:57 35121 8192000000 10095087744 10540326912 1122626576 12.052 98 31 61 0 1165448336 36414960 6406800
03:53:33 43644 8192000000 10124838496 10583146496 1145892704 12.271 100 43 83 0 1242085712 87616976 8576032
03:55:21 85037 8192000000 10111248784 10588749824 1120972720 12.036 98 34 67 1 1201519456 73720048 6826688
03:57:49 26943 8192000000 10091467760 10539806720 1142036064 12.235 100 39 78 0 1192608416 42523056 8049296
03:59:17 53013 8192000000 10086815584 10516484096 1095288656 11.793 96 32 64 0 1143996048 41894032 6813360
04:01:16 60516 8192000000 10097755984 10537349120 1115465344 11.984 98 32 64 0 1174106432 52188256 6452832
04:03:56 79082 8192000000 10125322176 10596999168 1120006560 12.027 99 30 55 0 1200019840 74384048 5629232
04:06:20 74626 8192000000 10069737872 10539511808 1126031056 12.084 99 8 16 0 1159897120 32228352 1637712
04:09:55 77614 8192000000 10066178608 10527739904 1122571648 12.051 99 11 22 0 1163995824 39008080 2416096
04:12:13 61686 8192000000 10058819136 10517237760 1128013968 12.103 99 14 26 1 1184948304 54768688 2165648
04:14:20 59965 8192000000 10066676816 10512592896 1106335616 11.898 97 21 42 0 1160411088 49673632 4401840
04:16:23 33622 8192000000 10116054880 10576203776 1103405824 11.870 98 33 65 0 1199655104 89735968 6513312
04:17:59 38567 8192000000 10067491248 10512674816 1078188064 11.630 95 27 50 2 1141228608 58160304 4880240
04:19:43 37987 8192000000 10104384240 10552479744 1103487264 11.871 98 31 60 0 1172087168 62930144 5669760
04:21:25 52922 8192000000 10105127088 10557554688 1109351152 11.926 98 39 74 1 1177849456 61054256 7444048
04:23:22 20778 8192000000 10124566688 10557652992 1111334432 11.945 98 35 70 0 1199387008 80779600 7272976
04:24:55 49883 8192000000 10086274224 10518126592 1101502560 11.852 98 46 85 1 1144415728 34233200 8679968
04:27:02 33464 8192000000 10077899376 10535714816 1101398048 11.851 99 44 83 0 1174577648 64316976 8862624
04:28:56 97210 8192000000 10060757840 10519199744 1086097456 11.706 97 38 74 0 1146053648 52822576 7133616
04:31:34 23855 8192000000 10041710112 10490761216 1118146608 12.009 100 27 54 1 1152813824 28735712 5931504
04:32:58 65519 8192000000 10041402288 10493591552 1081973760 11.666 96 14 28 0 1135331504 50546320 2811424
04:35:37 71874 8192000000 10133793792 10600624128 1119460672 12.022 99 11 22 0 1233582336 111527072 2594592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 27-02-2023 04:40:11 to 27-02-2023 04:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-02-2023 04:40:29 to 27-02-2023 04:40:31.

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          222327
MON$OLDEST_ACTIVE               222328
MON$OLDEST_SNAPSHOT             222328
MON$NEXT_TRANSACTION            222328
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                       2100096
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
--- end of diff output ---

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

27.02.23 04:40:32. 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-02-27 04:40:35 No severe PSQL-related problems occured
27.02.23 04:40:36. End of report.