Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2022 07: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 15182076 kB
MemAvailable 30545180 kB
Buffers 0 kB
Cached 16551332 kB
SwapCached 2072 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-10-13 07:37:20
DTS_END                         2022-10-13 07:37:20.0700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34544
TRN_ID                          224251
ATT_ID                          593
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: 9392.62 22818 1127114
customer order: creation 176.40 469 21168
customer order: refuse 14.97 412 1796
order to supplier: creation 46.69 1358 5603
order to supplier: removal 3.51 165733 421
invoice (draft): creation 47.18 3166 5662
invoice (draft): removal 53.61 919 6433
invoice accept: apply 42.45 26418 5094
invoice accept: cancel 47.55 1150 5706
customer reserve: creation 3971.26 250 476551
customer reserve: removal 4692.07 97 563048
realization accept: apply 83.69 282 10043
realization accept: cancel 80.87 175 9704
payment from customer: creation 59.87 175 7184
payment from customer: removal 5.90 132 708
payment to supplier: creation 50.21 193 6025
payment to supplier: removal 3.48 144 417
service: total inventory turnovers 4.16 3890 499
service: total monetary turnovers 7.85 328 942
service: aggregate perf. data 0.88 17302 105
service: refresh index statistics 0.04 233766 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2022 07:38:44 to 13-10-2022 07:38:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8995
TEST_TIME 2 6166
TEST_TIME 3 6519
TEST_TIME 4 6502
TEST_TIME 5 7007
TEST_TIME 6 6863
TEST_TIME 7 6745
TEST_TIME 8 6866
TEST_TIME 9 7231
TEST_TIME 10 7320
TEST_TIME 11 7619
TEST_TIME 12 7615
TEST_TIME 13 7664
TEST_TIME 14 7614
TEST_TIME 15 7728
TEST_TIME 16 7764
TEST_TIME 17 7811
TEST_TIME 18 7854
TEST_TIME 19 7764
TEST_TIME 20 7944
TEST_TIME 21 8074
TEST_TIME 22 8164
TEST_TIME 23 8279
TEST_TIME 24 8190
TEST_TIME 25 8222
TEST_TIME 26 8544
TEST_TIME 27 8977
TEST_TIME 28 8962
TEST_TIME 29 9469
TEST_TIME 30 9622
TEST_TIME 31 9806
TEST_TIME 32 10144
TEST_TIME 33 11055
TEST_TIME 34 11499
TEST_TIME 35 11221
TEST_TIME 36 11260
TEST_TIME 37 11471
TEST_TIME 38 11421
TEST_TIME 39 11205
TEST_TIME 40 11301
TEST_TIME 41 11172
TEST_TIME 42 10983
TEST_TIME 43 10820
TEST_TIME 44 10790
TEST_TIME 45 10605
TEST_TIME 46 10501
TEST_TIME 47 10440
TEST_TIME 48 10330
TEST_TIME 49 10357
TEST_TIME 50 10428
TEST_TIME 51 10392
TEST_TIME 52 10310
TEST_TIME 53 10183
TEST_TIME 54 10235
TEST_TIME 55 10133
TEST_TIME 56 10209
TEST_TIME 57 10150
TEST_TIME 58 10123
TEST_TIME 59 10212
TEST_TIME 60 10254
TEST_TIME 61 10204
TEST_TIME 62 10144
TEST_TIME 63 10206
TEST_TIME 64 10253
TEST_TIME 65 10217
TEST_TIME 66 10350
TEST_TIME 67 10354
TEST_TIME 68 10295
TEST_TIME 69 10237
TEST_TIME 70 10116
TEST_TIME 71 10122
TEST_TIME 72 10057
TEST_TIME 73 9992
TEST_TIME 74 9990
TEST_TIME 75 9944
TEST_TIME 76 9877
TEST_TIME 77 9887
TEST_TIME 78 9834
TEST_TIME 79 9807
TEST_TIME 80 9766
TEST_TIME 81 9844
TEST_TIME 82 9803
TEST_TIME 83 9801
TEST_TIME 84 9809
TEST_TIME 85 9811
TEST_TIME 86 9847
TEST_TIME 87 9820
TEST_TIME 88 9827
TEST_TIME 89 9874
TEST_TIME 90 9845
TEST_TIME 91 9794
TEST_TIME 92 9780
TEST_TIME 93 9777
TEST_TIME 94 9770
TEST_TIME 95 9735
TEST_TIME 96 9710
TEST_TIME 97 9669
TEST_TIME 98 9657
TEST_TIME 99 9685
TEST_TIME 100 9672
TEST_TIME 101 9640
TEST_TIME 102 9629
TEST_TIME 103 9613
TEST_TIME 104 9686
TEST_TIME 105 9632
TEST_TIME 106 9706
TEST_TIME 107 9762
TEST_TIME 108 9782
TEST_TIME 109 9734
TEST_TIME 110 9703
TEST_TIME 111 9674
TEST_TIME 112 9718
TEST_TIME 113 9703
TEST_TIME 114 9650
TEST_TIME 115 9593
TEST_TIME 116 9559
TEST_TIME 117 9516
TEST_TIME 118 9490
TEST_TIME 119 9478
TEST_TIME 120 9416

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-10-2022 07:38:48 to 13-10-2022 07:38:51.

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 1565515 1565515 0 0.00 0 20176 14 0 0
doc_list_biud 1565505 1565505 0 0.00 0 44967 40 0 0
sp_add_invoice_to_stock 5221 5094 127 2.43 61 212738 26419 0 127
sp_cancel_adding_invoice 5706 5706 0 0.00 7 88531 1150 0 0
sp_cancel_client_order 1796 1796 0 0.00 5 15247 412 0 0
sp_cancel_customer_reserve 563048 563048 0 0.00 1 30937 97 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 3 7947 132 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 4672 145 0 0
sp_cancel_supplier_invoice 6441 6433 8 0.12 3 44976 920 0 8
sp_cancel_supplier_order 421 421 0 0.00 13 1403920 165733 0 0
sp_cancel_write_off 9704 9704 0 0.00 2 15957 175 0 0
sp_client_order 21168 21168 0 0.00 5 29987 470 0 0
sp_customer_reserve 476551 476551 0 0.00 0 99675 250 0 0
sp_fill_shopping_cart 32427 32427 0 0.00 1 28509 82 0 0
sp_fill_shopping_cart_clo_res 476517 476517 0 0.00 0 16252 17 0 0
sp_get_clo_for_invoice 5056 5056 0 0.00 0 18027 221 0 0
sp_kill_cost_storno 16535 16535 0 0.00 0 9506 69 0 0
sp_kill_qstorno_ret_qs2qd 1142654 1142654 0 0.00 0 44966 41 0 0
sp_kill_qty_storno 602207 602207 0 0.00 0 44967 96 0 0
sp_lock_selected_doc 21001 21001 0 0.00 0 12072 9 0 0
sp_make_cost_storno 28308 28308 0 0.00 0 13662 77 0 0
sp_multiply_rows_for_pdistr 28308 28308 0 0.00 0 9373 38 0 0
sp_multiply_rows_for_qdistr 21168 21168 0 0.00 0 12526 168 0 0
sp_pay_from_customer 7184 7184 0 0.00 5 10611 176 0 0
sp_pay_to_supplier 6025 6025 0 0.00 6 10494 194 0 0
sp_payment_common 13209 13209 0 0.00 2 4265 43 0 0
sp_qd_handle_on_cancel_clo 1796 1796 0 0.00 0 15237 330 0 0
sp_qd_handle_on_invoice_upd_sts 10762 10762 0 0.00 0 14901 493 0 0
sp_qd_handle_on_reserve_upd_sts 10043 10043 0 0.00 0 25246 66 0 0
sp_reserve_write_off 10043 10043 0 0.00 9 25260 282 0 0
sp_supplier_invoice 5662 5662 0 0.00 10 83687 3166 0 0
sp_supplier_order 5603 5603 0 0.00 7 31065 1358 0 0
srv_aggregate_perf_data 105 105 0 0.00 2546 200525 17303 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 681 156815 33218 0 0
srv_find_qd_qs_mism 1076701 1076701 0 0.00 0 20174 11 0 0
srv_make_invnt_saldo 499 499 0 0.00 61 129180 3891 0 0
srv_make_money_saldo 942 942 0 0.00 0 13015 329 0 0
srv_recalc_idx_stat 5 5 0 0.00 74278 571971 233767 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 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 5098 5098 0 0.00 1 17962 978 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 1 8720 458 0 0
t$perf-norm:sp_cancel_client_order 1796 1796 0 0.00 0 7301 35 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 1019 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 1349 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 552 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 453 453 0 0.00 0 819 37 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 94169 6471 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 3157 21 0 0
t$perf-norm:sp_client_order 21169 21169 0 0.00 0 10237 37 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 1480 14 0 0
t$perf-norm:sp_pay_from_customer 7184 7184 0 0.00 0 5096 20 0 0
t$perf-norm:sp_pay_to_supplier 6025 6025 0 0.00 0 8121 22 0 0
t$perf-norm:sp_reserve_write_off 10043 10043 0 0.00 0 10649 25 0 0
t$perf-norm:sp_supplier_invoice 5662 5662 0 0.00 0 3252 31 0 0
t$perf-norm:sp_supplier_order 5604 5604 0 0.00 0 4762 34 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 298 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 461 14 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 1151 13 0 0
t$perf-norm:srv_make_money_saldo 942 942 0 0.00 0 1704 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 15 160 78 0 0
v_add_invoice_to_stock 10366 10112 254 2.45 0 5886 29 0 254
v_all_customers 39058 39058 0 0.00 0 131 4 0 0
v_all_suppliers 31870 31870 0 0.00 0 74 4 0 0
v_all_wares 42332 42332 0 0.00 0 169 4 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 4978 28 0 0
v_cancel_client_order 3592 3592 0 0.00 0 4492 13 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 876 11 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 730 12 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 4577 32 0 0
v_cancel_supplier_invoice 922 906 16 1.73 0 705 24 0 16
v_cancel_supplier_order 842 842 0 0.00 0 3904 20 0 0
v_cancel_write_off 2058 2058 0 0.00 0 3262 26 0 0
v_max_id_clo_ord 5601 5601 0 0.00 0 459 5 0 0
v_max_id_clo_res 1045 1045 0 0.00 0 5869 16 0 0
v_max_id_ord_sup 5659 5659 0 0.00 0 5213 7 0 0
v_max_non_paid_invoice 1350 1350 0 0.00 0 264 9 0 0
v_max_non_paid_realizn 3717 3717 0 0.00 0 3655 10 0 0
v_min_id_clo_ord 5601 5601 0 0.00 0 1355 8 0 0
v_min_id_clo_res 1045 1045 0 0.00 0 3408 13 0 0
v_min_id_ord_sup 5659 5659 0 0.00 0 6738 14 0 0
v_min_non_paid_invoice 6025 6025 0 0.00 0 4259 12 0 0
v_min_non_paid_realizn 7183 7183 0 0.00 0 4041 14 0 0
v_our_firm 10208 10208 0 0.00 0 65 5 0 0
v_reserve_write_off 20085 20085 0 0.00 0 5311 13 0 0
x_lock_depdocs_on_canc_invoice 11686 11686 0 0.00 0 13240 112 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 8526 349 0 0
x_make_qstorno_1000_1200 5603 5603 0 0.00 3 31036 1193 0 0
x_make_qstorno_1200_2000 5662 5662 0 0.00 5 77956 2952 0 0
x_make_qstorno_2100_3300 476551 476551 0 0.00 0 99662 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2022 07:38:52 to 13-10-2022 07:38:56.

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:23 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:25 28185 8192000000 9717406368 10014621696 785999056 8.754 100 88 176 0 814980352 7091168 21890128
05:09:55 18232 8192000000 9752961696 10058018816 829451232 9.194 100 94 186 0 863456096 11761264 22243600
05:11:20 9634 8192000000 9775196576 10094231552 855052320 9.451 100 78 156 0 891694432 18292720 18349392
05:12:32 11794 8192000000 9854875264 10186702848 905244768 9.950 100 83 166 0 966351728 42700464 18406496
05:13:49 5646 8192000000 9922058128 10266517504 957647232 10.466 100 84 168 1 1038322784 62447840 18227712
05:14:58 2169 8192000000 9937155664 10288328704 988222544 10.764 100 89 172 0 1056315584 51016384 17076656
05:16:03 1896 8192000000 10007088768 10368036864 1014616896 11.020 100 100 193 2 1129330896 94898832 19815168
05:17:07 958 8192000000 10021466656 10393468928 1036378384 11.230 100 85 164 0 1138649424 85973040 16298000
05:18:10 1055 8192000000 10033913568 10408656896 1056347856 11.422 100 95 176 0 1156343232 82026112 17969264
05:19:13 1111 8192000000 10049016288 10425712640 1066470240 11.518 100 94 181 2 1169678784 84569056 18639488
05:20:16 1114 8192000000 10038741168 10417475584 1071495344 11.566 100 89 173 1 1157473152 69616288 16361520
05:21:19 1003 8192000000 10071442448 10454204416 1076143280 11.611 100 95 182 0 1194287840 99966800 18177760
05:22:22 954 8192000000 10043391120 10425126912 1081426272 11.661 100 94 184 1 1164124096 64124064 18573760
05:23:24 1181 8192000000 10062527520 10450628608 1088774880 11.731 100 95 181 2 1186370848 78420784 19175184
05:24:26 1154 8192000000 10048000048 10428530688 1093894624 11.780 100 90 160 1 1163959536 55333968 14730944
05:25:28 2205 8192000000 10058980368 10457997312 1096718336 11.806 100 83 158 1 1185804512 72954304 16131872
05:26:32 1228 8192000000 10071334032 10463309824 1098539744 11.824 100 97 180 0 1193603728 76529184 18534800
05:27:34 1298 8192000000 10051918016 10437505024 1098161152 11.820 100 97 182 2 1170112208 53255360 18695696
05:28:36 2856 8192000000 10078462480 10475081728 1102892608 11.865 100 86 163 0 1201273952 81782048 16599296
05:29:40 986 8192000000 10056174128 10447130624 1102694320 11.863 100 90 171 2 1176930240 57657696 16578224
05:30:42 1098 8192000000 10043932800 10428301312 1078647648 11.635 100 88 170 2 1165134976 69352016 17135312
05:31:46 2301 8192000000 9984712800 10363781120 1049012160 11.351 99 70 135 2 1104408288 41944224 13451904
05:32:49 907 8192000000 9975235296 10352431104 1038419312 11.249 99 91 164 3 1098546208 41625008 18501888
05:33:50 4151 8192000000 9971756256 10346971136 1022628544 11.097 98 75 130 0 1090372976 54393632 13350800
05:34:56 4847 8192000000 9983477904 10369769472 1047652496 11.338 100 62 113 1 1107436816 48707584 11076736
05:36:02 1846 8192000000 10012868144 10394423296 1047991680 11.341 100 63 122 1 1131347792 70283088 13073024
05:37:04 1298 8192000000 10006897232 10384781312 1050654112 11.367 100 79 144 4 1125893664 60264800 14974752
05:38:13 2172 8192000000 10013215632 10389331968 1062094576 11.477 99 81 149 1 1133332624 57289936 13948112
05:39:17 15928 8192000000 10062370336 10445979648 1077639184 11.625 100 80 153 1 1176108960 82366544 16103232
05:40:40 2347 8192000000 10040428096 10423660544 1081449872 11.661 100 82 157 1 1161188080 63421408 16316800
05:41:46 4384 8192000000 10094912224 10477146112 1101524496 11.852 100 90 171 0 1202540176 82612736 18402944
05:42:56 1200 8192000000 10104259680 10491740160 1109392496 11.927 100 89 161 6 1216855920 90911504 16551920
05:44:01 2371 8192000000 10126792544 10522501120 1121206592 12.038 100 72 137 0 1245554928 109345568 15002768
05:45:14 1714 8192000000 10173762640 10565423104 1132117520 12.141 100 92 171 1 1284350000 133488416 18744064
05:46:18 1295 8192000000 10195045888 10591358976 1142373024 12.238 100 94 181 0 1312101168 150746704 18981440
05:47:22 831 8192000000 10236799744 10632278016 1155704768 12.363 100 90 168 1 1348857312 175497360 17655184
05:48:29 1504 8192000000 10240301360 10640211968 1167874752 12.477 100 85 164 0 1356754304 171786016 17093536
05:49:34 910 8192000000 10256759808 10661138432 1171473344 12.511 100 92 175 0 1368375744 178283680 18618720
05:50:38 1430 8192000000 10292358640 10699345920 1182099936 12.610 100 90 170 0 1406130128 205719088 18311104
05:51:42 1299 8192000000 10340917152 10740035584 1182096880 12.610 100 96 187 2 1450874928 247274240 21503808
05:52:46 1288 8192000000 10368948880 10780348416 1184667840 12.634 100 97 186 0 1473681744 267422144 21591760
05:53:50 1162 8192000000 10424071168 10834190336 1197870288 12.757 100 95 186 1 1533802160 314195664 21736208
05:54:56 1269 8192000000 10417970752 10839343104 1208319616 12.854 100 86 160 0 1526050208 298126432 19604160
05:56:01 820 8192000000 10472694112 10874740736 1200906176 12.785 100 98 190 2 1576886048 353858048 22121824
05:57:04 1086 8192000000 10486237216 10917715968 1192237536 12.704 100 92 178 0 1590705232 376867792 21599904
05:58:09 767 8192000000 10509012752 10939322368 1190166048 12.685 100 97 189 1 1614057520 400783312 23108160
05:59:11 908 8192000000 10556728272 10975170560 1185101712 12.638 100 98 189 0 1663261952 454604016 23556224
06:00:14 804 8192000000 10563193440 10992324608 1191235216 12.695 100 95 180 0 1666855680 453715568 21904896
06:01:17 1223 8192000000 10610737456 11019685888 1187104064 12.656 100 90 168 3 1714655296 506368768 21182464
06:02:23 870 8192000000 10635374160 11092537344 1188740432 12.672 100 88 170 0 1739358384 527964400 22653552
06:03:27 672 8192000000 10642300192 11118768128 1197641456 12.754 100 94 182 1 1743685680 524190784 21853440
06:04:31 3335 8192000000 10607936720 11074846720 1197216384 12.750 100 60 118 0 1711817536 499434544 15166608
06:05:42 14195 8192000000 10588454832 11049701376 1186915008 12.655 100 45 90 1 1699341392 500095808 12330576
06:07:04 12849 8192000000 10502974816 11003764736 1180669856 12.596 100 30 58 0 1634715712 445837120 8208736
06:08:54 20374 8192000000 10423653600 10913013760 1138066080 12.197 100 29 58 0 1524498576 378754112 7678384
06:10:18 38571 8192000000 10187140768 10654502912 1149799424 12.308 99 21 42 1 1336926384 182070672 5056288
06:11:57 65476 8192000000 10111161984 10539606016 1092312000 11.765 96 36 70 1 1174066000 73981408 7772592
06:14:09 105684 8192000000 10079014048 10573365248 1095624960 11.796 97 10 20 0 1164541936 66388800 2528176
06:16:54 76589 8192000000 10077536432 10546741248 1112583920 11.957 98 27 50 0 1189112624 72141920 4386784
06:19:15 84503 8192000000 10076103728 10515959808 1142134496 12.236 100 31 60 1 1181359968 32914560 6310912
06:21:43 20999 8192000000 10100956528 10539835392 1152501776 12.333 100 74 124 4 1189261072 23918448 12840848
06:23:17 5780 8192000000 10127728848 10566475776 1152888832 12.337 100 45 85 0 1218873472 57538608 8446032
06:24:23 54650 8192000000 10154196080 10599763968 1152289536 12.331 100 32 61 0 1233210704 74671728 6249440
06:26:27 48970 8192000000 10121595392 10608054272 1128891872 12.111 98 35 65 1 1233526992 96531200 8103920
06:28:20 28454 8192000000 10097314288 10552004608 1148254400 12.293 100 30 57 0 1199040544 45017520 5768624
06:30:03 45816 8192000000 10134402896 10605318144 1132859984 12.148 98 11 22 0 1212826736 77554640 2412112
06:31:50 48043 8192000000 10110247216 10566324224 1146515856 12.277 100 18 34 0 1201785936 51973040 3297040
06:33:38 41655 8192000000 10083935424 10536542208 1134641008 12.165 98 21 38 1 1192491312 52897584 4952720
06:35:26 120260 8192000000 10080661456 10560204800 1122267840 12.048 97 11 23 0 1180459488 55936784 2254864
06:38:39 25065 8192000000 10108518992 10556416000 1149291920 12.303 100 41 80 0 1197882848 40307024 8283904
06:40:06 84564 8192000000 10089270352 10566733824 1134683136 12.165 98 16 32 0 1233081200 94994624 3403440
06:42:34 41489 8192000000 10160441872 10624471040 1126723776 12.090 97 33 66 0 1234775600 101186512 6865312
06:44:16 66620 8192000000 10094656688 10545987584 1137353408 12.191 99 20 40 0 1180029968 38513536 4163024
06:46:35 76722 8192000000 10122012352 10570350592 1128126752 12.104 98 44 86 0 1201977568 63231936 10618880
06:48:54 31060 8192000000 10109040160 10583228416 1141498960 12.230 100 29 56 0 1211122640 64262832 5360848
06:50:26 82351 8192000000 10096774224 10594635776 1121449168 12.041 98 15 28 0 1214793616 90144880 3199568
06:52:49 66723 8192000000 10098664912 10555580416 1088816384 11.731 96 15 28 0 1156673392 64794384 3062624
06:55:12 24449 8192000000 10102437680 10562134016 1115374944 11.983 99 20 38 0 1191800432 72522320 3903168
06:56:53 46405 8192000000 10093022896 10542948352 1120846400 12.035 99 14 27 0 1206415952 82257664 3311888
06:58:49 17933 8192000000 10044807024 10481561600 1091871248 11.760 97 42 78 2 1122185744 22656448 7658048
07:00:08 15524 8192000000 10109747312 10539560960 1130627296 12.127 100 54 101 1 1203514176 63085952 9800928
07:01:27 21176 8192000000 10112372912 10559176704 1125716144 12.081 100 46 92 0 1225401520 90152704 9532672
07:02:49 8245 8192000000 10092649664 10510303232 1108711632 11.920 99 63 123 0 1183100288 62023456 12365200
07:03:58 4951 8192000000 10067525072 10493054976 1105579984 11.891 99 52 98 0 1156593104 40519296 10493824
07:05:05 38732 8192000000 10097065472 10541125632 1104205712 11.878 99 44 87 0 1200287984 87467248 8615024
07:06:44 40265 8192000000 10064381248 10490404864 1053230544 11.392 94 26 52 0 1081926576 23610384 5085648
07:08:27 156814 8192000000 10074197296 10542215168 1089382448 11.737 98 25 46 1 1178712032 84834128 4495456
07:12:08 45847 8192000000 10054599136 10522193920 1110167488 11.934 99 16 30 1 1164506560 51545920 2793152
07:14:01 50098 8192000000 10076517248 10530721792 1084840352 11.694 96 9 18 0 1132941408 46497552 1603504
07:16:09 91489 8192000000 10069298416 10515378176 1122402112 12.050 99 13 26 0 1142413808 17306560 2705136
07:18:42 18182 8192000000 10148384624 10587127808 1149798336 12.308 100 39 77 0 1230581280 72786624 7996320
07:20:01 17480 8192000000 10168605824 10607570944 1157628656 12.381 100 33 64 1 1268388432 103576992 7182784
07:21:19 49262 8192000000 10119209168 10545102848 1105915200 11.894 96 36 67 0 1181662176 69209200 6537776
07:23:10 30413 8192000000 10201843904 10674462720 1141884912 12.233 100 43 86 0 1289141296 138494688 8761696
07:24:43 71863 8192000000 10073430032 10548887552 1084861312 11.694 95 13 26 0 1152299264 64859904 2578048
07:26:56 27478 8192000000 10137374896 10591899648 1139664560 12.212 100 41 80 0 1225283792 77365984 8253248
07:28:32 94682 8192000000 10112028976 10626617344 1127571296 12.098 99 24 48 0 1198661776 66432672 4657808
07:31:09 38553 8192000000 10062576704 10598273024 1130165232 12.123 100 6 12 0 1184862704 53850944 846528
07:32:48 54717 8192000000 10048823792 10510888960 1109106144 11.924 98 36 68 0 1152649600 34737024 8806432
07:35:02 100992 8192000000 10047348400 10509619200 1094845040 11.789 98 18 36 0 1154590896 55505920 4239936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-10-2022 07:38:58 to 13-10-2022 07:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 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 13-10-2022 07:39:15 to 13-10-2022 07:39:17.

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          222700
MON$OLDEST_ACTIVE               224429
MON$OLDEST_SNAPSHOT             224429
MON$NEXT_TRANSACTION            224429
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                       2070176
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2858 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2858 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2858 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2858 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 ---
:110: 
:111: mscs-test-161.budzdorov.ru    Thu Oct 13 07:37:22 2022
:112:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:113: 
:114: 
:115: mscs-test-161.budzdorov.ru    Thu Oct 13 07:37:22 2022
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:117: 
--- end of diff output ---

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

13.10.22 07:39:19. 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-10-13 07:39:21 No severe PSQL-related problems occured
13.10.22 07:39:22. End of report.