Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.06.2022 19:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16517888 kB
MemAvailable 31330560 kB
Buffers 0 kB
Cached 15178716 kB
SwapCached 2444 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-01 19:38:20
DTS_END                         2022-06-01 19:38:20.0230
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52272
TRN_ID                          245423
ATT_ID                          731
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: 9187.13 19148 1102456
customer order: creation 184.85 382 22182
customer order: refuse 15.78 334 1894
order to supplier: creation 47.89 1338 5747
order to supplier: removal 3.67 82526 440
invoice (draft): creation 48.56 3578 5827
invoice (draft): removal 51.08 669 6129
invoice accept: apply 43.72 17553 5246
invoice accept: cancel 45.53 859 5463
customer reserve: creation 3980.78 177 477694
customer reserve: removal 4459.90 55 535188
realization accept: apply 87.62 157 10514
realization accept: cancel 80.61 104 9673
payment from customer: creation 63.15 121 7578
payment from customer: removal 6.08 81 729
payment to supplier: creation 51.70 133 6204
payment to supplier: removal 3.49 56 419
service: total inventory turnovers 5.11 972 613
service: total monetary turnovers 6.82 35 818
service: aggregate perf. data 0.78 23309 94
service: refresh index statistics 0.03 250517 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-06-2022 19:39:11 to 01-06-2022 19:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6165
TEST_TIME 2 4778
TEST_TIME 3 4684
TEST_TIME 4 4306
TEST_TIME 5 4265
TEST_TIME 6 4919
TEST_TIME 7 4945
TEST_TIME 8 5521
TEST_TIME 9 5508
TEST_TIME 10 5599
TEST_TIME 11 5673
TEST_TIME 12 6025
TEST_TIME 13 6368
TEST_TIME 14 6624
TEST_TIME 15 6693
TEST_TIME 16 6951
TEST_TIME 17 7335
TEST_TIME 18 7861
TEST_TIME 19 8113
TEST_TIME 20 8098
TEST_TIME 21 8481
TEST_TIME 22 9133
TEST_TIME 23 8989
TEST_TIME 24 9454
TEST_TIME 25 9832
TEST_TIME 26 10044
TEST_TIME 27 10115
TEST_TIME 28 10186
TEST_TIME 29 10096
TEST_TIME 30 10146
TEST_TIME 31 10120
TEST_TIME 32 9951
TEST_TIME 33 9759
TEST_TIME 34 9660
TEST_TIME 35 9569
TEST_TIME 36 9427
TEST_TIME 37 9458
TEST_TIME 38 9461
TEST_TIME 39 9388
TEST_TIME 40 9465
TEST_TIME 41 9499
TEST_TIME 42 9381
TEST_TIME 43 9414
TEST_TIME 44 9408
TEST_TIME 45 9324
TEST_TIME 46 9493
TEST_TIME 47 9417
TEST_TIME 48 9547
TEST_TIME 49 9504
TEST_TIME 50 9593
TEST_TIME 51 9626
TEST_TIME 52 9624
TEST_TIME 53 9643
TEST_TIME 54 9660
TEST_TIME 55 9603
TEST_TIME 56 9747
TEST_TIME 57 9802
TEST_TIME 58 9823
TEST_TIME 59 9697
TEST_TIME 60 9598
TEST_TIME 61 9558
TEST_TIME 62 9471
TEST_TIME 63 9512
TEST_TIME 64 9474
TEST_TIME 65 9390
TEST_TIME 66 9315
TEST_TIME 67 9350
TEST_TIME 68 9382
TEST_TIME 69 9403
TEST_TIME 70 9411
TEST_TIME 71 9391
TEST_TIME 72 9331
TEST_TIME 73 9335
TEST_TIME 74 9480
TEST_TIME 75 9460
TEST_TIME 76 9470
TEST_TIME 77 9514
TEST_TIME 78 9458
TEST_TIME 79 9541
TEST_TIME 80 9472
TEST_TIME 81 9407
TEST_TIME 82 9377
TEST_TIME 83 9326
TEST_TIME 84 9355
TEST_TIME 85 9324
TEST_TIME 86 9272
TEST_TIME 87 9256
TEST_TIME 88 9266
TEST_TIME 89 9230
TEST_TIME 90 9224
TEST_TIME 91 9223
TEST_TIME 92 9216
TEST_TIME 93 9268
TEST_TIME 94 9269
TEST_TIME 95 9297
TEST_TIME 96 9305
TEST_TIME 97 9354
TEST_TIME 98 9382
TEST_TIME 99 9360
TEST_TIME 100 9431
TEST_TIME 101 9387
TEST_TIME 102 9357
TEST_TIME 103 9372
TEST_TIME 104 9302
TEST_TIME 105 9286
TEST_TIME 106 9257
TEST_TIME 107 9212
TEST_TIME 108 9197
TEST_TIME 109 9221
TEST_TIME 110 9186
TEST_TIME 111 9161
TEST_TIME 112 9145
TEST_TIME 113 9137
TEST_TIME 114 9147
TEST_TIME 115 9176
TEST_TIME 116 9157
TEST_TIME 117 9201
TEST_TIME 118 9195
TEST_TIME 119 9250
TEST_TIME 120 9189

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-06-2022 19:39:14 to 01-06-2022 19:39:18.

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 1545126 1545126 0 0.00 0 22991 6 0 0
doc_list_biud 1545121 1545121 0 0.00 0 32312 24 0 0
sp_add_invoice_to_stock 5393 5246 147 2.72 35 189978 17554 0 147
sp_cancel_adding_invoice 5463 5463 0 0.00 3 87736 859 0 0
sp_cancel_client_order 1894 1894 0 0.00 4 14664 335 0 0
sp_cancel_customer_reserve 535188 535188 0 0.00 1 30405 56 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 2 6691 81 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 2 1361 57 0 0
sp_cancel_supplier_invoice 6141 6129 12 0.19 1 10926 670 0 12
sp_cancel_supplier_order 440 440 0 0.00 7 1209572 82527 0 0
sp_cancel_write_off 9673 9673 0 0.00 1 12946 104 0 0
sp_client_order 22182 22182 0 0.00 4 23172 383 0 0
sp_customer_reserve 477694 477694 0 0.00 0 62279 177 0 0
sp_fill_shopping_cart 33747 33747 0 0.00 1 12131 36 0 0
sp_fill_shopping_cart_clo_res 477686 477686 0 0.00 0 14247 14 0 0
sp_get_clo_for_invoice 5237 5237 0 0.00 0 23262 301 0 0
sp_kill_cost_storno 16284 16284 0 0.00 0 7719 53 0 0
sp_kill_qstorno_ret_qs2qd 1086618 1086618 0 0.00 0 30380 30 0 0
sp_kill_qty_storno 574537 574537 0 0.00 0 32311 64 0 0
sp_lock_selected_doc 21973 21973 0 0.00 0 6680 4 0 0
sp_make_cost_storno 29533 29533 0 0.00 0 22986 52 0 0
sp_multiply_rows_for_pdistr 29533 29533 0 0.00 0 8149 29 0 0
sp_multiply_rows_for_qdistr 22182 22182 0 0.00 0 12421 189 0 0
sp_pay_from_customer 7578 7578 0 0.00 4 10779 122 0 0
sp_pay_to_supplier 6204 6204 0 0.00 5 10778 133 0 0
sp_payment_common 13782 13782 0 0.00 1 9072 29 0 0
sp_qd_handle_on_cancel_clo 1894 1894 0 0.00 0 14657 313 0 0
sp_qd_handle_on_invoice_upd_sts 10700 10700 0 0.00 0 32310 303 0 0
sp_qd_handle_on_reserve_upd_sts 10513 10513 0 0.00 0 15168 49 0 0
sp_reserve_write_off 10514 10514 0 0.00 7 15225 158 0 0
sp_supplier_invoice 5827 5827 0 0.00 6 99948 3578 0 0
sp_supplier_order 5747 5747 0 0.00 4 33060 1338 0 0
srv_aggregate_perf_data 94 94 0 0.00 2533 272801 23309 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1922 113894 45718 0 0
srv_find_qd_qs_mism 1052372 1052372 0 0.00 0 16114 5 0 0
srv_make_invnt_saldo 613 613 0 0.00 35 46138 973 0 0
srv_make_money_saldo 818 818 0 0.00 1 7203 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 127809 438728 250518 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 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 5246 5246 0 0.00 0 13245 185 0 0
t$perf-norm:sp_cancel_adding_invoice 465 465 0 0.00 0 8524 104 0 0
t$perf-norm:sp_cancel_client_order 1894 1894 0 0.00 0 5006 22 0 0
t$perf-norm:sp_cancel_customer_reserve 771 771 0 0.00 0 3241 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 1846 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 1976 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 274 7 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 19501 1453 0 0
t$perf-norm:sp_cancel_write_off 1078 1078 0 0.00 0 5007 16 0 0
t$perf-norm:sp_client_order 22182 22182 0 0.00 0 11850 17 0 0
t$perf-norm:sp_customer_reserve 1074 1074 0 0.00 0 3749 16 0 0
t$perf-norm:sp_pay_from_customer 7579 7579 0 0.00 0 9992 13 0 0
t$perf-norm:sp_pay_to_supplier 6204 6204 0 0.00 0 6663 14 0 0
t$perf-norm:sp_reserve_write_off 10514 10514 0 0.00 0 8358 14 0 0
t$perf-norm:sp_supplier_invoice 5827 5827 0 0.00 0 9830 28 0 0
t$perf-norm:sp_supplier_order 5747 5747 0 0.00 0 11105 18 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 259 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 613 613 0 0.00 0 5875 32 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 6998 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 5 0 0
v_add_invoice_to_stock 10768 10474 294 2.73 0 4344 17 0 294
v_all_customers 40430 40430 0 0.00 0 88 1 0 0
v_all_suppliers 32168 32168 0 0.00 0 24 1 0 0
v_all_wares 44364 44364 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 930 930 0 0.00 0 1363 9 0 0
v_cancel_client_order 3788 3788 0 0.00 0 2578 4 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 734 4 0 0
v_cancel_customer_reserve 1542 1542 0 0.00 0 2630 7 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 929 4 0 0
v_cancel_supplier_invoice 878 854 24 2.73 0 1474 14 0 24
v_cancel_supplier_order 880 880 0 0.00 0 1218 7 0 0
v_cancel_write_off 2156 2156 0 0.00 0 2394 13 0 0
v_max_id_clo_ord 5747 5747 0 0.00 0 378 1 0 0
v_max_id_clo_res 1074 1074 0 0.00 0 2759 11 0 0
v_max_id_ord_sup 5818 5818 0 0.00 0 7288 9 0 0
v_max_non_paid_invoice 1685 1685 0 0.00 0 663 3 0 0
v_max_non_paid_realizn 3922 3922 0 0.00 0 3870 5 0 0
v_min_id_clo_ord 5747 5747 0 0.00 0 5781 9 0 0
v_min_id_clo_res 1074 1074 0 0.00 0 5309 9 0 0
v_min_id_ord_sup 5818 5818 0 0.00 0 3744 4 0 0
v_min_non_paid_invoice 6204 6204 0 0.00 0 9066 21 0 0
v_min_non_paid_realizn 7578 7578 0 0.00 0 7678 22 0 0
v_our_firm 11246 11246 0 0.00 0 17 1 0 0
v_reserve_write_off 21026 21026 0 0.00 0 2845 5 0 0
x_lock_depdocs_on_canc_invoice 11165 11165 0 0.00 0 22303 111 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 15377 342 0 0
x_make_qstorno_1000_1200 5747 5747 0 0.00 1 33037 1258 0 0
x_make_qstorno_1200_2000 5827 5827 0 0.00 3 99845 3448 0 0
x_make_qstorno_2100_3300 477693 477693 0 0.00 0 62261 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-06-2022 19:39:19 to 01-06-2022 19:39:23.

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
17:08:23 15 8192000000 8806729520 8820396032 11932512 0.145 3 3 5 0 12135936 77360 126064
17:09:24 22927 8192000000 9908904848 10219872256 993012928 10.811 100 89 178 0 1023106736 6373360 23720448
17:11:01 19557 8192000000 9949778032 10273198080 1029422592 11.163 100 91 180 2 1061585936 8539552 23623792
17:12:24 14940 8192000000 9980180832 10314956800 1060957584 11.466 100 78 156 1 1091083376 10032240 20093552
17:13:46 9437 8192000000 10032538448 10385178624 1125268752 12.077 100 83 166 0 1156833984 11332240 20232992
17:15:04 8802 8192000000 10081054656 10440261632 1169713744 12.494 100 73 144 0 1198004704 11611392 16679568
17:16:16 6056 8192000000 10124559760 10494521344 1212067600 12.888 100 77 154 3 1243233248 13628864 17536784
17:17:24 2477 8192000000 10172097936 10547728384 1255354448 13.287 100 81 160 1 1286426400 13373456 17698496
17:18:28 3197 8192000000 10193456320 10571751424 1276992960 13.486 100 83 165 0 1308279824 13017632 18269232
17:19:33 2326 8192000000 10218471472 10602143744 1303184064 13.724 100 88 176 0 1337325424 13450656 20690704
17:20:37 2948 8192000000 10209747344 10610991104 1312942960 13.813 100 65 130 3 1338435648 11273584 14219104
17:21:41 7575 8192000000 10221943968 10624188416 1317789360 13.857 100 49 98 0 1338467552 9774192 10904000
17:22:51 14261 8192000000 10206363504 10627063808 1320806192 13.884 100 34 68 0 1335354896 6844208 7704496
17:24:09 9051 8192000000 10219676576 10635468800 1326224224 13.933 100 26 51 1 1337857280 5601712 6031344
17:25:20 8131 8192000000 10224806368 10650021888 1339555632 14.053 100 26 52 1 1352331344 6367344 6408368
17:26:29 19403 8192000000 10248514352 10660843520 1347309104 14.123 100 25 50 0 1359310544 5327504 6673936
17:27:49 21873 8192000000 10255016208 10659217408 1337780720 14.037 99 73 150 2 1362787568 6660912 18345936
17:29:11 6513 8192000000 10228133424 10649395200 1338205376 14.041 100 25 50 2 1349998064 6066064 5726624
17:30:23 6836 8192000000 10195095248 10605256704 1299680864 13.692 100 24 48 1 1310987728 5725104 5581760
17:31:30 14502 8192000000 10152365216 10555146240 1216681472 12.931 96 51 103 0 1233852000 4786592 12383936
17:32:46 6339 8192000000 10125583584 10526633984 1221446880 12.975 98 19 37 3 1231058608 5350144 4261584
17:33:55 8905 8192000000 10140590320 10543165440 1252079728 13.257 100 31 62 1 1265453936 5555984 7818224
17:35:04 62536 8192000000 10160108144 10573086720 1278587040 13.500 100 24 48 1 1290019648 5689248 5743360
17:37:12 4250 8192000000 10187827344 10594676736 1292924208 13.631 100 41 83 3 1309823216 6207072 10691936
17:38:17 14424 8192000000 10217480704 10626625536 1316269392 13.843 100 17 35 1 1324759824 4414272 4076160
17:39:39 25226 8192000000 10246934624 10656301056 1342598720 14.081 100 50 100 1 1362492368 7900352 11993296
17:41:20 102456 8192000000 10263546384 10687954944 1356921312 14.210 100 28 56 0 1378140960 14384432 6835216
17:44:08 25067 8192000000 10261542832 10688929792 1351677120 14.163 99 23 46 1 1372673920 14967648 6029152
17:45:37 4298 8192000000 10294733440 10712739840 1377946160 14.398 100 59 114 3 1408018368 17407056 12665152
17:46:47 14129 8192000000 10305008160 10728497152 1379085488 14.408 99 56 112 1 1409901952 17252032 13564432
17:48:11 2512 8192000000 10344691264 10755993600 1406477968 14.653 100 82 163 4 1448700240 22439360 19782912
17:49:19 12304 8192000000 10348037968 10768551936 1413567328 14.716 100 67 134 0 1459330368 29448736 16314304
17:50:38 3458 8192000000 10345519408 10768809984 1414563136 14.724 100 64 127 2 1458229856 27239360 16427360
17:51:46 10777 8192000000 10365834080 10781634560 1424059440 14.809 100 79 157 0 1472672464 29220320 19392704
17:53:02 5676 8192000000 10363353408 10784403456 1412042368 14.702 99 77 154 2 1464547152 32398560 20106224
17:54:14 8939 8192000000 10347245936 10772234240 1403610752 14.627 99 62 122 0 1447462320 28423360 15428208
17:55:32 2482 8192000000 10344663728 10750754816 1392852416 14.531 100 79 158 3 1444727280 32438992 19435872
17:56:37 16445 8192000000 10318829344 10746212352 1384763632 14.459 100 49 98 2 1431766928 33671456 13331840
17:57:58 3017 8192000000 10297326816 10705862656 1342892528 14.083 99 73 144 2 1388936144 28160608 17883008
17:59:06 4256 8192000000 10315050672 10724683776 1363273552 14.267 100 70 140 1 1415916016 35086176 17556288
18:00:14 14300 8192000000 10300563728 10737369088 1374695392 14.369 100 31 61 2 1411001072 28102704 8202976
18:01:30 23515 8192000000 10282230960 10717773824 1357579008 14.216 99 24 48 0 1385677056 21572592 6525456
18:03:03 39585 8192000000 10257906320 10709680128 1330798928 13.974 98 16 32 0 1354444144 19362304 4282912
18:04:43 23797 8192000000 10244422192 10676752384 1315747536 13.838 98 23 47 0 1331047408 9516672 5783200
18:06:08 72443 8192000000 10250598480 10678341632 1273594832 13.454 94 35 68 3 1290455760 8218656 8642272
18:08:20 51573 8192000000 10264579808 10709757952 1347358496 14.124 99 9 19 0 1361357968 11814272 2185200
18:10:13 43694 8192000000 10290245616 10712379392 1323237856 13.906 96 40 81 0 1340998432 8628976 9131600
18:12:03 39161 8192000000 10283426800 10708889600 1346688336 14.118 99 59 117 5 1367934928 7318880 13927712
18:13:46 60278 8192000000 10276791008 10726088704 1365869440 14.290 99 15 28 1 1379567040 10268144 3429456
18:15:47 43709 8192000000 10304648976 10750611456 1391412848 14.518 100 21 42 0 1410928976 14641184 4874944
18:17:39 1920 8192000000 10330738432 10762944512 1398605584 14.583 100 75 152 0 1435592960 17783248 19204128
18:18:44 69595 8192000000 10304319600 10765434880 1386960368 14.479 99 22 44 3 1413550192 20819136 5770688
18:20:54 31228 8192000000 10300933168 10755399680 1398169200 14.579 100 18 36 0 1418635536 16186816 4279520
18:22:45 32548 8192000000 10306754400 10763886592 1399772240 14.593 100 10 20 0 1413039472 11054176 2213056
18:24:19 78762 8192000000 10312094896 10767998976 1390965856 14.514 99 10 20 0 1413131392 20014864 2150672
18:26:40 32941 8192000000 10308242864 10769190912 1406159248 14.650 100 17 34 2 1418923808 9172480 3592080
18:28:13 39270 8192000000 10288619056 10754895872 1381828928 14.433 98 33 66 0 1401976352 11889744 8257680
18:30:02 30045 8192000000 10303612000 10751582208 1368721360 14.316 98 40 80 0 1385563552 8181360 8660832
18:31:35 70453 8192000000 10293926944 10745315328 1328731312 13.956 95 12 24 1 1342096512 10912832 2452368
18:33:48 54278 8192000000 10297516688 10761187328 1372442336 14.349 99 34 68 0 1394280128 14479168 7358624
18:35:46 70528 8192000000 10281506064 10735034368 1327895056 13.948 96 14 28 2 1347893360 16891680 3106624
18:37:56 76616 8192000000 10284654736 10732359680 1345908720 14.111 97 29 58 1 1365667824 13078672 6680432
18:40:27 14539 8192000000 10273026032 10712338432 1351344368 14.160 99 38 76 1 1372938816 13007088 8587360
18:41:43 43833 8192000000 10267950224 10732662784 1361810112 14.254 99 15 30 0 1376569600 11535696 3223792
18:43:29 40638 8192000000 10292753824 10747842560 1370624208 14.333 99 19 38 0 1388789216 13761616 4403392
18:45:21 62816 8192000000 10283185104 10734911488 1326777184 13.938 96 22 44 0 1346040256 14103664 5159408
18:47:31 31331 8192000000 10273124688 10712530944 1332943440 13.994 97 30 59 0 1346176256 6679264 6553552
18:49:06 58268 8192000000 10271066896 10721181696 1325872864 13.930 97 13 26 0 1341364800 12452576 3039360
18:51:06 102219 8192000000 10274934944 10730758144 1339497184 14.053 98 20 40 0 1364840864 20770784 4572896
18:53:49 69051 8192000000 10269687872 10716581888 1316592640 13.846 96 19 38 0 1329347984 8102640 4652704
18:55:58 47738 8192000000 10286913408 10724655104 1312915968 13.813 96 34 68 2 1329992528 9357376 7719184
18:57:49 102319 8192000000 10269849824 10716352512 1352347488 14.169 99 14 28 1 1368061968 12772672 2941808
19:00:50 50850 8192000000 10263428896 10706522112 1352764464 14.172 99 46 92 0 1374907296 11683952 10458880
19:02:44 52683 8192000000 10263707376 10724556800 1366847856 14.299 100 14 28 0 1378059488 8275728 2935904
19:04:40 76862 8192000000 10274535280 10733584384 1356643440 14.207 98 16 32 2 1369698400 8074224 4980736
19:07:04 59170 8192000000 10272984512 10734866432 1363722032 14.271 99 10 20 0 1374848880 8961152 2165696
19:09:10 99301 8192000000 10311320832 10766635008 1369903152 14.326 98 12 24 0 1380956864 8597072 2456640
19:11:50 97880 8192000000 10307076176 10759819264 1379572800 14.413 99 18 36 0 1392831536 9702352 3556384
19:14:28 25930 8192000000 10315391312 10749407232 1379371760 14.411 99 49 97 2 1397966976 7862864 10732352
19:16:09 113894 8192000000 10291828000 10751287296 1320891520 13.885 95 9 18 0 1335655344 12933408 1830416
19:19:13 35535 8192000000 10289215040 10728787968 1332179008 13.987 96 38 73 2 1347527184 7813264 7534912
19:20:52 31939 8192000000 10286282560 10727915520 1365330208 14.285 100 38 76 2 1384541632 11120256 8091168
19:22:25 101600 8192000000 10267292880 10714873856 1310108304 13.787 95 31 62 1 1325041248 7614256 7318688
19:25:25 83689 8192000000 10252510304 10709733376 1320560784 13.882 96 15 30 0 1337600848 13082720 3957344
19:28:13 38272 8192000000 10240796400 10700259328 1311090768 13.796 96 11 22 0 1322323536 8635552 2597216
19:30:05 90491 8192000000 10250347680 10705399808 1280839136 13.521 94 16 31 0 1292677104 7705504 4132464
19:33:03 68541 8192000000 10258540928 10712506368 1313751920 13.820 96 20 40 1 1332748640 14241424 4755296
19:35:13 56303 8192000000 10259521232 10704842752 1315393056 13.835 96 20 40 0 1331141184 10813232 4934896
19:37:10 60180 8192000000 10274250560 10724311040 1360072336 14.238 100 23 46 1 1379201248 13460048 5668864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-06-2022 19:39:25 to 01-06-2022 19:39:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 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:02, from 01-06-2022 19:39:41 to 01-06-2022 19:39:43.

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          245196
MON$OLDEST_ACTIVE               246192
MON$OLDEST_SNAPSHOT             246192
MON$NEXT_TRANSACTION            246192
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                       2015808
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.33598 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33598 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33598 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33598 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Wed Jun  1 19:38:21 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Wed Jun  1 19:38:26 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Jun  1 19:38:29 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
--- end of diff output ---

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

01.06.22 19:39:44. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-06-01 19:39:46 No severe PSQL-related problems occured
01.06.22 19:39:47. End of report.