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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16410956 kB
MemAvailable 31341520 kB
Buffers 0 kB
Cached 15332532 kB
SwapCached 2452 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-08 19:38:16
DTS_END                         2022-06-08 19:38:16.3620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46680
TRN_ID                          237563
ATT_ID                          709
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: 9292.23 18905 1115068
customer order: creation 169.49 348 20339
customer order: refuse 14.61 354 1753
order to supplier: creation 45.23 1199 5428
order to supplier: removal 3.32 112942 398
invoice (draft): creation 44.20 3147 5304
invoice (draft): removal 54.66 791 6559
invoice accept: apply 40.63 17647 4876
invoice accept: cancel 49.39 721 5927
customer reserve: creation 3762.88 174 451545
customer reserve: removal 4818.16 61 578179
realization accept: apply 81.48 167 9778
realization accept: cancel 82.23 114 9867
payment from customer: creation 57.28 114 6873
payment from customer: removal 5.31 92 637
payment to supplier: creation 47.38 149 5685
payment to supplier: removal 3.45 93 414
service: total inventory turnovers 4.90 1147 588
service: total monetary turnovers 6.78 23 814
service: aggregate perf. data 0.83 19306 100
service: refresh index statistics 0.03 219519 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-06-2022 19:39:48 to 08-06-2022 19:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8999
TEST_TIME 2 7017
TEST_TIME 3 7706
TEST_TIME 4 7105
TEST_TIME 5 6471
TEST_TIME 6 6165
TEST_TIME 7 6120
TEST_TIME 8 5965
TEST_TIME 9 6400
TEST_TIME 10 6426
TEST_TIME 11 6377
TEST_TIME 12 6510
TEST_TIME 13 6777
TEST_TIME 14 6710
TEST_TIME 15 6699
TEST_TIME 16 7024
TEST_TIME 17 7594
TEST_TIME 18 8130
TEST_TIME 19 8033
TEST_TIME 20 8047
TEST_TIME 21 8533
TEST_TIME 22 8727
TEST_TIME 23 8612
TEST_TIME 24 8794
TEST_TIME 25 9176
TEST_TIME 26 9839
TEST_TIME 27 10914
TEST_TIME 28 11582
TEST_TIME 29 11287
TEST_TIME 30 11044
TEST_TIME 31 10871
TEST_TIME 32 10697
TEST_TIME 33 10561
TEST_TIME 34 10568
TEST_TIME 35 10505
TEST_TIME 36 10437
TEST_TIME 37 10259
TEST_TIME 38 10147
TEST_TIME 39 10112
TEST_TIME 40 10053
TEST_TIME 41 10040
TEST_TIME 42 9993
TEST_TIME 43 9904
TEST_TIME 44 9835
TEST_TIME 45 9786
TEST_TIME 46 9646
TEST_TIME 47 9733
TEST_TIME 48 9686
TEST_TIME 49 9558
TEST_TIME 50 9602
TEST_TIME 51 9627
TEST_TIME 52 9571
TEST_TIME 53 9527
TEST_TIME 54 9610
TEST_TIME 55 9575
TEST_TIME 56 9569
TEST_TIME 57 9660
TEST_TIME 58 9601
TEST_TIME 59 9640
TEST_TIME 60 9676
TEST_TIME 61 9673
TEST_TIME 62 9706
TEST_TIME 63 9690
TEST_TIME 64 9758
TEST_TIME 65 9632
TEST_TIME 66 9608
TEST_TIME 67 9588
TEST_TIME 68 9562
TEST_TIME 69 9529
TEST_TIME 70 9447
TEST_TIME 71 9386
TEST_TIME 72 9337
TEST_TIME 73 9365
TEST_TIME 74 9412
TEST_TIME 75 9441
TEST_TIME 76 9405
TEST_TIME 77 9401
TEST_TIME 78 9424
TEST_TIME 79 9433
TEST_TIME 80 9487
TEST_TIME 81 9525
TEST_TIME 82 9562
TEST_TIME 83 9589
TEST_TIME 84 9657
TEST_TIME 85 9664
TEST_TIME 86 9729
TEST_TIME 87 9627
TEST_TIME 88 9595
TEST_TIME 89 9548
TEST_TIME 90 9523
TEST_TIME 91 9460
TEST_TIME 92 9439
TEST_TIME 93 9459
TEST_TIME 94 9438
TEST_TIME 95 9393
TEST_TIME 96 9485
TEST_TIME 97 9498
TEST_TIME 98 9477
TEST_TIME 99 9515
TEST_TIME 100 9479
TEST_TIME 101 9433
TEST_TIME 102 9561
TEST_TIME 103 9568
TEST_TIME 104 9552
TEST_TIME 105 9602
TEST_TIME 106 9640
TEST_TIME 107 9671
TEST_TIME 108 9613
TEST_TIME 109 9534
TEST_TIME 110 9536
TEST_TIME 111 9544
TEST_TIME 112 9526
TEST_TIME 113 9483
TEST_TIME 114 9483
TEST_TIME 115 9458
TEST_TIME 116 9423
TEST_TIME 117 9379
TEST_TIME 118 9345
TEST_TIME 119 9338
TEST_TIME 120 9300

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-06-2022 19:39:51 to 08-06-2022 19:39:55.

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 1530898 1530898 0 0.00 0 17789 6 0 0
doc_list_biud 1530894 1530894 0 0.00 0 24674 29 0 0
sp_add_invoice_to_stock 5028 4876 152 3.02 29 180461 17648 0 152
sp_cancel_adding_invoice 5927 5927 0 0.00 5 71291 722 0 0
sp_cancel_client_order 1753 1753 0 0.00 4 10449 354 0 0
sp_cancel_customer_reserve 578179 578179 0 0.00 1 24676 61 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 2 9585 92 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 7900 93 0 0
sp_cancel_supplier_invoice 6575 6559 16 0.24 1 19801 791 0 16
sp_cancel_supplier_order 398 398 0 0.00 5 1189565 112942 0 0
sp_cancel_write_off 9867 9867 0 0.00 1 8310 114 0 0
sp_client_order 20339 20339 0 0.00 4 48577 349 0 0
sp_customer_reserve 451545 451545 0 0.00 0 38048 175 0 0
sp_fill_shopping_cart 31067 31067 0 0.00 1 23971 38 0 0
sp_fill_shopping_cart_clo_res 451530 451530 0 0.00 0 13141 13 0 0
sp_get_clo_for_invoice 4860 4860 0 0.00 0 30598 312 0 0
sp_kill_cost_storno 16845 16845 0 0.00 0 7057 58 0 0
sp_kill_qstorno_ret_qs2qd 1173182 1173182 0 0.00 0 24650 33 0 0
sp_kill_qty_storno 617320 617320 0 0.00 0 24673 71 0 0
sp_lock_selected_doc 20302 20302 0 0.00 0 9576 6 0 0
sp_make_cost_storno 27196 27196 0 0.00 0 17778 62 0 0
sp_multiply_rows_for_pdistr 27196 27196 0 0.00 0 8930 31 0 0
sp_multiply_rows_for_qdistr 20339 20339 0 0.00 0 48566 157 0 0
sp_pay_from_customer 6873 6873 0 0.00 4 10357 114 0 0
sp_pay_to_supplier 5685 5685 0 0.00 5 20021 149 0 0
sp_payment_common 12557 12557 0 0.00 1 10350 31 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 0 10434 330 0 0
sp_qd_handle_on_invoice_upd_sts 10786 10786 0 0.00 0 20321 323 0 0
sp_qd_handle_on_reserve_upd_sts 9778 9778 0 0.00 0 12381 57 0 0
sp_reserve_write_off 9778 9778 0 0.00 6 13055 167 0 0
sp_supplier_invoice 5304 5304 0 0.00 7 78912 3148 0 0
sp_supplier_order 5428 5428 0 0.00 5 40371 1200 0 0
srv_aggregate_perf_data 100 100 0 0.00 2696 240599 19306 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2280 119838 46968 0 0
srv_find_qd_qs_mism 1067309 1067309 0 0.00 0 15417 5 0 0
srv_make_invnt_saldo 588 588 0 0.00 26 146691 1148 0 0
srv_make_money_saldo 814 814 0 0.00 1 1199 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 60500 392253 219519 0 0
t$perf-abend:sp_add_invoice_to_stock 152 152 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4877 4877 0 0.00 0 16139 173 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 5272 75 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 13929 33 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 2220 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 957 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 702 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 3103 24 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 20467 1643 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 1583 7 0 0
t$perf-norm:sp_client_order 20339 20339 0 0.00 0 11997 17 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 8376 19 0 0
t$perf-norm:sp_pay_from_customer 6873 6873 0 0.00 0 6433 11 0 0
t$perf-norm:sp_pay_to_supplier 5685 5685 0 0.00 0 16126 12 0 0
t$perf-norm:sp_reserve_write_off 9778 9778 0 0.00 0 10709 14 0 0
t$perf-norm:sp_supplier_invoice 5304 5304 0 0.00 0 4926 18 0 0
t$perf-norm:sp_supplier_order 5428 5428 0 0.00 0 7638 18 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 429 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 475 9 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 1124 21 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 1073 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10022 9718 304 3.03 0 6291 15 0 304
v_all_customers 37104 37104 0 0.00 0 18 1 0 0
v_all_suppliers 29420 29420 0 0.00 0 79 1 0 0
v_all_wares 40678 40678 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 178 5 0 0
v_cancel_client_order 3506 3506 0 0.00 0 1063 4 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 2798 5 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 489 3 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 143 2 0 0
v_cancel_supplier_invoice 842 810 32 3.80 0 590 15 0 32
v_cancel_supplier_order 796 796 0 0.00 0 431 3 0 0
v_cancel_write_off 1948 1948 0 0.00 0 1144 11 0 0
v_max_id_clo_ord 5428 5428 0 0.00 0 5040 2 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 1030 10 0 0
v_max_id_ord_sup 5300 5300 0 0.00 0 4465 4 0 0
v_max_non_paid_invoice 1702 1702 0 0.00 0 1220 4 0 0
v_max_non_paid_realizn 3645 3645 0 0.00 0 3726 5 0 0
v_min_id_clo_ord 5428 5428 0 0.00 0 2855 7 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 3648 10 0 0
v_min_id_ord_sup 5300 5300 0 0.00 0 8269 7 0 0
v_min_non_paid_invoice 5684 5684 0 0.00 0 7963 21 0 0
v_min_non_paid_realizn 6873 6873 0 0.00 0 8086 23 0 0
v_our_firm 10030 10030 0 0.00 0 14 1 0 0
v_reserve_write_off 19556 19556 0 0.00 0 3308 4 0 0
x_lock_depdocs_on_canc_invoice 12081 12081 0 0.00 0 18646 116 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 6824 400 0 0
x_make_qstorno_1000_1200 5428 5428 0 0.00 2 40362 1116 0 0
x_make_qstorno_1200_2000 5304 5304 0 0.00 4 78131 3018 0 0
x_make_qstorno_2100_3300 451545 451545 0 0.00 0 38047 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-06-2022 19:39:56 to 08-06-2022 19:40:00.

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:20 3 8192000000 8801244336 8813252608 7257136 0.088 2 2 3 0 7398224 66144 74944
17:09:21 28626 8192000000 9904660320 10217631744 988092800 10.763 100 89 178 0 1016376368 5937520 22346048
17:10:52 16951 8192000000 9951123008 10274082816 1037410480 11.240 100 93 186 0 1069428064 8521440 23496144
17:12:21 18693 8192000000 9978576960 10314764288 1063733376 11.492 100 79 158 0 1093814736 9854912 20226448
17:13:45 14448 8192000000 10043681424 10391330816 1120941888 12.036 100 77 153 1 1150197312 11133504 18121920
17:15:04 6167 8192000000 10079564768 10436501504 1163659488 12.438 100 88 176 0 1198331600 14800416 19871696
17:16:13 5689 8192000000 10114533056 10478915584 1206588080 12.837 100 89 177 2 1240545504 13464560 20492864
17:17:21 2191 8192000000 10167134816 10539376640 1242809520 13.172 100 88 175 2 1275779200 13703216 19266464
17:18:24 1534 8192000000 10199301136 10578935808 1283832960 13.548 100 92 184 1 1319886320 15180368 20872992
17:19:26 1467 8192000000 10205710240 10592296960 1291855056 13.621 100 74 150 0 1319504352 11279824 16369472
17:20:28 4257 8192000000 10187504784 10595495936 1299170848 13.688 100 42 84 1 1317275008 8536576 9567584
17:21:33 5190 8192000000 10193831424 10609541120 1310324016 13.789 100 22 44 0 1322069952 6697744 5048192
17:22:42 13767 8192000000 10202835552 10619805696 1316564048 13.846 100 19 39 1 1326852224 6389168 3899008
17:23:59 65360 8192000000 10219785328 10638028800 1323579120 13.909 100 29 58 2 1336234512 5979568 6675824
17:26:05 5135 8192000000 10216732416 10633277440 1309638912 13.783 99 36 73 1 1328738864 10989344 8110608
17:27:12 7153 8192000000 10201720496 10621542400 1294928016 13.649 98 24 48 2 1306356208 5906816 5521376
17:28:20 46392 8192000000 10182480944 10577461248 1204660496 12.820 92 56 112 0 1225681168 7037216 13983456
17:30:10 5201 8192000000 10145679792 10554392576 1221672208 12.977 96 27 53 1 1233758768 6218496 5868064
17:31:19 4847 8192000000 10126008656 10526367744 1203867168 12.812 97 19 38 0 1218228128 9665216 4695744
17:32:25 12793 8192000000 10144664016 10544189440 1246283936 13.204 98 32 64 0 1259475296 5126608 8064752
17:33:38 15393 8192000000 10171062208 10577727488 1276550608 13.482 100 27 54 1 1287881072 5506176 5824288
17:34:54 6667 8192000000 10187190576 10596622336 1290426752 13.608 100 29 58 1 1303007200 6278736 6301712
17:36:01 7843 8192000000 10190450016 10605355008 1299571376 13.691 100 21 42 0 1309858480 5514928 4772176
17:37:09 61447 8192000000 10195649184 10615181312 1309442432 13.781 100 26 52 0 1321061792 5868400 5750960
17:39:17 8221 8192000000 10246782608 10657570816 1341659232 14.072 100 64 126 1 1366858944 11035488 14164224
17:40:32 11392 8192000000 10274943056 10682241024 1353072672 14.175 100 51 102 0 1376031136 11800064 11158400
17:41:48 11433 8192000000 10285769504 10709655552 1370755952 14.334 100 47 93 1 1394527776 13540576 10231248
17:43:11 7521 8192000000 10297340944 10716299264 1382025296 14.435 100 54 106 0 1412007472 18045568 11936608
17:44:29 17877 8192000000 10320008224 10742722560 1394846096 14.549 100 61 121 0 1433629104 24907392 13875616
17:45:51 13091 8192000000 10322868608 10742210560 1391328112 14.518 100 52 104 0 1429419840 25529136 12562592
17:47:09 9297 8192000000 10319423472 10744221696 1386447888 14.474 99 49 98 1 1425908832 27759152 11701792
17:48:23 6786 8192000000 10316511856 10733588480 1359001264 14.228 97 61 120 0 1400739024 27898224 13839536
17:49:33 6801 8192000000 10356663424 10777415680 1412849232 14.709 100 51 102 4 1457883344 32116144 12917968
17:50:48 2280 8192000000 10365389408 10776637440 1414554512 14.724 100 81 160 2 1472818336 38580208 19683616
17:51:54 4618 8192000000 10356246016 10765672448 1403834112 14.629 99 63 124 0 1459408848 39597280 15977456
17:53:04 2950 8192000000 10391650976 10803281920 1425748064 14.824 100 82 164 0 1493350912 46849728 20753120
17:54:10 3979 8192000000 10387366224 10798817280 1414165792 14.721 100 74 142 2 1489446080 56040928 19239360
17:55:17 3706 8192000000 10384115520 10798657536 1407242848 14.659 100 71 142 2 1485043696 58436080 19364768
17:56:26 8994 8192000000 10366562656 10778714112 1397338384 14.571 100 52 104 2 1463123936 51876272 13909280
17:57:42 7529 8192000000 10345983248 10762244096 1378051840 14.399 99 51 102 2 1440486288 48966656 13467792
17:59:09 9835 8192000000 10364289904 10794307584 1400258816 14.597 100 53 106 2 1471916480 56938384 14719280
18:00:21 12682 8192000000 10351351376 10783928320 1395165232 14.552 100 34 68 1 1453959088 49493296 9300560
18:01:39 10301 8192000000 10345535856 10771644416 1385732864 14.468 100 36 73 1 1447916512 51818016 10365632
18:02:57 32523 8192000000 10331307776 10768801792 1362922880 14.264 99 28 56 0 1430194352 58843792 8427680
18:04:30 22538 8192000000 10309497312 10755170304 1348920960 14.138 99 25 50 1 1416755904 60326192 7508752
18:05:56 58260 8192000000 10257790672 10710257664 1283526560 13.545 94 9 18 1 1308748624 23315904 1906160
18:07:55 83598 8192000000 10271674432 10705223680 1344682576 14.100 99 28 54 1 1359226800 8383632 6160592
18:10:22 56384 8192000000 10280634400 10722021376 1351299632 14.159 98 35 70 0 1375471696 16221824 7950240
18:12:26 61335 8192000000 10272493120 10734018560 1373866352 14.362 100 9 18 0 1388983040 13198128 1918560
18:14:47 46702 8192000000 10279187824 10728259584 1356293216 14.204 98 16 32 1 1369149440 8669248 4186976
18:16:45 61070 8192000000 10282884384 10735677440 1371179808 14.338 99 10 19 0 1387554000 13880416 2493776
18:18:58 59901 8192000000 10291765088 10753372160 1388859808 14.496 100 23 46 1 1408777440 14133104 5784528
18:21:24 73938 8192000000 10298019440 10762440704 1387149056 14.480 100 13 26 1 1403711328 13478128 3084144
18:24:21 48964 8192000000 10301670592 10765680640 1403822880 14.629 100 27 55 0 1421994304 12488704 5682720
18:26:26 50617 8192000000 10305042896 10765193216 1407143712 14.659 100 9 18 0 1419537760 9878032 2516016
18:28:18 60614 8192000000 10307021392 10780106752 1408384176 14.670 100 7 14 0 1426255600 16347680 1523744
18:30:18 29294 8192000000 10299153712 10745208832 1369990416 14.327 98 35 66 3 1383930608 6233920 7706272
18:31:49 36043 8192000000 10312681760 10766274560 1402933472 14.621 100 37 74 0 1423041104 11800272 8307360
18:33:27 19311 8192000000 10314687536 10754965504 1392138144 14.525 100 47 93 1 1409331936 6264816 10928976
18:34:50 67320 8192000000 10289273072 10756333568 1360612656 14.243 97 20 40 1 1376767776 11195744 4959376
18:36:59 100571 8192000000 10304383232 10760896512 1336463904 14.026 96 16 32 1 1355753776 16196912 3092960
18:39:39 80325 8192000000 10288151984 10744463360 1311957008 13.804 94 17 34 0 1328608240 12747328 3903904
18:42:00 87427 8192000000 10261949456 10725253120 1282912272 13.540 93 18 36 1 1305800720 18512320 4376128
18:44:28 119838 8192000000 10271690016 10732195840 1337844592 14.038 98 14 28 0 1354894112 14381808 2667712
18:47:30 55491 8192000000 10266063136 10724175872 1339094400 14.049 97 18 36 0 1354032240 11116144 3821696
18:49:48 69228 8192000000 10287189760 10750451712 1367309728 14.303 99 20 40 1 1386087184 13011776 5765680
18:52:34 65847 8192000000 10263831424 10725904384 1329263792 13.961 97 17 32 2 1340000720 6887120 3849808
18:54:51 66707 8192000000 10264072208 10708127744 1306227952 13.752 96 17 34 0 1320975168 10816432 3930784
18:56:58 81455 8192000000 10270714912 10721296384 1312107200 13.805 96 21 42 1 1331196000 13751216 5337584
18:59:41 86526 8192000000 10269325728 10714116096 1323314496 13.907 97 19 38 0 1347622400 19608816 4699088
19:02:28 59972 8192000000 10287399056 10731970560 1356893296 14.209 99 32 64 0 1374771232 10620592 7257344
19:05:43 108689 8192000000 10297323392 10739515392 1375460912 14.376 99 28 56 1 1394089408 12165488 6463008
19:08:32 44469 8192000000 10288623840 10752299008 1377583200 14.395 99 9 18 0 1395964112 16724016 1656896
19:10:17 49673 8192000000 10277387872 10744610816 1359589872 14.234 98 5 10 0 1371739808 11225200 924736
19:12:20 65475 8192000000 10304209616 10765189120 1390334224 14.509 100 21 42 2 1413406496 18426944 4645328
19:14:43 105491 8192000000 10300097984 10753896448 1384879168 14.460 99 14 28 1 1401461232 13192480 3389584
19:17:33 45994 8192000000 10304507584 10751971328 1373330736 14.357 98 21 42 1 1387989408 9310304 5348368
19:19:20 58163 8192000000 10314934816 10771021824 1387998416 14.488 99 20 40 2 1412891696 19551216 5342064
19:21:22 76686 8192000000 10297048416 10741161984 1347839744 14.128 97 16 32 0 1361211680 10012240 3359696
19:23:41 68936 8192000000 10283368720 10744750080 1332546480 13.990 96 15 30 0 1350814960 14888528 3379952
19:25:50 101528 8192000000 10279862720 10727231488 1361445872 14.250 99 17 34 0 1386359216 20738912 4174432
19:29:20 76199 8192000000 10260357536 10732310528 1354222576 14.185 98 14 28 0 1371572000 13824432 3524992
19:31:48 56898 8192000000 10262792768 10726404096 1345222640 14.104 98 10 20 0 1361634128 14264752 2146736
19:33:49 24967 8192000000 10252424592 10708197376 1345005248 14.103 98 23 49 1 1355781744 5503584 5272912
19:35:15 95780 8192000000 10263443152 10726580224 1335444896 14.016 97 18 36 0 1348469520 8853760 4170864
19:37:51 31893 8192000000 9255287504 9536032768 407204896 4.735 30 5 10 0 411632848 3715568 712384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-06-2022 19:40:02 to 08-06-2022 19:40:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 152 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-06-2022 19:40:17 to 08-06-2022 19:40:19.

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          237844
MON$OLDEST_ACTIVE               237845
MON$OLDEST_SNAPSHOT             237845
MON$NEXT_TRANSACTION            237845
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                       2025056
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:179: 
:180: mscs-test-161.budzdorov.ru    Wed Jun  8 19:38:26 2022
:181:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:182: 
:183: 
:184: mscs-test-161.budzdorov.ru    Wed Jun  8 19:38:30 2022
:185:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:186: 
:187: 
:188: mscs-test-161.budzdorov.ru    Wed Jun  8 19:38:32 2022
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:190: 
--- end of diff output ---

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

08.06.22 19:40:20. 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-08 19:40:23 No severe PSQL-related problems occured
08.06.22 19:40:24. End of report.