Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.03.2022 16: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 16859048 kB
MemAvailable 31580880 kB
Buffers 0 kB
Cached 14832100 kB
SwapCached 2192 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-03-29 16:38:16
DTS_END                         2022-03-29 16:38:16.7860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46726
TRN_ID                          238885
ATT_ID                          684
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: 9121.91 21439 1094629
customer order: creation 173.09 348 20771
customer order: refuse 15.53 340 1864
order to supplier: creation 45.24 1282 5429
order to supplier: removal 3.48 102566 418
invoice (draft): creation 45.08 3315 5410
invoice (draft): removal 50.06 796 6007
invoice accept: apply 42.05 20555 5046
invoice accept: cancel 45.69 877 5483
customer reserve: creation 3895.38 204 467445
customer reserve: removal 4507.74 63 540929
realization accept: apply 84.25 193 10110
realization accept: cancel 84.97 111 10196
payment from customer: creation 59.40 136 7128
payment from customer: removal 5.75 77 690
payment to supplier: creation 48.51 140 5821
payment to supplier: removal 3.38 66 405
service: total inventory turnovers 4.80 1148 576
service: total monetary turnovers 6.59 26 791
service: aggregate perf. data 0.88 19813 106
service: refresh index statistics 0.03 276719 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-03-2022 16:39:33 to 29-03-2022 16:39:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1784
TEST_TIME 2 1445
TEST_TIME 3 3307
TEST_TIME 4 4570
TEST_TIME 5 4809
TEST_TIME 6 5228
TEST_TIME 7 5353
TEST_TIME 8 5316
TEST_TIME 9 5444
TEST_TIME 10 5647
TEST_TIME 11 5809
TEST_TIME 12 5978
TEST_TIME 13 6222
TEST_TIME 14 6416
TEST_TIME 15 6861
TEST_TIME 16 6996
TEST_TIME 17 7368
TEST_TIME 18 7181
TEST_TIME 19 7460
TEST_TIME 20 8152
TEST_TIME 21 8412
TEST_TIME 22 8994
TEST_TIME 23 9195
TEST_TIME 24 9224
TEST_TIME 25 9459
TEST_TIME 26 10278
TEST_TIME 27 10922
TEST_TIME 28 10758
TEST_TIME 29 10398
TEST_TIME 30 10101
TEST_TIME 31 10085
TEST_TIME 32 10059
TEST_TIME 33 10038
TEST_TIME 34 9942
TEST_TIME 35 9770
TEST_TIME 36 9678
TEST_TIME 37 9641
TEST_TIME 38 9692
TEST_TIME 39 9643
TEST_TIME 40 9484
TEST_TIME 41 9523
TEST_TIME 42 9441
TEST_TIME 43 9331
TEST_TIME 44 9417
TEST_TIME 45 9449
TEST_TIME 46 9295
TEST_TIME 47 9354
TEST_TIME 48 9276
TEST_TIME 49 9312
TEST_TIME 50 9342
TEST_TIME 51 9360
TEST_TIME 52 9422
TEST_TIME 53 9377
TEST_TIME 54 9446
TEST_TIME 55 9443
TEST_TIME 56 9464
TEST_TIME 57 9582
TEST_TIME 58 9616
TEST_TIME 59 9616
TEST_TIME 60 9549
TEST_TIME 61 9427
TEST_TIME 62 9417
TEST_TIME 63 9316
TEST_TIME 64 9263
TEST_TIME 65 9277
TEST_TIME 66 9238
TEST_TIME 67 9238
TEST_TIME 68 9206
TEST_TIME 69 9159
TEST_TIME 70 9071
TEST_TIME 71 9111
TEST_TIME 72 9218
TEST_TIME 73 9233
TEST_TIME 74 9222
TEST_TIME 75 9253
TEST_TIME 76 9274
TEST_TIME 77 9290
TEST_TIME 78 9280
TEST_TIME 79 9305
TEST_TIME 80 9414
TEST_TIME 81 9428
TEST_TIME 82 9362
TEST_TIME 83 9445
TEST_TIME 84 9389
TEST_TIME 85 9309
TEST_TIME 86 9303
TEST_TIME 87 9226
TEST_TIME 88 9230
TEST_TIME 89 9183
TEST_TIME 90 9186
TEST_TIME 91 9239
TEST_TIME 92 9178
TEST_TIME 93 9162
TEST_TIME 94 9126
TEST_TIME 95 9203
TEST_TIME 96 9232
TEST_TIME 97 9220
TEST_TIME 98 9210
TEST_TIME 99 9264
TEST_TIME 100 9268
TEST_TIME 101 9258
TEST_TIME 102 9242
TEST_TIME 103 9229
TEST_TIME 104 9216
TEST_TIME 105 9311
TEST_TIME 106 9324
TEST_TIME 107 9344
TEST_TIME 108 9319
TEST_TIME 109 9252
TEST_TIME 110 9259
TEST_TIME 111 9209
TEST_TIME 112 9237
TEST_TIME 113 9183
TEST_TIME 114 9166
TEST_TIME 115 9183
TEST_TIME 116 9172
TEST_TIME 117 9143
TEST_TIME 118 9182
TEST_TIME 119 9154
TEST_TIME 120 9124

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-03-2022 16:39:36 to 29-03-2022 16:39:40.

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 1526494 1526494 0 0.00 0 14568 7 0 0
doc_list_biud 1526494 1526494 0 0.00 0 57572 28 0 0
sp_add_invoice_to_stock 5180 5046 134 2.58 30 218535 20556 0 134
sp_cancel_adding_invoice 5483 5483 0 0.00 5 72353 877 0 0
sp_cancel_client_order 1864 1864 0 0.00 4 8739 341 0 0
sp_cancel_customer_reserve 540929 540929 0 0.00 1 57574 64 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 3 2737 77 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 2 2812 66 0 0
sp_cancel_supplier_invoice 6019 6007 12 0.19 1 39502 796 0 12
sp_cancel_supplier_order 418 418 0 0.00 6 1137339 102566 0 0
sp_cancel_write_off 10196 10196 0 0.00 1 18204 111 0 0
sp_client_order 20771 20771 0 0.00 3 49405 348 0 0
sp_customer_reserve 467445 467445 0 0.00 0 46704 204 0 0
sp_fill_shopping_cart 31601 31601 0 0.00 1 15194 37 0 0
sp_fill_shopping_cart_clo_res 467445 467445 0 0.00 0 17976 14 0 0
sp_get_clo_for_invoice 5043 5043 0 0.00 0 38161 309 0 0
sp_kill_cost_storno 16774 16774 0 0.00 0 9753 56 0 0
sp_kill_qstorno_ret_qs2qd 1098479 1098479 0 0.00 0 57570 34 0 0
sp_kill_qty_storno 580048 580048 0 0.00 0 57572 74 0 0
sp_lock_selected_doc 21012 21012 0 0.00 0 10957 7 0 0
sp_make_cost_storno 28100 28100 0 0.00 0 13995 57 0 0
sp_multiply_rows_for_pdistr 28100 28100 0 0.00 0 12342 30 0 0
sp_multiply_rows_for_qdistr 20771 20771 0 0.00 0 19371 150 0 0
sp_pay_from_customer 7128 7128 0 0.00 4 19178 137 0 0
sp_pay_to_supplier 5821 5821 0 0.00 5 16826 141 0 0
sp_payment_common 12949 12949 0 0.00 1 13604 36 0 0
sp_qd_handle_on_cancel_clo 1864 1864 0 0.00 0 8733 316 0 0
sp_qd_handle_on_invoice_upd_sts 10524 10524 0 0.00 0 42994 326 0 0
sp_qd_handle_on_reserve_upd_sts 10110 10110 0 0.00 0 39581 71 0 0
sp_reserve_write_off 10110 10110 0 0.00 6 39760 194 0 0
sp_supplier_invoice 5410 5410 0 0.00 6 114662 3316 0 0
sp_supplier_order 5429 5429 0 0.00 5 30866 1283 0 0
srv_aggregate_perf_data 106 106 0 0.00 2584 485468 19814 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1614 138783 49380 0 0
srv_find_qd_qs_mism 1046251 1046251 0 0.00 0 14126 7 0 0
srv_make_invnt_saldo 576 576 0 0.00 33 55115 1148 0 0
srv_make_money_saldo 791 791 0 0.00 0 2284 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 217888 415545 276720 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5046 5046 0 0.00 0 6094 145 0 0
t$perf-norm:sp_cancel_adding_invoice 454 454 0 0.00 0 10346 92 0 0
t$perf-norm:sp_cancel_client_order 1864 1864 0 0.00 0 2873 14 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 1695 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 634 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 3346 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 563 12 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 21806 1461 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 10479 16 0 0
t$perf-norm:sp_client_order 20771 20771 0 0.00 0 4165 14 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 2007 11 0 0
t$perf-norm:sp_pay_from_customer 7128 7128 0 0.00 0 4726 8 0 0
t$perf-norm:sp_pay_to_supplier 5821 5821 0 0.00 0 3401 7 0 0
t$perf-norm:sp_reserve_write_off 10110 10110 0 0.00 0 3033 8 0 0
t$perf-norm:sp_supplier_invoice 5410 5410 0 0.00 0 3937 16 0 0
t$perf-norm:sp_supplier_order 5429 5429 0 0.00 0 5487 16 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 171 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 498 15 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 1704 22 0 0
t$perf-norm:srv_make_money_saldo 791 791 0 0.00 0 2243 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10350 10082 268 2.58 0 6494 18 0 268
v_all_customers 37756 37756 0 0.00 0 16 1 0 0
v_all_suppliers 30068 30068 0 0.00 0 19 1 0 0
v_all_wares 41540 41540 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 908 908 0 0.00 0 917 6 0 0
v_cancel_client_order 3728 3728 0 0.00 0 3642 5 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 2678 7 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 1575 5 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 251 3 0 0
v_cancel_supplier_invoice 772 748 24 3.10 0 3138 21 0 24
v_cancel_supplier_order 836 836 0 0.00 0 2381 7 0 0
v_cancel_write_off 1992 1992 0 0.00 0 3932 14 0 0
v_max_id_clo_ord 5428 5428 0 0.00 0 749 2 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 3525 18 0 0
v_max_id_ord_sup 5402 5402 0 0.00 0 1793 2 0 0
v_max_non_paid_invoice 1617 1617 0 0.00 0 105 2 0 0
v_max_non_paid_realizn 3796 3796 0 0.00 0 5002 6 0 0
v_min_id_clo_ord 5428 5428 0 0.00 0 3309 6 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 4803 12 0 0
v_min_id_ord_sup 5402 5402 0 0.00 0 8436 8 0 0
v_min_non_paid_invoice 5821 5821 0 0.00 0 13600 28 0 0
v_min_non_paid_realizn 7128 7128 0 0.00 0 10733 26 0 0
v_our_firm 10448 10448 0 0.00 0 16 1 0 0
v_reserve_write_off 20220 20220 0 0.00 0 6754 5 0 0
x_lock_depdocs_on_canc_invoice 11116 11116 0 0.00 0 14676 133 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 8536 350 0 0
x_make_qstorno_1000_1200 5429 5429 0 0.00 1 30833 1203 0 0
x_make_qstorno_1200_2000 5410 5410 0 0.00 2 114647 3181 0 0
x_make_qstorno_2100_3300 467445 467445 0 0.00 0 46703 188 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-03-2022 16:39:41 to 29-03-2022 16:39:45.

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
14:08:20 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:20 35865 8192000000 9909652288 10221568000 986730352 10.750 100 89 178 0 1016249184 6218480 23300352
14:11:02 22588 8192000000 9949715568 10273931264 1032144848 11.189 100 81 162 1 1061236848 8460080 20631920
14:12:32 12357 8192000000 9988440720 10326007808 1077031760 11.619 100 77 154 0 1105523360 9859376 18632224
14:13:47 11682 8192000000 10047154064 10394263552 1128339840 12.106 100 82 164 0 1158692736 11110800 19242096
14:15:06 10010 8192000000 10091006848 10455216128 1177891552 12.571 100 76 152 0 1207301024 11785376 17624096
14:16:19 6587 8192000000 10125413568 10496237568 1215589056 12.921 100 78 156 0 1246162704 12765392 17808256
14:17:33 4121 8192000000 10170440688 10550960128 1256531920 13.298 100 74 148 0 1285090352 11924624 16633808
14:18:41 2120 8192000000 10201473504 10581594112 1281006480 13.522 100 90 182 2 1316596672 14698032 20892160
14:19:47 1835 8192000000 10221900032 10605494272 1299468992 13.690 100 96 193 0 1333823392 13087296 21267104
14:20:51 1650 8192000000 10222621552 10612019200 1302174272 13.715 100 87 176 1 1333077632 10888944 20014416
14:21:53 7955 8192000000 10190963152 10610307072 1312104496 13.805 100 25 49 0 1324964896 6783440 6076960
14:23:04 6492 8192000000 10200705296 10618134528 1317636784 13.855 100 30 60 2 1331759552 7079328 7043440
14:24:12 34850 8192000000 10245248016 10647265280 1327909504 13.948 100 69 139 1 1350181952 5510928 16761520
14:25:47 5496 8192000000 10229126496 10646327296 1331132976 13.977 100 30 60 0 1344605824 6551456 6921392
14:26:56 7930 8192000000 10221167968 10642227200 1331489904 13.981 100 30 60 1 1345251280 5998112 7763264
14:28:05 8346 8192000000 10215538480 10636353536 1315466304 13.836 99 22 44 0 1326379088 5466736 5446048
14:29:14 16496 8192000000 10188926752 10600288256 1224540112 13.004 92 42 84 3 1240306416 6634480 9131824
14:30:37 6326 8192000000 10193966960 10605191168 1290364192 13.608 99 31 62 1 1305003584 8072544 6566848
14:31:44 7249 8192000000 10145714448 10541772800 1237469680 13.123 98 30 60 0 1250563216 6447088 6646448
14:32:52 30732 8192000000 10148481552 10560167936 1243045136 13.174 98 31 60 2 1255551312 5721920 6784256
14:34:40 5528 8192000000 10176220032 10579210240 1276182976 13.478 100 19 38 0 1286140864 5474640 4483248
14:35:51 9794 8192000000 10181370640 10588676096 1282957504 13.540 100 32 63 2 1300642688 10101776 7583408
14:37:01 7961 8192000000 10198125472 10610069504 1298309904 13.680 100 19 38 0 1312438640 9908672 4220064
14:38:11 128840 8192000000 10227551184 10644344832 1322956768 13.903 100 32 64 3 1335912240 5105424 7850048
14:41:27 8897 8192000000 10258816864 10670837760 1345537136 14.107 100 48 95 1 1368554640 12175152 10842352
14:42:41 17047 8192000000 10265684512 10690916352 1360767248 14.244 100 43 86 3 1386694896 15911440 10016208
14:44:01 21797 8192000000 10309904768 10723393536 1383267872 14.446 100 67 134 1 1416859632 18284976 15306784
14:45:29 17027 8192000000 10316111856 10742300672 1398418336 14.581 100 49 98 1 1428356528 18934128 11004064
14:46:59 10673 8192000000 10341886160 10762440704 1412215168 14.704 100 63 126 1 1452923952 25911952 14796832
14:48:18 7287 8192000000 10332830752 10756915200 1392871904 14.531 98 54 106 2 1435707936 30265744 12570288
14:49:30 3619 8192000000 10358540320 10776477696 1418186912 14.757 100 71 138 1 1465149600 30495568 16467120
14:50:38 3877 8192000000 10353984080 10768592896 1411739664 14.699 100 81 159 6 1465600448 34319840 19540944
14:51:47 3246 8192000000 10368851888 10781724672 1417733808 14.753 100 85 169 2 1472747968 35237216 19776944
14:52:55 5427 8192000000 10366757152 10790498304 1415203264 14.730 100 59 118 1 1472807056 42071632 15532160
14:54:05 1612 8192000000 10382066528 10795540480 1420930704 14.781 100 81 162 0 1483464672 41836336 20697632
14:55:10 6049 8192000000 10353314832 10767638528 1379884080 14.416 97 60 121 1 1441142032 45540496 15717456
14:56:22 5090 8192000000 10363572592 10775183360 1398515488 14.582 100 77 153 0 1466123936 47866976 19741472
14:57:32 1970 8192000000 10386669920 10791223296 1398187568 14.579 100 84 168 3 1485545280 65028704 22329008
14:58:39 7358 8192000000 10361609552 10779971584 1385929472 14.470 100 50 100 3 1466179520 66056848 14193200
14:59:52 4497 8192000000 10363804272 10787864576 1400518368 14.600 100 58 117 0 1474382512 57989920 15874224
15:01:02 19761 8192000000 10332170224 10773131264 1390512480 14.510 100 32 64 2 1442862320 43438288 8911552
15:02:29 10325 8192000000 10336532336 10766999552 1390347600 14.509 100 34 66 2 1436083648 36934256 8801792
15:03:46 22570 8192000000 10335187040 10778370048 1386915872 14.478 100 26 52 1 1439029200 44387648 7725680
15:05:15 31077 8192000000 10278432208 10720620544 1322686912 13.901 96 14 28 1 1348677168 22283088 3707168
15:06:51 116449 8192000000 10273797936 10715271168 1323230720 13.906 97 29 55 2 1347591344 17973568 6387056
15:09:50 39147 8192000000 10299091728 10735947776 1377419392 14.393 100 46 91 1 1397195264 9513056 10262816
15:11:32 85362 8192000000 10265492016 10715832320 1316816912 13.848 95 15 30 0 1329871088 9782624 3271552
15:14:02 49813 8192000000 10278087232 10728771584 1359049856 14.229 99 19 38 0 1379084272 14613744 5420672
15:15:59 47272 8192000000 10284858144 10733486080 1361995392 14.255 99 14 29 0 1380275536 14856960 3423184
15:17:47 43286 8192000000 10291619040 10740989952 1385384368 14.465 100 12 24 0 1402721744 14599792 2737584
15:19:35 78633 8192000000 10304058768 10762170368 1396837568 14.567 100 19 38 0 1419297184 17968560 4491056
15:21:58 36620 8192000000 10313721264 10759667712 1386502000 14.475 100 50 101 0 1418632112 20237248 11892864
15:23:48 68146 8192000000 10312392400 10753896448 1378401472 14.402 99 30 61 1 1392095008 6547136 7146400
15:26:12 45221 8192000000 10308327408 10765201408 1399721840 14.593 100 29 58 0 1417781808 11019328 7040640
15:27:57 82861 8192000000 10308717184 10766667776 1345799952 14.110 96 19 38 1 1363669296 13475712 4393632
15:30:30 69473 8192000000 10299412448 10747666432 1354838384 14.191 97 31 62 1 1372572400 10959024 6774992
15:32:40 76849 8192000000 10299413248 10747772928 1329389824 13.962 95 37 73 1 1351687792 14356320 7941648
15:34:57 137511 8192000000 10302803840 10745737216 1327012512 13.940 95 10 20 0 1346159856 16753328 2394016
15:38:16 81212 8192000000 10283710048 10725650432 1360364160 14.241 99 23 46 1 1374542528 8939408 5238960
15:40:40 36355 8192000000 10279477408 10712215552 1337597136 14.036 96 43 86 1 1352585680 6436912 8551632
15:42:18 8498 8192000000 10284558352 10725502976 1372085488 14.346 99 49 98 1 1394695040 11772672 10836880
15:43:32 48740 8192000000 10281742864 10741035008 1370510576 14.332 99 11 22 1 1387503552 14230512 2762464
15:45:23 56266 8192000000 10299305552 10752000000 1381513440 14.430 99 15 30 0 1398541664 13811296 3216928
15:47:19 80566 8192000000 10304723680 10756935680 1367223936 14.302 98 20 40 0 1393398800 21067312 5107552
15:49:42 80182 8192000000 10267757664 10721939456 1303018432 13.723 94 14 28 2 1317205616 10936000 3251184
15:52:02 47462 8192000000 10258448064 10703581184 1270359200 13.425 92 13 26 0 1280965088 7900832 2705056
15:53:58 68058 8192000000 10263514080 10713903104 1340577120 14.063 98 22 44 0 1356464432 10979152 4908160
15:56:10 78109 8192000000 10291197520 10742018048 1348774480 14.136 99 23 46 1 1366457536 12710336 4972720
15:58:29 100684 8192000000 10304743584 10747449344 1365574480 14.287 99 15 30 1 1378630480 9824192 3231808
16:01:12 83841 8192000000 10290384496 10734075904 1355633760 14.198 98 21 40 0 1382976336 22792128 4550448
16:03:36 93202 8192000000 10266169872 10714329088 1315583568 13.837 96 31 62 1 1330740928 7972608 7184752
16:06:10 28615 8192000000 10270005376 10730303488 1369462080 14.322 100 18 36 1 1389287184 15960384 3864720
16:07:48 57589 8192000000 10271044912 10733871104 1367716144 14.307 100 10 20 0 1385715840 15743488 2256208
16:09:54 71242 8192000000 10282031808 10740649984 1383258288 14.446 100 10 20 0 1397390704 11882752 2249664
16:12:10 56764 8192000000 10304407584 10775031808 1385844928 14.469 99 21 42 0 1413915760 22884288 5186544
16:14:28 62437 8192000000 10291700400 10741317632 1379494064 14.412 99 18 35 1 1394631216 10708096 4429056
16:16:42 65407 8192000000 10307648288 10762973184 1376137248 14.382 98 22 44 1 1393231056 11920288 5173520
16:19:06 52204 8192000000 10294119424 10743939072 1361325888 14.249 98 21 42 0 1375971536 9741552 4904096
16:21:05 28191 8192000000 10297291568 10730774528 1328097328 13.950 96 45 90 3 1349183168 11067024 10018816
16:22:34 16799 8192000000 10313687328 10750287872 1384705488 14.459 100 53 106 1 1412835296 16763440 11366368
16:23:51 138783 8192000000 10288014400 10744205312 1329748144 13.965 96 11 22 0 1347458096 15539680 2170272
16:27:12 100872 8192000000 10291258176 10744336384 1372934528 14.353 99 27 54 0 1389321296 10122512 6264256
16:29:56 4591 8192000000 10295869584 10740871168 1376741232 14.387 100 44 86 0 1403380256 17084672 9554352
16:31:03 92617 8192000000 10285147696 10748301312 1374989952 14.372 99 10 19 1 1386135536 9113328 2032256
16:33:39 51164 8192000000 10273121440 10740146176 1365971488 14.291 99 7 14 0 1376114320 8615632 1527200
16:35:31 105725 8192000000 10261238848 10720059392 1326604032 13.936 96 25 49 1 1345461008 12723312 6133664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-03-2022 16:39:47 to 29-03-2022 16:40:02.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 29-03-2022 16:40:03 to 29-03-2022 16:40:04.

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          237992
MON$OLDEST_ACTIVE               239004
MON$OLDEST_SNAPSHOT             239004
MON$NEXT_TRANSACTION            239004
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                       2011072
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:674: 
:675: mscs-test-161.budzdorov.ru    Tue Mar 29 16:38:21 2022
:676:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:677: 
--- end of diff output ---

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

29.03.22 16:40:05. 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-03-29 16:40:08 No severe PSQL-related problems occured
29.03.22 16:40:09. End of report.