Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.06.2022 22: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 16185912 kB
MemAvailable 31251448 kB
Buffers 0 kB
Cached 15499196 kB
SwapCached 2452 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.10
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-06-14 22:38:18
DTS_END                         2022-06-14 22:38:18.9650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55910
TRN_ID                          246274
ATT_ID                          664
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: 9118.58 20915 1094230
customer order: creation 180.64 338 21677
customer order: refuse 15.22 391 1826
order to supplier: creation 46.91 1310 5629
order to supplier: removal 3.48 100528 417
invoice (draft): creation 46.88 3509 5626
invoice (draft): removal 50.35 818 6042
invoice accept: apply 42.73 18945 5127
invoice accept: cancel 45.69 768 5483
customer reserve: creation 3953.05 187 474366
customer reserve: removal 4427.82 62 531338
realization accept: apply 86.82 185 10418
realization accept: cancel 84.58 106 10149
payment from customer: creation 61.80 121 7416
payment from customer: removal 5.83 73 699
payment to supplier: creation 50.63 140 6076
payment to supplier: removal 3.32 105 398
service: total inventory turnovers 4.93 1210 592
service: total monetary turnovers 7.18 46 861
service: aggregate perf. data 0.72 27444 86
service: refresh index statistics 0.03 262012 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-06-2022 22:39:02 to 14-06-2022 22:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3882
TEST_TIME 2 7384
TEST_TIME 3 6024
TEST_TIME 4 5976
TEST_TIME 5 6047
TEST_TIME 6 6615
TEST_TIME 7 6491
TEST_TIME 8 6342
TEST_TIME 9 6292
TEST_TIME 10 6287
TEST_TIME 11 6365
TEST_TIME 12 6463
TEST_TIME 13 6486
TEST_TIME 14 6406
TEST_TIME 15 6600
TEST_TIME 16 7038
TEST_TIME 17 7400
TEST_TIME 18 7648
TEST_TIME 19 7913
TEST_TIME 20 8046
TEST_TIME 21 8188
TEST_TIME 22 8338
TEST_TIME 23 8142
TEST_TIME 24 8761
TEST_TIME 25 8966
TEST_TIME 26 9221
TEST_TIME 27 9230
TEST_TIME 28 9735
TEST_TIME 29 10224
TEST_TIME 30 10611
TEST_TIME 31 10304
TEST_TIME 32 10306
TEST_TIME 33 10159
TEST_TIME 34 10160
TEST_TIME 35 10252
TEST_TIME 36 10144
TEST_TIME 37 9959
TEST_TIME 38 9945
TEST_TIME 39 9883
TEST_TIME 40 9793
TEST_TIME 41 9662
TEST_TIME 42 9535
TEST_TIME 43 9459
TEST_TIME 44 9371
TEST_TIME 45 9307
TEST_TIME 46 9322
TEST_TIME 47 9212
TEST_TIME 48 9253
TEST_TIME 49 9142
TEST_TIME 50 9105
TEST_TIME 51 9141
TEST_TIME 52 9219
TEST_TIME 53 9218
TEST_TIME 54 9149
TEST_TIME 55 9110
TEST_TIME 56 9093
TEST_TIME 57 9104
TEST_TIME 58 9027
TEST_TIME 59 9100
TEST_TIME 60 9265
TEST_TIME 61 9238
TEST_TIME 62 9304
TEST_TIME 63 9292
TEST_TIME 64 9348
TEST_TIME 65 9413
TEST_TIME 66 9382
TEST_TIME 67 9426
TEST_TIME 68 9448
TEST_TIME 69 9452
TEST_TIME 70 9325
TEST_TIME 71 9383
TEST_TIME 72 9318
TEST_TIME 73 9253
TEST_TIME 74 9305
TEST_TIME 75 9271
TEST_TIME 76 9293
TEST_TIME 77 9268
TEST_TIME 78 9215
TEST_TIME 79 9207
TEST_TIME 80 9135
TEST_TIME 81 9114
TEST_TIME 82 9147
TEST_TIME 83 9114
TEST_TIME 84 9070
TEST_TIME 85 9099
TEST_TIME 86 9090
TEST_TIME 87 9097
TEST_TIME 88 9097
TEST_TIME 89 9136
TEST_TIME 90 9130
TEST_TIME 91 9134
TEST_TIME 92 9114
TEST_TIME 93 9180
TEST_TIME 94 9210
TEST_TIME 95 9257
TEST_TIME 96 9248
TEST_TIME 97 9267
TEST_TIME 98 9268
TEST_TIME 99 9309
TEST_TIME 100 9308
TEST_TIME 101 9235
TEST_TIME 102 9241
TEST_TIME 103 9182
TEST_TIME 104 9153
TEST_TIME 105 9129
TEST_TIME 106 9110
TEST_TIME 107 9100
TEST_TIME 108 9126
TEST_TIME 109 9114
TEST_TIME 110 9090
TEST_TIME 111 9089
TEST_TIME 112 9055
TEST_TIME 113 9079
TEST_TIME 114 9109
TEST_TIME 115 9094
TEST_TIME 116 9130
TEST_TIME 117 9128
TEST_TIME 118 9130
TEST_TIME 119 9179
TEST_TIME 120 9119

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-06-2022 22:39:06 to 14-06-2022 22: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 1533196 1533196 0 0.00 0 25838 6 0 0
doc_list_biud 1533196 1533196 0 0.00 0 27547 28 0 0
sp_add_invoice_to_stock 5303 5127 176 3.31 21 162416 18946 0 176
sp_cancel_adding_invoice 5483 5483 0 0.00 4 52279 768 0 0
sp_cancel_client_order 1826 1826 0 0.00 4 13176 392 0 0
sp_cancel_customer_reserve 531338 531338 0 0.00 1 24321 63 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 6938 73 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 9448 105 0 0
sp_cancel_supplier_invoice 6059 6042 17 0.28 1 25101 818 0 17
sp_cancel_supplier_order 417 417 0 0.00 5 996701 100529 0 0
sp_cancel_write_off 10149 10149 0 0.00 1 9339 107 0 0
sp_client_order 21677 21677 0 0.00 3 25189 339 0 0
sp_customer_reserve 474366 474366 0 0.00 0 36131 188 0 0
sp_fill_shopping_cart 32923 32923 0 0.00 1 25209 39 0 0
sp_fill_shopping_cart_clo_res 474363 474363 0 0.00 0 13492 13 0 0
sp_get_clo_for_invoice 5124 5124 0 0.00 0 21160 325 0 0
sp_kill_cost_storno 16729 16729 0 0.00 0 25824 52 0 0
sp_kill_qstorno_ret_qs2qd 1079284 1079284 0 0.00 0 25097 34 0 0
sp_kill_qty_storno 570796 570796 0 0.00 0 27546 73 0 0
sp_lock_selected_doc 21417 21417 0 0.00 0 7875 5 0 0
sp_make_cost_storno 29033 29033 0 0.00 0 14138 49 0 0
sp_multiply_rows_for_pdistr 29033 29033 0 0.00 0 10001 28 0 0
sp_multiply_rows_for_qdistr 21677 21677 0 0.00 0 13824 135 0 0
sp_pay_from_customer 7416 7416 0 0.00 4 16986 122 0 0
sp_pay_to_supplier 6076 6076 0 0.00 5 25331 140 0 0
sp_payment_common 13492 13492 0 0.00 1 11608 31 0 0
sp_qd_handle_on_cancel_clo 1826 1826 0 0.00 0 13171 348 0 0
sp_qd_handle_on_invoice_upd_sts 10606 10606 0 0.00 0 27543 333 0 0
sp_qd_handle_on_reserve_upd_sts 10418 10418 0 0.00 0 15303 62 0 0
sp_reserve_write_off 10418 10418 0 0.00 6 18643 185 0 0
sp_supplier_invoice 5626 5626 0 0.00 7 80898 3509 0 0
sp_supplier_order 5629 5629 0 0.00 5 27259 1311 0 0
srv_aggregate_perf_data 86 86 0 0.00 3481 311610 27445 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1642 144226 44276 0 0
srv_find_qd_qs_mism 1044705 1044705 0 0.00 0 10139 6 0 0
srv_make_invnt_saldo 592 592 0 0.00 32 60838 1211 0 0
srv_make_money_saldo 861 861 0 0.00 0 12097 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 133794 388172 262013 0 0
t$perf-abend:sp_add_invoice_to_stock 176 176 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5127 5127 0 0.00 0 12363 180 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 2824 71 0 0
t$perf-norm:sp_cancel_client_order 1826 1826 0 0.00 0 6559 14 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 1582 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 2162 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 704 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1060 13 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 31456 1599 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 1338 5 0 0
t$perf-norm:sp_client_order 21677 21677 0 0.00 0 8779 18 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 7580 14 0 0
t$perf-norm:sp_pay_from_customer 7416 7416 0 0.00 0 6321 11 0 0
t$perf-norm:sp_pay_to_supplier 6076 6076 0 0.00 0 11472 12 0 0
t$perf-norm:sp_reserve_write_off 10418 10418 0 0.00 0 5296 12 0 0
t$perf-norm:sp_supplier_invoice 5626 5626 0 0.00 0 7594 23 0 0
t$perf-norm:sp_supplier_order 5629 5629 0 0.00 0 6224 17 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 359 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 92 3 0 0
t$perf-norm:srv_make_invnt_saldo 592 592 0 0.00 0 8130 39 0 0
t$perf-norm:srv_make_money_saldo 861 861 0 0.00 0 6005 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 939 236 0 0
v_add_invoice_to_stock 10598 10246 352 3.32 0 9343 18 0 352
v_all_customers 39422 39422 0 0.00 0 23 1 0 0
v_all_suppliers 30840 30840 0 0.00 0 23 1 0 0
v_all_wares 43354 43354 0 0.00 0 178 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1425 9 0 0
v_cancel_client_order 3652 3652 0 0.00 0 2241 6 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 1275 8 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 1354 5 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 9328 20 0 0
v_cancel_supplier_invoice 866 832 34 3.92 0 1204 16 0 34
v_cancel_supplier_order 834 834 0 0.00 0 1311 6 0 0
v_cancel_write_off 2008 2008 0 0.00 0 2277 15 0 0
v_max_id_clo_ord 5629 5629 0 0.00 0 1511 2 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 4479 13 0 0
v_max_id_ord_sup 5617 5617 0 0.00 0 3654 3 0 0
v_max_non_paid_invoice 1902 1902 0 0.00 0 1308 3 0 0
v_max_non_paid_realizn 4042 4042 0 0.00 0 2337 5 0 0
v_min_id_clo_ord 5629 5629 0 0.00 0 2328 7 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 3406 7 0 0
v_min_id_ord_sup 5617 5617 0 0.00 0 6732 6 0 0
v_min_non_paid_invoice 6076 6076 0 0.00 0 5890 25 0 0
v_min_non_paid_realizn 7416 7416 0 0.00 1 11605 22 0 0
v_our_firm 10680 10680 0 0.00 0 75 1 0 0
v_reserve_write_off 20836 20836 0 0.00 0 9191 9 0 0
x_lock_depdocs_on_canc_invoice 11109 11109 0 0.00 0 29386 120 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 3263 306 0 0
x_make_qstorno_1000_1200 5629 5629 0 0.00 1 27243 1228 0 0
x_make_qstorno_1200_2000 5626 5626 0 0.00 3 74957 3364 0 0
x_make_qstorno_2100_3300 474366 474366 0 0.00 0 36130 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-06-2022 22:39:11 to 14-06-2022 22: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
20:08:22 3 8192000000 8798900800 8810233856 5315664 0.064 2 1 2 0 5415264 56816 42784
20:09:23 17384 8192000000 9897148416 10206650368 983690176 10.720 100 87 174 0 1012275936 5601984 22983776
20:10:46 24959 8192000000 9948762528 10268024832 1024496016 11.115 100 91 182 0 1055855104 8088272 23270816
20:12:24 11995 8192000000 9978874096 10308259840 1061458320 11.470 100 89 178 0 1093472496 9890352 22123824
20:13:41 17757 8192000000 10033376960 10381336576 1115647280 11.986 100 81 162 1 1145488528 10607504 19233744
20:15:02 7973 8192000000 10094954032 10454511616 1178173888 12.573 100 81 160 1 1209518960 12121600 19223472
20:16:13 7771 8192000000 10141273936 10512162816 1227545664 13.031 100 83 166 2 1259434928 13898512 17990752
20:17:23 3384 8192000000 10173347952 10549084160 1258617392 13.317 100 87 174 0 1291355136 13958848 18778896
20:18:29 1973 8192000000 10209708672 10592325632 1294938944 13.649 100 88 176 2 1328076784 13783072 19354768
20:19:31 22289 8192000000 10224667984 10615275520 1311018112 13.795 100 78 156 1 1340671168 11944944 17708112
20:20:55 3076 8192000000 10215032512 10626129920 1325333424 13.925 100 33 66 0 1340905232 8679104 6892704
20:22:02 6281 8192000000 10228236368 10647060480 1335201856 14.014 100 19 38 0 1350296992 10856448 4238688
20:23:13 10835 8192000000 10234397072 10653855744 1346457312 14.116 100 38 76 1 1361380064 5040224 9882528
20:24:25 31114 8192000000 10244608240 10666590208 1351503456 14.161 100 27 54 3 1364066000 6251552 6310992
20:25:56 6037 8192000000 10241400704 10666807296 1354096448 14.184 100 32 64 2 1368005440 6555936 7353056
20:27:05 10320 8192000000 10242679600 10665562112 1348321472 14.132 100 21 42 0 1360036704 6866528 4848704
20:28:18 52993 8192000000 10240524736 10635878400 1314655904 13.828 98 61 122 0 1337066672 7996032 14414736
20:30:11 6124 8192000000 10180030016 10577252352 1248562048 13.225 96 32 64 1 1262402496 6367392 7473056
20:31:18 5584 8192000000 10158448912 10565189632 1255489504 13.289 99 29 56 2 1272871520 11009616 6372400
20:32:25 9408 8192000000 10162786096 10554974208 1249703232 13.235 99 44 87 0 1266305072 6376752 10225088
20:33:50 8712 8192000000 10154518320 10560626688 1262822848 13.356 100 29 59 0 1275429952 6444992 6162112
20:35:00 5832 8192000000 10181140976 10593787904 1289760912 13.602 100 20 40 1 1300831456 5234560 5835984
20:36:08 9886 8192000000 10191976656 10610102272 1304242832 13.734 100 18 36 0 1314456976 5879552 4334592
20:37:22 8571 8192000000 10202661552 10619670528 1313229168 13.815 100 21 42 3 1323423424 5431600 4762656
20:38:34 39928 8192000000 10236453552 10641096704 1302692576 13.720 98 50 101 0 1320649056 7038176 10918304
20:40:22 13646 8192000000 10254008368 10672865280 1348445760 14.133 100 59 117 1 1372491424 10990320 13055344
20:41:41 5431 8192000000 10276326144 10688118784 1359135472 14.230 100 60 120 1 1385197088 12526592 13535024
20:42:50 20137 8192000000 10283343616 10701295616 1368615456 14.315 100 44 88 1 1393464992 14913312 9936224
20:44:17 21707 8192000000 10291282896 10712776704 1372532768 14.350 100 51 102 3 1411929584 26944672 12452144
20:45:49 41937 8192000000 10302064784 10728558592 1387294816 14.482 100 48 96 1 1415301440 16629584 11377040
20:47:45 8859 8192000000 10296779024 10721718272 1370792320 14.334 98 47 92 0 1398062608 16733920 10536368
20:49:12 1781 8192000000 10328633232 10743824384 1398645440 14.583 100 69 136 1 1433205760 17307200 17253120
20:50:20 4072 8192000000 10340550304 10754277376 1402669824 14.619 100 79 158 0 1445966512 25009600 18287088
20:51:32 4311 8192000000 10327976368 10733694976 1383375184 14.447 99 77 154 1 1428809600 26475072 18959344
20:52:41 8834 8192000000 10362236896 10778976256 1407789856 14.664 99 68 136 1 1457680064 32963952 16926256
20:53:58 1637 8192000000 10366863920 10774351872 1409566432 14.680 100 81 161 0 1469590448 39006848 21017168
20:55:05 1879 8192000000 10354610688 10762219520 1395062160 14.551 100 86 172 2 1455655088 39010288 21582640
20:56:13 3738 8192000000 10340788192 10741809152 1360171776 14.239 98 82 163 3 1419526624 38706784 20648064
20:57:23 4348 8192000000 10350943904 10759876608 1394657632 14.547 100 83 166 0 1450320944 34547728 21115584
20:58:31 15776 8192000000 10330669760 10758500352 1380464320 14.421 99 36 71 1 1434340896 44185920 9690656
20:59:55 3051 8192000000 10318441328 10734084096 1359372528 14.232 99 50 100 0 1414582656 42057456 13152672
21:01:00 8305 8192000000 10341888816 10765017088 1397633904 14.574 100 47 93 2 1447107200 36742272 12731024
21:02:16 8796 8192000000 10347962352 10769268736 1380774176 14.423 99 42 84 0 1441139392 48813808 11551408
21:03:37 15862 8192000000 10333130896 10769092608 1393061408 14.533 100 29 57 1 1437050096 36251360 7737328
21:05:00 10708 8192000000 10328196352 10763579392 1390712576 14.512 100 27 52 0 1431892928 33870256 7310096
21:06:12 25548 8192000000 10301336144 10745016320 1341457008 14.071 97 19 38 1 1386492576 39200176 5835392
21:07:41 7758 8192000000 10275727072 10707529728 1317680528 13.856 98 31 63 0 1359891584 34379200 7831856
21:08:54 72539 8192000000 10270658832 10712657920 1329352224 13.961 98 26 52 0 1356636416 21602640 5681552
21:11:19 37960 8192000000 10276423456 10716663808 1348843952 14.137 99 23 46 1 1368530160 14710896 4975312
21:12:58 38788 8192000000 10267295024 10731130880 1349862416 14.146 99 12 24 0 1370130864 17557968 2710480
21:14:38 29457 8192000000 10279513056 10721370112 1370723696 14.334 100 26 53 0 1384193264 7336208 6133360
21:16:14 51993 8192000000 10288599584 10721595392 1361897808 14.254 99 44 86 2 1382846080 10739568 10208704
21:18:22 72298 8192000000 10277073424 10720505856 1352431840 14.169 98 13 26 0 1363079280 7834416 2813024
21:20:41 84280 8192000000 10289253024 10734956544 1385746832 14.468 100 25 50 0 1403400864 11251808 6402224
21:23:07 105463 8192000000 10305941536 10757799936 1381958096 14.434 99 34 68 0 1407922720 18008480 7956144
21:25:55 50368 8192000000 10302056032 10768510976 1384067904 14.453 99 16 32 1 1404792816 16917792 3807120
21:27:46 34183 8192000000 10330591408 10769584128 1404659216 14.636 100 32 65 0 1425806576 13974000 7173360
21:29:26 82492 8192000000 10272224496 10742607872 1343077824 14.085 96 23 47 1 1369642432 20537824 6026784
21:31:48 59826 8192000000 10269333680 10736971776 1353868304 14.182 98 7 14 0 1370554480 14950016 1736160
21:33:49 50148 8192000000 10261863296 10717933568 1330698496 13.973 97 13 26 0 1341409792 7952176 2759120
21:36:00 57663 8192000000 10286124240 10734727168 1336324384 14.024 97 24 48 0 1359366720 16725360 6316976
21:37:58 76152 8192000000 10284208256 10745499648 1335900160 14.020 97 11 22 0 1350437760 12310032 2227568
21:40:26 98915 8192000000 10278289648 10732273664 1339561728 14.053 97 15 30 0 1352357568 9429136 3366704
21:43:16 98392 8192000000 10278018080 10723450880 1289252384 13.597 93 14 28 0 1307857424 15503408 3101632
21:45:59 70803 8192000000 10256705392 10700607488 1279664320 13.510 94 17 34 0 1295832704 12460928 3707456
21:48:18 117784 8192000000 10280850784 10725310464 1363429680 14.268 100 23 46 0 1383037536 13872080 5735776
21:51:17 19634 8192000000 10279959760 10723643392 1356264544 14.204 99 32 62 2 1378850960 16241168 6345248
21:52:38 58310 8192000000 10246266928 10702823424 1327908400 13.948 98 22 44 1 1346252640 13356640 4987600
21:54:37 64670 8192000000 10234348176 10670866432 1294464736 13.645 95 31 63 1 1310551728 8923568 7163424
21:56:45 74374 8192000000 10260334640 10711228416 1359496912 14.233 100 12 24 0 1374001920 11520528 2984480
21:59:00 81539 8192000000 10238579408 10688229376 1336011872 14.021 98 13 26 1 1352993232 13639504 3341856
22:01:37 65192 8192000000 10248422640 10701238272 1352234432 14.168 99 11 22 0 1365826848 10756464 2835952
22:04:00 84362 8192000000 10277599840 10732371968 1354256128 14.186 99 13 26 0 1370052368 12959536 2836704
22:06:27 36965 8192000000 10261496832 10710364160 1341878960 14.074 98 17 32 1 1360162704 14779920 3503824
22:08:05 30903 8192000000 10277767984 10714025984 1354475104 14.188 99 29 58 0 1369948064 9157152 6315808
22:09:39 18036 8192000000 10270234880 10707832832 1346487584 14.116 98 29 58 2 1360672544 7960480 6224480
22:11:01 60054 8192000000 10301227072 10752479232 1381620864 14.431 100 28 56 1 1405061968 17402064 6039040
22:13:01 144226 8192000000 10299568768 10753679360 1350278464 14.150 97 13 26 1 1369763184 16662000 2822720
22:16:29 38296 8192000000 10313972432 10757484544 1379320672 14.410 99 36 72 0 1401415168 13901104 8193392
22:18:43 126029 8192000000 10292329600 10741575680 1373615104 14.359 99 35 70 0 1392681264 11484000 7582160
22:21:56 18121 8192000000 10290967296 10726350848 1363594736 14.270 99 40 79 1 1384176368 10924256 9657376
22:23:14 85261 8192000000 10279795840 10743250944 1369815696 14.325 99 18 36 1 1393330432 19223664 4291072
22:25:41 90998 8192000000 10271747296 10736390144 1341646016 14.072 97 10 20 0 1362340752 18118688 2576048
22:28:15 47488 8192000000 10255143776 10701385728 1319718256 13.874 97 22 45 0 1332936960 7696928 5521776
22:30:09 35084 8192000000 10280309392 10712354816 1335864864 14.020 98 34 68 0 1356761712 13645968 7250880
22:31:47 31044 8192000000 10279430640 10720174080 1369637712 14.324 100 21 42 1 1382788304 8766880 4383712
22:33:33 32858 8192000000 10279322352 10723725312 1362593680 14.261 99 27 54 1 1376974080 7970224 6410176
22:35:09 37162 8192000000 10270825648 10705911808 1338648976 14.045 98 29 58 0 1354029008 8061296 7318736
22:36:48 29678 8192000000 10269865232 10692837376 1320971696 13.886 98 43 86 1 1342577616 12310384 9295536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-06-2022 22:39:16 to 14-06-2022 22:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 176 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-06-2022 22:39:32 to 14-06-2022 22:39:34.

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          242664
MON$OLDEST_ACTIVE               246404
MON$OLDEST_SNAPSHOT             246404
MON$NEXT_TRANSACTION            246404
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                       2039968
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:596: 
:597: mscs-test-161.budzdorov.ru    Tue Jun 14 22:38:20 2022
:598:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:599: 
:600: 
:601: mscs-test-161.budzdorov.ru    Tue Jun 14 22:38:21 2022
:602:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:603: 
:604: 
:605: mscs-test-161.budzdorov.ru    Tue Jun 14 22:38:21 2022
:606:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:607: 
--- end of diff output ---

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

14.06.22 22:39:35. 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-06-14 22:39:38 No severe PSQL-related problems occured
14.06.22 22:39:39. End of report.