Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.12.2022 04:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14305880 kB
MemAvailable 30016876 kB
Buffers 20 kB
Cached 17299296 kB
SwapCached 4108 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-17 04:37:20
DTS_END                         2022-12-17 04:37:20.6810
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46964
TRN_ID                          230898
ATT_ID                          655
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: 9300.77 23314 1116092
customer order: creation 180.55 452 21666
customer order: refuse 15.71 416 1885
order to supplier: creation 48.68 1354 5841
order to supplier: removal 3.43 168855 412
invoice (draft): creation 48.07 3013 5768
invoice (draft): removal 51.12 1045 6134
invoice accept: apply 43.28 26500 5193
invoice accept: cancel 46.08 1326 5529
customer reserve: creation 4057.69 249 486923
customer reserve: removal 4493.17 101 539180
realization accept: apply 86.94 279 10433
realization accept: cancel 89.27 176 10712
payment from customer: creation 63.27 169 7592
payment from customer: removal 5.98 125 717
payment to supplier: creation 51.07 180 6128
payment to supplier: removal 3.55 146 426
service: total inventory turnovers 3.93 4452 471
service: total monetary turnovers 7.98 362 958
service: aggregate perf. data 0.99 18405 119
service: refresh index statistics 0.04 238680 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-12-2022 04:38:18 to 17-12-2022 04:38:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8738
TEST_TIME 2 6878
TEST_TIME 3 7211
TEST_TIME 4 6880
TEST_TIME 5 7714
TEST_TIME 6 7605
TEST_TIME 7 7722
TEST_TIME 8 7762
TEST_TIME 9 7661
TEST_TIME 10 7326
TEST_TIME 11 7332
TEST_TIME 12 7286
TEST_TIME 13 7438
TEST_TIME 14 7737
TEST_TIME 15 8064
TEST_TIME 16 8115
TEST_TIME 17 8329
TEST_TIME 18 8371
TEST_TIME 19 8420
TEST_TIME 20 8703
TEST_TIME 21 8572
TEST_TIME 22 8482
TEST_TIME 23 8574
TEST_TIME 24 8532
TEST_TIME 25 8431
TEST_TIME 26 8685
TEST_TIME 27 8942
TEST_TIME 28 9798
TEST_TIME 29 9752
TEST_TIME 30 9822
TEST_TIME 31 9933
TEST_TIME 32 10345
TEST_TIME 33 10872
TEST_TIME 34 11062
TEST_TIME 35 11218
TEST_TIME 36 11688
TEST_TIME 37 11626
TEST_TIME 38 11582
TEST_TIME 39 11469
TEST_TIME 40 11293
TEST_TIME 41 11305
TEST_TIME 42 11248
TEST_TIME 43 11034
TEST_TIME 44 10902
TEST_TIME 45 10862
TEST_TIME 46 10733
TEST_TIME 47 10616
TEST_TIME 48 10606
TEST_TIME 49 10477
TEST_TIME 50 10348
TEST_TIME 51 10495
TEST_TIME 52 10485
TEST_TIME 53 10409
TEST_TIME 54 10389
TEST_TIME 55 10366
TEST_TIME 56 10288
TEST_TIME 57 10289
TEST_TIME 58 10142
TEST_TIME 59 10216
TEST_TIME 60 10127
TEST_TIME 61 10099
TEST_TIME 62 10028
TEST_TIME 63 9973
TEST_TIME 64 9934
TEST_TIME 65 9955
TEST_TIME 66 10106
TEST_TIME 67 10098
TEST_TIME 68 10133
TEST_TIME 69 10094
TEST_TIME 70 10035
TEST_TIME 71 9973
TEST_TIME 72 9999
TEST_TIME 73 10011
TEST_TIME 74 9974
TEST_TIME 75 10105
TEST_TIME 76 9979
TEST_TIME 77 9893
TEST_TIME 78 9830
TEST_TIME 79 9783
TEST_TIME 80 9732
TEST_TIME 81 9672
TEST_TIME 82 9634
TEST_TIME 83 9570
TEST_TIME 84 9489
TEST_TIME 85 9505
TEST_TIME 86 9573
TEST_TIME 87 9546
TEST_TIME 88 9509
TEST_TIME 89 9512
TEST_TIME 90 9495
TEST_TIME 91 9575
TEST_TIME 92 9528
TEST_TIME 93 9615
TEST_TIME 94 9643
TEST_TIME 95 9730
TEST_TIME 96 9746
TEST_TIME 97 9679
TEST_TIME 98 9772
TEST_TIME 99 9769
TEST_TIME 100 9702
TEST_TIME 101 9660
TEST_TIME 102 9629
TEST_TIME 103 9601
TEST_TIME 104 9550
TEST_TIME 105 9541
TEST_TIME 106 9513
TEST_TIME 107 9510
TEST_TIME 108 9544
TEST_TIME 109 9494
TEST_TIME 110 9458
TEST_TIME 111 9430
TEST_TIME 112 9441
TEST_TIME 113 9428
TEST_TIME 114 9400
TEST_TIME 115 9390
TEST_TIME 116 9400
TEST_TIME 117 9437
TEST_TIME 118 9413
TEST_TIME 119 9385
TEST_TIME 120 9327

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-12-2022 04:38:22 to 17-12-2022 04:38: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 1563607 1563607 0 0.00 0 13360 15 0 0
doc_list_biud 1563600 1563600 0 0.00 0 41241 41 0 0
sp_add_invoice_to_stock 5361 5193 168 3.13 59 241026 26500 0 168
sp_cancel_adding_invoice 5529 5529 0 0.00 9 69886 1327 0 0
sp_cancel_client_order 1885 1885 0 0.00 6 8318 416 0 0
sp_cancel_customer_reserve 539180 539180 0 0.00 1 41242 101 0 0
sp_cancel_pay_from_customer 717 717 0 0.00 4 5143 126 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 4 6607 147 0 0
sp_cancel_supplier_invoice 6153 6134 19 0.30 4 20087 1045 0 19
sp_cancel_supplier_order 412 412 0 0.00 8 1442012 168855 0 0
sp_cancel_write_off 10712 10712 0 0.00 2 11327 177 0 0
sp_client_order 21666 21666 0 0.00 5 21775 452 0 0
sp_customer_reserve 486923 486923 0 0.00 1 83283 249 0 0
sp_fill_shopping_cart 33268 33268 0 0.00 1 10826 84 0 0
sp_fill_shopping_cart_clo_res 486895 486895 0 0.00 0 11604 16 0 0
sp_get_clo_for_invoice 5151 5151 0 0.00 0 31095 259 0 0
sp_kill_cost_storno 17384 17384 0 0.00 0 6835 72 0 0
sp_kill_qstorno_ret_qs2qd 1095618 1095618 0 0.00 0 41238 43 0 0
sp_kill_qty_storno 579434 579434 0 0.00 0 41241 101 0 0
sp_lock_selected_doc 21706 21706 0 0.00 0 8958 10 0 0
sp_make_cost_storno 29303 29303 0 0.00 0 11985 75 0 0
sp_multiply_rows_for_pdistr 29302 29302 0 0.00 0 8548 37 0 0
sp_multiply_rows_for_qdistr 21666 21666 0 0.00 0 11696 157 0 0
sp_pay_from_customer 7592 7592 0 0.00 6 13283 170 0 0
sp_pay_to_supplier 6128 6128 0 0.00 6 11739 180 0 0
sp_payment_common 13720 13720 0 0.00 2 9314 46 0 0
sp_qd_handle_on_cancel_clo 1885 1885 0 0.00 0 8310 343 0 0
sp_qd_handle_on_invoice_upd_sts 10678 10678 0 0.00 0 24341 555 0 0
sp_qd_handle_on_reserve_upd_sts 10433 10433 0 0.00 0 16265 70 0 0
sp_reserve_write_off 10433 10433 0 0.00 8 16344 280 0 0
sp_supplier_invoice 5768 5768 0 0.00 9 77650 3014 0 0
sp_supplier_order 5841 5841 0 0.00 7 32993 1355 0 0
srv_aggregate_perf_data 119 119 0 0.00 2786 184298 18406 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 749 136650 32594 0 0
srv_find_qd_qs_mism 1064099 1064099 0 0.00 0 13359 12 0 0
srv_make_invnt_saldo 471 471 0 0.00 55 71904 4452 0 0
srv_make_money_saldo 958 958 0 0.00 1 11673 363 0 0
srv_recalc_idx_stat 5 5 0 0.00 63042 521646 238681 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5198 5198 0 0.00 1 11505 991 0 0
t$perf-norm:sp_cancel_adding_invoice 453 453 0 0.00 2 6640 458 0 0
t$perf-norm:sp_cancel_client_order 1885 1885 0 0.00 0 4672 28 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 1719 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 717 717 0 0.00 0 492 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 1786 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 2179 37 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 110906 7134 0 0
t$perf-norm:sp_cancel_write_off 1074 1074 0 0.00 0 602 17 0 0
t$perf-norm:sp_client_order 21666 21666 0 0.00 0 5851 34 0 0
t$perf-norm:sp_customer_reserve 1111 1111 0 0.00 0 7450 24 0 0
t$perf-norm:sp_pay_from_customer 7592 7592 0 0.00 0 9709 20 0 0
t$perf-norm:sp_pay_to_supplier 6128 6128 0 0.00 0 5901 22 0 0
t$perf-norm:sp_reserve_write_off 10433 10433 0 0.00 0 6637 22 0 0
t$perf-norm:sp_supplier_invoice 5768 5768 0 0.00 0 7351 31 0 0
t$perf-norm:sp_supplier_order 5841 5841 0 0.00 0 1891 29 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 959 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 1313 36 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 769 14 0 0
t$perf-norm:srv_make_money_saldo 958 958 0 0.00 0 2349 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 180 75 0 0
v_add_invoice_to_stock 10628 10292 336 3.16 0 5999 28 0 336
v_all_customers 40194 40194 0 0.00 0 114 4 0 0
v_all_suppliers 32244 32244 0 0.00 0 109 4 0 0
v_all_wares 43332 43332 0 0.00 0 140 4 0 0
v_cancel_adding_invoice 906 906 0 0.00 0 1630 17 0 0
v_cancel_client_order 3770 3770 0 0.00 0 2827 10 0 0
v_cancel_customer_prepayment 1434 1434 0 0.00 0 1640 14 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 449 10 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 6600 26 0 0
v_cancel_supplier_invoice 926 888 38 4.10 0 5798 31 0 38
v_cancel_supplier_order 824 824 0 0.00 0 1887 16 0 0
v_cancel_write_off 2148 2148 0 0.00 0 953 23 0 0
v_max_id_clo_ord 5840 5840 0 0.00 0 3317 6 0 0
v_max_id_clo_res 1111 1111 0 0.00 0 1511 14 0 0
v_max_id_ord_sup 5762 5762 0 0.00 0 3126 8 0 0
v_max_non_paid_invoice 1608 1608 0 0.00 0 100 8 0 0
v_max_non_paid_realizn 3679 3679 0 0.00 0 2629 9 0 0
v_min_id_clo_ord 5840 5840 0 0.00 0 1354 8 0 0
v_min_id_clo_res 1111 1111 0 0.00 0 7334 25 0 0
v_min_id_ord_sup 5762 5762 0 0.00 0 6849 9 0 0
v_min_non_paid_invoice 6128 6128 0 0.00 0 5381 15 0 0
v_min_non_paid_realizn 7592 7592 0 0.00 0 5444 17 0 0
v_our_firm 10964 10964 0 0.00 0 67 5 0 0
v_reserve_write_off 20866 20866 0 0.00 0 3581 11 0 0
x_lock_depdocs_on_canc_invoice 11219 11219 0 0.00 0 25902 129 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 10534 369 0 0
x_make_qstorno_1000_1200 5841 5841 0 0.00 3 32984 1177 0 0
x_make_qstorno_1200_2000 5768 5768 0 0.00 4 77629 2799 0 0
x_make_qstorno_2100_3300 486923 486923 0 0.00 0 83262 220 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-12-2022 04:38:26 to 17-12-2022 04:38:30.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:24 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:25 23314 8192000000 9722283536 10020048896 799679328 8.893 100 90 180 0 829227104 7435952 22111824
02:09:53 20283 8192000000 9759519216 10065530880 833019728 9.230 100 88 176 0 867295824 13200624 21075472
02:11:29 9874 8192000000 9808625952 10128539648 868554560 9.586 100 83 166 0 916589632 28411872 19623200
02:12:46 4359 8192000000 9863931024 10196791296 917739536 10.074 100 84 168 0 980149536 43879504 18530496
02:13:53 10258 8192000000 9916487472 10257838080 963545664 10.524 100 85 166 0 1025366384 43962688 17858032
02:15:07 1581 8192000000 9968301744 10321440768 999743072 10.876 100 90 172 0 1090319392 73493952 17082368
02:16:10 1042 8192000000 9984191760 10346799104 1024577792 11.116 100 96 187 0 1106552176 62564400 19409984
02:17:13 1045 8192000000 9995907184 10365599744 1037059776 11.236 100 95 177 2 1118807024 63803680 17943568
02:18:15 1122 8192000000 10021187536 10396426240 1057103520 11.429 100 92 170 4 1138103792 63502752 17497520
02:19:17 1074 8192000000 10031403040 10407358464 1066935056 11.523 100 96 184 1 1151280064 65802928 18542080
02:20:19 1415 8192000000 10038879984 10423541760 1070279488 11.555 100 79 150 1 1162425136 76256864 15888784
02:21:23 5453 8192000000 10041168032 10435428352 1075758592 11.607 100 55 109 1 1164061232 77794256 10508384
02:22:29 983 8192000000 10024358432 10411503616 1080888416 11.656 100 85 167 0 1145198320 47370528 16939376
02:23:34 859 8192000000 10015275072 10404945920 1081261600 11.659 100 72 138 0 1140289456 44679312 14348544
02:24:36 1065 8192000000 10038677232 10428289024 1088441952 11.728 100 82 158 0 1164883360 60046640 16394768
02:25:38 1111 8192000000 10074073600 10467999744 1097837552 11.817 100 84 157 1 1196237136 80867280 17532304
02:26:40 1242 8192000000 10058289520 10444435456 1091163600 11.754 100 95 184 1 1178270480 68674016 18432864
02:27:42 897 8192000000 10055275312 10443550720 1093675488 11.778 100 93 176 0 1178118272 65178896 19263888
02:28:45 1192 8192000000 10047895632 10439528448 1091938656 11.761 100 93 174 1 1168294784 55673376 20682752
02:29:47 860 8192000000 10039677792 10427789312 1088854224 11.732 100 83 148 3 1161130784 57789584 14486976
02:30:48 1044 8192000000 10046567968 10433458176 1075607728 11.606 100 90 172 2 1171616064 78711264 17297072
02:31:51 937 8192000000 10037934960 10419015680 1057438096 11.432 100 82 151 0 1158064192 84926752 15699344
02:32:53 966 8192000000 9993960624 10367156224 1031794752 11.186 99 87 171 2 1116143840 66594832 17754256
02:33:55 1175 8192000000 9979762528 10353164288 1036730080 11.233 100 90 164 2 1104123440 50868160 16525200
02:34:57 1101 8192000000 9973404224 10344062976 1019450240 11.067 100 87 158 1 1097252608 62620368 15182000
02:35:59 1166 8192000000 9976315136 10344284160 1022949392 11.100 100 91 176 1 1099305008 57842832 18512784
02:37:01 1523 8192000000 9965654816 10337771520 1031764640 11.185 100 82 147 0 1084230512 37902640 14563232
02:38:08 1231 8192000000 10023668304 10399342592 1056687168 11.425 100 96 175 1 1140208384 66427824 17093392
02:39:12 1802 8192000000 10067643936 10446835712 1069828912 11.550 100 88 171 2 1185711408 98189120 17693376
02:40:20 1083 8192000000 10089851840 10472656896 1086011280 11.705 100 96 179 1 1204214416 99441008 18762128
02:41:25 6706 8192000000 10092692752 10479325184 1096156592 11.801 100 83 154 1 1212356656 100807056 15393008
02:42:37 1529 8192000000 10133634992 10535673856 1113613504 11.967 100 86 168 0 1251032352 118850272 18568576
02:43:42 5053 8192000000 10149595872 10537455616 1125029968 12.074 100 88 166 1 1259474944 117754800 16690176
02:44:50 1405 8192000000 10201849168 10597740544 1142416944 12.238 100 90 181 1 1321029968 158446112 20166912
02:45:53 2007 8192000000 10195339152 10600767488 1138299824 12.200 100 78 155 0 1305624832 150333856 16991152
02:47:01 2337 8192000000 10232310560 10629091328 1155377152 12.360 100 86 158 2 1346558848 173970624 17211072
02:48:06 2687 8192000000 10270129728 10670882816 1164966016 12.450 100 83 163 0 1380285488 197039536 18279936
02:49:12 878 8192000000 10320676592 10723696640 1171857264 12.514 100 90 177 2 1431392032 239510496 20024272
02:50:16 1072 8192000000 10356904512 10760454144 1173523696 12.530 100 94 185 3 1462160768 266648336 21988736
02:51:22 992 8192000000 10355042912 10801115136 1175019792 12.544 100 83 162 0 1466987504 273278512 18689200
02:52:28 952 8192000000 10402767440 10836598784 1192474384 12.706 100 94 183 0 1509812608 295647296 21690928
02:53:33 2307 8192000000 10395105296 10817728512 1190977008 12.692 100 70 133 1 1499463168 292179664 16306496
02:54:38 1454 8192000000 10433769088 10854461440 1203164640 12.806 100 95 189 0 1545729072 320765344 21799088
02:55:43 718 8192000000 10445374224 10866278400 1201617184 12.791 100 91 181 0 1554051664 330541392 21893088
02:56:46 762 8192000000 10476576496 10897408000 1202563744 12.800 100 97 194 0 1584189488 358487168 23138576
02:57:48 2737 8192000000 10482564016 10905772032 1198527168 12.763 100 82 160 0 1592839952 375390944 18921840
02:58:55 778 8192000000 10551854144 10963222528 1203282736 12.807 100 96 185 2 1656992176 430255872 23453568
03:00:00 1596 8192000000 10575481840 10998923264 1197536608 12.753 100 81 153 1 1680692912 464455984 18700320
03:01:03 1481 8192000000 10623346656 11040808960 1211484544 12.883 100 80 158 1 1725768592 495420688 18863360
03:02:09 2339 8192000000 10590632016 11038564352 1188877920 12.673 100 71 138 1 1695851712 489360192 17613600
03:03:16 1633 8192000000 10599476368 11052527616 1184330176 12.631 100 94 187 1 1707289552 500235184 22724192
03:04:20 5777 8192000000 10550512240 11038900224 1171032496 12.506 100 58 115 0 1678760816 492768688 14959632
03:05:35 15739 8192000000 10491008480 11007901696 1177336960 12.565 100 43 86 1 1598171056 409973168 10860928
03:07:23 19950 8192000000 10481862656 10977124352 1160301376 12.406 99 33 66 0 1578596352 409611664 8683312
03:09:00 11095 8192000000 10386414960 10888679424 1124074176 12.065 99 30 60 0 1497634720 365915712 7644832
03:10:14 27989 8192000000 10246530912 10740248576 1129015376 12.112 99 36 72 1 1399329040 261474960 8838704
03:11:44 26084 8192000000 10195709632 10709934080 1130751312 12.128 98 30 58 1 1300700016 163386176 6562528
03:13:14 97113 8192000000 10056405264 10539663360 1103432960 11.870 98 13 24 0 1200217200 93703552 3080688
03:15:52 83428 8192000000 10102164960 10531373056 1119639552 12.024 98 18 34 0 1200312848 76729216 3944080
03:18:16 17628 8192000000 10068066384 10491645952 1106950032 11.904 97 37 73 0 1148771648 34096352 7725264
03:19:53 105719 8192000000 10079747328 10512584704 1106887568 11.903 98 32 59 1 1160289808 46510576 6891664
03:23:00 85524 8192000000 10065111616 10507796480 1103228080 11.868 98 46 90 0 1164840528 52547312 9065136
03:25:32 28889 8192000000 10112445056 10567421952 1127521440 12.098 99 33 64 0 1201804896 66943520 7339936
03:27:02 8093 8192000000 10180726288 10613952512 1147855216 12.289 100 54 106 1 1272748928 113774528 11119184
03:28:12 26647 8192000000 10175940944 10629382144 1123155840 12.057 98 36 72 0 1270421904 139342384 7923680
03:29:50 32262 8192000000 10081636656 10522443776 1117009248 11.999 98 18 34 0 1180484176 59665216 3809712
03:31:23 69260 8192000000 10102089552 10589278208 1127846912 12.101 99 19 39 0 1221100672 88944192 4309568
03:33:38 48345 8192000000 10114582624 10573225984 1109525120 11.928 98 21 42 0 1190488048 76346512 4616416
03:35:35 70707 8192000000 10053560368 10560610304 1097212800 11.811 97 22 43 0 1173839120 72573248 4053072
03:38:47 30859 8192000000 10035124208 10466725888 1103565440 11.871 98 32 58 0 1124529216 14289712 6674064
03:40:18 27289 8192000000 10069613248 10502574080 1108089472 11.914 98 45 87 0 1160086160 42828112 9168576
03:41:49 10146 8192000000 10159777248 10592354304 1142681648 12.241 100 49 98 0 1250467584 97193280 10592656
03:43:24 40773 8192000000 10101692784 10564788224 1131939632 12.140 100 20 39 0 1192803360 57358592 3505136
03:45:07 62730 8192000000 10090003968 10579128320 1122485920 12.050 99 17 34 0 1204018112 77583552 3948640
03:47:12 83577 8192000000 10147980240 10603208704 1135280688 12.171 99 40 80 0 1200035920 56487968 8267264
03:49:44 101119 8192000000 10105827488 10555637760 1095953792 11.799 96 24 48 0 1153298336 52169072 5175472
03:52:53 94441 8192000000 10070714176 10522861568 1134559440 12.164 100 23 45 0 1175844944 36950800 4334704
03:55:30 29413 8192000000 10062721344 10521833472 1125485808 12.079 99 34 69 0 1161140416 29061424 6593184
03:57:17 28147 8192000000 10050977920 10517037056 1113813424 11.969 99 8 16 0 1150265968 34740528 1712016
03:58:46 87786 8192000000 10091089008 10550407168 1132960768 12.149 100 16 32 0 1180586336 43690896 3934672
04:01:19 66856 8192000000 10151091952 10624548864 1140024576 12.216 100 20 40 0 1236741232 92354304 4362352
04:03:38 60736 8192000000 10073642624 10516176896 1094215264 11.783 96 17 34 0 1143983872 46269152 3499456
04:05:39 13942 8192000000 10126418976 10558271488 1129470432 12.116 99 48 96 0 1207720784 68394976 9855376
04:06:54 28112 8192000000 10130380848 10570530816 1116043280 11.990 98 41 83 0 1203421024 78819680 8558064
04:08:23 25583 8192000000 10169861504 10655162368 1141123520 12.226 100 34 68 0 1242886736 94888384 6874832
04:09:48 37190 8192000000 10178660176 10620084224 1154129936 12.348 99 38 76 0 1269166208 106732656 8303616
04:12:02 136650 8192000000 10130484784 10590371840 1126174960 12.085 98 30 59 0 1217111824 84420592 6516272
04:15:19 61512 8192000000 10113885728 10580905984 1126254672 12.086 98 26 52 0 1182097472 50579280 5263520
04:17:21 99001 8192000000 10062279696 10515165184 1115714496 11.986 98 25 46 0 1149955920 29309168 4932256
04:20:01 47386 8192000000 10124050944 10594627584 1150067776 12.310 100 34 67 0 1220851600 64163712 6620112
04:21:48 26960 8192000000 10158146928 10600296448 1149770496 12.307 99 40 80 0 1238749136 79892256 9086384
04:23:20 32850 8192000000 10166511104 10627563520 1146313760 12.275 100 33 67 0 1269284240 115752672 7217808
04:24:55 53737 8192000000 10070492880 10588364800 1134657200 12.165 99 9 16 0 1208928144 72402400 1868544
04:26:49 73959 8192000000 10084013232 10556178432 1123109248 12.056 98 11 22 0 1146018528 21096592 1812688
04:29:03 54265 8192000000 10081153424 10546364416 1109630288 11.929 97 24 48 0 1158938240 43912480 5395472
04:30:58 22426 8192000000 10076670096 10513461248 1101794048 11.855 97 42 81 0 1148519728 38391744 8333936
04:32:20 12276 8192000000 10103506112 10536882176 1110627744 11.938 99 59 118 0 1188785824 66829872 11328208
04:33:37 16551 8192000000 10090913168 10524872704 1120716144 12.034 100 56 108 0 1191922416 60441392 10764880
04:34:55 45472 8192000000 10097021184 10538180608 1105402944 11.889 98 23 46 0 1156181536 46604240 4174352
04:36:49 41456 8192000000 9278209376 9627123712 407067792 4.733 36 19 38 0 473653360 62681952 3903616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 17-12-2022 04:38:32 to 17-12-2022 04:38:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-12-2022 04:38:50 to 17-12-2022 04:38: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          227207
MON$OLDEST_ACTIVE               231277
MON$OLDEST_SNAPSHOT             231277
MON$NEXT_TRANSACTION            231277
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                       2102464
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

17.12.22 04:38: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
2022-12-17 04:38:55 No severe PSQL-related problems occured
17.12.22 04:38:57. End of report.