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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15367428 kB
MemAvailable 30479996 kB
Buffers 20 kB
Cached 16312336 kB
SwapCached 2048 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-19 04:37:22
DTS_END                         2022-10-19 04:37:22.8640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40524
TRN_ID                          214572
ATT_ID                          626
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: 8802.60 23715 1056312
customer order: creation 162.83 466 19540
customer order: refuse 13.95 374 1674
order to supplier: creation 44.10 1328 5292
order to supplier: removal 3.28 167261 394
invoice (draft): creation 44.22 2889 5306
invoice (draft): removal 47.92 1038 5750
invoice accept: apply 39.88 27730 4785
invoice accept: cancel 43.26 1130 5191
customer reserve: creation 3795.91 261 455509
customer reserve: removal 4322.91 100 518749
realization accept: apply 78.71 282 9445
realization accept: cancel 80.46 171 9655
payment from customer: creation 56.33 165 6759
payment from customer: removal 5.79 142 695
payment to supplier: creation 47.01 185 5641
payment to supplier: removal 3.50 93 420
service: total inventory turnovers 4.23 3607 508
service: total monetary turnovers 7.38 364 885
service: aggregate perf. data 0.91 15820 109
service: refresh index statistics 0.04 250886 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-10-2022 04:38:52 to 19-10-2022 04:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4898
TEST_TIME 2 4202
TEST_TIME 3 5396
TEST_TIME 4 5509
TEST_TIME 5 6222
TEST_TIME 6 5851
TEST_TIME 7 5854
TEST_TIME 8 5921
TEST_TIME 9 5945
TEST_TIME 10 6203
TEST_TIME 11 6418
TEST_TIME 12 6401
TEST_TIME 13 6969
TEST_TIME 14 7225
TEST_TIME 15 7271
TEST_TIME 16 7305
TEST_TIME 17 7380
TEST_TIME 18 7404
TEST_TIME 19 7547
TEST_TIME 20 7797
TEST_TIME 21 7828
TEST_TIME 22 7901
TEST_TIME 23 8423
TEST_TIME 24 8464
TEST_TIME 25 8509
TEST_TIME 26 8938
TEST_TIME 27 9070
TEST_TIME 28 9089
TEST_TIME 29 10198
TEST_TIME 30 10578
TEST_TIME 31 10967
TEST_TIME 32 11010
TEST_TIME 33 10913
TEST_TIME 34 10818
TEST_TIME 35 10613
TEST_TIME 36 10493
TEST_TIME 37 10370
TEST_TIME 38 10223
TEST_TIME 39 10276
TEST_TIME 40 10295
TEST_TIME 41 10089
TEST_TIME 42 10012
TEST_TIME 43 9949
TEST_TIME 44 9900
TEST_TIME 45 9913
TEST_TIME 46 9831
TEST_TIME 47 9825
TEST_TIME 48 9755
TEST_TIME 49 9693
TEST_TIME 50 9560
TEST_TIME 51 9457
TEST_TIME 52 9696
TEST_TIME 53 9662
TEST_TIME 54 9647
TEST_TIME 55 9613
TEST_TIME 56 9702
TEST_TIME 57 9729
TEST_TIME 58 9660
TEST_TIME 59 9664
TEST_TIME 60 9711
TEST_TIME 61 9743
TEST_TIME 62 9789
TEST_TIME 63 9763
TEST_TIME 64 9649
TEST_TIME 65 9564
TEST_TIME 66 9507
TEST_TIME 67 9486
TEST_TIME 68 9411
TEST_TIME 69 9336
TEST_TIME 70 9273
TEST_TIME 71 9230
TEST_TIME 72 9198
TEST_TIME 73 9204
TEST_TIME 74 9199
TEST_TIME 75 9187
TEST_TIME 76 9113
TEST_TIME 77 9113
TEST_TIME 78 9056
TEST_TIME 79 9085
TEST_TIME 80 9111
TEST_TIME 81 9280
TEST_TIME 82 9272
TEST_TIME 83 9316
TEST_TIME 84 9291
TEST_TIME 85 9284
TEST_TIME 86 9270
TEST_TIME 87 9212
TEST_TIME 88 9202
TEST_TIME 89 9150
TEST_TIME 90 9093
TEST_TIME 91 9040
TEST_TIME 92 8961
TEST_TIME 93 8907
TEST_TIME 94 9002
TEST_TIME 95 9088
TEST_TIME 96 9066
TEST_TIME 97 9050
TEST_TIME 98 9087
TEST_TIME 99 9064
TEST_TIME 100 9083
TEST_TIME 101 9110
TEST_TIME 102 9185
TEST_TIME 103 9232
TEST_TIME 104 9212
TEST_TIME 105 9221
TEST_TIME 106 9203
TEST_TIME 107 9126
TEST_TIME 108 9148
TEST_TIME 109 9099
TEST_TIME 110 9050
TEST_TIME 111 8997
TEST_TIME 112 8955
TEST_TIME 113 8950
TEST_TIME 114 8916
TEST_TIME 115 8936
TEST_TIME 116 8913
TEST_TIME 117 8889
TEST_TIME 118 8883
TEST_TIME 119 8855
TEST_TIME 120 8821

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-10-2022 04:38:56 to 19-10-2022 04:38:59.

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 1475488 1475488 0 0.00 0 20207 14 0 0
doc_list_biud 1475483 1475483 0 0.00 0 55588 41 0 0
sp_add_invoice_to_stock 4867 4785 82 1.68 55 222227 27730 0 82
sp_cancel_adding_invoice 5191 5191 0 0.00 6 91902 1131 0 0
sp_cancel_client_order 1674 1674 0 0.00 6 10559 374 0 0
sp_cancel_customer_reserve 518749 518749 0 0.00 1 49280 100 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 12555 143 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 2628 94 0 0
sp_cancel_supplier_invoice 5760 5750 10 0.17 3 55596 1038 0 10
sp_cancel_supplier_order 394 394 0 0.00 10 1298328 167261 0 0
sp_cancel_write_off 9655 9655 0 0.00 2 11111 171 0 0
sp_client_order 19540 19540 0 0.00 6 23415 467 0 0
sp_customer_reserve 455509 455509 0 0.00 0 97120 262 0 0
sp_fill_shopping_cart 30136 30136 0 0.00 1 16561 83 0 0
sp_fill_shopping_cart_clo_res 455495 455495 0 0.00 0 16096 19 0 0
sp_get_clo_for_invoice 4762 4762 0 0.00 0 22018 293 0 0
sp_kill_cost_storno 15961 15961 0 0.00 0 11068 73 0 0
sp_kill_qstorno_ret_qs2qd 1053297 1053297 0 0.00 0 55586 44 0 0
sp_kill_qty_storno 555620 555620 0 0.00 0 55587 100 0 0
sp_lock_selected_doc 19775 19775 0 0.00 0 12161 8 0 0
sp_make_cost_storno 26607 26607 0 0.00 0 14864 79 0 0
sp_multiply_rows_for_pdistr 26607 26607 0 0.00 0 8683 38 0 0
sp_multiply_rows_for_qdistr 19540 19540 0 0.00 0 10919 200 0 0
sp_pay_from_customer 6759 6759 0 0.00 5 13210 165 0 0
sp_pay_to_supplier 5641 5641 0 0.00 7 20582 186 0 0
sp_payment_common 12400 12400 0 0.00 2 10089 44 0 0
sp_qd_handle_on_cancel_clo 1674 1674 0 0.00 0 7299 296 0 0
sp_qd_handle_on_invoice_upd_sts 9953 9953 0 0.00 0 20048 467 0 0
sp_qd_handle_on_reserve_upd_sts 9445 9445 0 0.00 0 16327 66 0 0
sp_reserve_write_off 9445 9445 0 0.00 9 26670 283 0 0
sp_supplier_invoice 5306 5306 0 0.00 8 86218 2890 0 0
sp_supplier_order 5292 5292 0 0.00 8 36157 1329 0 0
srv_aggregate_perf_data 109 109 0 0.00 2881 151583 15821 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 750 147716 36466 0 0
srv_find_qd_qs_mism 1008803 1008803 0 0.00 0 16062 11 0 0
srv_make_invnt_saldo 508 508 0 0.00 56 62808 3608 0 0
srv_make_money_saldo 885 885 0 0.00 1 11137 365 0 0
srv_recalc_idx_stat 5 5 0 0.00 98181 445254 250886 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 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 4787 4787 0 0.00 1 16299 886 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 1 7911 467 0 0
t$perf-norm:sp_cancel_client_order 1674 1674 0 0.00 0 1086 26 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 2998 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1470 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 467 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 1730 36 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 85686 6356 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 428 14 0 0
t$perf-norm:sp_client_order 19540 19540 0 0.00 0 10140 34 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 1491 15 0 0
t$perf-norm:sp_pay_from_customer 6759 6759 0 0.00 0 8728 20 0 0
t$perf-norm:sp_pay_to_supplier 5641 5641 0 0.00 0 7379 22 0 0
t$perf-norm:sp_reserve_write_off 9445 9445 0 0.00 0 8072 23 0 0
t$perf-norm:sp_supplier_invoice 5306 5306 0 0.00 0 25327 34 0 0
t$perf-norm:sp_supplier_order 5292 5292 0 0.00 0 10140 35 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 562 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 577 15 0 0
t$perf-norm:srv_make_invnt_saldo 508 508 0 0.00 0 1448 16 0 0
t$perf-norm:srv_make_money_saldo 885 885 0 0.00 0 376 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 293 113 0 0
v_add_invoice_to_stock 9686 9522 164 1.69 0 5235 24 0 164
v_all_customers 36194 36194 0 0.00 0 126 4 0 0
v_all_suppliers 29922 29922 0 0.00 0 90 4 0 0
v_all_wares 39080 39080 0 0.00 0 140 4 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 1049 18 0 0
v_cancel_client_order 3348 3348 0 0.00 0 6194 11 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 2678 14 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 1 3316 13 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 2595 12 0 0
v_cancel_supplier_invoice 788 768 20 2.53 0 2661 27 0 20
v_cancel_supplier_order 788 788 0 0.00 0 5477 20 0 0
v_cancel_write_off 1906 1906 0 0.00 0 8361 26 0 0
v_max_id_clo_ord 5292 5292 0 0.00 0 258 5 0 0
v_max_id_clo_res 977 977 0 0.00 0 1839 13 0 0
v_max_id_ord_sup 5304 5304 0 0.00 0 5864 9 0 0
v_max_non_paid_invoice 1276 1276 0 0.00 0 257 8 0 0
v_max_non_paid_realizn 3367 3367 0 0.00 0 2088 8 0 0
v_min_id_clo_ord 5292 5292 0 0.00 0 2327 7 0 0
v_min_id_clo_res 977 977 0 0.00 0 2504 13 0 0
v_min_id_ord_sup 5304 5304 0 0.00 0 8357 11 0 0
v_min_non_paid_invoice 5641 5641 0 0.00 0 6922 17 0 0
v_min_non_paid_realizn 6759 6759 0 0.00 0 6958 19 0 0
v_our_firm 9670 9670 0 0.00 0 85 5 0 0
v_reserve_write_off 18890 18890 0 0.00 0 3316 10 0 0
x_lock_depdocs_on_canc_invoice 10557 10557 0 0.00 0 10529 125 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 3410 337 0 0
x_make_qstorno_1000_1200 5292 5292 0 0.00 2 36145 1171 0 0
x_make_qstorno_1200_2000 5306 5306 0 0.00 3 86187 2657 0 0
x_make_qstorno_2100_3300 455509 455509 0 0.00 0 97119 231 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 19-10-2022 04:39:00 to 19-10-2022 04:39:05.

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:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:29 16586 8192000000 9696297280 9990676480 776688736 8.660 100 91 182 0 806952192 7553648 22709808
02:09:54 28606 8192000000 9754415296 10058608640 827719456 9.176 100 88 176 0 859811056 10725136 21366464
02:11:41 8851 8192000000 9797514640 10115203072 867307072 9.573 100 84 167 0 904743552 17814880 19621600
02:12:54 8512 8192000000 9844556000 10181177344 920410080 10.100 100 79 153 2 965469136 28746208 16312848
02:14:05 3372 8192000000 9917050896 10259795968 959064416 10.480 100 88 172 0 1025914064 48671152 18178496
02:15:11 2055 8192000000 9959583312 10318503936 1003079216 10.908 100 89 174 1 1080374992 59733248 17562528
02:16:15 916 8192000000 10022221696 10384363520 1021379952 11.085 100 95 186 0 1139096832 99295424 18421456
02:17:18 994 8192000000 10015684512 10381426688 1044289648 11.306 100 94 184 2 1134583504 71251232 19042624
02:18:20 1357 8192000000 10033628368 10411143168 1056266000 11.421 100 92 179 1 1151934096 77708240 17959856
02:19:23 982 8192000000 10030168768 10408435712 1065361840 11.508 100 94 177 1 1153174928 68146160 19666928
02:20:25 3983 8192000000 10029010080 10404012032 1067096384 11.524 100 91 171 2 1141410032 56725776 17587872
02:21:29 6193 8192000000 10036880848 10425937920 1072524080 11.576 100 71 140 2 1151318752 64929680 13864992
02:22:37 1267 8192000000 10034945904 10422480896 1082962160 11.676 100 87 163 2 1158266912 57974992 17329760
02:23:39 1310 8192000000 10055307904 10440544256 1087188880 11.716 100 90 170 1 1177246480 73570544 16487056
02:24:42 1273 8192000000 10052834400 10435510272 1090926400 11.751 100 96 189 4 1173656640 63697072 19033168
02:25:45 4880 8192000000 10062707232 10458390528 1089268512 11.736 100 72 137 0 1182359392 80017936 13072944
02:26:51 1175 8192000000 10048491216 10438664192 1098649712 11.825 100 86 160 1 1174568608 59481632 16437264
02:27:53 1088 8192000000 10059036640 10448343040 1097184656 11.811 100 94 176 0 1179728560 63837488 18706416
02:28:54 1286 8192000000 10035202512 10426986496 1093122784 11.772 100 84 149 7 1157771760 48279040 16369936
02:29:57 7587 8192000000 10045806176 10440052736 1088225840 11.726 100 65 125 1 1163614176 62942592 12445744
02:31:05 962 8192000000 9992508416 10365919232 1035886192 11.225 97 88 165 2 1111205728 59822880 15496656
02:32:07 2270 8192000000 10000388416 10382561280 1057113920 11.429 100 68 130 3 1130565824 60062800 13389104
02:33:10 2446 8192000000 10006229216 10381221888 1042198800 11.286 99 79 144 0 1127689408 69613728 15876880
02:34:14 1275 8192000000 10015574544 10388135936 1044156032 11.305 100 85 163 2 1131707952 70529344 17022576
02:35:16 835 8192000000 9992597856 10365009920 1031391408 11.182 100 94 178 1 1112223008 61699840 19131760
02:36:21 2808 8192000000 9968204256 10345590784 1029341536 11.162 100 72 140 1 1089426800 46107088 13978176
02:37:27 12506 8192000000 9964313216 10346037248 1038314144 11.248 100 50 91 2 1087687328 41361776 8011408
02:38:44 3258 8192000000 10008941808 10388992000 1059511504 11.452 100 65 128 0 1130538064 58119856 12906704
02:39:53 15542 8192000000 10063155328 10448560128 1069795664 11.550 100 65 129 0 1166445216 83378896 13270656
02:41:19 1436 8192000000 10055457024 10439946240 1090617792 11.749 100 83 165 1 1178918896 70576608 17724496
02:42:23 2582 8192000000 10098347968 10499887104 1105106672 11.886 100 83 152 2 1216684928 96843424 14734832
02:43:31 5226 8192000000 10117033728 10504826880 1115506272 11.985 100 77 144 0 1228788464 99311072 13971120
02:44:39 2871 8192000000 10156140944 10550345728 1130779744 12.129 100 86 163 1 1270819312 123368944 16670624
02:45:45 1336 8192000000 10214175904 10610704384 1143730048 12.251 100 88 169 2 1330677584 167834272 19113264
02:46:51 1230 8192000000 10243701648 10641514496 1153771376 12.345 100 88 169 0 1354630320 182504224 18354720
02:47:56 820 8192000000 10261269040 10658697216 1158410640 12.388 100 94 177 1 1372749680 194942400 19396640
02:49:00 4452 8192000000 10293195824 10702684160 1171477552 12.511 100 80 156 1 1405119680 215813840 17828288
02:50:07 1285 8192000000 10324849056 10760601600 1173980560 12.534 100 80 156 1 1432404016 241174128 17249328
02:51:13 776 8192000000 10325428304 10741354496 1187911792 12.664 100 95 188 1 1434854016 226207008 20735216
02:52:17 6596 8192000000 10321565440 10726367232 1183614912 12.624 100 72 141 0 1423747440 223826656 16305872
02:53:26 910 8192000000 10391736368 10794430464 1194841728 12.728 100 95 190 0 1495899824 279424576 21633520
02:54:28 1681 8192000000 10450252416 10854297600 1184654272 12.634 99 97 194 1 1553464928 346890192 21920464
02:55:33 1214 8192000000 10495592560 10906501120 1193755584 12.718 100 92 182 1 1605216496 390934720 20526192
02:56:37 738 8192000000 10521889408 10964172800 1189349664 12.677 100 100 197 4 1627237200 414901456 22986080
02:57:40 974 8192000000 10529809216 10956386304 1190415776 12.687 100 95 187 2 1635545920 422026432 23103712
02:58:44 1464 8192000000 10555460464 10981498880 1185566784 12.642 99 82 160 0 1661817392 456729360 19521248
02:59:48 1123 8192000000 10541733968 11016421376 1194380336 12.724 100 81 157 0 1645632784 431845408 19407040
03:00:55 4136 8192000000 10531954304 10983993344 1191258416 12.695 100 58 112 1 1631386176 426243120 13884640
03:02:23 14204 8192000000 10550428000 10990272512 1183791904 12.626 100 50 96 0 1648191616 452327232 12072480
03:03:53 24874 8192000000 10498463440 10948628480 1171819792 12.514 100 28 56 0 1597078368 417195264 8063312
03:05:20 28252 8192000000 10408878608 10912030720 1156018208 12.366 99 31 62 1 1553676464 389289440 8368816
03:06:50 46595 8192000000 10193741248 10651815936 1145492032 12.267 99 18 36 0 1322028160 171972896 4563232
03:08:38 17112 8192000000 10110374336 10613571584 1140979424 12.225 100 14 26 0 1225580864 81989328 2612112
03:09:56 109504 8192000000 10036064848 10484932608 1017321792 11.046 89 11 22 0 1050628720 31422944 1883984
03:12:47 11711 8192000000 10088482736 10506514432 1119088448 12.018 99 53 100 1 1185440976 55048512 11304016
03:14:00 76441 8192000000 10133613200 10607620096 1115121360 11.981 98 26 52 0 1230448608 108760176 6567072
03:17:23 111699 8192000000 10092111840 10512310272 1093183936 11.773 96 51 98 0 1146113376 42986880 9942560
03:20:15 27204 8192000000 10116427360 10550407168 1127750432 12.100 100 32 60 0 1194132256 59564832 6816992
03:21:53 36691 8192000000 10099599552 10570620928 1113997648 11.970 98 32 60 1 1197660112 76863296 6799168
03:23:31 147716 8192000000 10116516800 10585280512 1104651232 11.882 97 20 39 0 1227184896 117779104 4754560
03:27:15 68681 8192000000 10139947792 10591576064 1107911936 11.913 99 23 43 1 1236483360 123909664 4661760
03:29:54 54935 8192000000 10053952320 10506244096 1093710688 11.778 97 35 66 0 1145824592 45099472 7014432
03:31:55 22539 8192000000 10115805120 10556497920 1120260544 12.029 99 48 94 1 1192794064 63355040 9178480
03:33:22 63781 8192000000 10099265184 10536058880 1098873888 11.827 97 24 48 0 1181859104 78193904 4791312
03:35:26 51897 8192000000 10099087600 10522705920 1119568608 12.023 99 32 61 0 1196859584 71050864 6240112
03:37:19 47181 8192000000 10110270336 10581471232 1118029776 12.008 99 11 22 0 1174630288 54340704 2259808
03:39:21 59369 8192000000 10113662256 10560503808 1127493488 12.098 99 36 73 0 1193119056 58743552 6882016
03:41:23 79889 8192000000 10096173184 10516578304 1126671568 12.090 98 58 109 4 1180751456 42370976 11708912
03:43:51 29255 8192000000 10112717280 10569089024 1143526512 12.249 100 47 90 0 1200388976 47442320 9420144
03:45:25 31304 8192000000 10100093792 10565951488 1150688512 12.316 100 6 12 0 1201316176 49411344 1216320
03:46:57 50884 8192000000 10129395760 10600886272 1158408496 12.388 100 13 26 0 1236224512 74939200 2876816
03:49:10 85844 8192000000 10105292272 10623737856 1154857968 12.355 100 9 18 0 1233828512 76921040 2049504
03:51:43 58881 8192000000 10090401664 10557931520 1115428736 11.984 97 21 42 0 1175669104 55569232 4671136
03:53:42 77492 8192000000 10134329040 10608865280 1117351088 12.002 98 19 36 0 1215822256 94763600 3707568
03:56:00 41786 8192000000 10247629648 10688282624 1140202032 12.217 99 40 80 0 1302462992 153144128 9116832
03:57:54 58826 8192000000 10173735760 10650836992 1120202576 12.029 98 17 34 0 1268208416 143962352 4043488
03:59:57 20040 8192000000 10113841280 10549440512 1123917056 12.064 99 42 82 0 1191779712 59382800 8479856
04:01:17 25331 8192000000 10117049392 10543198208 1121716848 12.043 98 39 78 0 1203750496 74028896 8004752
04:02:44 81778 8192000000 10121738064 10611359744 1135437312 12.173 100 47 92 1 1214471504 69397568 9636624
04:05:12 64654 8192000000 10079693680 10540105728 1134950672 12.168 99 16 32 1 1172354848 34276128 3128048
04:07:20 50600 8192000000 10121170832 10569240576 1127936688 12.102 98 10 20 0 1188506976 58401344 2168944
04:09:13 92978 8192000000 10213016016 10670800896 1133346912 12.153 98 19 37 0 1277177552 139622688 4207952
04:12:34 33610 8192000000 10086641184 10522345472 1117691568 12.005 98 30 56 0 1173947344 51099056 5156720
04:14:09 47499 8192000000 10130528832 10569072640 1133005872 12.150 99 28 56 1 1203568880 64924672 5638336
04:15:56 40669 8192000000 10126909184 10554396672 1082794208 11.674 95 34 68 1 1183950032 93738400 7417424
04:17:46 23129 8192000000 10156611504 10603417600 1132510592 12.145 100 36 71 0 1246550880 106233472 7806816
04:19:15 66594 8192000000 10081103104 10595520512 1070435632 11.556 95 16 32 0 1161054160 87281488 3337040
04:21:33 46655 8192000000 10097374336 10554327040 1121509456 12.041 100 43 86 0 1200162992 69907424 8746112
04:23:25 12931 8192000000 10115213440 10538479616 1122342448 12.049 100 56 109 1 1189903520 57108384 10452688
04:24:44 52044 8192000000 10093890064 10562502656 1123597632 12.061 100 44 88 0 1199854416 67125136 9131648
04:26:46 81540 8192000000 10073914000 10539544576 1127840080 12.101 100 13 26 0 1167211952 36873584 2498288
04:29:07 85177 8192000000 10092068496 10592280576 1119242144 12.020 99 16 32 0 1203898096 80832688 3823264
04:31:35 45578 8192000000 10053873440 10508304384 1098720784 11.826 98 23 45 0 1145392592 41984480 4687328
04:33:23 29305 8192000000 10101421248 10542436352 1131253984 12.133 100 28 56 0 1188106672 50571952 6280736
04:34:58 22259 8192000000 10140179936 10595258368 1143522944 12.249 100 34 67 1 1251859072 100877872 7458256
04:36:21 23272 8192000000 10208642608 10650570752 1144448832 12.257 99 47 94 0 1285732112 131420400 9862880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-10-2022 04:39:07 to 19-10-2022 04:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 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 19-10-2022 04:39:24 to 19-10-2022 04:39:26.

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          206962
MON$OLDEST_ACTIVE               214715
MON$OLDEST_SNAPSHOT             214715
MON$NEXT_TRANSACTION            214715
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                       2040032
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             762
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2860 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2860 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2860 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2860 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 ---
:117: 
:118: mscs-test-161.budzdorov.ru    Wed Oct 19 04:37:35 2022
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:120: 
--- end of diff output ---

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

19.10.22 04:39:27. 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-19 04:39:30 No severe PSQL-related problems occured
19.10.22 04:39:31. End of report.