Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.02.2023 13: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 15025756 kB
MemAvailable 30442600 kB
Buffers 0 kB
Cached 16697716 kB
SwapCached 3368 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-12 13:37:26
DTS_END                         2023-02-12 13:37:26.4960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49900
TRN_ID                          216694
ATT_ID                          657
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: 8908.36 24669 1069003
customer order: creation 167.44 516 20093
customer order: refuse 14.28 483 1713
order to supplier: creation 45.24 1364 5429
order to supplier: removal 3.38 191955 406
invoice (draft): creation 43.65 3324 5238
invoice (draft): removal 49.56 1116 5947
invoice accept: apply 40.92 29613 4910
invoice accept: cancel 45.29 1469 5435
customer reserve: creation 3765.66 285 451879
customer reserve: removal 4444.69 115 533363
realization accept: apply 81.32 311 9758
realization accept: cancel 79.22 194 9506
payment from customer: creation 58.51 198 7021
payment from customer: removal 5.56 150 667
payment to supplier: creation 47.80 212 5736
payment to supplier: removal 3.41 164 409
service: total inventory turnovers 3.93 4487 471
service: total monetary turnovers 7.63 409 915
service: aggregate perf. data 0.84 15138 101
service: refresh index statistics 0.05 241868 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-02-2023 13:39:03 to 12-02-2023 13:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9606
TEST_TIME 2 6399
TEST_TIME 3 5870
TEST_TIME 4 6186
TEST_TIME 5 6241
TEST_TIME 6 6408
TEST_TIME 7 6153
TEST_TIME 8 6562
TEST_TIME 9 6728
TEST_TIME 10 6675
TEST_TIME 11 6599
TEST_TIME 12 6696
TEST_TIME 13 6716
TEST_TIME 14 6782
TEST_TIME 15 6917
TEST_TIME 16 6919
TEST_TIME 17 6943
TEST_TIME 18 7070
TEST_TIME 19 7025
TEST_TIME 20 7011
TEST_TIME 21 6917
TEST_TIME 22 7025
TEST_TIME 23 7175
TEST_TIME 24 7308
TEST_TIME 25 7478
TEST_TIME 26 7688
TEST_TIME 27 7970
TEST_TIME 28 7965
TEST_TIME 29 7917
TEST_TIME 30 7917
TEST_TIME 31 8443
TEST_TIME 32 8236
TEST_TIME 33 8676
TEST_TIME 34 8687
TEST_TIME 35 8928
TEST_TIME 36 9256
TEST_TIME 37 9608
TEST_TIME 38 10302
TEST_TIME 39 10387
TEST_TIME 40 10346
TEST_TIME 41 10247
TEST_TIME 42 10088
TEST_TIME 43 10074
TEST_TIME 44 9929
TEST_TIME 45 9782
TEST_TIME 46 9761
TEST_TIME 47 9705
TEST_TIME 48 9638
TEST_TIME 49 9585
TEST_TIME 50 9529
TEST_TIME 51 9483
TEST_TIME 52 9494
TEST_TIME 53 9432
TEST_TIME 54 9483
TEST_TIME 55 9402
TEST_TIME 56 9438
TEST_TIME 57 9315
TEST_TIME 58 9245
TEST_TIME 59 9268
TEST_TIME 60 9280
TEST_TIME 61 9385
TEST_TIME 62 9292
TEST_TIME 63 9332
TEST_TIME 64 9444
TEST_TIME 65 9416
TEST_TIME 66 9405
TEST_TIME 67 9418
TEST_TIME 68 9433
TEST_TIME 69 9529
TEST_TIME 70 9522
TEST_TIME 71 9547
TEST_TIME 72 9549
TEST_TIME 73 9502
TEST_TIME 74 9403
TEST_TIME 75 9451
TEST_TIME 76 9351
TEST_TIME 77 9309
TEST_TIME 78 9301
TEST_TIME 79 9277
TEST_TIME 80 9250
TEST_TIME 81 9176
TEST_TIME 82 9113
TEST_TIME 83 9112
TEST_TIME 84 9084
TEST_TIME 85 9096
TEST_TIME 86 9090
TEST_TIME 87 9104
TEST_TIME 88 9186
TEST_TIME 89 9168
TEST_TIME 90 9187
TEST_TIME 91 9280
TEST_TIME 92 9272
TEST_TIME 93 9316
TEST_TIME 94 9281
TEST_TIME 95 9293
TEST_TIME 96 9206
TEST_TIME 97 9206
TEST_TIME 98 9217
TEST_TIME 99 9167
TEST_TIME 100 9127
TEST_TIME 101 9096
TEST_TIME 102 9136
TEST_TIME 103 9108
TEST_TIME 104 9079
TEST_TIME 105 9068
TEST_TIME 106 9061
TEST_TIME 107 9063
TEST_TIME 108 9029
TEST_TIME 109 9052
TEST_TIME 110 9102
TEST_TIME 111 9118
TEST_TIME 112 9149
TEST_TIME 113 9159
TEST_TIME 114 9118
TEST_TIME 115 9084
TEST_TIME 116 9037
TEST_TIME 117 9024
TEST_TIME 118 8998
TEST_TIME 119 8958
TEST_TIME 120 8935

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-02-2023 13:39:07 to 12-02-2023 13:39:10.

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 1487290 1487290 0 0.00 0 25695 15 0 0
doc_list_biud 1487287 1487287 0 0.00 0 30384 47 0 0
sp_add_invoice_to_stock 5049 4910 139 2.75 46 203709 29614 0 139
sp_cancel_adding_invoice 5435 5435 0 0.00 6 100321 1469 0 0
sp_cancel_client_order 1713 1713 0 0.00 6 15413 483 0 0
sp_cancel_customer_reserve 533363 533363 0 0.00 1 30438 116 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 4 15914 151 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 4 8630 164 0 0
sp_cancel_supplier_invoice 5959 5947 12 0.20 2 16745 1117 0 12
sp_cancel_supplier_order 406 406 0 0.00 15 1478307 191956 0 0
sp_cancel_write_off 9506 9506 0 0.00 2 18094 195 0 0
sp_client_order 20093 20093 0 0.00 6 25943 517 0 0
sp_customer_reserve 451879 451879 0 0.00 0 96974 285 0 0
sp_fill_shopping_cart 30745 30745 0 0.00 1 11871 84 0 0
sp_fill_shopping_cart_clo_res 451844 451844 0 0.00 0 20377 22 0 0
sp_get_clo_for_invoice 4866 4866 0 0.00 0 29686 300 0 0
sp_kill_cost_storno 16017 16017 0 0.00 0 10684 89 0 0
sp_kill_qstorno_ret_qs2qd 1082585 1082585 0 0.00 0 30314 50 0 0
sp_kill_qty_storno 570991 570991 0 0.00 0 30380 114 0 0
sp_lock_selected_doc 20333 20333 0 0.00 0 11233 11 0 0
sp_make_cost_storno 27379 27379 0 0.00 0 25659 89 0 0
sp_multiply_rows_for_pdistr 27379 27379 0 0.00 0 9273 41 0 0
sp_multiply_rows_for_qdistr 20093 20093 0 0.00 0 9742 185 0 0
sp_pay_from_customer 7021 7021 0 0.00 5 16666 198 0 0
sp_pay_to_supplier 5736 5736 0 0.00 7 15406 212 0 0
sp_payment_common 12757 12757 0 0.00 2 7217 46 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 1 15374 391 0 0
sp_qd_handle_on_invoice_upd_sts 10300 10300 0 0.00 0 18441 564 0 0
sp_qd_handle_on_reserve_upd_sts 9756 9756 0 0.00 0 26912 67 0 0
sp_reserve_write_off 9758 9758 0 0.00 9 26957 311 0 0
sp_supplier_invoice 5238 5238 0 0.00 8 78737 3324 0 0
sp_supplier_order 5429 5429 0 0.00 8 25771 1365 0 0
srv_aggregate_perf_data 101 101 0 0.00 2005 197678 15139 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 717 119753 28516 0 0
srv_find_qd_qs_mism 1022240 1022240 0 0.00 0 16652 11 0 0
srv_make_invnt_saldo 471 471 0 0.00 108 94149 4487 0 0
srv_make_money_saldo 915 915 0 0.00 1 8285 409 0 0
srv_recalc_idx_stat 6 6 0 0.00 45248 496193 241868 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4919 4919 0 0.00 1 11497 985 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 1 8199 517 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 5852 33 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 802 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 1187 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 418 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 2180 40 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 84767 8070 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 1664 24 0 0
t$perf-norm:sp_client_order 20094 20094 0 0.00 0 6395 40 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 1573 18 0 0
t$perf-norm:sp_pay_from_customer 7022 7022 0 0.00 0 4531 20 0 0
t$perf-norm:sp_pay_to_supplier 5736 5736 0 0.00 0 4337 22 0 0
t$perf-norm:sp_reserve_write_off 9758 9758 0 0.00 0 5991 22 0 0
t$perf-norm:sp_supplier_invoice 5238 5238 0 0.00 0 8245 31 0 0
t$perf-norm:sp_supplier_order 5429 5429 0 0.00 0 4254 34 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 2506 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 62 5 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 724 17 0 0
t$perf-norm:srv_make_money_saldo 915 915 0 0.00 0 731 6 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 295 112 0 0
v_add_invoice_to_stock 10006 9728 278 2.77 0 4626 25 0 278
v_all_customers 37170 37170 0 0.00 0 138 4 0 0
v_all_suppliers 29852 29852 0 0.00 0 94 4 0 0
v_all_wares 40182 40182 0 0.00 0 154 4 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 1910 16 0 0
v_cancel_client_order 3426 3426 0 0.00 0 5943 19 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 5942 17 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 362 10 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 1256 14 0 0
v_cancel_supplier_invoice 826 802 24 2.90 0 1902 25 0 24
v_cancel_supplier_order 812 812 0 0.00 0 3655 20 0 0
v_cancel_write_off 1946 1946 0 0.00 0 4620 29 0 0
v_max_id_clo_ord 5426 5426 0 0.00 0 910 5 0 0
v_max_id_clo_res 942 942 0 0.00 0 2867 18 0 0
v_max_id_ord_sup 5228 5228 0 0.00 0 6441 9 0 0
v_max_non_paid_invoice 1464 1464 0 0.00 0 976 9 0 0
v_max_non_paid_realizn 3567 3567 0 0.00 0 417 8 0 0
v_min_id_clo_ord 5426 5426 0 0.00 0 455 7 0 0
v_min_id_clo_res 942 942 0 0.00 0 3497 17 0 0
v_min_id_ord_sup 5228 5228 0 0.00 0 3896 11 0 0
v_min_non_paid_invoice 5736 5736 0 0.00 0 6700 17 0 0
v_min_non_paid_realizn 7021 7021 0 0.00 0 7211 19 0 0
v_our_firm 9920 9920 0 0.00 0 52 5 0 0
v_reserve_write_off 19510 19510 0 0.00 0 5549 11 0 0
x_lock_depdocs_on_canc_invoice 10981 10981 0 0.00 0 20459 149 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 17330 473 0 0
x_make_qstorno_1000_1200 5429 5429 0 0.00 2 25751 1195 0 0
x_make_qstorno_1200_2000 5238 5238 0 0.00 3 78721 3089 0 0
x_make_qstorno_2100_3300 451879 451879 0 0.00 0 96962 250 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-02-2023 13:39:11 to 12-02-2023 13:39:15.

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
11:07:30 926 8192000000 8927322704 8983171072 19146288 0.233 6 3 7 0 19847776 132752 568736
11:08:32 25307 8192000000 9721934000 10021175296 801151904 8.908 100 87 172 0 829777984 7614272 21011808
11:10:03 22006 8192000000 9764200912 10074324992 843001232 9.330 100 87 172 0 875636432 12377680 20257520
11:11:35 12698 8192000000 9813166128 10132582400 875595312 9.656 100 86 172 0 917867808 22448032 19824464
11:12:57 10785 8192000000 9859110224 10190139392 912670992 10.024 100 80 160 0 967179936 36588672 17920272
11:14:14 3548 8192000000 9922805120 10262999040 954550704 10.436 100 86 170 0 1033710816 61254640 17905472
11:15:21 3392 8192000000 9948369696 10305880064 988335456 10.765 100 80 158 0 1069413216 64869952 16207808
11:16:27 1329 8192000000 10000544592 10360029184 1012228016 10.997 100 89 167 0 1106486656 77387264 16871376
11:17:30 1336 8192000000 10024621696 10388525056 1034520304 11.212 100 95 178 0 1141288608 89439280 17329024
11:18:33 1228 8192000000 10029114016 10401144832 1054183104 11.401 100 97 187 0 1150310800 77164288 18963408
11:19:35 1368 8192000000 10041526528 10417922048 1066496416 11.519 100 97 185 2 1160247472 75074624 18676432
11:20:38 3472 8192000000 10016992384 10393329664 1074617792 11.596 100 91 175 1 1137340704 45483872 17239040
11:21:42 1134 8192000000 10044298160 10432413696 1077942928 11.628 100 87 168 3 1165223216 71041968 16238320
11:22:44 1096 8192000000 10050626720 10438590464 1085074736 11.696 100 94 180 2 1169550432 66617824 17857872
11:23:48 1462 8192000000 10039324896 10427367424 1089754544 11.740 100 95 185 1 1164127312 54756944 19615824
11:24:50 1323 8192000000 10079060816 10467110912 1097490768 11.814 100 97 191 1 1205275712 88599600 19185344
11:25:53 997 8192000000 10069759072 10461843456 1102936432 11.865 100 97 181 1 1191805680 69817104 19052144
11:26:55 987 8192000000 10038929648 10431176704 1096744208 11.807 100 95 175 1 1157474592 43163744 17566640
11:27:57 923 8192000000 10047565376 10436034560 1097830160 11.817 100 96 183 4 1170313984 51938768 20545056
11:29:00 1315 8192000000 10061571040 10451079168 1099430912 11.832 100 99 184 1 1186607200 68176128 19000160
11:30:03 881 8192000000 10060684544 10448138240 1085954944 11.704 100 96 184 1 1183124976 78808592 18361440
11:31:06 1378 8192000000 10020597232 10408845312 1079851552 11.646 100 79 151 2 1146307312 51237760 15218000
11:32:08 1042 8192000000 10004756176 10382540800 1055348688 11.412 99 88 170 0 1126032464 52700256 17983520
11:33:10 1371 8192000000 10005479168 10380541952 1042758560 11.291 99 81 148 2 1127374688 69973280 14642848
11:34:14 1063 8192000000 9999462656 10369425408 1017536032 11.048 99 95 183 0 1122070864 85387232 19147600
11:35:16 2819 8192000000 9991387584 10372931584 1035435520 11.221 100 70 133 1 1107796944 58782464 13578960
11:36:19 1595 8192000000 9988997360 10370457600 1029855280 11.167 100 70 139 2 1114531200 70497744 14178176
11:37:22 990 8192000000 10004017392 10380369920 1049386752 11.355 100 97 173 5 1123918224 56639440 17892032
11:38:30 1385 8192000000 10042333792 10425327616 1070548608 11.557 100 91 169 1 1165095088 78073264 16473216
11:39:42 24120 8192000000 10047451040 10434875392 1081052112 11.657 100 74 138 1 1165776592 71149728 13574752
11:41:17 1882 8192000000 10088238496 10470821888 1092704960 11.768 100 87 155 2 1201092384 93004864 15382560
11:42:23 8264 8192000000 10087738096 10473902080 1100617360 11.843 100 73 141 0 1200778224 85385696 14775168
11:43:35 1621 8192000000 10110547888 10499883008 1104382128 11.879 100 79 142 1 1230102608 110191360 15529120
11:44:42 6950 8192000000 10134096560 10528202752 1108810144 11.921 100 70 140 0 1246087696 122099312 15178240
11:45:57 1272 8192000000 10137167296 10540732416 1125398832 12.078 100 90 171 2 1249783088 104548528 19835728
11:47:01 1576 8192000000 10177159312 10583220224 1140099232 12.216 100 82 154 2 1296366080 139027296 17239552
11:48:06 919 8192000000 10204805520 10603814912 1148228224 12.293 100 96 182 1 1319405296 148956528 22220544
11:49:13 9776 8192000000 10222459280 10636574720 1156282800 12.368 100 66 130 2 1342057200 171390624 14383776
11:50:30 4064 8192000000 10215407488 10627022848 1151565280 12.324 100 86 165 1 1328680688 158885328 18230080
11:51:44 840 8192000000 10277058704 10679439360 1171196160 12.508 100 97 186 2 1389461936 196018832 22246944
11:52:50 1044 8192000000 10303139440 10706493440 1180367888 12.594 100 87 167 0 1411197376 212963488 17866000
11:53:55 2981 8192000000 10334449792 10740539392 1177924656 12.571 100 92 180 0 1449623616 251570352 20128608
11:55:04 2381 8192000000 10377162432 10805231616 1201927264 12.794 100 82 164 1 1486318528 265423680 18967584
11:56:11 1021 8192000000 10437816928 10844413952 1214463648 12.910 100 97 192 2 1548943776 312220576 22259552
11:57:17 1404 8192000000 10446224128 10853728256 1187514560 12.660 100 93 182 1 1549344592 339210432 22619600
11:58:26 933 8192000000 10479703952 10900815872 1212712800 12.894 100 92 173 0 1581917408 348942352 20262256
11:59:35 946 8192000000 10514815408 10934870016 1207081312 12.842 100 95 177 0 1621305120 391606016 22617792
12:00:41 1624 8192000000 10524500896 10967375872 1206903920 12.840 100 92 180 1 1628767968 399014768 22849280
12:01:47 1034 8192000000 10509500496 10942763008 1194701280 12.727 99 97 192 1 1615938368 397537904 23699184
12:02:51 1003 8192000000 10557447776 10974097408 1204402000 12.817 100 99 194 1 1659094544 431185488 23507056
12:03:57 739 8192000000 10532227344 10994688000 1202226432 12.797 100 79 150 2 1636114368 414503856 19384080
12:05:02 2736 8192000000 10556264288 10992500736 1197421008 12.752 100 74 148 0 1657785664 442491888 17872768
12:06:18 1065 8192000000 10606556192 11036651520 1204531376 12.818 100 82 157 0 1709292160 485104160 19656624
12:07:27 2724 8192000000 10640605888 11070513152 1196385584 12.743 100 62 122 1 1745088400 532202592 16500224
12:08:37 12924 8192000000 10559694080 11030335488 1187199376 12.657 100 54 108 0 1663977008 463721040 13056592
12:09:52 2173 8192000000 10609556752 11035475968 1183328848 12.621 100 72 141 2 1711938448 511305328 17304272
12:10:59 13150 8192000000 10521306208 11048693760 1170597872 12.502 100 33 66 0 1616883648 437879440 8406336
12:12:25 13500 8192000000 10466514368 11004805120 1159607824 12.400 100 24 49 0 1583830080 417728480 6493776
12:13:41 35585 8192000000 10348820368 10833657856 1136894352 12.186 99 24 48 0 1479360640 335932016 6534272
12:15:21 95705 8192000000 10076197056 10583408640 1127784336 12.100 98 11 22 0 1223601008 93430144 2386528
12:18:03 70016 8192000000 10053891776 10488119296 1049415840 11.355 92 20 40 0 1075379904 21616560 4347504
12:20:25 62327 8192000000 10070617600 10514673664 1135814480 12.176 100 28 52 1 1184665616 43930256 4920880
12:22:34 53470 8192000000 10094769696 10561388544 1146049632 12.272 100 41 78 0 1216166912 61830000 8287280
12:24:31 96703 8192000000 10063753712 10490347520 1097064880 11.810 96 47 90 1 1131524864 25583136 8876848
12:27:09 18772 8192000000 10097078336 10527039488 1108814592 11.921 98 49 91 1 1183509264 65107312 9587360
12:28:47 23820 8192000000 10136454176 10574618624 1125929888 12.083 99 49 92 1 1236410720 100592704 9888128
12:30:14 25412 8192000000 10101375328 10554519552 1129444368 12.116 100 13 26 0 1198940304 66121088 3374848
12:32:50 26459 8192000000 10087034448 10535837696 1141375488 12.228 100 30 58 0 1200350768 52517184 6458096
12:35:00 43926 8192000000 10141227872 10596708352 1143966528 12.253 100 18 36 0 1236548528 88759824 3822176
12:36:45 60285 8192000000 10097669072 10556260352 1116759696 11.996 97 20 40 0 1187784752 66641968 4383088
12:39:02 30821 8192000000 10078157264 10508898304 1075255312 11.602 94 34 64 1 1140935520 58712016 6968192
12:40:37 44265 8192000000 10079500016 10522521600 1114138560 11.972 99 23 46 0 1194944240 75919008 4886672
12:42:21 18008 8192000000 10062338352 10492137472 1111680352 11.948 99 37 74 0 1154228144 34895568 7652224
12:43:40 30087 8192000000 10078567120 10532401152 1089426240 11.737 97 21 42 0 1165802736 71878944 4497552
12:45:19 9416 8192000000 10099645488 10545876992 1111734656 11.949 100 52 102 2 1184322880 61233152 11355072
12:46:33 17293 8192000000 10073709248 10513620992 1104169024 11.877 99 43 86 2 1177561344 64996624 8395696
12:47:57 48056 8192000000 10088468528 10552971264 1112615248 11.957 99 35 70 0 1174040032 54393856 7030928
12:49:52 71439 8192000000 10101635536 10540544000 1122335728 12.049 99 38 76 0 1178566592 48397504 7833360
12:52:24 51987 8192000000 10042889392 10478989312 1109773840 11.930 98 24 43 1 1151950016 37816704 4359472
12:54:18 712 8192000000 10105345776 10518626304 1138271280 12.199 100 95 188 2 1201254992 43479680 19504032
12:55:19 64759 8192000000 10090799312 10558713856 1115244064 11.982 98 31 62 0 1203568992 82045520 6279408
12:57:24 71643 8192000000 10066245088 10519646208 1074112624 11.591 95 12 24 0 1131163376 54495280 2555472
13:00:09 48756 8192000000 10101123280 10565070848 1127644160 12.099 100 16 32 0 1208355712 76794144 3917408
13:02:06 49957 8192000000 10121680592 10566868992 1098763056 11.826 96 22 44 0 1183862496 80210208 4889232
13:03:57 26426 8192000000 10118803984 10552659968 1111712224 11.949 98 33 63 1 1209071808 89832320 7527264
13:05:25 30660 8192000000 10115930272 10605871104 1097134496 11.810 97 42 82 0 1225669936 119729792 8805648
13:06:56 38737 8192000000 10127432848 10599542784 1072492080 11.576 96 36 70 1 1173641456 94075280 7074096
13:08:35 21571 8192000000 10054266464 10493894656 1100451152 11.842 99 48 83 4 1146906560 37662784 8792624
13:09:59 18770 8192000000 10135570256 10558685184 1129744848 12.119 100 49 93 0 1208961104 70152816 9063440
13:11:20 3884 8192000000 10123833424 10546343936 1142352112 12.238 100 67 129 1 1209973808 54339872 13281824
13:12:27 119752 8192000000 10160182448 10637418496 1139301696 12.209 99 28 56 0 1261317328 116034176 5981456
13:15:32 54014 8192000000 10073038672 10532696064 1112850992 11.959 97 16 31 1 1139157728 23453328 2853408
13:17:26 86793 8192000000 10091411760 10579394560 1103239856 11.868 96 14 26 1 1175748528 69730304 2778368
13:19:53 34176 8192000000 10074959968 10522365952 1127094544 12.094 99 52 97 3 1165694064 26969136 11630384
13:21:31 39682 8192000000 10102547376 10540740608 1150151920 12.311 100 46 84 1 1208160736 48365536 9643280
13:23:12 30232 8192000000 10146691264 10588631040 1152674304 12.335 100 34 66 0 1238933200 78705024 7553872
13:24:51 44891 8192000000 10164338256 10610315264 1141767280 12.232 98 29 58 0 1233939648 86364112 5808256
13:26:39 72420 8192000000 10116561152 10635726848 1081214944 11.659 95 12 24 0 1169023568 85401024 2407600
13:29:40 53458 8192000000 10065510704 10538184704 1108233360 11.916 98 13 24 0 1167901744 57395184 2273200
13:31:36 76511 8192000000 10070655856 10519171072 1065486864 11.509 95 35 70 0 1140049408 67538544 7024000
13:34:24 721 8192000000 10061874288 10500169728 1113402336 11.965 100 56 102 4 1165173136 41612880 10157920
13:35:25 61479 8192000000 10052742192 10509643776 1101072064 11.848 98 11 22 0 1154442352 50667968 2702320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 12-02-2023 13:39:17 to 12-02-2023 13:39:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-02-2023 13:39:35 to 12-02-2023 13:39:37.

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          215812
MON$OLDEST_ACTIVE               216809
MON$OLDEST_SNAPSHOT             216809
MON$NEXT_TRANSACTION            216809
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                       2068128
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 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 ---
:216: 
:217: mscs-test-161.budzdorov.ru    Sun Feb 12 13:37:32 2023
:218:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:219: 
:220: 
:221: mscs-test-161.budzdorov.ru    Sun Feb 12 13:37:33 2023
:222:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:223: 
--- end of diff output ---

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

12.02.23 13:39:38. 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-12 13:39:41 No severe PSQL-related problems occured
12.02.23 13:39:42. End of report.