Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.11.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 15707788 kB
MemAvailable 30788024 kB
Buffers 0 kB
Cached 15964516 kB
SwapCached 2432 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-11-05 22:37:57
DTS_END                         2021-11-05 22:37:57.1830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49730
TRN_ID                          240011
ATT_ID                          694
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: 9392.06 23773 1127047
customer order: creation 170.53 345 20464
customer order: refuse 14.86 363 1783
order to supplier: creation 43.18 1413 5181
order to supplier: removal 3.65 98414 438
invoice (draft): creation 45.01 3530 5401
invoice (draft): removal 58.15 832 6978
invoice accept: apply 40.01 18246 4801
invoice accept: cancel 52.27 828 6272
customer reserve: creation 3587.08 186 430449
customer reserve: removal 5063.40 54 607608
realization accept: apply 82.61 178 9913
realization accept: cancel 103.18 99 12381
payment from customer: creation 59.72 122 7166
payment from customer: removal 5.59 93 671
payment to supplier: creation 47.54 132 5705
payment to supplier: removal 3.24 87 389
service: total inventory turnovers 4.85 1040 582
service: total monetary turnovers 6.46 28 775
service: aggregate perf. data 0.72 26887 86
service: refresh index statistics 0.03 322590 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-11-2021 22:38:44 to 05-11-2021 22:38:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 118
TEST_TIME 2 1273
TEST_TIME 3 2001
TEST_TIME 4 2640
TEST_TIME 5 2814
TEST_TIME 6 3711
TEST_TIME 7 3900
TEST_TIME 8 4504
TEST_TIME 9 4380
TEST_TIME 10 4824
TEST_TIME 11 5369
TEST_TIME 12 5579
TEST_TIME 13 5703
TEST_TIME 14 6047
TEST_TIME 15 6384
TEST_TIME 16 6549
TEST_TIME 17 6939
TEST_TIME 18 7100
TEST_TIME 19 7165
TEST_TIME 20 7398
TEST_TIME 21 7491
TEST_TIME 22 7914
TEST_TIME 23 8466
TEST_TIME 24 9100
TEST_TIME 25 9293
TEST_TIME 26 9232
TEST_TIME 27 9512
TEST_TIME 28 9921
TEST_TIME 29 10529
TEST_TIME 30 10302
TEST_TIME 31 10438
TEST_TIME 32 10329
TEST_TIME 33 10355
TEST_TIME 34 10312
TEST_TIME 35 10200
TEST_TIME 36 10003
TEST_TIME 37 10086
TEST_TIME 38 9887
TEST_TIME 39 9798
TEST_TIME 40 9746
TEST_TIME 41 9765
TEST_TIME 42 9681
TEST_TIME 43 9757
TEST_TIME 44 9769
TEST_TIME 45 9673
TEST_TIME 46 9574
TEST_TIME 47 9563
TEST_TIME 48 9567
TEST_TIME 49 9562
TEST_TIME 50 9574
TEST_TIME 51 9529
TEST_TIME 52 9429
TEST_TIME 53 9444
TEST_TIME 54 9386
TEST_TIME 55 9286
TEST_TIME 56 9164
TEST_TIME 57 9137
TEST_TIME 58 9127
TEST_TIME 59 9096
TEST_TIME 60 9058
TEST_TIME 61 9118
TEST_TIME 62 9075
TEST_TIME 63 9227
TEST_TIME 64 9263
TEST_TIME 65 9249
TEST_TIME 66 9290
TEST_TIME 67 9329
TEST_TIME 68 9352
TEST_TIME 69 9444
TEST_TIME 70 9548
TEST_TIME 71 9592
TEST_TIME 72 9576
TEST_TIME 73 9638
TEST_TIME 74 9563
TEST_TIME 75 9545
TEST_TIME 76 9494
TEST_TIME 77 9462
TEST_TIME 78 9411
TEST_TIME 79 9355
TEST_TIME 80 9383
TEST_TIME 81 9316
TEST_TIME 82 9280
TEST_TIME 83 9307
TEST_TIME 84 9356
TEST_TIME 85 9345
TEST_TIME 86 9298
TEST_TIME 87 9272
TEST_TIME 88 9333
TEST_TIME 89 9320
TEST_TIME 90 9346
TEST_TIME 91 9367
TEST_TIME 92 9363
TEST_TIME 93 9382
TEST_TIME 94 9461
TEST_TIME 95 9447
TEST_TIME 96 9556
TEST_TIME 97 9603
TEST_TIME 98 9626
TEST_TIME 99 9580
TEST_TIME 100 9564
TEST_TIME 101 9487
TEST_TIME 102 9526
TEST_TIME 103 9477
TEST_TIME 104 9487
TEST_TIME 105 9438
TEST_TIME 106 9420
TEST_TIME 107 9393
TEST_TIME 108 9334
TEST_TIME 109 9280
TEST_TIME 110 9263
TEST_TIME 111 9387
TEST_TIME 112 9369
TEST_TIME 113 9364
TEST_TIME 114 9368
TEST_TIME 115 9357
TEST_TIME 116 9354
TEST_TIME 117 9348
TEST_TIME 118 9434
TEST_TIME 119 9413
TEST_TIME 120 9392

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-11-2021 22:38:47 to 05-11-2021 22:38:51.

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 1529404 1529404 0 0.00 0 18033 6 0 0
doc_list_biud 1529401 1529401 0 0.00 0 26381 28 0 0
sp_add_invoice_to_stock 4895 4801 94 1.92 42 225932 18247 0 94
sp_cancel_adding_invoice 6272 6272 0 0.00 4 59554 828 0 0
sp_cancel_client_order 1783 1783 0 0.00 4 17957 364 0 0
sp_cancel_customer_reserve 607608 607608 0 0.00 1 23503 55 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 2 4095 94 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 2949 87 0 0
sp_cancel_supplier_invoice 6990 6978 12 0.17 1 26396 833 0 12
sp_cancel_supplier_order 438 438 0 0.00 7 1140139 98414 0 0
sp_cancel_write_off 12381 12381 0 0.00 1 8475 99 0 0
sp_client_order 20464 20464 0 0.00 3 14181 346 0 0
sp_customer_reserve 430449 430449 0 0.00 0 50812 186 0 0
sp_fill_shopping_cart 31043 31043 0 0.00 1 11539 39 0 0
sp_fill_shopping_cart_clo_res 430449 430449 0 0.00 0 15329 15 0 0
sp_get_clo_for_invoice 4801 4801 0 0.00 0 13351 288 0 0
sp_kill_cost_storno 19713 19713 0 0.00 0 5851 51 0 0
sp_kill_qstorno_ret_qs2qd 1235013 1235013 0 0.00 0 26374 30 0 0
sp_kill_qty_storno 650172 650172 0 0.00 0 26379 66 0 0
sp_lock_selected_doc 20488 20488 0 0.00 0 4827 3 0 0
sp_make_cost_storno 27585 27585 0 0.00 0 10627 46 0 0
sp_multiply_rows_for_pdistr 27582 27582 0 0.00 0 7216 32 0 0
sp_multiply_rows_for_qdistr 20464 20464 0 0.00 0 10493 159 0 0
sp_pay_from_customer 7166 7166 0 0.00 4 15521 122 0 0
sp_pay_to_supplier 5705 5705 0 0.00 4 11870 132 0 0
sp_payment_common 12870 12870 0 0.00 1 15511 24 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 17948 335 0 0
sp_qd_handle_on_invoice_upd_sts 11071 11071 0 0.00 0 21765 347 0 0
sp_qd_handle_on_reserve_upd_sts 9913 9913 0 0.00 0 15322 64 0 0
sp_reserve_write_off 9913 9913 0 0.00 6 15345 179 0 0
sp_supplier_invoice 5401 5401 0 0.00 6 96471 3530 0 0
sp_supplier_order 5181 5181 0 0.00 6 50523 1414 0 0
srv_aggregate_perf_data 86 86 0 0.00 2926 515674 26888 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1828 115057 43326 0 0
srv_find_qd_qs_mism 1082823 1082823 0 0.00 0 18017 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 39 55803 1041 0 0
srv_make_money_saldo 775 775 0 0.00 1 749 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 231400 390257 322590 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 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 4801 4801 0 0.00 0 15751 186 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 3453 102 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 3585 12 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 5674 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 1647 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 1366 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 829 18 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 32810 1758 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 2728 10 0 0
t$perf-norm:sp_client_order 20464 20464 0 0.00 0 11824 18 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 1084 7 0 0
t$perf-norm:sp_pay_from_customer 7166 7166 0 0.00 0 6036 9 0 0
t$perf-norm:sp_pay_to_supplier 5705 5705 0 0.00 0 5768 13 0 0
t$perf-norm:sp_reserve_write_off 9913 9913 0 0.00 0 16089 12 0 0
t$perf-norm:sp_supplier_invoice 5401 5401 0 0.00 0 7674 23 0 0
t$perf-norm:sp_supplier_order 5181 5181 0 0.00 0 5388 19 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 133 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 382 8 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 790 21 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 2526 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9786 9598 188 1.92 0 5151 22 0 188
v_all_customers 37944 37944 0 0.00 0 124 1 0 0
v_all_suppliers 29208 29208 0 0.00 0 19 1 0 0
v_all_wares 40928 40928 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 2490 14 0 0
v_cancel_client_order 3566 3566 0 0.00 0 2607 8 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 4080 18 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 2129 11 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 2939 8 0 0
v_cancel_supplier_invoice 854 830 24 2.81 0 2348 22 0 24
v_cancel_supplier_order 876 876 0 0.00 0 2350 15 0 0
v_cancel_write_off 1896 1896 0 0.00 0 2356 16 0 0
v_max_id_clo_ord 5179 5179 0 0.00 0 2488 2 0 0
v_max_id_clo_res 961 961 0 0.00 0 1754 15 0 0
v_max_id_ord_sup 5399 5399 0 0.00 0 5763 3 0 0
v_max_non_paid_invoice 1679 1679 0 0.00 0 184 2 0 0
v_max_non_paid_realizn 3612 3612 0 0.00 0 15504 9 0 0
v_min_id_clo_ord 5179 5179 0 0.00 0 2694 6 0 0
v_min_id_clo_res 961 961 0 0.00 0 5199 14 0 0
v_min_id_ord_sup 5399 5399 0 0.00 0 4772 7 0 0
v_min_non_paid_invoice 5705 5705 0 0.00 0 4485 13 0 0
v_min_non_paid_realizn 7165 7165 0 0.00 0 12969 18 0 0
v_our_firm 10090 10090 0 0.00 0 16 1 0 0
v_reserve_write_off 19826 19826 0 0.00 0 5282 10 0 0
x_lock_depdocs_on_canc_invoice 12835 12835 0 0.00 0 19838 126 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 3345 320 0 0
x_make_qstorno_1000_1200 5181 5181 0 0.00 2 50516 1329 0 0
x_make_qstorno_1200_2000 5401 5401 0 0.00 3 93064 3391 0 0
x_make_qstorno_2100_3300 430449 430449 0 0.00 0 50809 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-11-2021 22:38:52 to 05-11-2021 22:38:56.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:01 16 8192000000 8814450128 8830820352 17782384 0.216 5 4 6 0 18021056 89376 149296
20:09:02 35189 8192000000 9927720576 10245918720 1005913680 10.936 100 91 182 1 1036200000 6766368 23519952
20:10:49 18981 8192000000 9962994048 10290589696 1045986640 11.322 100 90 180 0 1078615136 9860880 22767616
20:12:19 13731 8192000000 9997674816 10337050624 1078994464 11.638 100 82 164 0 1109933088 10907648 20030976
20:13:44 10830 8192000000 10053470160 10407239680 1136648464 12.184 100 81 162 0 1168887808 13021904 19217440
20:15:02 6274 8192000000 10107981488 10474037248 1194718976 12.727 100 80 160 1 1226441280 13378048 18344256
20:16:09 2471 8192000000 10145348480 10516062208 1235782992 13.107 100 94 187 0 1270114032 14159824 20171216
20:17:14 1667 8192000000 10174443680 10553434112 1267355184 13.397 100 87 174 0 1300152000 12897376 19899440
20:18:17 3036 8192000000 10211099248 10595012608 1294882096 13.649 100 88 176 0 1328223936 13168640 20173200
20:19:21 6463 8192000000 10187014336 10598252544 1303433760 13.726 100 44 88 1 1320250160 6276896 10539504
20:20:30 8930 8192000000 10197462256 10613350400 1314084880 13.823 100 20 41 0 1323976032 5599152 4292000
20:21:39 8346 8192000000 10209251328 10628075520 1323376400 13.907 100 24 48 1 1335129696 6245568 5507728
20:22:47 70922 8192000000 10223758176 10645929984 1332268576 13.988 100 30 60 0 1344916976 5423840 7224560
20:24:58 5696 8192000000 10231072320 10653523968 1336812016 14.029 100 31 63 2 1350090784 6060512 7218256
20:26:09 4173 8192000000 10222052544 10644963328 1334094560 14.004 100 31 61 1 1347682672 6376992 7211120
20:27:16 22664 8192000000 10182204800 10595139584 1209965280 12.869 92 13 26 0 1217408192 4670096 2772816
20:28:41 28121 8192000000 10189063824 10587934720 1214515040 12.911 93 39 75 1 1232650176 9279952 8855184
20:30:13 4163 8192000000 10149586896 10549297152 1256702704 13.300 100 33 66 0 1269881872 5264208 7914960
20:31:19 8950 8192000000 10139965040 10545291264 1232013680 13.073 98 15 31 0 1240316064 4872128 3430256
20:32:28 11931 8192000000 10164594592 10565115904 1263722336 13.364 100 20 40 0 1273874336 5031824 5120176
20:33:44 35886 8192000000 10166320128 10575495168 1280502608 13.518 100 27 54 2 1292112352 6211792 5397952
20:35:23 8181 8192000000 10184664496 10591916032 1289647200 13.601 100 32 64 3 1303605152 6645360 7312592
20:36:32 8212 8192000000 10186248320 10599727104 1294356848 13.644 100 27 54 2 1306423744 4851824 7215072
20:37:41 91719 8192000000 10220263712 10628284416 1306533584 13.755 100 25 50 1 1318806416 5916032 6356800
20:40:21 39694 8192000000 10237227680 10658942976 1341633952 14.072 100 48 95 1 1362048224 9073440 11340832
20:42:21 28141 8192000000 10271916144 10691022848 1352499072 14.170 99 50 100 2 1377881408 14063216 11319120
20:44:06 15998 8192000000 10282109680 10703073280 1354271088 14.186 98 40 80 0 1375607472 11476800 9859584
20:45:30 2033 8192000000 10304235408 10722230272 1389255568 14.499 100 58 112 3 1417953696 15180960 13517168
20:46:36 9951 8192000000 10317919056 10737491968 1394553728 14.546 100 58 116 0 1427686224 19925648 13206848
20:47:55 4032 8192000000 10304561072 10722267136 1366615376 14.297 98 58 116 0 1406211888 25384480 14212032
20:49:02 4527 8192000000 10321413344 10735165440 1385975088 14.470 100 73 146 0 1429232992 25538832 17719072
20:50:11 5847 8192000000 10324378000 10743132160 1395115808 14.551 100 62 124 0 1439863968 28757600 15990560
20:51:22 3182 8192000000 10331529440 10742538240 1389268800 14.499 100 79 157 0 1437176272 28320736 19586736
20:52:33 1822 8192000000 10339298240 10743717888 1390566256 14.511 100 81 159 2 1444573056 32987552 21019248
20:53:39 3407 8192000000 10338541280 10738077696 1372119200 14.346 99 85 165 2 1429939888 36449040 21371648
20:54:49 4990 8192000000 10334579952 10740981760 1385801424 14.468 100 77 154 1 1443328448 38553328 18973696
20:55:58 7426 8192000000 10345012192 10755428352 1390711040 14.512 100 70 140 2 1446909344 38227712 17970592
20:57:11 4858 8192000000 10315180832 10724196352 1367625072 14.306 100 73 146 1 1426117488 39471488 19020928
20:58:26 10233 8192000000 10320147984 10740494336 1360570176 14.242 99 46 92 0 1422177440 48695088 12912176
20:59:44 5150 8192000000 10327190320 10737684480 1360043712 14.238 100 60 120 0 1430644480 54657168 15943600
21:00:54 8773 8192000000 10330411664 10756222976 1378728944 14.405 100 40 80 0 1435245024 45587264 10928816
21:02:06 8465 8192000000 10322290224 10753314816 1387763840 14.486 100 38 75 1 1424091072 26083120 10244112
21:03:18 16833 8192000000 10312173712 10750328832 1384561264 14.457 100 28 56 0 1420419056 28300304 7557488
21:05:00 20148 8192000000 10297048800 10742501376 1359101104 14.229 99 22 45 0 1398811232 33348880 6361248
21:06:22 62494 8192000000 10272902976 10716782592 1324709504 13.919 97 13 27 1 1349199584 21548176 2941904
21:08:28 74847 8192000000 10311052160 10752983040 1378532928 14.403 100 11 22 2 1401790816 20380112 2877776
21:10:46 87226 8192000000 10287209296 10726088704 1370312688 14.330 99 29 57 1 1385022816 8920080 5790048
21:13:15 75377 8192000000 10279387712 10725580800 1354046096 14.184 98 44 88 3 1374793472 11104192 9643184
21:15:32 37422 8192000000 10286872416 10736738304 1378097248 14.400 100 27 54 0 1399976160 14891648 6987264
21:17:12 23346 8192000000 10282248352 10736541696 1368347216 14.312 99 24 48 0 1386179200 11806608 6025376
21:18:36 34766 8192000000 10292285264 10743984128 1354131984 14.185 98 24 48 1 1377265712 17401088 5732640
21:20:19 52625 8192000000 10292910032 10752823296 1381095152 14.426 100 16 32 0 1409214112 24034144 4084816
21:22:12 35261 8192000000 10281573728 10740310016 1357224864 14.212 98 11 22 1 1370948496 11313776 2409856
21:23:49 78564 8192000000 10310947552 10767450112 1382291568 14.437 99 22 44 0 1402161376 14139888 5729920
21:26:25 83040 8192000000 10311868256 10778062848 1402958544 14.621 100 21 42 1 1420933792 12662720 5312528
21:28:48 92800 8192000000 10296333136 10763780096 1382123840 14.436 98 22 44 1 1399839184 12620224 5095120
21:31:34 48132 8192000000 10284990048 10741903360 1364585552 14.279 98 13 26 0 1377455648 9629296 3240800
21:33:32 57233 8192000000 10275290640 10745737216 1388997344 14.497 100 12 24 0 1400788288 9061344 2729600
21:35:49 49790 8192000000 10281655488 10746159104 1363053728 14.265 98 12 24 1 1375869888 10027712 2788448
21:37:59 62994 8192000000 10295894640 10759864320 1355575936 14.198 97 26 52 0 1380523504 18539696 6407872
21:40:30 76421 8192000000 10266568576 10722811904 1258190816 13.313 91 12 24 0 1274432784 13554480 2687488
21:42:49 75138 8192000000 10283285872 10739961856 1332212752 13.987 97 16 32 0 1357218736 20260080 4745904
21:45:05 42508 8192000000 10307064032 10756120576 1363774864 14.271 98 34 68 1 1391666720 20144560 7747296
21:46:48 65648 8192000000 10276195200 10728857600 1319798016 13.875 96 12 24 0 1344550784 21851456 2901312
21:49:02 29765 8192000000 10272456816 10708684800 1322521744 13.900 97 30 60 2 1342536496 12317488 7697264
21:50:32 91510 8192000000 10263451184 10716790784 1351764672 14.163 100 12 24 0 1372808368 18529872 2513824
21:53:29 2493 8192000000 10263759744 10720129024 1348613856 14.135 99 16 32 0 1362706128 10930608 3161664
21:54:33 38380 8192000000 10277843856 10735030272 1376315504 14.384 100 13 26 0 1386261536 7189632 2756400
21:56:15 67295 8192000000 10292812960 10748170240 1372159728 14.346 99 11 22 0 1385256400 10452656 2644016
21:58:28 63422 8192000000 10298357184 10762797056 1388786864 14.495 100 20 40 1 1419688224 26389248 4512112
22:00:39 55471 8192000000 10296975296 10758606848 1388833664 14.495 100 26 52 1 1410389552 15360736 6195152
22:02:35 53115 8192000000 10298654928 10767314944 1386295424 14.473 99 16 31 1 1402854032 12213232 4345376
22:04:44 26834 8192000000 10291629088 10741084160 1359468288 14.233 98 27 54 2 1376351184 10644976 6237920
22:06:13 47284 8192000000 10281138720 10730491904 1352850704 14.173 98 29 58 2 1371934496 12120592 6963200
22:08:03 81567 8192000000 10302901104 10753269760 1348238336 14.132 97 18 36 0 1363260656 11236064 3786256
22:10:27 75261 8192000000 10301886416 10767400960 1372477968 14.349 98 19 38 1 1390017904 13680768 3859168
22:13:28 77049 8192000000 10321733344 10781540352 1398072016 14.578 100 8 16 0 1414538192 15069184 1396992
22:15:46 30995 8192000000 10317647680 10751549440 1363344736 14.267 97 47 94 0 1385721872 11239456 11137680
22:17:18 17867 8192000000 10319483696 10761818112 1390987968 14.515 100 47 94 0 1416391520 14844352 10559200
22:18:42 110005 8192000000 10300671872 10746134528 1353168848 14.176 97 35 70 2 1371612592 10328016 8115728
22:21:33 32644 8192000000 10276556848 10737766400 1375506400 14.376 99 40 84 5 1389948560 4687600 9754560
22:23:25 72097 8192000000 10286896256 10747400192 1365416848 14.286 98 9 18 0 1375025456 7361696 2246912
22:25:40 71657 8192000000 10317525600 10780921856 1388358464 14.491 99 17 34 0 1415728896 23394384 3976048
22:28:17 115045 8192000000 10281276640 10741198848 1338459296 14.044 96 14 28 0 1361699072 19210240 4029536
22:31:16 24868 8192000000 10277342800 10718339072 1341373280 14.070 98 33 66 1 1358137488 8939872 7824336
22:32:53 28470 8192000000 10281100304 10726866944 1353234192 14.177 99 20 41 1 1370864608 13046720 4583696
22:34:24 40309 8192000000 10293718032 10740789248 1359014624 14.229 99 28 56 1 1380373152 15003952 6354576
22:36:09 49845 8192000000 10281118928 10719670272 1328827344 13.957 97 36 72 1 1347378752 10949152 7602256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-11-2021 22:38:58 to 05-11-2021 22:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 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 05-11-2021 22:39:14 to 05-11-2021 22:39:16.

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          236123
MON$OLDEST_ACTIVE               241781
MON$OLDEST_SNAPSHOT             241781
MON$NEXT_TRANSACTION            241781
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                       2065632
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:124: 
:125: mscs-test-161.budzdorov.ru    Fri Nov  5 22:38:02 2021
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Fri Nov  5 22:38:03 2021
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Fri Nov  5 22:38:04 2021
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:135: 
--- end of diff output ---

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

05.11.21 22:39:17. 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-11-05 22:39:19 No severe PSQL-related problems occured
05.11.21 22:39:20. End of report.