Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.03.2023 07:40
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 15003160 kB
MemAvailable 30460144 kB
Buffers 0 kB
Cached 16722880 kB
SwapCached 3040 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-03-12 07:37:26
DTS_END                         2023-03-12 07:37:26.1720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52980
TRN_ID                          222799
ATT_ID                          571
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8733.03 30272 1047963
customer order: creation 168.13 446 20175
customer order: refuse 14.88 419 1785
order to supplier: creation 43.98 1391 5278
order to supplier: removal 3.38 158608 405
invoice (draft): creation 45.33 3328 5439
invoice (draft): removal 47.83 996 5739
invoice accept: apply 42.07 29462 5048
invoice accept: cancel 43.33 1322 5199
customer reserve: creation 3865.64 284 463877
customer reserve: removal 4173.48 101 500817
realization accept: apply 83.57 284 10028
realization accept: cancel 72.63 172 8715
payment from customer: creation 58.34 181 7001
payment from customer: removal 5.55 135 666
payment to supplier: creation 48.79 205 5855
payment to supplier: removal 3.60 119 432
service: total inventory turnovers 3.80 4371 456
service: total monetary turnovers 7.86 419 943
service: aggregate perf. data 0.83 16520 100
service: refresh index statistics 0.04 386677 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-03-2023 07:40:30 to 12-03-2023 07:40:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9637
TEST_TIME 2 7544
TEST_TIME 3 7691
TEST_TIME 4 7477
TEST_TIME 5 7259
TEST_TIME 6 7170
TEST_TIME 7 6858
TEST_TIME 8 6924
TEST_TIME 9 6958
TEST_TIME 10 6929
TEST_TIME 11 7017
TEST_TIME 12 6978
TEST_TIME 13 6903
TEST_TIME 14 6942
TEST_TIME 15 7221
TEST_TIME 16 7406
TEST_TIME 17 7547
TEST_TIME 18 7607
TEST_TIME 19 7661
TEST_TIME 20 7819
TEST_TIME 21 7938
TEST_TIME 22 8057
TEST_TIME 23 8179
TEST_TIME 24 8523
TEST_TIME 25 8482
TEST_TIME 26 8595
TEST_TIME 27 8847
TEST_TIME 28 9009
TEST_TIME 29 9242
TEST_TIME 30 9404
TEST_TIME 31 9891
TEST_TIME 32 10029
TEST_TIME 33 10243
TEST_TIME 34 10641
TEST_TIME 35 10968
TEST_TIME 36 10997
TEST_TIME 37 10765
TEST_TIME 38 10555
TEST_TIME 39 10356
TEST_TIME 40 10338
TEST_TIME 41 10272
TEST_TIME 42 10214
TEST_TIME 43 10142
TEST_TIME 44 10041
TEST_TIME 45 9945
TEST_TIME 46 9970
TEST_TIME 47 9807
TEST_TIME 48 9709
TEST_TIME 49 9843
TEST_TIME 50 9817
TEST_TIME 51 9653
TEST_TIME 52 9558
TEST_TIME 53 9521
TEST_TIME 54 9637
TEST_TIME 55 9593
TEST_TIME 56 9576
TEST_TIME 57 9516
TEST_TIME 58 9591
TEST_TIME 59 9506
TEST_TIME 60 9525
TEST_TIME 61 9596
TEST_TIME 62 9539
TEST_TIME 63 9494
TEST_TIME 64 9547
TEST_TIME 65 9501
TEST_TIME 66 9588
TEST_TIME 67 9524
TEST_TIME 68 9419
TEST_TIME 69 9385
TEST_TIME 70 9309
TEST_TIME 71 9279
TEST_TIME 72 9290
TEST_TIME 73 9228
TEST_TIME 74 9174
TEST_TIME 75 9145
TEST_TIME 76 9054
TEST_TIME 77 8957
TEST_TIME 78 8948
TEST_TIME 79 8974
TEST_TIME 80 9071
TEST_TIME 81 9032
TEST_TIME 82 9122
TEST_TIME 83 9074
TEST_TIME 84 9092
TEST_TIME 85 9083
TEST_TIME 86 9120
TEST_TIME 87 9103
TEST_TIME 88 9198
TEST_TIME 89 9158
TEST_TIME 90 9151
TEST_TIME 91 9252
TEST_TIME 92 9176
TEST_TIME 93 9115
TEST_TIME 94 9084
TEST_TIME 95 9026
TEST_TIME 96 9041
TEST_TIME 97 8991
TEST_TIME 98 8977
TEST_TIME 99 8924
TEST_TIME 100 8884
TEST_TIME 101 8848
TEST_TIME 102 8832
TEST_TIME 103 8811
TEST_TIME 104 8796
TEST_TIME 105 8775
TEST_TIME 106 8744
TEST_TIME 107 8738
TEST_TIME 108 8783
TEST_TIME 109 8809
TEST_TIME 110 8840
TEST_TIME 111 8885
TEST_TIME 112 8911
TEST_TIME 113 8911
TEST_TIME 114 8954
TEST_TIME 115 8925
TEST_TIME 116 8908
TEST_TIME 117 8855
TEST_TIME 118 8861
TEST_TIME 119 8817
TEST_TIME 120 8758

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-03-2023 07:40:34 to 12-03-2023 07:40:38.

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 1475030 1475030 0 0.00 0 19283 15 0 0
doc_list_biud 1475016 1475016 0 0.00 0 44078 41 0 0
sp_add_invoice_to_stock 5161 5048 113 2.18 49 221327 29462 0 113
sp_cancel_adding_invoice 5199 5199 0 0.00 4 114577 1322 0 0
sp_cancel_client_order 1785 1785 0 0.00 5 12414 419 0 0
sp_cancel_customer_reserve 500817 500817 0 0.00 1 33829 101 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 5269 136 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 4 1743 120 0 0
sp_cancel_supplier_invoice 5747 5739 8 0.13 3 44083 996 0 8
sp_cancel_supplier_order 405 405 0 0.00 6 1466427 158609 0 0
sp_cancel_write_off 8715 8715 0 0.00 1 13143 173 0 0
sp_client_order 20175 20175 0 0.00 5 23912 446 0 0
sp_customer_reserve 463877 463877 0 0.00 0 41667 285 0 0
sp_fill_shopping_cart 30875 30875 0 0.00 1 17227 90 0 0
sp_fill_shopping_cart_clo_res 463846 463846 0 0.00 0 16704 19 0 0
sp_get_clo_for_invoice 5010 5010 0 0.00 0 34179 299 0 0
sp_kill_cost_storno 15012 15012 0 0.00 0 11864 73 0 0
sp_kill_qstorno_ret_qs2qd 1016493 1016493 0 0.00 0 44076 44 0 0
sp_kill_qty_storno 537697 537697 0 0.00 0 44077 102 0 0
sp_lock_selected_doc 20885 20885 0 0.00 0 7661 7 0 0
sp_make_cost_storno 27894 27894 0 0.00 0 19275 83 0 0
sp_multiply_rows_for_pdistr 27893 27893 0 0.00 0 11648 37 0 0
sp_multiply_rows_for_qdistr 20175 20175 0 0.00 0 16184 166 0 0
sp_pay_from_customer 7001 7001 0 0.00 5 13998 181 0 0
sp_pay_to_supplier 5855 5855 0 0.00 6 14247 205 0 0
sp_payment_common 12856 12856 0 0.00 2 10981 48 0 0
sp_qd_handle_on_cancel_clo 1785 1785 0 0.00 0 12405 349 0 0
sp_qd_handle_on_invoice_upd_sts 10208 10208 0 0.00 0 31807 560 0 0
sp_qd_handle_on_reserve_upd_sts 10028 10028 0 0.00 0 9233 66 0 0
sp_reserve_write_off 10028 10028 0 0.00 8 17504 284 0 0
sp_supplier_invoice 5439 5439 0 0.00 7 115744 3329 0 0
sp_supplier_order 5278 5278 0 0.00 9 65500 1391 0 0
srv_aggregate_perf_data 100 100 0 0.00 2456 156789 16521 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 668 107782 34940 0 0
srv_find_qd_qs_mism 999399 999399 0 0.00 0 15325 13 0 0
srv_make_invnt_saldo 456 456 0 0.00 37 150516 4371 0 0
srv_make_money_saldo 943 943 0 0.00 1 14319 420 0 0
srv_recalc_idx_stat 5 5 0 0.00 245606 538472 386677 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5050 5050 0 0.00 0 10486 1001 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 1 10567 512 0 0
t$perf-norm:sp_cancel_client_order 1785 1785 0 0.00 0 2413 27 0 0
t$perf-norm:sp_cancel_customer_reserve 739 739 0 0.00 0 1910 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 1631 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 779 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 1187 42 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 87114 6502 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 1718 26 0 0
t$perf-norm:sp_client_order 20175 20175 0 0.00 0 15548 40 0 0
t$perf-norm:sp_customer_reserve 1049 1049 0 0.00 0 1852 15 0 0
t$perf-norm:sp_pay_from_customer 7001 7001 0 0.00 0 15349 22 0 0
t$perf-norm:sp_pay_to_supplier 5855 5855 0 0.00 0 15544 27 0 0
t$perf-norm:sp_reserve_write_off 10028 10028 0 0.00 0 9029 21 0 0
t$perf-norm:sp_supplier_invoice 5441 5441 0 0.00 0 5900 33 0 0
t$perf-norm:sp_supplier_order 5278 5278 0 0.00 0 9973 34 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 561 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 203 13 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 1106 15 0 0
t$perf-norm:srv_make_money_saldo 943 943 0 0.00 0 681 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 184 75 0 0
v_add_invoice_to_stock 10228 10002 226 2.20 0 9277 26 0 226
v_all_customers 36602 36602 0 0.00 0 256 4 0 0
v_all_suppliers 29780 29780 0 0.00 0 256 5 0 0
v_all_wares 40338 40338 0 0.00 0 75 4 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 729 18 0 0
v_cancel_client_order 3570 3570 0 0.00 0 3266 10 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 5250 17 0 0
v_cancel_customer_reserve 1478 1478 0 0.00 0 707 12 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 1596 15 0 0
v_cancel_supplier_invoice 794 778 16 2.01 0 845 27 0 16
v_cancel_supplier_order 810 810 0 0.00 0 3164 18 0 0
v_cancel_write_off 2046 2046 0 0.00 0 5253 31 0 0
v_max_id_clo_ord 5276 5276 0 0.00 0 3388 6 0 0
v_max_id_clo_res 1049 1049 0 0.00 0 1341 11 0 0
v_max_id_ord_sup 5429 5429 0 0.00 0 3984 7 0 0
v_max_non_paid_invoice 1669 1669 0 0.00 0 133 8 0 0
v_max_non_paid_realizn 3789 3789 0 0.00 0 2744 9 0 0
v_min_id_clo_ord 5276 5276 0 0.00 0 6329 15 0 0
v_min_id_clo_res 1049 1049 0 0.00 0 3156 15 0 0
v_min_id_ord_sup 5428 5428 0 0.00 0 8702 20 0 0
v_min_non_paid_invoice 5855 5855 0 0.00 0 9627 17 0 0
v_min_non_paid_realizn 7001 7001 0 0.00 0 10961 18 0 0
v_our_firm 10158 10158 0 0.00 0 70 5 0 0
v_reserve_write_off 20056 20056 0 0.00 0 4133 11 0 0
x_lock_depdocs_on_canc_invoice 10549 10549 0 0.00 0 23343 140 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 14855 494 0 0
x_make_qstorno_1000_1200 5278 5278 0 0.00 2 65483 1204 0 0
x_make_qstorno_1200_2000 5439 5439 0 0.00 2 115314 3080 0 0
x_make_qstorno_2100_3300 463875 463875 0 0.00 0 41658 251 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-03-2023 07:40:39 to 12-03-2023 07:40:43.

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:30 2 8192000000 8797536816 8808288256 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:31 13360 8192000000 9715607776 10009047040 792588160 8.821 100 88 176 0 821823808 7455872 21779776
05:09:57 27658 8192000000 9760720192 10069508096 837860288 9.278 100 91 182 0 870362240 10811120 21690832
05:11:28 10622 8192000000 9798127408 10116399104 867223648 9.572 100 82 162 1 903907792 18181456 18502688
05:12:44 6280 8192000000 9849445232 10181181440 919114576 10.087 100 84 161 0 961847584 24720320 18012688
05:13:54 3143 8192000000 9917529024 10259202048 955127328 10.441 100 91 182 0 1034213136 59613168 19472640
05:15:00 1704 8192000000 9971553600 10324930560 991117664 10.792 100 93 179 0 1089027360 79257264 18652432
05:16:04 1101 8192000000 9991372896 10349891584 1015146688 11.025 100 86 168 0 1106324576 74522304 16655584
05:17:08 1168 8192000000 10013788400 10383159296 1038229120 11.248 100 92 180 5 1140027648 83762048 18036480
05:18:11 1423 8192000000 10007667472 10379837440 1054341872 11.402 100 96 183 0 1127904864 55120736 18442256
05:19:13 1138 8192000000 10037514560 10414903296 1067063440 11.524 100 95 177 0 1155983792 71059200 17861152
05:20:15 1125 8192000000 10041888992 10423234560 1072677408 11.578 100 88 164 3 1165798768 75542304 17579056
05:21:17 3019 8192000000 10025890624 10420011008 1079625728 11.644 100 71 134 3 1154164800 60924592 13614480
05:22:22 1261 8192000000 10045084992 10430291968 1084915856 11.694 100 94 176 5 1167319920 64227376 18176688
05:23:25 2907 8192000000 10033175104 10426372096 1083500192 11.681 100 70 136 0 1169131728 72093504 13538032
05:24:29 799 8192000000 10045134896 10434367488 1091482016 11.757 100 79 160 0 1171929408 63420304 17027088
05:25:31 5857 8192000000 10039257056 10434351104 1091762320 11.759 100 67 124 0 1169656912 65414192 12480400
05:26:38 923 8192000000 10068459328 10461229056 1090576288 11.748 100 94 181 0 1188423584 79790848 18056448
05:27:39 1216 8192000000 10071707728 10457653248 1094112944 11.782 100 95 183 2 1192033168 79396032 18524192
05:28:41 1218 8192000000 10059991776 10446757888 1092965216 11.771 100 94 186 1 1183045504 71322800 18757488
05:29:43 1097 8192000000 10053868192 10448748544 1100888016 11.846 100 89 168 2 1174217952 56325568 17004368
05:30:45 1132 8192000000 9998351296 10378014720 1052258576 11.382 98 80 137 0 1118542336 50403184 15880576
05:31:47 951 8192000000 10004097136 10379911168 1052117888 11.381 100 90 173 1 1127265104 55475040 19672176
05:32:49 1173 8192000000 9981460480 10352328704 1025530480 11.125 98 93 174 5 1106499360 62735632 18233248
05:33:51 1349 8192000000 9995351472 10368946176 1048221952 11.344 99 96 178 3 1121306560 55710912 17373696
05:34:53 1100 8192000000 9989657632 10367504384 1044578848 11.309 100 90 166 2 1108294768 46476048 17239872
05:35:55 1266 8192000000 9992060064 10366595072 1027909472 11.148 100 88 171 4 1108354160 63163872 17280816
05:36:58 1168 8192000000 9987938944 10360696832 1032339728 11.191 100 95 179 0 1108470448 57990416 18140304
05:38:01 2103 8192000000 10006030384 10386956288 1053658592 11.396 100 89 169 2 1126277408 55624144 16994672
05:39:10 21320 8192000000 10013316816 10405830656 1056490656 11.423 99 58 114 1 1131047728 63082544 11474528
05:40:37 1556 8192000000 10063759168 10449723392 1085651936 11.701 100 85 160 3 1185440544 84780832 15007776
05:41:42 1305 8192000000 10076858032 10464428032 1098999648 11.828 100 92 175 0 1195425744 78274592 18151504
05:42:47 5181 8192000000 10096269648 10493722624 1111650352 11.948 100 72 140 1 1213912544 88054000 14208192
05:43:55 8751 8192000000 10131087392 10528133120 1121050528 12.037 100 65 121 0 1237311392 103845424 12415440
05:45:13 2696 8192000000 10153934272 10551320576 1130095024 12.122 100 78 145 0 1270250512 125459264 14696224
05:46:18 975 8192000000 10177048736 10583248896 1140407824 12.219 100 85 160 2 1288577280 132001248 16168208
05:47:23 2043 8192000000 10189500048 10586656768 1149028976 12.300 100 80 146 1 1307006272 141911904 16065392
05:48:29 1759 8192000000 10246803696 10650017792 1159080016 12.395 100 83 151 1 1357963552 182995648 15887888
05:49:33 2902 8192000000 10279690240 10685128704 1175119024 12.545 100 77 144 1 1389372240 198521952 15731264
05:50:41 942 8192000000 10308162528 10706366464 1179484112 12.585 100 97 187 0 1421428976 220753888 21190976
05:51:47 942 8192000000 10311329792 10731376640 1187377648 12.659 100 94 178 1 1424258912 216847920 20033344
05:52:50 868 8192000000 10328922368 10759217152 1177498080 12.567 100 88 167 2 1435243024 238685328 19059616
05:53:53 1078 8192000000 10342655584 10751541248 1182904912 12.617 100 96 180 0 1448146592 244415440 20826240
05:54:57 903 8192000000 10393816048 10800992256 1193591584 12.717 100 95 188 0 1497186400 282487344 21107472
05:56:01 1260 8192000000 10395049696 10827427840 1181001104 12.600 100 98 193 0 1499285888 295532080 22752704
05:57:07 889 8192000000 10429405664 10829516800 1172171840 12.517 100 95 187 1 1535985024 342968848 20844336
05:58:10 703 8192000000 10454387216 10858975232 1183546272 12.623 100 98 194 2 1561000288 354155952 23298064
05:59:12 2060 8192000000 10480580672 10901766144 1180902208 12.599 100 96 193 0 1577779776 373931072 22946496
06:00:18 952 8192000000 10490659264 10906357760 1186002208 12.646 100 96 191 1 1594129552 386180432 21946912
06:01:22 659 8192000000 10476269904 10929446912 1188223936 12.667 100 91 161 0 1579558192 371206864 20127392
06:02:27 1576 8192000000 10521595008 10931306496 1186186304 12.648 100 88 175 1 1623874240 415747696 21940240
06:03:31 727 8192000000 10512537888 10968793088 1192901600 12.710 100 93 185 0 1617226496 400900576 23424320
06:04:34 1689 8192000000 10491349904 10958090240 1192868720 12.710 100 72 139 1 1594443200 384611232 16963248
06:05:44 11104 8192000000 10488563792 10918367232 1192402848 12.706 100 63 115 2 1593954208 388059200 13492160
06:07:00 17641 8192000000 10464066816 10948173824 1170377136 12.500 100 33 66 0 1565907648 386146736 9383776
06:08:19 6660 8192000000 10375730576 10872614912 1159737552 12.401 100 35 70 0 1496177600 326785824 9654224
06:09:27 28197 8192000000 10276037760 10755919872 1132675456 12.147 99 17 34 0 1381938192 244326736 4936000
06:11:28 49405 8192000000 10199921200 10724900864 1144459568 12.257 100 21 42 0 1305321808 156075552 4786688
06:13:23 52093 8192000000 10060877424 10497089536 1094319792 11.784 96 25 43 1 1109701072 11810048 3571232
06:15:16 87112 8192000000 10156912320 10603786240 1136928800 12.187 99 48 86 3 1253892944 107141328 9822816
06:18:21 107781 8192000000 10098017280 10540949504 1127500176 12.098 99 34 64 1 1189084768 55268480 6316112
06:21:12 63041 8192000000 10138965856 10601086976 1140869776 12.224 100 18 36 0 1228088464 83007264 4211424
06:23:27 80722 8192000000 10113163616 10565931008 1096397776 11.803 96 14 28 0 1186333328 86783440 3152112
06:25:48 41726 8192000000 10109710736 10642460672 1126019328 12.084 99 15 29 1 1231793872 102490080 3284464
06:27:38 81082 8192000000 10122299888 10576039936 1149320176 12.303 100 17 35 0 1188293936 35403888 3569872
06:30:00 37578 8192000000 10101569168 10580049920 1145796864 12.270 100 20 40 0 1225065728 75361568 3907296
06:31:39 25978 8192000000 10066934464 10557042688 1127229440 12.095 99 10 19 0 1176814032 47963344 1621248
06:33:05 28783 8192000000 10103040080 10534645760 1114249840 11.973 98 40 76 0 1176180944 53718992 8212112
06:34:38 63161 8192000000 10122744512 10575351808 1111219152 11.944 97 17 34 0 1191789920 76986688 3584080
06:36:41 43851 8192000000 10129812112 10582827008 1132398752 12.144 100 33 66 0 1220550720 81055840 7096128
06:38:25 76727 8192000000 10088836064 10531573760 1140394224 12.219 100 40 78 0 1181836624 33358128 8084272
06:40:44 51931 8192000000 10071330240 10542104576 1086749376 11.712 95 30 60 0 1166279744 73492608 6037760
06:42:50 46456 8192000000 10143099600 10642690048 1130380560 12.125 99 39 78 0 1258817872 120172576 8264736
06:44:37 79574 8192000000 10054519456 10492256256 1109863552 11.931 98 36 69 0 1151546496 34057856 7625088
06:46:58 61803 8192000000 10045436400 10472722432 1096699008 11.806 98 53 103 1 1140692432 32501584 11491840
06:49:03 69353 8192000000 10044357024 10512769024 1132292400 12.143 100 19 39 1 1181417984 44963440 4162144
06:51:16 39882 8192000000 10087903120 10542600192 1129467808 12.116 99 19 38 0 1184149776 50520608 4161360
06:53:24 74390 8192000000 10134178304 10590806016 1122062992 12.046 98 16 31 0 1218033776 92626464 3344320
06:55:51 83732 8192000000 10130595552 10632814592 1116331824 11.992 98 18 36 0 1251306672 130926240 4048608
06:58:31 79630 8192000000 10064289120 10576416768 1117312336 12.002 98 16 32 1 1188805408 68429072 3064000
07:00:51 12771 8192000000 10116634624 10543935488 1133926880 12.158 100 61 121 1 1202019856 56004912 12088064
07:02:07 53009 8192000000 10105742048 10541395968 1099554464 11.833 97 26 51 0 1173659408 69144160 4960784
07:04:01 29842 8192000000 10150604480 10578219008 1119906368 12.026 98 50 96 0 1213042576 82827248 10308960
07:05:34 59466 8192000000 10089953552 10532847616 1128687712 12.109 99 22 44 0 1175343424 42073808 4581904
07:07:46 51426 8192000000 10147384256 10617634816 1136466192 12.182 99 29 58 1 1240855952 98192080 6197680
07:09:41 94991 8192000000 10074098032 10527338496 1116539280 11.994 97 33 66 1 1156912736 32991264 7382192
07:12:17 7562 8192000000 10072919952 10497970176 1130198512 12.123 99 56 104 1 1168985296 27927808 10858976
07:13:25 57772 8192000000 10052375312 10510409728 1112428224 11.955 97 17 34 0 1148994128 33166736 3399168
07:15:23 64767 8192000000 10053489232 10532089856 1111527360 11.947 98 8 16 0 1162233536 48788624 1917552
07:17:50 48184 8192000000 10071657328 10536816640 1147245168 12.284 100 29 47 3 1174325328 22360304 4719856
07:19:40 51673 8192000000 10071225792 10531823616 1148755712 12.298 100 12 22 0 1189528736 37885712 2887312
07:21:41 48710 8192000000 10092361520 10536632320 1082534448 11.672 94 21 42 0 1148429984 61460336 4435200
07:23:31 28577 8192000000 10165574720 10592772096 1133850688 12.158 99 42 84 0 1239410544 96488608 9071248
07:25:05 37157 8192000000 10198076864 10623741952 1117591680 12.004 97 40 80 0 1255706256 129127712 8986864
07:26:43 56807 8192000000 10137284800 10590822400 1098900864 11.827 96 23 45 0 1218616288 115094240 4621184
07:28:44 53023 8192000000 10078333984 10548891648 1081593328 11.663 96 29 58 0 1161349456 73923840 5832288
07:30:38 10583 8192000000 10082961744 10501230592 1095738336 11.797 98 49 98 0 1157825360 52631552 9455472
07:31:49 37536 8192000000 10078468992 10498498560 1106260224 11.897 99 46 91 0 1193557488 78328048 8969216
07:33:32 95033 8192000000 10069628336 10495111168 1102421328 11.861 98 56 112 0 1185410592 71576352 11412912
07:36:13 61205 8192000000 10070008992 10532925440 1119861200 12.026 99 7 14 0 1164944672 43591136 1492336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-03-2023 07:40:45 to 12-03-2023 07:41:01.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-03-2023 07:41:02 to 12-03-2023 07:41:04.

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          221516
MON$OLDEST_ACTIVE               222925
MON$OLDEST_SNAPSHOT             222925
MON$NEXT_TRANSACTION            222925
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                       2072608
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

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

12.03.23 07:41:06. 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-03-12 07:41:08 No severe PSQL-related problems occured
12.03.23 07:41:10. End of report.