Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.08.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 16250340 kB
MemAvailable 31012892 kB
Buffers 0 kB
Cached 15496720 kB
SwapCached 2512 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-08-03 01:38:28
DTS_END                         2022-08-03 01:38:28.2990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44286
TRN_ID                          235099
ATT_ID                          697
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: 9099.41 22821 1091929
customer order: creation 166.65 366 19998
customer order: refuse 14.18 397 1701
order to supplier: creation 43.82 1131 5258
order to supplier: removal 3.48 116903 418
invoice (draft): creation 43.94 3147 5273
invoice (draft): removal 53.28 774 6394
invoice accept: apply 40.15 17676 4818
invoice accept: cancel 47.83 749 5739
customer reserve: creation 3767.68 172 452122
customer reserve: removal 4627.11 70 555253
realization accept: apply 79.90 174 9588
realization accept: cancel 87.42 118 10490
payment from customer: creation 56.74 108 6809
payment from customer: removal 5.14 76 617
payment to supplier: creation 47.05 126 5646
payment to supplier: removal 3.38 90 406
service: total inventory turnovers 4.51 1227 541
service: total monetary turnovers 6.20 49 744
service: aggregate perf. data 0.92 17667 110
service: refresh index statistics 0.03 295404 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-08-2022 01:39:55 to 03-08-2022 01:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 971
TEST_TIME 2 3141
TEST_TIME 3 4044
TEST_TIME 4 4482
TEST_TIME 5 4642
TEST_TIME 6 4662
TEST_TIME 7 4507
TEST_TIME 8 4487
TEST_TIME 9 4616
TEST_TIME 10 4544
TEST_TIME 11 4872
TEST_TIME 12 5442
TEST_TIME 13 5701
TEST_TIME 14 5915
TEST_TIME 15 6184
TEST_TIME 16 6730
TEST_TIME 17 7050
TEST_TIME 18 7093
TEST_TIME 19 7662
TEST_TIME 20 7858
TEST_TIME 21 8118
TEST_TIME 22 8600
TEST_TIME 23 9045
TEST_TIME 24 9829
TEST_TIME 25 10855
TEST_TIME 26 11193
TEST_TIME 27 10878
TEST_TIME 28 10711
TEST_TIME 29 10511
TEST_TIME 30 10290
TEST_TIME 31 10057
TEST_TIME 32 9909
TEST_TIME 33 9993
TEST_TIME 34 9798
TEST_TIME 35 9593
TEST_TIME 36 9721
TEST_TIME 37 9584
TEST_TIME 38 9452
TEST_TIME 39 9515
TEST_TIME 40 9354
TEST_TIME 41 9204
TEST_TIME 42 9221
TEST_TIME 43 9301
TEST_TIME 44 9227
TEST_TIME 45 9241
TEST_TIME 46 9148
TEST_TIME 47 9252
TEST_TIME 48 9316
TEST_TIME 49 9391
TEST_TIME 50 9366
TEST_TIME 51 9409
TEST_TIME 52 9606
TEST_TIME 53 9617
TEST_TIME 54 9567
TEST_TIME 55 9614
TEST_TIME 56 9597
TEST_TIME 57 9495
TEST_TIME 58 9384
TEST_TIME 59 9418
TEST_TIME 60 9384
TEST_TIME 61 9395
TEST_TIME 62 9317
TEST_TIME 63 9308
TEST_TIME 64 9293
TEST_TIME 65 9267
TEST_TIME 66 9198
TEST_TIME 67 9213
TEST_TIME 68 9429
TEST_TIME 69 9409
TEST_TIME 70 9415
TEST_TIME 71 9522
TEST_TIME 72 9490
TEST_TIME 73 9463
TEST_TIME 74 9499
TEST_TIME 75 9468
TEST_TIME 76 9534
TEST_TIME 77 9497
TEST_TIME 78 9499
TEST_TIME 79 9428
TEST_TIME 80 9449
TEST_TIME 81 9422
TEST_TIME 82 9381
TEST_TIME 83 9328
TEST_TIME 84 9366
TEST_TIME 85 9373
TEST_TIME 86 9349
TEST_TIME 87 9299
TEST_TIME 88 9246
TEST_TIME 89 9151
TEST_TIME 90 9274
TEST_TIME 91 9239
TEST_TIME 92 9239
TEST_TIME 93 9226
TEST_TIME 94 9195
TEST_TIME 95 9244
TEST_TIME 96 9323
TEST_TIME 97 9309
TEST_TIME 98 9312
TEST_TIME 99 9359
TEST_TIME 100 9391
TEST_TIME 101 9374
TEST_TIME 102 9356
TEST_TIME 103 9345
TEST_TIME 104 9417
TEST_TIME 105 9383
TEST_TIME 106 9311
TEST_TIME 107 9285
TEST_TIME 108 9285
TEST_TIME 109 9284
TEST_TIME 110 9244
TEST_TIME 111 9198
TEST_TIME 112 9204
TEST_TIME 113 9161
TEST_TIME 114 9138
TEST_TIME 115 9147
TEST_TIME 116 9129
TEST_TIME 117 9110
TEST_TIME 118 9115
TEST_TIME 119 9123
TEST_TIME 120 9106

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-08-2022 01:39:58 to 03-08-2022 01:40:02.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1510074 1510074 0 0.00 0 38090 6 0 0
doc_list_biud 1510073 1510073 0 0.00 0 28484 32 0 0
sp_add_invoice_to_stock 4992 4818 174 3.48 39 225440 17677 0 174
sp_cancel_adding_invoice 5739 5739 0 0.00 4 66363 749 0 0
sp_cancel_client_order 1701 1701 0 0.00 3 12386 397 0 0
sp_cancel_customer_reserve 555253 555253 0 0.00 0 23277 71 0 0
sp_cancel_pay_from_customer 617 617 0 0.00 2 4784 76 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 3722 91 0 0
sp_cancel_supplier_invoice 6402 6394 8 0.12 1 27852 775 0 8
sp_cancel_supplier_order 418 418 0 0.00 10 1256657 116903 0 0
sp_cancel_write_off 10490 10490 0 0.00 1 10154 119 0 0
sp_client_order 19998 19998 0 0.00 3 32188 367 0 0
sp_customer_reserve 452122 452122 0 0.00 0 109658 173 0 0
sp_fill_shopping_cart 30515 30515 0 0.00 1 17612 36 0 0
sp_fill_shopping_cart_clo_res 452121 452121 0 0.00 0 28028 14 0 0
sp_get_clo_for_invoice 4809 4809 0 0.00 0 26207 350 0 0
sp_kill_cost_storno 17252 17252 0 0.00 0 20294 58 0 0
sp_kill_qstorno_ret_qs2qd 1127808 1127808 0 0.00 0 27833 38 0 0
sp_kill_qty_storno 594391 594391 0 0.00 0 28483 79 0 0
sp_lock_selected_doc 19983 19983 0 0.00 0 9359 8 0 0
sp_make_cost_storno 26851 26851 0 0.00 0 38088 54 0 0
sp_multiply_rows_for_pdistr 26851 26851 0 0.00 0 22935 25 0 0
sp_multiply_rows_for_qdistr 19998 19998 0 0.00 0 22951 165 0 0
sp_pay_from_customer 6809 6809 0 0.00 4 22954 109 0 0
sp_pay_to_supplier 5646 5646 0 0.00 4 38095 126 0 0
sp_payment_common 12455 12455 0 0.00 1 14119 33 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 12376 361 0 0
sp_qd_handle_on_invoice_upd_sts 10547 10547 0 0.00 0 28483 313 0 0
sp_qd_handle_on_reserve_upd_sts 9588 9588 0 0.00 0 11094 52 0 0
sp_reserve_write_off 9588 9588 0 0.00 6 20484 175 0 0
sp_supplier_invoice 5273 5273 0 0.00 5 118753 3148 0 0
sp_supplier_order 5258 5258 0 0.00 4 54265 1131 0 0
srv_aggregate_perf_data 110 110 0 0.00 1919 168774 17667 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1670 132685 52288 0 0
srv_find_qd_qs_mism 1045500 1045500 0 0.00 0 20920 5 0 0
srv_make_invnt_saldo 541 541 0 0.00 49 127166 1227 0 0
srv_make_money_saldo 744 744 0 0.00 1 13970 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 186481 430785 295404 0 0
t$perf-abend:sp_add_invoice_to_stock 174 174 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4818 4818 0 0.00 0 23283 161 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 5065 80 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 6314 18 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 1047 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 617 617 0 0.00 0 3589 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 1583 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 2617 23 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 53358 1655 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 5358 11 0 0
t$perf-norm:sp_client_order 19998 19998 0 0.00 0 9488 18 0 0
t$perf-norm:sp_customer_reserve 900 900 0 0.00 0 3426 10 0 0
t$perf-norm:sp_pay_from_customer 6809 6809 0 0.00 0 10788 16 0 0
t$perf-norm:sp_pay_to_supplier 5646 5646 0 0.00 0 5356 11 0 0
t$perf-norm:sp_reserve_write_off 9588 9588 0 0.00 0 11029 18 0 0
t$perf-norm:sp_supplier_invoice 5273 5273 0 0.00 0 6499 17 0 0
t$perf-norm:sp_supplier_order 5258 5258 0 0.00 0 10695 17 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 451 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 8 0 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 759 17 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 4386 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9964 9616 348 3.49 0 8315 19 0 348
v_all_customers 36974 36974 0 0.00 0 35 0 0 0
v_all_suppliers 29614 29614 0 0.00 0 41 1 0 0
v_all_wares 39988 39988 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 1879 10 0 0
v_cancel_client_order 3402 3402 0 0.00 0 2082 6 0 0
v_cancel_customer_prepayment 1234 1234 0 0.00 0 4350 15 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 1663 7 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 3697 15 0 0
v_cancel_supplier_invoice 836 820 16 1.91 0 1457 16 0 16
v_cancel_supplier_order 836 836 0 0.00 0 5059 16 0 0
v_cancel_write_off 2010 2010 0 0.00 0 4795 18 0 0
v_max_id_clo_ord 5257 5257 0 0.00 0 157 1 0 0
v_max_id_clo_res 900 900 0 0.00 0 1800 10 0 0
v_max_id_ord_sup 5264 5264 0 0.00 0 6162 4 0 0
v_max_non_paid_invoice 1359 1359 0 0.00 0 6572 8 0 0
v_max_non_paid_realizn 3328 3328 0 0.00 0 3448 6 0 0
v_min_id_clo_ord 5257 5257 0 0.00 0 1284 4 0 0
v_min_id_clo_res 900 900 0 0.00 0 4230 15 0 0
v_min_id_ord_sup 5264 5264 0 0.00 0 14853 10 0 0
v_min_non_paid_invoice 5646 5646 0 0.00 0 14114 20 0 0
v_min_non_paid_realizn 6809 6809 0 0.00 0 13380 25 0 0
v_our_firm 9976 9976 0 0.00 0 20 1 0 0
v_reserve_write_off 19176 19176 0 0.00 0 5057 6 0 0
x_lock_depdocs_on_canc_invoice 11723 11723 0 0.00 0 15163 122 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 28012 408 0 0
x_make_qstorno_1000_1200 5258 5258 0 0.00 1 54256 1074 0 0
x_make_qstorno_1200_2000 5273 5273 0 0.00 2 112762 3000 0 0
x_make_qstorno_2100_3300 452122 452122 0 0.00 0 109657 156 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 03-08-2022 01:40:02 to 03-08-2022 01:40:07.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:32 6 8192000000 8798879696 8810168320 5307904 0.064 2 1 2 0 5407504 56816 42784
23:09:33 21313 8192000000 9902566864 10212298752 984586080 10.729 100 92 184 0 1015223216 6442512 24194624
23:11:11 15343 8192000000 9945479456 10266820608 1026800720 11.138 100 87 174 0 1058865088 9335920 22728448
23:12:29 15902 8192000000 9980799280 10313646080 1063215680 11.487 100 76 152 1 1091733488 9422624 19095184
23:13:53 12521 8192000000 10029960880 10380316672 1122686064 12.052 100 79 156 0 1151994624 10651152 18657408
23:15:07 4279 8192000000 10075344768 10434883584 1170477376 12.501 100 82 164 0 1201020768 11780304 18763088
23:16:16 3272 8192000000 10126239856 10493624320 1215805664 12.923 100 80 156 2 1245369920 11644448 17919808
23:17:22 1376 8192000000 10163799152 10540535808 1253374512 13.269 100 76 151 2 1282566736 12900112 16292112
23:18:25 1606 8192000000 10195194784 10581094400 1284641840 13.555 100 83 166 0 1315619216 12375072 18602304
23:19:28 770 8192000000 10214285696 10599354368 1297499568 13.673 100 93 183 0 1331681696 13532928 20649200
23:20:30 14799 8192000000 10209172912 10609807360 1305019280 13.741 100 59 113 1 1325614400 8364608 12230512
23:21:45 6407 8192000000 10201790592 10615906304 1315220224 13.833 100 34 66 1 1329756048 6860656 7675168
23:22:55 12261 8192000000 10208228736 10624626688 1321702544 13.892 100 12 24 0 1330672944 5661504 3308896
23:24:09 69076 8192000000 10220114352 10642259968 1330102496 13.968 100 34 66 0 1342976784 5355584 7518704
23:26:23 5630 8192000000 10230019168 10652557312 1339507872 14.053 100 17 35 0 1348250976 5215472 3527632
23:27:30 4332 8192000000 10221092688 10645630976 1326343872 13.934 99 33 65 0 1340915920 6675760 7896288
23:28:37 8504 8192000000 10180236176 10597064704 1268010928 13.403 97 14 28 0 1276598224 5357840 3229456
23:29:49 16948 8192000000 10163445456 10570436608 1222976976 12.989 95 16 32 2 1231381328 4996032 3408320
23:31:08 3681 8192000000 10168872032 10571423744 1272493344 13.444 100 43 86 0 1289225968 6847184 9885440
23:32:12 3770 8192000000 10148358112 10541023232 1238815584 13.135 98 44 88 0 1256016128 6934368 10266176
23:33:17 28734 8192000000 10156600928 10570706944 1274614000 13.464 100 22 44 0 1285371072 5287392 5469680
23:35:01 4972 8192000000 10199889168 10603094016 1295370752 13.653 100 43 87 3 1312326896 7300912 9655232
23:36:07 5685 8192000000 10200516656 10611781632 1305482224 13.745 100 24 48 0 1317324496 6349680 5492592
23:37:17 6961 8192000000 10200481008 10616492032 1311520384 13.800 100 18 36 0 1321261200 5501904 4238912
23:38:24 132685 8192000000 10253717888 10671923200 1338087664 14.040 100 41 81 0 1358302752 11222528 8992560
23:41:40 39476 8192000000 10298666576 10721681408 1383253936 14.446 100 43 86 1 1411101600 17663088 10184576
23:43:22 35771 8192000000 10312145840 10740715520 1397045888 14.569 100 46 90 0 1428781440 19632448 12103104
23:45:03 12072 8192000000 10327245328 10754220032 1409893040 14.683 100 36 72 0 1439378832 20520112 8965680
23:46:21 13014 8192000000 10336514240 10765565952 1418268176 14.757 100 42 82 1 1450881104 22259376 10353552
23:47:43 11296 8192000000 10354479120 10786304000 1429108656 14.853 100 45 90 0 1471793728 30440784 12244288
23:49:00 9976 8192000000 10366783360 10789257216 1423486480 14.804 100 62 122 1 1474775936 35849952 15439504
23:50:18 9520 8192000000 10369539072 10794442752 1424204144 14.810 100 57 114 0 1482150960 42387648 15559168
23:51:33 3430 8192000000 10381985216 10801123328 1425902272 14.825 99 67 134 1 1487866848 44655472 17309104
23:52:44 1726 8192000000 10384734896 10801713152 1426621424 14.831 100 84 168 2 1495824288 47764336 21438528
23:53:56 2886 8192000000 10396402288 10808520704 1422618768 14.796 100 82 163 0 1501651632 57808400 21224464
23:55:03 1664 8192000000 10399412464 10811494400 1412946688 14.710 99 80 161 0 1489003808 55031808 21025312
23:56:08 4081 8192000000 10402121600 10822922240 1429285472 14.855 100 64 127 1 1506500144 60547328 16667344
23:57:17 8214 8192000000 10391751904 10814414848 1417857264 14.754 100 53 106 0 1498779008 65827120 15094624
23:58:29 12425 8192000000 10375757024 10806804480 1412834768 14.709 100 41 82 1 1485448240 60502608 12110864
23:59:45 4480 8192000000 10393531824 10823114752 1412137360 14.703 100 55 110 1 1499750432 72207520 15405552
00:00:50 19842 8192000000 10364367936 10808012800 1388485808 14.492 99 31 62 0 1465013152 66881104 9646240
00:02:32 31708 8192000000 10295724464 10748715008 1305996192 13.750 96 15 30 0 1369441424 58850976 4594256
00:04:06 100571 8192000000 10250809696 10681249792 1273089520 13.450 94 22 46 2 1290859520 12271104 5498896
00:06:47 69035 8192000000 10242782096 10688491520 1300139360 13.697 97 23 45 0 1311598656 6995200 4464096
00:08:57 122729 8192000000 10278393616 10729406464 1368306688 14.312 100 14 29 0 1383729744 11696112 3726944
00:12:00 39957 8192000000 10264049584 10706219008 1346269712 14.114 98 13 24 1 1361550704 12806160 2474832
00:13:47 51347 8192000000 10272831856 10725408768 1329676416 13.964 97 30 60 0 1355487264 18488512 7322336
00:15:40 12641 8192000000 10263830896 10718707712 1365619168 14.288 100 17 34 1 1378946752 9551744 3775840
00:17:10 43493 8192000000 10282702032 10744545280 1380360384 14.420 100 21 43 2 1397480656 11581760 5538512
00:18:56 62327 8192000000 10289817968 10740727808 1358319568 14.222 98 29 58 1 1380639520 15307520 7012432
00:21:06 61607 8192000000 10282056944 10749882368 1375444368 14.376 99 16 32 0 1391093104 11448160 4200576
00:23:15 71485 8192000000 10300212368 10754088960 1361561088 14.251 98 24 47 1 1378840544 11901424 5378032
00:25:26 97443 8192000000 10296012000 10746142720 1352734064 14.172 97 19 38 0 1368252384 11220336 4297984
00:28:12 74798 8192000000 10312929040 10768400384 1390031504 14.506 100 15 28 0 1410637408 17243184 3362720
00:30:31 87300 8192000000 10308399888 10774155264 1402835488 14.620 100 7 14 0 1417713856 13475776 1402592
00:32:59 96242 8192000000 10317986896 10777788416 1389623904 14.503 98 25 51 1 1408677232 13666464 5386864
00:35:35 57479 8192000000 10323688800 10762948608 1405178336 14.641 100 35 70 0 1422446208 9392768 7875104
00:37:37 56893 8192000000 10303886800 10768056320 1389111808 14.498 99 25 50 0 1408907392 14552576 5243008
00:39:35 51508 8192000000 10287151936 10749808640 1380035024 14.417 99 8 16 0 1393104928 11709440 1360464
00:41:29 53641 8192000000 10277474784 10735546368 1377204832 14.392 99 10 20 0 1386216944 7084400 1927712
00:43:24 52845 8192000000 10311912912 10769448960 1391836512 14.522 99 22 44 0 1409993808 12946944 5210352
00:45:17 52470 8192000000 10300278256 10768867328 1387644816 14.485 99 23 46 0 1407168592 13969504 5554272
00:47:17 64845 8192000000 10300257824 10751590400 1333757568 14.001 95 17 34 0 1349474336 12220704 3496064
00:49:45 58367 8192000000 10305290240 10752847872 1376845872 14.388 99 39 79 0 1398109504 12586720 8676912
00:51:49 25153 8192000000 10306164544 10745659392 1364167648 14.275 98 46 92 0 1385517872 11480560 9869664
00:53:14 35043 8192000000 10283856464 10722304000 1328161568 13.951 96 34 68 0 1347776496 12715008 6899920
00:54:51 40806 8192000000 10260137536 10691956736 1281772784 13.529 95 19 38 2 1298480080 13073456 3633840
00:56:33 103498 8192000000 10253862112 10701852672 1349850432 14.146 99 17 33 1 1365033424 11432448 3750544
00:59:18 57513 8192000000 10251921456 10704707584 1341252320 14.069 99 21 42 0 1355948160 10462384 4233456
01:01:16 104096 8192000000 10262529712 10731397120 1345814304 14.110 98 9 18 0 1364265088 16050432 2400352
01:04:17 37582 8192000000 10257206608 10711867392 1356173152 14.203 99 18 36 0 1370819408 10691344 3954912
01:05:55 107236 8192000000 10280398192 10732023808 1348640608 14.135 98 20 40 0 1370033680 16794176 4598896
01:09:22 79682 8192000000 10255756976 10711691264 1308382928 13.771 95 8 16 0 1327484816 17565376 1536512
01:11:43 59355 8192000000 10296384832 10748289024 1371971680 14.345 99 24 48 0 1388136928 10457056 5708192
01:13:43 83163 8192000000 10292016864 10756431872 1363446816 14.268 98 19 38 0 1388283728 20226288 4610624
01:16:07 99336 8192000000 10308832864 10769752064 1382434528 14.438 99 20 40 1 1401090240 14444640 4211072
01:18:46 78067 8192000000 10299586656 10756497408 1344499808 14.098 96 20 40 0 1359609808 10485632 4624368
01:21:28 15435 8192000000 10313428528 10750554112 1383618192 14.449 100 43 86 0 1404553744 11782192 9153360
01:22:45 85580 8192000000 10267273984 10716078080 1314541552 13.827 95 9 18 0 1328211136 11938096 1731488
01:25:48 70284 8192000000 10286588608 10748719104 1380232544 14.419 100 22 43 1 1397017760 12298576 4486640
01:28:04 67842 8192000000 10276186272 10742059008 1382927600 14.443 100 5 10 0 1392012176 8093872 990704
01:30:12 61552 8192000000 10275686064 10737639424 1362919952 14.264 99 9 18 0 1373930544 8847248 2163344
01:32:22 58591 8192000000 10265133696 10725048320 1329433232 13.962 96 10 20 0 1340470944 9292560 1745152
01:34:47 51064 8192000000 10268172704 10715537408 1314283840 13.825 96 20 38 0 1326586576 7974256 4328480
01:37:11 75343 8192000000 10248581088 10700722176 1305462912 13.745 96 16 32 0 1329146592 19891680 3792000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-08-2022 01:40:08 to 03-08-2022 01:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 174 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-08-2022 01:40:24 to 03-08-2022 01:40:26.

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          230910
MON$OLDEST_ACTIVE               235361
MON$OLDEST_SNAPSHOT             235361
MON$NEXT_TRANSACTION            235361
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                       2002176
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.33614 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33614 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33614 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33614 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Wed Aug  3 01:38:46 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Wed Aug  3 01:38:46 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Wed Aug  3 01:38:46 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
--- end of diff output ---

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

03.08.22 01:40:27. 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-08-03 01:40:29 No severe PSQL-related problems occured
03.08.22 01:40:31. End of report.