Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.09.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16089408 kB
MemAvailable 30861452 kB
Buffers 0 kB
Cached 15557232 kB
SwapCached 336 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.8
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 2021-09-02 22:37:48
DTS_END                         2021-09-02 22:37:48.0330
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/37294
TRN_ID                          246054
ATT_ID                          678
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: 9379.59 22299 1125551
customer order: creation 176.37 350 21164
customer order: refuse 14.75 371 1770
order to supplier: creation 48.16 1201 5779
order to supplier: removal 3.44 96050 413
invoice (draft): creation 47.41 3477 5689
invoice (draft): removal 54.53 710 6543
invoice accept: apply 42.33 19138 5079
invoice accept: cancel 49.22 774 5906
customer reserve: creation 3858.02 192 462962
customer reserve: removal 4776.97 54 573236
realization accept: apply 85.31 201 10237
realization accept: cancel 88.48 112 10618
payment from customer: creation 61.26 117 7351
payment from customer: removal 5.79 83 695
payment to supplier: creation 50.74 142 6089
payment to supplier: removal 3.84 62 461
service: total inventory turnovers 4.91 1184 589
service: total monetary turnovers 7.24 36 869
service: aggregate perf. data 0.81 25549 97
service: refresh index statistics 0.03 296167 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-09-2021 22:38:31 to 02-09-2021 22:38:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2611
TEST_TIME 2 3563
TEST_TIME 3 4726
TEST_TIME 4 4894
TEST_TIME 5 4528
TEST_TIME 6 4851
TEST_TIME 7 5363
TEST_TIME 8 6180
TEST_TIME 9 6397
TEST_TIME 10 7050
TEST_TIME 11 7089
TEST_TIME 12 7286
TEST_TIME 13 7452
TEST_TIME 14 7712
TEST_TIME 15 7694
TEST_TIME 16 8081
TEST_TIME 17 8067
TEST_TIME 18 8222
TEST_TIME 19 8569
TEST_TIME 20 9109
TEST_TIME 21 9216
TEST_TIME 22 9401
TEST_TIME 23 9483
TEST_TIME 24 9461
TEST_TIME 25 9664
TEST_TIME 26 9652
TEST_TIME 27 9621
TEST_TIME 28 10110
TEST_TIME 29 10669
TEST_TIME 30 10514
TEST_TIME 31 10651
TEST_TIME 32 10613
TEST_TIME 33 10539
TEST_TIME 34 10401
TEST_TIME 35 10228
TEST_TIME 36 10090
TEST_TIME 37 9933
TEST_TIME 38 9967
TEST_TIME 39 9990
TEST_TIME 40 10213
TEST_TIME 41 10092
TEST_TIME 42 9975
TEST_TIME 43 9858
TEST_TIME 44 9808
TEST_TIME 45 9776
TEST_TIME 46 9709
TEST_TIME 47 9541
TEST_TIME 48 9543
TEST_TIME 49 9512
TEST_TIME 50 9587
TEST_TIME 51 9479
TEST_TIME 52 9404
TEST_TIME 53 9394
TEST_TIME 54 9366
TEST_TIME 55 9390
TEST_TIME 56 9281
TEST_TIME 57 9200
TEST_TIME 58 9275
TEST_TIME 59 9219
TEST_TIME 60 9268
TEST_TIME 61 9217
TEST_TIME 62 9309
TEST_TIME 63 9328
TEST_TIME 64 9421
TEST_TIME 65 9346
TEST_TIME 66 9441
TEST_TIME 67 9361
TEST_TIME 68 9477
TEST_TIME 69 9471
TEST_TIME 70 9627
TEST_TIME 71 9555
TEST_TIME 72 9592
TEST_TIME 73 9543
TEST_TIME 74 9488
TEST_TIME 75 9424
TEST_TIME 76 9419
TEST_TIME 77 9358
TEST_TIME 78 9356
TEST_TIME 79 9342
TEST_TIME 80 9316
TEST_TIME 81 9320
TEST_TIME 82 9277
TEST_TIME 83 9210
TEST_TIME 84 9188
TEST_TIME 85 9168
TEST_TIME 86 9201
TEST_TIME 87 9233
TEST_TIME 88 9236
TEST_TIME 89 9243
TEST_TIME 90 9224
TEST_TIME 91 9270
TEST_TIME 92 9284
TEST_TIME 93 9339
TEST_TIME 94 9355
TEST_TIME 95 9388
TEST_TIME 96 9376
TEST_TIME 97 9409
TEST_TIME 98 9470
TEST_TIME 99 9497
TEST_TIME 100 9437
TEST_TIME 101 9364
TEST_TIME 102 9326
TEST_TIME 103 9389
TEST_TIME 104 9381
TEST_TIME 105 9346
TEST_TIME 106 9297
TEST_TIME 107 9243
TEST_TIME 108 9214
TEST_TIME 109 9228
TEST_TIME 110 9237
TEST_TIME 111 9247
TEST_TIME 112 9210
TEST_TIME 113 9216
TEST_TIME 114 9308
TEST_TIME 115 9293
TEST_TIME 116 9314
TEST_TIME 117 9303
TEST_TIME 118 9342
TEST_TIME 119 9315
TEST_TIME 120 9387

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-09-2021 22:38:35 to 02-09-2021 22:38:38.

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 1554576 1554576 0 0.00 0 18897 7 0 0
doc_list_biud 1554575 1554575 0 0.00 0 46401 26 0 0
sp_add_invoice_to_stock 5168 5079 89 1.72 34 199203 19139 0 89
sp_cancel_adding_invoice 5906 5906 0 0.00 4 68072 774 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 10866 371 0 0
sp_cancel_customer_reserve 573236 573236 0 0.00 1 46407 54 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 2 7359 83 0 0
sp_cancel_pay_to_supplier 461 461 0 0.00 3 1623 63 0 0
sp_cancel_supplier_invoice 6550 6543 7 0.10 1 18533 711 0 7
sp_cancel_supplier_order 413 413 0 0.00 7 1092694 96051 0 0
sp_cancel_write_off 10618 10618 0 0.00 1 13933 113 0 0
sp_client_order 21164 21164 0 0.00 4 19042 350 0 0
sp_customer_reserve 462962 462962 0 0.00 0 70872 193 0 0
sp_fill_shopping_cart 32626 32626 0 0.00 1 15877 40 0 0
sp_fill_shopping_cart_clo_res 462958 462958 0 0.00 0 15924 14 0 0
sp_get_clo_for_invoice 5062 5062 0 0.00 0 25386 267 0 0
sp_kill_cost_storno 17680 17680 0 0.00 0 11628 56 0 0
sp_kill_qstorno_ret_qs2qd 1164046 1164046 0 0.00 0 46390 29 0 0
sp_kill_qty_storno 613784 613784 0 0.00 0 46401 64 0 0
sp_lock_selected_doc 21209 21209 0 0.00 0 7903 5 0 0
sp_make_cost_storno 28739 28739 0 0.00 0 18888 61 0 0
sp_multiply_rows_for_pdistr 28738 28738 0 0.00 0 9431 28 0 0
sp_multiply_rows_for_qdistr 21164 21164 0 0.00 0 9844 144 0 0
sp_pay_from_customer 7351 7351 0 0.00 4 20828 117 0 0
sp_pay_to_supplier 6089 6089 0 0.00 5 19044 142 0 0
sp_payment_common 13440 13440 0 0.00 1 20807 37 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 10860 346 0 0
sp_qd_handle_on_invoice_upd_sts 10968 10968 0 0.00 0 18538 339 0 0
sp_qd_handle_on_reserve_upd_sts 10236 10236 0 0.00 0 19435 68 0 0
sp_reserve_write_off 10237 10237 0 0.00 6 19456 201 0 0
sp_supplier_invoice 5689 5689 0 0.00 7 87834 3477 0 0
sp_supplier_order 5779 5779 0 0.00 5 33447 1202 0 0
srv_aggregate_perf_data 97 97 0 0.00 2595 292902 25549 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1555 139488 45109 0 0
srv_find_qd_qs_mism 1076885 1076885 0 0.00 0 17076 6 0 0
srv_make_invnt_saldo 589 589 0 0.00 45 52707 1184 0 0
srv_make_money_saldo 869 869 0 0.00 0 2852 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 125093 488593 296167 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5080 5080 0 0.00 0 15414 181 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 5466 88 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 3477 16 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 2728 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 4154 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 461 461 0 0.00 0 4961 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 3697 20 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 29838 1720 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 1824 10 0 0
t$perf-norm:sp_client_order 21165 21165 0 0.00 0 8180 19 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 4111 9 0 0
t$perf-norm:sp_pay_from_customer 7351 7351 0 0.00 0 11419 14 0 0
t$perf-norm:sp_pay_to_supplier 6089 6089 0 0.00 0 11514 11 0 0
t$perf-norm:sp_reserve_write_off 10237 10237 0 0.00 0 13816 14 0 0
t$perf-norm:sp_supplier_invoice 5689 5689 0 0.00 0 6572 21 0 0
t$perf-norm:sp_supplier_order 5779 5779 0 0.00 0 3920 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 398 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 620 16 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 889 21 0 0
t$perf-norm:srv_make_money_saldo 869 869 0 0.00 0 5561 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10302 10124 178 1.72 0 14332 20 0 178
v_all_customers 38586 38586 0 0.00 0 82 1 0 0
v_all_suppliers 32050 32050 0 0.00 0 23 1 0 0
v_all_wares 42328 42328 0 0.00 0 164 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 629 7 0 0
v_cancel_client_order 3540 3540 0 0.00 0 3296 4 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 5004 6 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 2116 5 0 0
v_cancel_payment_to_supplier 922 922 0 0.00 0 1579 5 0 0
v_cancel_supplier_invoice 798 784 14 1.75 0 18345 36 0 14
v_cancel_supplier_order 826 826 0 0.00 0 370 3 0 0
v_cancel_write_off 2046 2046 0 0.00 0 2681 15 0 0
v_max_id_clo_ord 5779 5779 0 0.00 0 4609 2 0 0
v_max_id_clo_res 990 990 0 0.00 0 1366 11 0 0
v_max_id_ord_sup 5683 5683 0 0.00 0 5818 5 0 0
v_max_non_paid_invoice 1526 1526 0 0.00 0 3191 9 0 0
v_max_non_paid_realizn 3900 3900 0 0.00 0 5244 8 0 0
v_min_id_clo_ord 5779 5779 0 0.00 0 3100 6 0 0
v_min_id_clo_res 990 990 0 0.00 0 3779 14 0 0
v_min_id_ord_sup 5683 5683 0 0.00 0 5523 8 0 0
v_min_non_paid_invoice 6089 6089 0 0.00 0 18975 28 0 0
v_min_non_paid_realizn 7351 7351 0 0.00 0 20787 22 0 0
v_our_firm 10644 10644 0 0.00 0 16 1 0 0
v_reserve_write_off 20468 20468 0 0.00 0 3623 5 0 0
x_lock_depdocs_on_canc_invoice 12057 12057 0 0.00 0 15944 120 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 6668 354 0 0
x_make_qstorno_1000_1200 5779 5779 0 0.00 1 33436 1113 0 0
x_make_qstorno_1200_2000 5689 5689 0 0.00 3 87680 3337 0 0
x_make_qstorno_2100_3300 462962 462962 0 0.00 0 70872 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-09-2021 22:38:39 to 02-09-2021 22:38:43.

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:07:51 413 8192000000 8935796720 8980135936 45615280 0.553 5 5 11 0 47011776 178560 1217936
20:08:52 23859 8192000000 10388520624 10716909568 1441652496 14.964 100 91 183 1 1477266336 6261856 29351984
20:10:19 23433 8192000000 10469095536 10803326976 1517114720 15.625 100 88 176 0 1553789728 8440160 28234848
20:11:47 15113 8192000000 10515436832 10861445120 1568427200 16.069 100 85 170 0 1605456000 9866432 27162368
20:13:10 15346 8192000000 10564324736 10924982272 1630280816 16.597 100 77 154 1 1664844752 10951424 23612512
20:14:33 9232 8192000000 10638274160 11006386176 1698691632 17.174 100 83 166 1 1736598704 13729472 24177600
20:15:44 6097 8192000000 10692502976 11066580992 1748433264 17.589 100 89 179 3 1787181712 13525008 25223440
20:16:52 2668 8192000000 10742428816 11120939008 1804112224 18.048 100 90 178 1 1843043008 14238112 24692672
20:17:57 2568 8192000000 10802695504 11189293056 1857927184 18.486 100 92 186 3 1901751072 17411664 26412224
20:19:00 1296 8192000000 10813742768 11211747328 1877998112 18.649 100 90 180 0 1916068720 12020192 26050416
20:20:22 4595 8192000000 10793155936 11212218368 1877300880 18.643 100 37 74 0 1895425264 7900736 10223648
20:21:28 15725 8192000000 10802969536 11234639872 1891841696 18.761 100 26 52 1 1905635248 6565184 7228368
20:22:44 42249 8192000000 10810272592 11245588480 1901427264 18.838 100 28 56 2 1915058336 6066224 7564848
20:24:31 4346 8192000000 10820551328 11251662848 1901227024 18.836 100 30 60 1 1920647744 11106288 8314432
20:25:39 6951 8192000000 10816679056 11255332864 1905277664 18.869 100 25 48 2 1919682432 7061536 7343232
20:26:48 45237 8192000000 10842859264 11267530752 1919653456 18.984 100 36 72 1 1937023216 7349808 10019952
20:28:37 8669 8192000000 10764029744 11193380864 1826283648 18.229 98 18 36 2 1837001648 5629536 5088464
20:29:46 9282 8192000000 10697586560 11111206912 1730276784 17.438 95 23 47 1 1742494240 5607920 6609536
20:30:56 71562 8192000000 10714798480 11134427136 1726433632 17.406 95 28 54 2 1739115360 5118576 7563152
20:33:08 4539 8192000000 10749972096 11170054144 1806996608 18.071 99 30 60 0 1830834560 15375296 8462656
20:34:13 7832 8192000000 10777910704 11195514880 1855094256 18.463 100 36 72 0 1871676544 5894384 10687904
20:35:21 72333 8192000000 10778143984 11216424960 1871370288 18.595 100 30 60 1 1885646112 5334960 8940864
20:37:38 6000 8192000000 10835361680 11260055552 1912232640 18.925 100 31 62 2 1926563616 5753696 8577280
20:38:53 13080 8192000000 10857414576 11281248256 1925440128 19.030 100 43 86 1 1946763584 9883872 11439584
20:40:13 11044 8192000000 10893871760 11313668096 1953197648 19.252 100 56 109 2 1982231504 14029088 15004768
20:41:34 35009 8192000000 10898912080 11336568832 1969832256 19.384 100 41 82 2 1994802608 13222576 11747776
20:43:20 8936 8192000000 10930503648 11359473664 1985812928 19.511 100 57 114 2 2021112880 18594736 16705216
20:44:33 7489 8192000000 10928363504 11362529280 1987058432 19.521 100 52 104 1 2023171760 20874176 15239152
20:45:47 4670 8192000000 10943892544 11361157120 1969742480 19.383 99 74 148 0 2016853344 26242544 20868320
20:46:54 9111 8192000000 10946705008 11378266112 1994536480 19.580 100 68 136 0 2039243824 24645952 20061392
20:48:08 9311 8192000000 10926692288 11360780288 1977115872 19.442 100 55 109 0 2015911616 23081152 15714592
20:49:24 3699 8192000000 10935173984 11346362368 1954581232 19.263 99 76 149 0 2002240976 25654976 22004768
20:50:31 2705 8192000000 10910976928 11326791680 1940818160 19.153 100 76 150 0 1989430352 26118816 22493376
20:51:43 5374 8192000000 10926265808 11336126464 1953514816 19.254 100 77 154 3 2004140496 27585472 23040208
20:52:55 4820 8192000000 10923401376 11341381632 1954314544 19.261 100 75 145 2 2008683360 31311472 23057344
20:54:09 3259 8192000000 10902639808 11310059520 1883467376 18.693 97 85 170 2 1939627888 30321136 25839376
20:55:17 1554 8192000000 10917147552 11324129280 1919864592 18.986 99 79 156 2 1983910848 39856688 24189568
20:56:25 2131 8192000000 10935205808 11355303936 1956763696 19.280 100 80 160 2 2024527120 41249136 26514288
20:57:31 9948 8192000000 10915564672 11354161152 1944025824 19.179 99 49 98 1 1994249104 34830464 15392816
20:58:53 8779 8192000000 10889159440 11327127552 1938385056 19.134 100 41 81 1 1979604640 27708800 13510784
21:00:06 23101 8192000000 10906885568 11352395776 1934711648 19.105 99 24 48 1 1971214128 29346640 7155840
21:01:35 11481 8192000000 10893684608 11339202560 1929446736 19.062 99 30 56 1 1967203632 28732944 9023952
21:03:04 14957 8192000000 10918325808 11365404672 1961036720 19.314 100 24 48 1 2002704880 34214592 7453568
21:04:35 18036 8192000000 10952285088 11389292544 1959186928 19.300 100 28 56 1 2014778928 46090752 9501248
21:05:55 31463 8192000000 10900854432 11371065344 1967216720 19.363 100 20 41 1 2012207376 38030672 6959984
21:07:27 67290 8192000000 10912308272 11360333824 1951626320 19.239 99 31 63 0 1982282128 21427872 9227936
21:09:43 59371 8192000000 10903447568 11356614656 1949636720 19.224 99 13 26 1 1960762176 7358912 3766544
21:11:50 52089 8192000000 10896072832 11352854528 1954102720 19.259 99 13 26 0 1969633712 11793744 3737248
21:13:44 46433 8192000000 10928530336 11381116928 1973089504 19.410 99 20 40 1 1997158448 18296096 5772848
21:15:31 57052 8192000000 10928169664 11396308992 1990954912 19.551 100 29 59 4 2023022752 22369248 9698592
21:17:30 35740 8192000000 10911158496 11396718592 1984366448 19.499 100 11 22 0 2005514784 17476432 3671904
21:19:14 84703 8192000000 10921354224 11381116928 1976355888 19.436 99 28 56 0 1991959600 7704768 7898944
21:21:39 56530 8192000000 10886793728 11359961088 1932966496 19.091 97 13 26 2 1946544000 8903168 4674336
21:23:36 98731 8192000000 10913228928 11381346304 1987910576 19.527 100 28 56 0 2004404096 8799824 7693696
21:26:15 90109 8192000000 10903687264 11387846656 1933576288 19.095 97 21 41 1 1956178304 16982400 5619616
21:29:18 39424 8192000000 10894008736 11350982656 1955357168 19.269 98 32 64 1 1975980672 11206544 9416960
21:30:58 54749 8192000000 10895108544 11369299968 1948564976 19.215 98 22 45 0 1967894880 12678240 6651664
21:32:57 58275 8192000000 10897233104 11369422848 1974539632 19.421 100 11 21 3 1992839184 14971728 3327824
21:35:11 78457 8192000000 10894948720 11390083072 1961761120 19.320 99 6 12 1 1976061424 12696432 1603872
21:37:32 53527 8192000000 10883378640 11354583040 1913893392 18.938 97 20 40 2 1932680928 13432752 5354784
21:39:26 120564 8192000000 10880636192 11356368896 1821915024 18.193 92 12 24 1 1840206784 14924624 3367136
21:43:17 52966 8192000000 10883309536 11346415616 1874402080 18.620 95 18 36 0 1893536576 14635088 4499408
21:45:10 43526 8192000000 10875918240 11339001856 1916355632 18.958 99 29 58 3 1946376368 21364624 8656112
21:46:59 109555 8192000000 10869012912 11324731392 1818359024 18.164 93 10 20 0 1837588112 16493248 2735840
21:50:00 21985 8192000000 10883037888 11329241088 1926109632 19.036 99 25 50 0 1940755488 7807088 6838768
21:51:28 51577 8192000000 10887624880 11342024704 1937160912 19.124 99 43 86 1 1960426128 11137840 12127376
21:53:46 63630 8192000000 10868852768 11336376320 1929426960 19.062 99 27 54 0 1955383824 18496112 7460752
21:55:50 37478 8192000000 10885721024 11351293952 1933502016 19.095 99 18 36 0 1950686352 12030192 5154144
21:57:29 90776 8192000000 10849836368 11323867136 1896589120 18.799 97 16 33 1 1910618960 9339616 4690224
22:00:13 79636 8192000000 10882139712 11353116672 1954902432 19.266 100 25 48 1 1970264272 8519456 6842384
22:03:30 97617 8192000000 10884241232 11365388288 1935117600 19.108 99 9 18 1 1954373472 16776096 2479776
22:06:12 35798 8192000000 10881002080 11351851008 1958240976 19.292 100 20 40 1 1972381936 8069840 6071120
22:07:53 59999 8192000000 10919706816 11382308864 1964612464 19.343 99 36 73 1 1988137744 13727360 9797920
22:09:59 62886 8192000000 10931184432 11412201472 1995508272 19.587 100 23 46 0 2018642288 17070720 6063296
22:12:04 72677 8192000000 10948722464 11420561408 2004859152 19.661 100 15 30 1 2021620192 12410192 4350848
22:14:29 57790 8192000000 10931374368 11422121984 1986688304 19.518 99 15 30 0 2010665696 19803328 4174064
22:16:33 139488 8192000000 10942847024 11399151616 1964008496 19.338 98 30 61 0 1990571376 17613536 8949344
22:19:57 61017 8192000000 10919284272 11414253568 1985895472 19.511 100 9 18 0 2014607040 26341936 2369632
22:22:12 80190 8192000000 10875079936 11353452544 1869855712 18.583 94 10 20 1 1881733440 8891856 2985872
22:25:13 40581 8192000000 10880476784 11360845824 1946884672 19.202 99 18 36 0 1968602624 15693584 6024368
22:27:04 70093 8192000000 10886368192 11363393536 1955241872 19.268 100 6 12 0 1972723776 15974864 1507040
22:29:17 23832 8192000000 10891444464 11341385728 1900971200 18.834 97 40 80 0 1925479264 13537712 10970352
22:30:41 53055 8192000000 10840266480 11337961472 1886163920 18.715 97 19 38 0 1908155680 15948320 6043440
22:32:37 29130 8192000000 10854727728 11320082432 1904753920 18.865 98 17 34 1 1920550464 11372640 4423904
22:34:07 69744 8192000000 10847841648 11324620800 1842162464 18.358 95 19 38 1 1864247248 16941136 5143648
22:36:21 43750 8192000000 10890499088 11330945024 1928772608 19.057 100 31 62 1 1953675728 16313232 8589888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-09-2021 22:38:45 to 02-09-2021 22:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-09-2021 22:39:01 to 02-09-2021 22:39:03.

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          244168
MON$OLDEST_ACTIVE               246169
MON$OLDEST_SNAPSHOT             246169
MON$NEXT_TRANSACTION            246169
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                       2036704
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Thu Sep  2 22:37:49 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Thu Sep  2 22:38:00 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
--- end of diff output ---

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

02.09.21 22:39:04. 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
2021-09-02 22:39:06 No severe PSQL-related problems occured
02.09.21 22:39:07. End of report.