Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2022 01:39
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 15188428 kB
MemAvailable 30076076 kB
Buffers 0 kB
Cached 16512184 kB
SwapCached 3264 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-12-24 01:38:37
DTS_END                         2022-12-24 01:38:37.4580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51636
TRN_ID                          237913
ATT_ID                          690
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: 9064.25 23642 1087710
customer order: creation 171.44 327 20573
customer order: refuse 15.06 378 1807
order to supplier: creation 44.48 1130 5338
order to supplier: removal 3.46 98316 415
invoice (draft): creation 45.16 3168 5419
invoice (draft): removal 49.43 746 5931
invoice accept: apply 41.63 17460 4996
invoice accept: cancel 45.58 763 5470
customer reserve: creation 3894.72 171 467366
customer reserve: removal 4458.28 61 534994
realization accept: apply 83.93 165 10071
realization accept: cancel 82.88 114 9946
payment from customer: creation 58.72 109 7046
payment from customer: removal 5.68 78 681
payment to supplier: creation 48.61 119 5833
payment to supplier: removal 3.17 54 380
service: total inventory turnovers 4.78 1318 574
service: total monetary turnovers 6.29 26 755
service: aggregate perf. data 0.93 20627 111
service: refresh index statistics 0.03 327700 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-12-2022 01:39:57 to 24-12-2022 01:40:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1661
TEST_TIME 2 2612
TEST_TIME 3 3002
TEST_TIME 4 3389
TEST_TIME 5 3884
TEST_TIME 6 4391
TEST_TIME 7 4566
TEST_TIME 8 5133
TEST_TIME 9 5270
TEST_TIME 10 5332
TEST_TIME 11 5788
TEST_TIME 12 5816
TEST_TIME 13 6202
TEST_TIME 14 6500
TEST_TIME 15 7028
TEST_TIME 16 7212
TEST_TIME 17 7359
TEST_TIME 18 8093
TEST_TIME 19 8157
TEST_TIME 20 8219
TEST_TIME 21 8643
TEST_TIME 22 9138
TEST_TIME 23 9235
TEST_TIME 24 9389
TEST_TIME 25 9670
TEST_TIME 26 10036
TEST_TIME 27 11061
TEST_TIME 28 10982
TEST_TIME 29 10694
TEST_TIME 30 10730
TEST_TIME 31 10474
TEST_TIME 32 10221
TEST_TIME 33 10197
TEST_TIME 34 10026
TEST_TIME 35 9995
TEST_TIME 36 9917
TEST_TIME 37 9895
TEST_TIME 38 9808
TEST_TIME 39 9611
TEST_TIME 40 9734
TEST_TIME 41 9538
TEST_TIME 42 9637
TEST_TIME 43 9523
TEST_TIME 44 9446
TEST_TIME 45 9424
TEST_TIME 46 9282
TEST_TIME 47 9119
TEST_TIME 48 9133
TEST_TIME 49 9098
TEST_TIME 50 9112
TEST_TIME 51 9053
TEST_TIME 52 9175
TEST_TIME 53 9247
TEST_TIME 54 9175
TEST_TIME 55 9269
TEST_TIME 56 9225
TEST_TIME 57 9185
TEST_TIME 58 9293
TEST_TIME 59 9330
TEST_TIME 60 9392
TEST_TIME 61 9363
TEST_TIME 62 9388
TEST_TIME 63 9323
TEST_TIME 64 9250
TEST_TIME 65 9200
TEST_TIME 66 9159
TEST_TIME 67 9145
TEST_TIME 68 9087
TEST_TIME 69 9056
TEST_TIME 70 8983
TEST_TIME 71 9034
TEST_TIME 72 9137
TEST_TIME 73 9145
TEST_TIME 74 9137
TEST_TIME 75 9090
TEST_TIME 76 9150
TEST_TIME 77 9146
TEST_TIME 78 9195
TEST_TIME 79 9205
TEST_TIME 80 9187
TEST_TIME 81 9208
TEST_TIME 82 9292
TEST_TIME 83 9336
TEST_TIME 84 9316
TEST_TIME 85 9216
TEST_TIME 86 9171
TEST_TIME 87 9113
TEST_TIME 88 9040
TEST_TIME 89 9054
TEST_TIME 90 9093
TEST_TIME 91 9083
TEST_TIME 92 9095
TEST_TIME 93 9142
TEST_TIME 94 9122
TEST_TIME 95 9133
TEST_TIME 96 9150
TEST_TIME 97 9188
TEST_TIME 98 9190
TEST_TIME 99 9225
TEST_TIME 100 9168
TEST_TIME 101 9279
TEST_TIME 102 9241
TEST_TIME 103 9225
TEST_TIME 104 9241
TEST_TIME 105 9232
TEST_TIME 106 9272
TEST_TIME 107 9200
TEST_TIME 108 9162
TEST_TIME 109 9120
TEST_TIME 110 9092
TEST_TIME 111 9158
TEST_TIME 112 9112
TEST_TIME 113 9093
TEST_TIME 114 9072
TEST_TIME 115 9063
TEST_TIME 116 9069
TEST_TIME 117 9075
TEST_TIME 118 9075
TEST_TIME 119 9053
TEST_TIME 120 9064

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-12-2022 01:40:01 to 24-12-2022 01:40:04.

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 1519689 1519689 0 0.00 0 20854 6 0 0
doc_list_biud 1519686 1519686 0 0.00 0 26543 27 0 0
sp_add_invoice_to_stock 5117 4996 121 2.36 30 156050 17460 0 121
sp_cancel_adding_invoice 5470 5470 0 0.00 3 54187 764 0 0
sp_cancel_client_order 1807 1807 0 0.00 4 10941 378 0 0
sp_cancel_customer_reserve 534994 534994 0 0.00 0 19955 62 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 2 8910 79 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 1684 54 0 0
sp_cancel_supplier_invoice 5942 5931 11 0.18 1 26600 746 0 11
sp_cancel_supplier_order 415 415 0 0.00 10 1046245 98316 0 0
sp_cancel_write_off 9946 9946 0 0.00 1 11963 114 0 0
sp_client_order 20573 20573 0 0.00 3 20110 327 0 0
sp_customer_reserve 467366 467366 0 0.00 0 39840 171 0 0
sp_fill_shopping_cart 31312 31312 0 0.00 1 20059 38 0 0
sp_fill_shopping_cart_clo_res 467365 467365 0 0.00 0 16152 14 0 0
sp_get_clo_for_invoice 4995 4995 0 0.00 0 34348 342 0 0
sp_kill_cost_storno 16477 16477 0 0.00 0 5572 55 0 0
sp_kill_qstorno_ret_qs2qd 1086280 1086280 0 0.00 0 26542 33 0 0
sp_kill_qty_storno 573628 573628 0 0.00 0 26542 71 0 0
sp_lock_selected_doc 20802 20802 0 0.00 0 8769 6 0 0
sp_make_cost_storno 27945 27945 0 0.00 0 20844 47 0 0
sp_multiply_rows_for_pdistr 27945 27945 0 0.00 0 5688 24 0 0
sp_multiply_rows_for_qdistr 20573 20573 0 0.00 0 20062 161 0 0
sp_pay_from_customer 7046 7046 0 0.00 4 15146 110 0 0
sp_pay_to_supplier 5833 5833 0 0.00 4 18610 119 0 0
sp_payment_common 12878 12878 0 0.00 1 18590 30 0 0
sp_qd_handle_on_cancel_clo 1807 1807 0 0.00 0 10934 340 0 0
sp_qd_handle_on_invoice_upd_sts 10465 10465 0 0.00 0 25356 289 0 0
sp_qd_handle_on_reserve_upd_sts 10070 10070 0 0.00 0 15839 60 0 0
sp_reserve_write_off 10071 10071 0 0.00 6 15859 166 0 0
sp_supplier_invoice 5419 5419 0 0.00 6 86457 3169 0 0
sp_supplier_order 5338 5338 0 0.00 5 21537 1131 0 0
srv_aggregate_perf_data 111 111 0 0.00 2174 381017 20628 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1131 126870 47759 0 0
srv_find_qd_qs_mism 1039698 1039698 0 0.00 0 19821 6 0 0
srv_make_invnt_saldo 574 574 0 0.00 23 206049 1319 0 0
srv_make_money_saldo 755 755 0 0.00 0 1845 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 149269 517017 327700 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4996 4996 0 0.00 0 11858 144 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 4694 91 0 0
t$perf-norm:sp_cancel_client_order 1807 1807 0 0.00 0 966 10 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 1550 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 751 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 49 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 1591 20 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 16926 1349 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 1530 5 0 0
t$perf-norm:sp_client_order 20573 20573 0 0.00 0 7149 12 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 1294 6 0 0
t$perf-norm:sp_pay_from_customer 7046 7046 0 0.00 0 7844 10 0 0
t$perf-norm:sp_pay_to_supplier 5833 5833 0 0.00 0 2431 7 0 0
t$perf-norm:sp_reserve_write_off 10071 10071 0 0.00 0 6374 9 0 0
t$perf-norm:sp_supplier_invoice 5419 5419 0 0.00 0 6717 15 0 0
t$perf-norm:sp_supplier_order 5338 5338 0 0.00 0 4109 14 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 488 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 69 2 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 1646 22 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 477 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10232 9990 242 2.36 0 8900 19 0 242
v_all_customers 37342 37342 0 0.00 0 23 1 0 0
v_all_suppliers 30112 30112 0 0.00 0 20 1 0 0
v_all_wares 41142 41142 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 710 6 0 0
v_cancel_client_order 3614 3614 0 0.00 0 9401 10 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 4639 11 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 1784 5 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 1655 7 0 0
v_cancel_supplier_invoice 742 720 22 2.96 0 599 15 0 22
v_cancel_supplier_order 830 830 0 0.00 0 4585 10 0 0
v_cancel_write_off 2000 2000 0 0.00 0 3191 17 0 0
v_max_id_clo_ord 5337 5337 0 0.00 0 657 2 0 0
v_max_id_clo_res 972 972 0 0.00 0 2617 12 0 0
v_max_id_ord_sup 5401 5401 0 0.00 0 3295 2 0 0
v_max_non_paid_invoice 1515 1515 0 0.00 0 3404 6 0 0
v_max_non_paid_realizn 3825 3825 0 0.00 0 3782 5 0 0
v_min_id_clo_ord 5337 5337 0 0.00 0 3524 5 0 0
v_min_id_clo_res 972 972 0 0.00 0 6996 24 0 0
v_min_id_ord_sup 5401 5401 0 0.00 0 4365 6 0 0
v_min_non_paid_invoice 5833 5833 0 0.00 0 6602 17 0 0
v_min_non_paid_realizn 7045 7045 0 0.00 0 15121 21 0 0
v_our_firm 10240 10240 0 0.00 0 16 1 0 0
v_reserve_write_off 20140 20140 0 0.00 0 12237 7 0 0
x_lock_depdocs_on_canc_invoice 11041 11041 0 0.00 0 16769 130 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 3588 327 0 0
x_make_qstorno_1000_1200 5338 5338 0 0.00 1 20914 1057 0 0
x_make_qstorno_1200_2000 5419 5419 0 0.00 3 86451 3022 0 0
x_make_qstorno_2100_3300 467366 467366 0 0.00 0 39839 155 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-12-2022 01:40:05 to 24-12-2022 01:40:10.

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
23:08:41 1 8192000000 8799267488 8810692608 5664016 0.069 2 1 2 0 5763648 56848 42784
23:09:42 22021 8192000000 9894782320 10206015488 981026880 10.694 100 86 174 0 1013409616 10179104 22203632
23:11:11 19449 8192000000 9937538144 10259492864 1022722272 11.098 100 88 176 0 1053559968 7896960 22940736
23:12:35 10741 8192000000 9979755424 10311639040 1063725584 11.492 100 86 172 0 1094868336 9778528 21364224
23:13:54 11533 8192000000 10026805072 10372526080 1114719952 11.977 100 84 168 0 1145921664 10535072 20666640
23:15:10 4706 8192000000 10057636768 10415919104 1154307632 12.350 100 74 148 0 1182586512 11272176 17006704
23:16:19 3965 8192000000 10108847696 10474053632 1197676368 12.755 100 73 142 1 1225468352 11891904 15900080
23:17:26 2235 8192000000 10147359952 10518024192 1239162448 13.139 100 86 170 0 1272350640 13793152 19395040
23:18:30 5119 8192000000 10167785520 10550325248 1265321072 13.379 100 69 138 0 1292364272 12188512 14854688
23:19:36 1481 8192000000 10208811280 10591375360 1292605136 13.628 100 89 179 3 1326092976 13653360 19834480
23:20:39 932 8192000000 10230608864 10620911616 1312127680 13.805 100 94 191 1 1347878688 14091376 21659632
23:21:41 6429 8192000000 10209785264 10617593856 1314154512 13.824 100 45 86 0 1332127600 8952880 9020208
23:22:49 5968 8192000000 10213924544 10630983680 1326944352 13.940 100 33 68 1 1343801200 10321296 6535552
23:23:56 18834 8192000000 10217511728 10638213120 1329259888 13.960 100 15 30 1 1338444560 6242112 2942560
23:25:19 26610 8192000000 10252566592 10653380608 1334093616 14.004 100 66 128 6 1355525648 5729344 15702688
23:26:46 4770 8192000000 10231583024 10654044160 1339213376 14.050 100 25 50 0 1351362528 6288480 5860672
23:27:52 6277 8192000000 10207654592 10625585152 1312149936 13.806 99 15 30 1 1320676400 5472176 3054288
23:29:03 43929 8192000000 10203733408 10623721472 1297923440 13.676 98 36 70 0 1312758368 7252656 7582272
23:30:49 8036 8192000000 10174319280 10584387584 1277322544 13.489 99 24 48 0 1288746752 6600736 4823472
23:31:58 5793 8192000000 10158503040 10562646016 1258658432 13.318 99 32 65 0 1272449920 7169840 6621648
23:33:05 53686 8192000000 10142349024 10535837696 1196837936 12.747 95 36 71 0 1210237264 4683552 8715776
23:35:09 4516 8192000000 10156421728 10563735552 1268810464 13.411 100 30 58 0 1281473840 5326016 7337360
23:36:15 9937 8192000000 10177431424 10590027776 1290328624 13.607 100 17 32 1 1300234720 5362400 4543696
23:37:26 30271 8192000000 10189222240 10604433408 1302488176 13.718 100 26 52 1 1313364704 5580288 5296240
23:39:20 36307 8192000000 10224768352 10639990784 1325748096 13.929 100 25 48 1 1336557680 5129296 5680288
23:41:09 22090 8192000000 10274406704 10689015808 1363942992 14.273 100 47 93 0 1388514080 14387440 10183648
23:42:33 33630 8192000000 10283753200 10704769024 1374460592 14.367 100 34 68 0 1397597552 15363040 7773920
23:44:10 25841 8192000000 10282290080 10712985600 1365396272 14.286 99 38 76 0 1393331712 18925680 9009760
23:45:46 14641 8192000000 10321954960 10737164288 1394006016 14.542 100 67 135 0 1435820464 26054224 15760224
23:47:04 10441 8192000000 10318877824 10746064896 1401012432 14.604 100 44 88 2 1434727536 22549872 11165232
23:48:25 17851 8192000000 10329715632 10750087168 1401483232 14.608 100 50 97 1 1443214496 29588992 12142272
23:50:02 7498 8192000000 10358209952 10772520960 1414199712 14.721 100 79 156 3 1466499760 33015104 19284944
23:51:21 7564 8192000000 10351566496 10779291648 1414568000 14.725 100 58 116 0 1467450400 37962800 14919600
23:52:34 2080 8192000000 10363722000 10777505792 1403789856 14.629 99 79 158 0 1464553680 41445872 19317952
23:53:41 3856 8192000000 10366985984 10788974592 1414175456 14.721 100 66 129 0 1471634112 41324352 16134304
23:54:53 2530 8192000000 10358513936 10773905408 1404810752 14.638 100 72 141 0 1463024160 41163472 17049936
23:55:58 1130 8192000000 10360004224 10765365248 1394019248 14.542 100 88 176 2 1461722512 46503328 21199936
23:57:02 27618 8192000000 10338729328 10764611584 1376344528 14.384 98 42 83 0 1431337952 43397440 11595984
23:58:38 5670 8192000000 10336816096 10751725568 1367744560 14.307 99 66 129 0 1430690272 46459280 16486432
23:59:47 8812 8192000000 10328844448 10759806976 1370596576 14.332 100 48 97 1 1443568448 59067776 13904096
00:01:06 20092 8192000000 10321470320 10756632576 1373119008 14.355 99 34 67 1 1424184688 41971456 9094224
00:02:27 21702 8192000000 10311544448 10746519552 1359235808 14.230 99 25 50 0 1407155424 40986384 6933232
00:04:04 45968 8192000000 10277412352 10721554432 1317965104 13.858 98 23 46 0 1370895280 46716560 6213616
00:06:05 54761 8192000000 10256995024 10707079168 1315525936 13.836 97 29 56 0 1333675392 11472176 6677280
00:08:02 91796 8192000000 10265976640 10718924800 1348090544 14.130 99 11 22 0 1358954480 8884960 1978976
00:11:44 97460 8192000000 10248384848 10696290304 1330211360 13.969 97 22 44 0 1344815392 9916752 4687280
00:14:24 73271 8192000000 10266385568 10719375360 1343113008 14.085 98 13 26 0 1363131616 17139136 2879472
00:16:39 72520 8192000000 10294394256 10743431168 1367843920 14.308 99 16 32 0 1390991504 19263952 3883632
00:18:51 108808 8192000000 10277812016 10735841280 1375046112 14.372 100 16 32 0 1397410912 18372448 3992352
00:21:48 32117 8192000000 10279864208 10727104512 1378195520 14.400 100 27 55 1 1392818256 8203872 6418864
00:23:26 59119 8192000000 10277602544 10737897472 1381385184 14.429 100 9 19 0 1392740800 9331536 2024080
00:26:39 110534 8192000000 10299335120 10774638592 1394043376 14.542 100 17 34 0 1412871440 14594688 4233376
00:29:30 67446 8192000000 10304173552 10773819392 1407612944 14.663 100 25 48 0 1432922656 19350864 5958848
00:32:04 26267 8192000000 10291874720 10745876480 1367000864 14.300 98 29 58 0 1380210176 6986608 6222704
00:33:32 51981 8192000000 10310687536 10770309120 1399650432 14.592 100 21 42 0 1417445280 13264432 4530416
00:35:31 26335 8192000000 10324328128 10768007168 1401031696 14.604 100 35 69 0 1421918944 13391504 7495744
00:37:03 23259 8192000000 10316952384 10764505088 1402576064 14.618 100 30 60 1 1418477264 9575520 6325680
00:38:30 60628 8192000000 10305078560 10762637312 1362465296 14.259 97 14 28 0 1373975856 8657968 2852592
00:40:32 30051 8192000000 10279612384 10720829440 1349445744 14.142 97 26 52 0 1364424768 9072192 5906832
00:42:07 87087 8192000000 10283337520 10733113344 1351815776 14.164 97 30 60 0 1366736352 8266032 6654544
00:44:35 32199 8192000000 10248003888 10698981376 1324898048 13.921 96 25 49 2 1340224096 9877712 5448336
00:46:10 40691 8192000000 10266158544 10718388224 1360370512 14.241 99 13 26 0 1372960896 9468384 3122000
00:47:53 57716 8192000000 10286148592 10740006912 1383294624 14.446 100 20 40 0 1400883104 12606528 4981952
00:49:51 59718 8192000000 10246420144 10706411520 1306111584 13.751 95 15 30 0 1323605712 13654976 3839152
00:51:53 48475 8192000000 10253748704 10701004800 1334634464 14.009 98 13 26 0 1345675648 8272176 2769008
00:53:42 49833 8192000000 10261344960 10708017152 1321294096 13.888 97 31 62 0 1338776528 10664688 6817744
00:55:33 26886 8192000000 10267689552 10706673664 1358042784 14.220 100 30 58 0 1373598448 9570432 5985232
00:57:00 110245 8192000000 10244313024 10687418368 1293139184 13.633 95 17 34 0 1308875408 12004880 3731344
00:59:53 62679 8192000000 10268338656 10718093312 1320402784 13.880 97 16 30 0 1339289568 15592688 3294096
01:02:02 126864 8192000000 10275837520 10724802560 1337275312 14.033 98 14 28 0 1357201888 16865408 3061168
01:05:09 60491 8192000000 10268504480 10725736448 1368391696 14.313 100 18 36 0 1383240400 10988912 3859792
01:08:27 42972 8192000000 10279882176 10738147328 1366688624 14.297 99 19 38 0 1386756768 15789872 4278272
01:10:13 48813 8192000000 10289870304 10746167296 1385641024 14.467 100 19 38 0 1402913040 12931536 4340480
01:12:14 19818 8192000000 10296565664 10736906240 1364860240 14.281 98 26 49 0 1375990928 5945264 5185424
01:13:34 31139 8192000000 10310424096 10760011776 1391196048 14.517 100 30 60 0 1410455968 12385280 6874640
01:15:06 72151 8192000000 10305628032 10769248256 1386432928 14.474 99 7 14 0 1402457360 14754016 1270416
01:17:19 84997 8192000000 10316392672 10753413120 1356684528 14.208 97 13 26 0 1370559152 10955312 2919312
01:20:06 69789 8192000000 10309538672 10751856640 1367405776 14.304 98 13 25 0 1378685616 8780208 2499632
01:22:17 54776 8192000000 10296507184 10740736000 1361491808 14.251 98 25 50 0 1377643056 11003216 5148032
01:24:17 65490 8192000000 10293982112 10755887104 1377132672 14.391 99 15 28 1 1393080944 12442384 3505888
01:26:32 73418 8192000000 10300963312 10746134528 1366888096 14.299 98 31 62 0 1382475296 8991280 6595920
01:28:47 35899 8192000000 10288507104 10743377920 1365335280 14.285 98 23 46 1 1380327184 9394256 5597648
01:30:28 55492 8192000000 10287264720 10727399424 1347339248 14.124 97 25 49 0 1361920112 8571360 6009504
01:32:31 103306 8192000000 10258262512 10715930624 1345481600 14.107 98 17 34 0 1361659984 11944176 4234208
01:35:20 69685 8192000000 10262034432 10701406208 1306771232 13.757 96 22 43 1 1326999584 15331024 4897328
01:37:32 68638 8192000000 9662879408 10018832384 764231568 8.532 56 18 36 0 778175280 9646112 4297600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-12-2022 01:40:12 to 24-12-2022 01:40:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-12-2022 01:40:28 to 24-12-2022 01:40:30.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          236231
MON$OLDEST_ACTIVE               238118
MON$OLDEST_SNAPSHOT             238118
MON$NEXT_TRANSACTION            238118
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-03 16:41:55.3470
MON$PAGES                       2008448
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:1155: 
:1156: mscs-test-161.budzdorov.ru    Sat Dec 24 01:38:51 2022
:1157:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1158: 
:1159: 
:1160: mscs-test-161.budzdorov.ru    Sat Dec 24 01:38:58 2022
:1161:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1162: 
--- end of diff output ---

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

24.12.22 01:40:31. There are 3 logs in /var/tmp/logs-oltp30 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-12-24 01:40:33 No severe PSQL-related problems occured
24.12.22 01:40:35. End of report.