Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.02.2023 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 14943876 kB
MemAvailable 30360408 kB
Buffers 20 kB
Cached 16686988 kB
SwapCached 3140 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-25 04:37:27
DTS_END                         2023-02-25 04:37:27.4930
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/46252
TRN_ID                          211362
ATT_ID                          561
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: 9189.10 20067 1102692
customer order: creation 172.63 421 20716
customer order: refuse 14.62 386 1754
order to supplier: creation 46.18 1272 5542
order to supplier: removal 3.42 159335 410
invoice (draft): creation 45.33 2924 5440
invoice (draft): removal 51.10 972 6132
invoice accept: apply 42.10 25069 5052
invoice accept: cancel 45.98 1067 5517
customer reserve: creation 3994.50 236 479340
customer reserve: removal 4472.19 94 536663
realization accept: apply 85.09 249 10211
realization accept: cancel 85.28 169 10234
payment from customer: creation 60.40 167 7248
payment from customer: removal 5.86 149 703
payment to supplier: creation 48.89 189 5867
payment to supplier: removal 3.32 87 398
service: total inventory turnovers 4.03 4109 483
service: total monetary turnovers 7.19 297 863
service: aggregate perf. data 0.95 15130 114
service: refresh index statistics 0.04 189013 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-02-2023 04:38:54 to 25-02-2023 04:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4146
TEST_TIME 2 4315
TEST_TIME 3 4896
TEST_TIME 4 4769
TEST_TIME 5 5198
TEST_TIME 6 5188
TEST_TIME 7 5198
TEST_TIME 8 5217
TEST_TIME 9 5387
TEST_TIME 10 5577
TEST_TIME 11 5793
TEST_TIME 12 5922
TEST_TIME 13 5988
TEST_TIME 14 6152
TEST_TIME 15 6306
TEST_TIME 16 6531
TEST_TIME 17 6651
TEST_TIME 18 6898
TEST_TIME 19 7009
TEST_TIME 20 7068
TEST_TIME 21 7219
TEST_TIME 22 7839
TEST_TIME 23 8484
TEST_TIME 24 8573
TEST_TIME 25 8611
TEST_TIME 26 9033
TEST_TIME 27 9478
TEST_TIME 28 10172
TEST_TIME 29 10109
TEST_TIME 30 10355
TEST_TIME 31 10618
TEST_TIME 32 10905
TEST_TIME 33 10712
TEST_TIME 34 10577
TEST_TIME 35 10557
TEST_TIME 36 10482
TEST_TIME 37 10611
TEST_TIME 38 10476
TEST_TIME 39 10390
TEST_TIME 40 10303
TEST_TIME 41 10118
TEST_TIME 42 10197
TEST_TIME 43 10474
TEST_TIME 44 10547
TEST_TIME 45 10526
TEST_TIME 46 10534
TEST_TIME 47 10334
TEST_TIME 48 10216
TEST_TIME 49 10142
TEST_TIME 50 10107
TEST_TIME 51 9955
TEST_TIME 52 9858
TEST_TIME 53 9909
TEST_TIME 54 9881
TEST_TIME 55 9883
TEST_TIME 56 9909
TEST_TIME 57 9876
TEST_TIME 58 9811
TEST_TIME 59 9785
TEST_TIME 60 9721
TEST_TIME 61 9904
TEST_TIME 62 9898
TEST_TIME 63 9839
TEST_TIME 64 9894
TEST_TIME 65 9928
TEST_TIME 66 9887
TEST_TIME 67 9926
TEST_TIME 68 9872
TEST_TIME 69 9880
TEST_TIME 70 9815
TEST_TIME 71 9780
TEST_TIME 72 9788
TEST_TIME 73 9730
TEST_TIME 74 9699
TEST_TIME 75 9801
TEST_TIME 76 9749
TEST_TIME 77 9677
TEST_TIME 78 9603
TEST_TIME 79 9535
TEST_TIME 80 9497
TEST_TIME 81 9487
TEST_TIME 82 9479
TEST_TIME 83 9437
TEST_TIME 84 9419
TEST_TIME 85 9393
TEST_TIME 86 9350
TEST_TIME 87 9326
TEST_TIME 88 9334
TEST_TIME 89 9274
TEST_TIME 90 9313
TEST_TIME 91 9296
TEST_TIME 92 9358
TEST_TIME 93 9367
TEST_TIME 94 9379
TEST_TIME 95 9414
TEST_TIME 96 9396
TEST_TIME 97 9396
TEST_TIME 98 9462
TEST_TIME 99 9497
TEST_TIME 100 9495
TEST_TIME 101 9418
TEST_TIME 102 9391
TEST_TIME 103 9421
TEST_TIME 104 9404
TEST_TIME 105 9351
TEST_TIME 106 9333
TEST_TIME 107 9293
TEST_TIME 108 9295
TEST_TIME 109 9247
TEST_TIME 110 9252
TEST_TIME 111 9241
TEST_TIME 112 9222
TEST_TIME 113 9229
TEST_TIME 114 9252
TEST_TIME 115 9249
TEST_TIME 116 9240
TEST_TIME 117 9232
TEST_TIME 118 9235
TEST_TIME 119 9215
TEST_TIME 120 9214

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-02-2023 04:38:58 to 25-02-2023 04:39:02.

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 1542478 1542478 0 0.00 0 25062 13 0 0
doc_list_biud 1542466 1542466 0 0.00 0 26917 38 0 0
sp_add_invoice_to_stock 5184 5052 132 2.54 57 210001 25069 0 132
sp_cancel_adding_invoice 5517 5517 0 0.00 6 74280 1068 0 0
sp_cancel_client_order 1754 1754 0 0.00 6 14171 386 0 0
sp_cancel_customer_reserve 536663 536663 0 0.00 1 21941 95 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 17090 149 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 4 1607 87 0 0
sp_cancel_supplier_invoice 6142 6132 10 0.16 3 18756 973 0 10
sp_cancel_supplier_order 410 410 0 0.00 15 1401077 159336 0 0
sp_cancel_write_off 10234 10234 0 0.00 2 17653 169 0 0
sp_client_order 20716 20716 0 0.00 5 25707 422 0 0
sp_customer_reserve 479340 479340 0 0.00 1 123579 236 0 0
sp_fill_shopping_cart 31683 31683 0 0.00 1 17597 72 0 0
sp_fill_shopping_cart_clo_res 479310 479310 0 0.00 0 13871 17 0 0
sp_get_clo_for_invoice 5015 5015 0 0.00 0 23596 262 0 0
sp_kill_cost_storno 16852 16852 0 0.00 0 17313 75 0 0
sp_kill_qstorno_ret_qs2qd 1090102 1090102 0 0.00 0 21913 42 0 0
sp_kill_qty_storno 575935 575935 0 0.00 0 26915 95 0 0
sp_lock_selected_doc 21006 21006 0 0.00 0 6199 7 0 0
sp_make_cost_storno 28340 28340 0 0.00 0 20955 76 0 0
sp_multiply_rows_for_pdistr 28339 28339 0 0.00 0 17039 40 0 0
sp_multiply_rows_for_qdistr 20716 20716 0 0.00 0 12068 176 0 0
sp_pay_from_customer 7248 7248 0 0.00 5 17318 167 0 0
sp_pay_to_supplier 5867 5867 0 0.00 6 25224 189 0 0
sp_payment_common 13114 13114 0 0.00 2 17061 43 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 14163 322 0 0
sp_qd_handle_on_invoice_upd_sts 10532 10532 0 0.00 0 26915 468 0 0
sp_qd_handle_on_reserve_upd_sts 10210 10210 0 0.00 0 19244 58 0 0
sp_reserve_write_off 10211 10211 0 0.00 9 24846 250 0 0
sp_supplier_invoice 5440 5440 0 0.00 8 87769 2925 0 0
sp_supplier_order 5542 5542 0 0.00 8 28881 1273 0 0
srv_aggregate_perf_data 114 114 0 0.00 2839 160554 15130 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 851 135375 35128 0 0
srv_find_qd_qs_mism 1052112 1052112 0 0.00 0 17921 10 0 0
srv_make_invnt_saldo 483 483 0 0.00 64 125286 4109 0 0
srv_make_money_saldo 863 863 0 0.00 1 17046 298 0 0
srv_recalc_idx_stat 5 5 0 0.00 96921 350712 189014 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5056 5056 0 0.00 1 27414 758 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 12927 391 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 5858 27 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 3594 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 2120 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 4744 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 1203 30 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 72735 5828 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 2720 19 0 0
t$perf-norm:sp_client_order 20716 20716 0 0.00 0 15901 34 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 734 12 0 0
t$perf-norm:sp_pay_from_customer 7248 7248 0 0.00 0 11826 22 0 0
t$perf-norm:sp_pay_to_supplier 5867 5867 0 0.00 0 5620 19 0 0
t$perf-norm:sp_reserve_write_off 10212 10212 0 0.00 0 7245 21 0 0
t$perf-norm:sp_supplier_invoice 5441 5441 0 0.00 0 4083 24 0 0
t$perf-norm:sp_supplier_order 5542 5542 0 0.00 0 12898 33 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 5776 58 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1571 50 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 1353 14 0 0
t$perf-norm:srv_make_money_saldo 863 863 0 0.00 0 2740 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 8 178 76 0 0
v_add_invoice_to_stock 10286 10022 264 2.56 0 9289 22 0 264
v_all_customers 37974 37974 0 0.00 0 118 3 0 0
v_all_suppliers 30804 30804 0 0.00 0 133 4 0 0
v_all_wares 41428 41428 0 0.00 0 129 3 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 708 13 0 0
v_cancel_client_order 3508 3508 0 0.00 0 4421 12 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 441 9 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 1 1808 10 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 228 7 0 0
v_cancel_supplier_invoice 860 840 20 2.32 0 3001 25 0 20
v_cancel_supplier_order 820 820 0 0.00 0 564 11 0 0
v_cancel_write_off 2076 2076 0 0.00 0 606 19 0 0
v_max_id_clo_ord 5539 5539 0 0.00 0 1985 5 0 0
v_max_id_clo_res 1029 1029 0 0.00 0 6167 22 0 0
v_max_id_ord_sup 5429 5429 0 0.00 0 3198 6 0 0
v_max_non_paid_invoice 1429 1429 0 0.00 0 68 7 0 0
v_max_non_paid_realizn 3720 3720 0 0.00 0 2321 8 0 0
v_min_id_clo_ord 5539 5539 0 0.00 0 1806 7 0 0
v_min_id_clo_res 1029 1029 0 0.00 0 773 9 0 0
v_min_id_ord_sup 5429 5429 0 0.00 0 3459 12 0 0
v_min_non_paid_invoice 5863 5863 0 0.00 0 17005 17 0 0
v_min_non_paid_realizn 7248 7248 0 0.00 0 6040 18 0 0
v_our_firm 10506 10506 0 0.00 0 64 4 0 0
v_reserve_write_off 20420 20420 0 0.00 0 4678 9 0 0
x_lock_depdocs_on_canc_invoice 11229 11229 0 0.00 0 17705 122 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 5902 336 0 0
x_make_qstorno_1000_1200 5542 5542 0 0.00 2 28855 1138 0 0
x_make_qstorno_1200_2000 5440 5440 0 0.00 3 85128 2714 0 0
x_make_qstorno_2100_3300 479340 479340 0 0.00 0 123575 209 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-02-2023 04:39:03 to 25-02-2023 04:39:07.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:31 9 8192000000 8811250784 8827301888 14325200 0.174 6 4 5 0 14608768 171008 112560
02:08:32 28782 8192000000 9736686416 10034413568 805123248 8.948 100 89 178 0 834317712 7329456 21865008
02:10:06 22781 8192000000 9758811664 10066296832 831841680 9.218 100 92 184 0 867616112 13816928 21957504
02:11:32 14491 8192000000 9799417280 10119299072 866948304 9.570 100 81 163 0 906544272 20627456 18968512
02:12:51 7689 8192000000 9855405344 10190565376 913676464 10.034 100 77 151 0 971564576 41407456 16480656
02:14:01 3106 8192000000 9926195216 10269646848 956845680 10.458 100 85 166 0 1038837088 64363456 17627952
02:15:06 2756 8192000000 9956827248 10309877760 998840048 10.867 100 92 178 3 1070682240 53264304 18577888
02:16:10 1481 8192000000 10017175920 10378113024 1017388592 11.047 100 94 180 0 1134061168 98540672 18131904
02:17:13 1393 8192000000 10005302448 10375016448 1037387952 11.240 100 86 168 1 1122792928 67853536 17551440
02:18:16 935 8192000000 10025287152 10397728768 1056898192 11.427 100 93 167 3 1146227584 73157840 16171552
02:19:19 1281 8192000000 10029617056 10402967552 1064232240 11.497 100 89 169 0 1148705264 69169552 15303472
02:20:22 3620 8192000000 10024123792 10404306944 1070504160 11.557 100 77 145 1 1148891696 64179888 14207648
02:21:27 1570 8192000000 10054659744 10441060352 1076735728 11.616 100 89 170 3 1170076320 75819440 17521152
02:22:29 1216 8192000000 10045162304 10430332928 1080319584 11.651 100 93 177 2 1168994272 70832464 17842224
02:23:32 4055 8192000000 10041862032 10431139840 1080910320 11.656 100 64 125 0 1169618880 75966928 12741632
02:24:37 2036 8192000000 10064471824 10456764416 1085322032 11.698 100 76 142 0 1189898816 90989872 13586912
02:25:40 9671 8192000000 10029954576 10418753536 1085086656 11.696 100 71 126 1 1144210800 47585984 11538160
02:26:50 3635 8192000000 10049903936 10445791232 1086206608 11.707 100 66 126 2 1172394128 72914752 13272768
02:27:55 1443 8192000000 10040757360 10437758976 1092684016 11.768 100 78 146 2 1166745872 58664928 15396928
02:28:57 1322 8192000000 10054220176 10440196096 1086748336 11.712 100 95 182 3 1176505152 68364672 21392144
02:30:00 1181 8192000000 10064346448 10446151680 1073416416 11.585 100 98 187 1 1183881024 90714176 19750432
02:31:02 1746 8192000000 10030541440 10414669824 1062943984 11.485 100 83 164 2 1156887696 76025248 17918464
02:32:05 1244 8192000000 10021392256 10401112064 1051124016 11.371 100 91 165 2 1144557696 76689712 16743968
02:33:08 1105 8192000000 10025888000 10402562048 1055327440 11.412 100 95 187 2 1147905440 71841952 20736048
02:34:09 1131 8192000000 10015012160 10394255360 1036350192 11.230 100 93 166 3 1135932944 81398416 18184336
02:35:11 1774 8192000000 9978922560 10355187712 1041612160 11.280 100 77 143 2 1103590704 47593056 14385488
02:36:14 1540 8192000000 9986490272 10357641216 1029812624 11.167 100 93 174 0 1102688288 53450544 19425120
02:37:16 5611 8192000000 9992588864 10375438336 1045599824 11.318 99 57 108 1 1109660272 52627328 11433120
02:38:30 4728 8192000000 10007430864 10394996736 1066702880 11.521 100 61 113 0 1132201648 54005280 11493488
02:39:44 1012 8192000000 10024332160 10406944768 1078699552 11.635 100 80 140 1 1147026544 52761712 15565280
02:40:49 15325 8192000000 10096584224 10493906944 1097179216 11.811 100 60 120 0 1203110464 93249472 12681776
02:42:18 3486 8192000000 10151308016 10542968832 1119546528 12.023 100 70 135 0 1270032112 136570768 13914816
02:43:25 2548 8192000000 10149333408 10548781056 1124765168 12.072 100 72 140 3 1268644480 128610368 15268944
02:44:34 4368 8192000000 10189005440 10585841664 1138750128 12.204 100 76 140 1 1300339936 146621776 14968032
02:45:42 1554 8192000000 10246935648 10643922944 1150931888 12.318 100 94 182 1 1364394288 192748944 20713456
02:46:48 1110 8192000000 10292447600 10687074304 1156681952 12.372 100 97 187 1 1406239232 228440176 21117104
02:47:51 1116 8192000000 10301950704 10698702848 1167201968 12.471 100 82 160 0 1412406880 226746816 18458096
02:48:56 1030 8192000000 10349006640 10750816256 1172359152 12.519 100 98 189 1 1456144288 263149040 20636096
02:49:59 1205 8192000000 10398192688 10806501376 1184661344 12.634 100 89 173 0 1509108672 303586112 20861216
02:51:09 1865 8192000000 10440051888 10857721856 1192561808 12.707 100 90 179 2 1546049520 331670928 21816784
02:52:15 1179 8192000000 10485882368 10904633344 1208178992 12.852 100 94 182 1 1594153696 363501504 22473200
02:53:19 1199 8192000000 10511376544 10937626624 1210130864 12.870 100 95 182 0 1623585712 390389152 23065696
02:54:25 1495 8192000000 10529572880 10963419136 1207727856 12.848 100 94 180 1 1638252624 408213024 22311744
02:55:30 1490 8192000000 10549550944 10973937664 1199596656 12.773 100 89 172 2 1653041424 433174176 20270592
02:56:34 1295 8192000000 10571753104 11015249920 1205306432 12.826 100 92 175 2 1679934688 453655520 20972736
02:57:40 845 8192000000 10590952544 11019624448 1198476896 12.762 99 92 184 2 1694141552 472848496 22816160
02:58:44 1201 8192000000 10582325488 11058786304 1201641872 12.792 100 79 158 1 1690127488 468711712 19773904
02:59:47 8311 8192000000 10509806000 11013701632 1199071168 12.768 100 40 78 0 1648604576 438081264 11452144
03:01:13 10996 8192000000 10473065952 10948915200 1194812384 12.728 100 45 88 0 1592701136 387265168 10623584
03:02:26 11964 8192000000 10488743808 10948263936 1179129568 12.582 100 34 66 1 1598713376 410332176 9251632
03:03:54 15549 8192000000 10399983376 10884694016 1158317680 12.388 100 34 68 0 1496518240 328983840 9216720
03:05:39 43340 8192000000 10242880608 10710335488 1161860176 12.421 100 26 52 0 1342688112 174718672 6109264
03:07:23 54512 8192000000 10169080800 10643443712 1146684496 12.278 98 18 36 0 1300756640 149917168 4154976
03:09:20 45517 8192000000 10090745296 10599112704 1160453168 12.408 100 7 14 0 1239166240 77480416 1232656
03:11:10 28466 8192000000 10095094736 10535890944 1138268432 12.199 98 52 96 0 1164901072 17676496 8956144
03:12:39 67754 8192000000 10138328240 10619486208 1128330512 12.106 97 18 35 1 1178403472 47076960 2996000
03:14:49 128473 8192000000 10107166640 10562330624 1065750080 11.511 92 18 35 1 1112511376 43475008 3286288
03:18:09 35557 8192000000 10175176032 10594775040 1116713776 11.996 97 56 99 3 1233821120 106264816 10842528
03:19:46 57907 8192000000 10136996448 10638618624 1108802736 11.921 97 19 38 0 1219612672 106486704 4323232
03:22:36 125127 8192000000 10052017504 10494332928 1079634832 11.644 95 13 26 0 1117377280 34858480 2883968
03:26:20 85695 8192000000 10092271984 10549690368 1104973520 11.885 97 28 52 2 1165864848 54926656 5964672
03:28:57 33528 8192000000 10137039520 10553327616 1115803216 11.987 98 87 162 2 1213890352 81742784 16344352
03:30:31 60477 8192000000 10138612544 10601799680 1113235680 11.963 98 21 42 0 1256239600 137813072 5190848
03:32:34 1035 8192000000 10150333936 10567430144 1128956128 12.112 100 78 144 2 1233993616 89664672 15372816
03:33:35 33441 8192000000 10133241840 10595692544 1119940048 12.026 99 28 56 0 1259699872 133328016 6431808
03:35:11 65694 8192000000 10149960576 10620166144 1116894112 11.998 99 14 28 0 1243592288 123365536 3332640
03:37:17 135375 8192000000 10062866880 10529570816 1110349072 11.936 98 14 28 0 1169065040 56009952 2706016
03:40:43 51352 8192000000 10108609056 10547281920 1140111856 12.217 100 26 50 1 1211265392 65904224 5249312
03:42:35 68976 8192000000 10120358656 10574643200 1120179248 12.029 98 18 33 0 1184468416 61140112 3149056
03:44:56 11109 8192000000 10077985248 10506977280 1127313760 12.096 100 47 85 2 1171648112 35418032 8916320
03:46:11 31269 8192000000 10099881264 10542252032 1134032768 12.159 100 29 58 0 1179027792 39262528 5732496
03:47:52 24460 8192000000 10110407312 10542170112 1131668576 12.137 99 53 99 0 1186629920 44731744 10229600
03:49:17 81307 8192000000 10088110000 10517876736 1107706064 11.911 97 34 55 2 1144656256 31818368 5131824
03:51:39 84686 8192000000 10079498368 10586697728 1129388240 12.116 99 29 58 0 1230449120 95281504 5779376
03:54:04 91559 8192000000 10049185200 10512949248 1108858144 11.922 98 18 36 1 1137654784 24973168 3823472
03:56:36 18031 8192000000 10054069472 10502332416 1125636256 12.080 100 32 62 1 1157959280 25678160 6644864
03:58:00 14858 8192000000 10107944896 10550239232 1133340256 12.153 100 49 94 1 1213903616 70901968 9661392
03:59:16 77396 8192000000 10073367776 10515431424 1110747824 11.939 98 16 32 0 1168663984 54136112 3780048
04:01:42 83249 8192000000 10059077728 10516508672 1098646880 11.825 97 16 30 0 1138142976 36335664 3160432
04:04:06 42915 8192000000 10064044016 10525519872 1110393680 11.936 99 10 20 0 1160207072 47887840 1925552
04:06:32 16764 8192000000 10078843040 10503479296 1108737104 11.920 100 61 117 2 1170792352 49456720 12598528
04:08:00 35583 8192000000 10100658096 10528993280 1093737680 11.778 98 41 80 0 1186712368 84757872 8216816
04:09:42 41557 8192000000 10087890784 10531487744 1103529344 11.871 98 35 70 0 1169894080 59515632 6849104
04:11:30 34719 8192000000 10072840848 10531479552 1099194736 11.830 98 47 93 0 1188910240 80246736 9468768
04:13:07 7206 8192000000 10068341280 10487709696 1108602608 11.919 99 53 101 0 1165123712 45905616 10615488
04:14:19 9385 8192000000 10132983072 10553720832 1134011232 12.159 100 57 106 0 1223385328 78351744 11022352
04:15:30 53694 8192000000 10069868480 10542383104 1102978480 11.866 98 16 32 0 1150130416 42620560 4531376
04:17:30 34558 8192000000 10112678368 10549370880 1102926432 11.865 99 33 66 0 1191578752 81966448 6685872
04:20:38 34273 8192000000 10055976800 10495799296 1082697424 11.673 97 31 61 0 1125631984 36637008 6297552
04:22:15 86243 8192000000 10077451168 10542989312 1122461568 12.050 100 13 26 0 1171555584 46311968 2782048
04:24:48 47057 8192000000 10046860848 10569592832 1111959920 11.951 99 6 12 0 1159354304 46065728 1328656
04:26:40 37719 8192000000 10100749168 10532970496 1119847360 12.026 99 33 66 0 1178663184 51830304 6985520
04:28:25 27273 8192000000 10088883392 10534232064 1133004336 12.150 100 29 58 0 1202513872 63254192 6255344
04:29:52 28944 8192000000 10130533552 10567438336 1139037456 12.206 100 34 66 2 1250738176 104796944 6903776
04:31:24 47270 8192000000 10133997776 10596290560 1132100192 12.141 99 27 53 0 1235235632 96781488 6353952
04:33:14 45825 8192000000 10170509600 10618957824 1141753456 12.232 99 29 58 0 1254539536 106209200 6576880
04:35:09 30086 8192000000 10218154912 10669899776 1153494560 12.342 100 38 76 0 1324847120 162559152 8793408
04:36:42 62685 8192000000 9158832192 9431687168 213072752 2.535 18 10 20 0 352448624 136820592 2555280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-02-2023 04:39:09 to 25-02-2023 04:39:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-02-2023 04:39:26 to 25-02-2023 04:39:28.

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          211361
MON$OLDEST_ACTIVE               221709
MON$OLDEST_SNAPSHOT             221709
MON$NEXT_TRANSACTION            221709
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                       2064032
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:225: 
:226: mscs-test-161.budzdorov.ru    Sat Feb 25 04:37:52 2023
:227:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:228: 
--- end of diff output ---

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

25.02.23 04:39:29. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-25 04:39:32 No severe PSQL-related problems occured
25.02.23 04:39:33. End of report.