Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.05.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 16506952 kB
MemAvailable 31470876 kB
Buffers 0 kB
Cached 15235772 kB
SwapCached 2652 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-05-16 19:38:15
DTS_END                         2022-05-16 19:38:15.2010
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60814
TRN_ID                          245203
ATT_ID                          726
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: 9247.57 23795 1109708
customer order: creation 171.44 332 20573
customer order: refuse 14.80 365 1776
order to supplier: creation 47.28 1283 5674
order to supplier: removal 3.49 102493 419
invoice (draft): creation 46.20 3418 5544
invoice (draft): removal 52.23 774 6268
invoice accept: apply 42.45 16883 5094
invoice accept: cancel 47.09 761 5651
customer reserve: creation 3846.54 170 461585
customer reserve: removal 4684.75 61 562170
realization accept: apply 82.41 186 9889
realization accept: cancel 77.51 109 9301
payment from customer: creation 60.39 111 7247
payment from customer: removal 5.55 65 666
payment to supplier: creation 49.81 130 5977
payment to supplier: removal 3.45 56 414
service: total inventory turnovers 4.72 1062 566
service: total monetary turnovers 6.61 26 793
service: aggregate perf. data 0.81 20510 97
service: refresh index statistics 0.03 327113 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-05-2022 19:39:45 to 16-05-2022 19:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4343
TEST_TIME 2 6521
TEST_TIME 3 5643
TEST_TIME 4 6221
TEST_TIME 5 5865
TEST_TIME 6 5486
TEST_TIME 7 5619
TEST_TIME 8 5658
TEST_TIME 9 5984
TEST_TIME 10 6426
TEST_TIME 11 6350
TEST_TIME 12 6726
TEST_TIME 13 6834
TEST_TIME 14 6955
TEST_TIME 15 7146
TEST_TIME 16 7359
TEST_TIME 17 8494
TEST_TIME 18 8292
TEST_TIME 19 8859
TEST_TIME 20 8961
TEST_TIME 21 9158
TEST_TIME 22 9279
TEST_TIME 23 9686
TEST_TIME 24 9801
TEST_TIME 25 9737
TEST_TIME 26 10053
TEST_TIME 27 11256
TEST_TIME 28 10947
TEST_TIME 29 10800
TEST_TIME 30 10620
TEST_TIME 31 10431
TEST_TIME 32 10365
TEST_TIME 33 10331
TEST_TIME 34 10154
TEST_TIME 35 10364
TEST_TIME 36 10285
TEST_TIME 37 10110
TEST_TIME 38 9998
TEST_TIME 39 10111
TEST_TIME 40 10042
TEST_TIME 41 9909
TEST_TIME 42 9833
TEST_TIME 43 9632
TEST_TIME 44 9674
TEST_TIME 45 9674
TEST_TIME 46 9637
TEST_TIME 47 9551
TEST_TIME 48 9505
TEST_TIME 49 9406
TEST_TIME 50 9374
TEST_TIME 51 9366
TEST_TIME 52 9336
TEST_TIME 53 9523
TEST_TIME 54 9487
TEST_TIME 55 9486
TEST_TIME 56 9530
TEST_TIME 57 9573
TEST_TIME 58 9526
TEST_TIME 59 9494
TEST_TIME 60 9494
TEST_TIME 61 9606
TEST_TIME 62 9631
TEST_TIME 63 9636
TEST_TIME 64 9691
TEST_TIME 65 9684
TEST_TIME 66 9705
TEST_TIME 67 9606
TEST_TIME 68 9669
TEST_TIME 69 9682
TEST_TIME 70 9610
TEST_TIME 71 9573
TEST_TIME 72 9561
TEST_TIME 73 9490
TEST_TIME 74 9486
TEST_TIME 75 9394
TEST_TIME 76 9449
TEST_TIME 77 9424
TEST_TIME 78 9447
TEST_TIME 79 9436
TEST_TIME 80 9432
TEST_TIME 81 9479
TEST_TIME 82 9517
TEST_TIME 83 9489
TEST_TIME 84 9437
TEST_TIME 85 9499
TEST_TIME 86 9557
TEST_TIME 87 9571
TEST_TIME 88 9567
TEST_TIME 89 9626
TEST_TIME 90 9665
TEST_TIME 91 9602
TEST_TIME 92 9548
TEST_TIME 93 9493
TEST_TIME 94 9508
TEST_TIME 95 9446
TEST_TIME 96 9395
TEST_TIME 97 9399
TEST_TIME 98 9381
TEST_TIME 99 9360
TEST_TIME 100 9403
TEST_TIME 101 9388
TEST_TIME 102 9376
TEST_TIME 103 9405
TEST_TIME 104 9406
TEST_TIME 105 9422
TEST_TIME 106 9459
TEST_TIME 107 9444
TEST_TIME 108 9504
TEST_TIME 109 9474
TEST_TIME 110 9556
TEST_TIME 111 9503
TEST_TIME 112 9430
TEST_TIME 113 9396
TEST_TIME 114 9396
TEST_TIME 115 9348
TEST_TIME 116 9318
TEST_TIME 117 9298
TEST_TIME 118 9304
TEST_TIME 119 9328
TEST_TIME 120 9255

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-05-2022 19:39:49 to 16-05-2022 19:39:52.

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 1537894 1537894 0 0.00 0 31000 6 0 0
doc_list_biud 1537892 1537892 0 0.00 0 23574 28 0 0
sp_add_invoice_to_stock 5211 5094 117 2.24 19 186358 16884 0 117
sp_cancel_adding_invoice 5651 5651 0 0.00 3 49130 762 0 0
sp_cancel_client_order 1776 1776 0 0.00 4 17461 366 0 0
sp_cancel_customer_reserve 562170 562170 0 0.00 1 18404 61 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 3438 66 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 2312 57 0 0
sp_cancel_supplier_invoice 6275 6268 7 0.11 1 23581 775 0 7
sp_cancel_supplier_order 419 419 0 0.00 6 974595 102493 0 0
sp_cancel_write_off 9301 9301 0 0.00 1 18107 109 0 0
sp_client_order 20573 20573 0 0.00 3 17581 332 0 0
sp_customer_reserve 461585 461585 0 0.00 0 65455 170 0 0
sp_fill_shopping_cart 31773 31773 0 0.00 1 19981 38 0 0
sp_fill_shopping_cart_clo_res 461570 461570 0 0.00 0 15480 13 0 0
sp_get_clo_for_invoice 5078 5078 0 0.00 0 12637 303 0 0
sp_kill_cost_storno 16032 16032 0 0.00 0 17548 56 0 0
sp_kill_qstorno_ret_qs2qd 1140328 1140328 0 0.00 0 23572 33 0 0
sp_kill_qty_storno 600551 600551 0 0.00 0 23573 71 0 0
sp_lock_selected_doc 20881 20881 0 0.00 0 6983 4 0 0
sp_make_cost_storno 28190 28190 0 0.00 0 14132 54 0 0
sp_multiply_rows_for_pdistr 28190 28190 0 0.00 0 7333 24 0 0
sp_multiply_rows_for_qdistr 20573 20573 0 0.00 0 10399 152 0 0
sp_pay_from_customer 7247 7247 0 0.00 4 12954 111 0 0
sp_pay_to_supplier 5977 5977 0 0.00 5 15800 130 0 0
sp_payment_common 13224 13224 0 0.00 1 15796 37 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 17422 331 0 0
sp_qd_handle_on_invoice_upd_sts 10728 10728 0 0.00 0 17478 301 0 0
sp_qd_handle_on_reserve_upd_sts 9889 9889 0 0.00 0 14175 71 0 0
sp_reserve_write_off 9889 9889 0 0.00 6 14241 186 0 0
sp_supplier_invoice 5544 5544 0 0.00 6 102900 3419 0 0
sp_supplier_order 5674 5674 0 0.00 5 41783 1284 0 0
srv_aggregate_perf_data 97 97 0 0.00 2473 226626 20510 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1369 148047 49215 0 0
srv_find_qd_qs_mism 1062081 1062081 0 0.00 0 30961 5 0 0
srv_make_invnt_saldo 566 566 0 0.00 47 59768 1063 0 0
srv_make_money_saldo 793 793 0 0.00 1 2663 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 140271 510733 327114 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5094 5094 0 0.00 0 23130 171 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 19905 166 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 2569 12 0 0
t$perf-norm:sp_cancel_customer_reserve 735 735 0 0.00 0 14234 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 3822 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 1885 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 2794 14 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 45586 1701 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 14962 34 0 0
t$perf-norm:sp_client_order 20573 20573 0 0.00 0 8679 18 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 2467 8 0 0
t$perf-norm:sp_pay_from_customer 7247 7247 0 0.00 0 21862 14 0 0
t$perf-norm:sp_pay_to_supplier 5977 5977 0 0.00 0 19329 15 0 0
t$perf-norm:sp_reserve_write_off 9889 9889 0 0.00 0 17017 15 0 0
t$perf-norm:sp_supplier_invoice 5544 5544 0 0.00 0 19371 21 0 0
t$perf-norm:sp_supplier_order 5674 5674 0 0.00 0 4121 19 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 86 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 15 1 0 0
t$perf-norm:srv_make_invnt_saldo 566 566 0 0.00 0 708 11 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 1717 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10388 10154 234 2.25 0 10086 21 0 234
v_all_customers 37898 37898 0 0.00 0 133 0 0 0
v_all_suppliers 31318 31318 0 0.00 0 18 1 0 0
v_all_wares 41146 41146 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 4532 14 0 0
v_cancel_client_order 3552 3552 0 0.00 0 6110 11 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 1804 7 0 0
v_cancel_customer_reserve 1470 1470 0 0.00 0 606 3 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 2290 10 0 0
v_cancel_supplier_invoice 858 844 14 1.63 0 2058 18 0 14
v_cancel_supplier_order 838 838 0 0.00 0 2322 6 0 0
v_cancel_write_off 2078 2078 0 0.00 0 2938 13 0 0
v_max_id_clo_ord 5674 5674 0 0.00 0 2210 2 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 3130 19 0 0
v_max_id_ord_sup 5526 5526 0 0.00 0 3757 3 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 8320 7 0 0
v_max_non_paid_realizn 3755 3755 0 0.00 0 5289 6 0 0
v_min_id_clo_ord 5674 5674 0 0.00 0 4389 5 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 2255 7 0 0
v_min_id_ord_sup 5526 5526 0 0.00 0 4445 6 0 0
v_min_non_paid_invoice 5977 5977 0 0.00 0 15793 30 0 0
v_min_non_paid_realizn 7247 7247 0 0.00 0 12941 30 0 0
v_our_firm 10232 10232 0 0.00 0 136 1 0 0
v_reserve_write_off 19778 19778 0 0.00 0 5768 8 0 0
x_lock_depdocs_on_canc_invoice 11497 11497 0 0.00 0 19760 136 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 15698 343 0 0
x_make_qstorno_1000_1200 5674 5674 0 0.00 2 41776 1211 0 0
x_make_qstorno_1200_2000 5544 5544 0 0.00 3 102696 3274 0 0
x_make_qstorno_2100_3300 461585 461585 0 0.00 0 65454 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-05-2022 19:39:53 to 16-05-2022 19:39:57.

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:18 5 8192000000 8799328512 8810758144 5315664 0.064 2 1 2 0 5415264 56816 42784
17:09:19 19988 8192000000 9897118736 10203574272 975316128 10.639 100 84 168 0 1003082496 5504272 22262096
17:10:42 35765 8192000000 9946261808 10268168192 1022747360 11.099 100 86 172 0 1053125504 8024480 22353664
17:12:22 19904 8192000000 9981968016 10314387456 1065782736 11.512 100 85 170 0 1096136544 9222128 21131680
17:13:47 15264 8192000000 10042904368 10390351872 1122695744 12.052 100 80 158 1 1151792352 10405904 18690704
17:15:06 7888 8192000000 10092359184 10448994304 1175557792 12.549 100 79 158 1 1206340256 13084304 17698160
17:16:15 5013 8192000000 10143455488 10513899520 1221887168 12.979 100 85 168 2 1258117120 17975664 18254288
17:17:23 2009 8192000000 10174875680 10551783424 1262025760 13.349 100 91 182 1 1295275136 13504496 19744880
17:18:26 1218 8192000000 10209838288 10590932992 1296781408 13.666 100 94 189 0 1329791472 12319904 20690160
17:19:28 2852 8192000000 10217886912 10607763456 1304503328 13.736 100 65 128 1 1330483648 11335424 14644896
17:20:32 8690 8192000000 10192538096 10607280128 1306184560 13.751 100 24 47 2 1318017536 6549552 5283424
17:21:42 6086 8192000000 10209551392 10622799872 1319395920 13.871 100 25 50 0 1330639968 6290096 4953952
17:22:49 7584 8192000000 10204233888 10626936832 1323410448 13.908 100 17 35 1 1333515024 6498656 3605920
17:23:57 40656 8192000000 10227765664 10643091456 1328626752 13.955 100 37 74 1 1343357168 6204352 8526064
17:25:38 6667 8192000000 10235790624 10652577792 1333549632 13.999 100 22 44 1 1344262944 6246592 4466720
17:26:45 6043 8192000000 10226938704 10650120192 1337483200 14.035 100 11 22 0 1345894912 5910800 2500912
17:27:53 10344 8192000000 10202052640 10616659968 1290662256 13.610 98 23 45 1 1304784272 9303328 4818688
17:29:05 24469 8192000000 10128387344 10529566720 1123898032 12.064 86 31 60 2 1140203808 9144544 7161232
17:30:30 7428 8192000000 10144514656 10544656384 1214278864 12.909 96 29 58 0 1227343264 6444448 6619952
17:31:40 5475 8192000000 10136218064 10535575552 1244177952 13.185 100 28 57 1 1257487952 7113024 6196976
17:32:48 52285 8192000000 10178687696 10566438912 1254980832 13.284 99 54 105 1 1274020992 5823728 13216432
17:34:41 3491 8192000000 10188116208 10592649216 1283527744 13.545 100 39 78 3 1299867904 6853728 9486432
17:35:45 9451 8192000000 10195589408 10612600832 1306095600 13.751 100 14 28 0 1314426032 5311232 3019200
17:36:55 21658 8192000000 10213302816 10625351680 1317059072 13.850 100 21 43 0 1327563072 5490368 5013632
17:38:17 11110 8192000000 10237162560 10642599936 1329044816 13.959 100 48 96 1 1345723200 5500192 11178192
17:39:35 46503 8192000000 10274835568 10688765952 1355758208 14.199 100 46 92 0 1376226864 9917536 10551120
17:41:24 17180 8192000000 10278807152 10693365760 1356725744 14.208 99 49 94 3 1381598240 13051264 11821232
17:42:48 26330 8192000000 10297219024 10722230272 1383684832 14.449 100 36 72 1 1404320864 12310720 8325312
17:44:17 9621 8192000000 10315650048 10734768128 1396242656 14.562 100 58 116 0 1426506752 16433104 13830992
17:45:29 12560 8192000000 10310046048 10736803840 1394741664 14.548 100 50 100 1 1427047648 19608640 12697344
17:46:51 5600 8192000000 10347638352 10762403840 1400571488 14.600 99 79 154 2 1446264848 27446112 18247248
17:48:08 6522 8192000000 10340506768 10770579456 1412548368 14.707 100 44 88 1 1450771616 27426400 10796848
17:49:18 4124 8192000000 10335160832 10756964352 1407199152 14.659 100 51 100 1 1444769760 24926368 12644240
17:50:25 8024 8192000000 10331951392 10751160320 1370624288 14.333 97 61 121 2 1418774928 32882128 15268512
17:51:47 7388 8192000000 10350659392 10768809984 1405838144 14.647 100 62 124 1 1453852752 32283920 15730688
17:53:03 2708 8192000000 10353936240 10764890112 1394499216 14.546 99 82 164 1 1454992976 39751104 20742656
17:54:12 10478 8192000000 10362237952 10778914816 1390655904 14.512 99 68 136 0 1458554592 49878816 18019872
17:55:26 1368 8192000000 10354775856 10764517376 1383110016 14.444 100 84 162 1 1456681696 52404080 21167600
17:56:32 7806 8192000000 10329871488 10748407808 1357616192 14.216 98 52 104 1 1415401664 44054240 13731232
17:57:49 12976 8192000000 10301579312 10726764544 1339116032 14.049 98 33 63 2 1388229024 39954032 9158960
17:59:09 20540 8192000000 10310363696 10741932032 1354807008 14.191 99 31 63 1 1406705040 42866480 9031552
18:00:33 13890 8192000000 10313745216 10746458112 1367196928 14.302 100 28 56 1 1418103152 43374432 7531792
18:01:56 17614 8192000000 10307879456 10745524224 1369914688 14.326 100 21 42 3 1419187712 43506560 5766464
18:03:16 19307 8192000000 10308813856 10739433472 1364495184 14.278 100 23 46 1 1412134960 40920000 6719776
18:04:46 37970 8192000000 10295959568 10744664064 1358881296 14.227 99 16 32 1 1412208160 48328848 4998016
18:06:27 83649 8192000000 10255129856 10698502144 1296921072 13.667 95 28 56 0 1311589488 7987376 6681040
18:08:53 63381 8192000000 10287154672 10733584384 1356382496 14.205 98 23 46 1 1375465936 13152896 5930544
18:11:12 54030 8192000000 10305253008 10758991872 1394389088 14.545 100 36 71 1 1423261136 20000704 8871344
18:13:13 26672 8192000000 10278422256 10745114624 1380556032 14.422 100 13 25 1 1399323696 15376832 3390832
18:14:41 87252 8192000000 10290506672 10742558720 1362266624 14.258 98 9 18 0 1374393712 10339344 1787744
18:17:14 38148 8192000000 10286977056 10742304768 1371046448 14.336 99 22 45 1 1390412512 14682320 4683744
18:18:57 20094 8192000000 10284384128 10742087680 1387346768 14.482 100 12 24 0 1398493376 8016464 3130144
18:20:20 113539 8192000000 10308216688 10761699328 1398274128 14.580 100 23 45 2 1416234544 11590880 6369536
18:23:23 62585 8192000000 10283042272 10742411264 1368894144 14.317 98 22 44 0 1382447136 8430480 5122512
18:25:31 23744 8192000000 10292598080 10748600320 1384105552 14.453 99 36 72 1 1402510832 10986096 7419184
18:26:59 78148 8192000000 10288709328 10751426560 1373937040 14.362 98 16 32 0 1389869920 11481152 4451728
18:29:17 56859 8192000000 10297375984 10758303744 1392080880 14.524 100 17 33 1 1413934224 16957600 4895744
18:31:20 78670 8192000000 10285960608 10750681088 1378115392 14.400 99 10 20 0 1394601520 14147968 2338160
18:33:49 42879 8192000000 10298544432 10744197120 1359064752 14.229 97 30 60 1 1375873296 9498432 7310112
18:35:41 56504 8192000000 10279639424 10736713728 1371165344 14.337 99 22 44 2 1387783856 12035440 4583072
18:37:39 19948 8192000000 10283395520 10720436224 1354321904 14.186 99 44 88 0 1374297904 10523968 9452032
18:39:00 65639 8192000000 10260776160 10698575872 1296637664 13.665 94 27 53 0 1317646160 15164016 5844480
18:41:07 85916 8192000000 10255973952 10692026368 1240791536 13.154 91 19 38 0 1255630144 10991312 3847296
18:43:34 70748 8192000000 10262242528 10732314624 1340958320 14.066 99 10 20 2 1370860848 27524160 2378368
18:46:31 92067 8192000000 10249114352 10701455360 1279362544 13.507 94 22 44 1 1295256528 11276848 4617136
18:49:14 60049 8192000000 10260025856 10715398144 1351361216 14.160 99 14 28 0 1365648880 10939472 3348192
18:51:16 44087 8192000000 10256638976 10703560704 1336691936 14.028 98 17 34 1 1349357984 8674096 3991952
18:53:34 108432 8192000000 10244682464 10705854464 1317076080 13.850 97 16 31 1 1334029488 12939680 4013728
18:56:27 46353 8192000000 10246000592 10702135296 1318448800 13.863 98 10 20 0 1332520688 11755984 2315904
18:58:14 148047 8192000000 10279796400 10736201728 1334794848 14.010 98 16 32 0 1352820656 14386464 3639344
19:01:47 23598 8192000000 10283365536 10722582528 1360166656 14.239 99 29 58 1 1374196688 7797680 6232352
19:03:13 31474 8192000000 10292969392 10737975296 1381166720 14.427 100 36 72 2 1401692960 12728688 7797552
19:04:53 66925 8192000000 10288098464 10737741824 1353107040 14.175 98 25 51 0 1368662784 10070656 5485088
19:07:01 52130 8192000000 10306903760 10765443072 1385726176 14.468 100 28 56 2 1406826064 14898192 6201696
19:09:02 139850 8192000000 10282496256 10740338688 1355614832 14.198 97 25 49 2 1369526240 8207984 5703424
19:12:23 65121 8192000000 10280743472 10743730176 1375049520 14.372 99 8 16 0 1383517872 6853344 1615008
19:14:32 45541 8192000000 10296490800 10757246976 1393351856 14.536 100 7 14 0 1404260672 9596944 1311872
19:16:18 76115 8192000000 10305613280 10763837440 1389824960 14.504 99 13 26 0 1404652944 11925008 2902976
19:18:37 97613 8192000000 10277317088 10724261888 1335051024 14.013 96 21 43 0 1347076448 7452432 4572992
19:21:20 31314 8192000000 10286480464 10728783872 1351391312 14.160 98 33 66 0 1366940896 8530128 7019456
19:22:54 84005 8192000000 10291621920 10743799808 1346694112 14.118 97 20 40 0 1362916576 11600560 4621904
19:26:10 115250 8192000000 10302919904 10742775808 1321864880 13.894 96 12 24 0 1339775008 15380928 2529200
19:29:06 116697 8192000000 10254077872 10700046336 1308153520 13.769 95 15 30 0 1323533840 12191648 3188672
19:32:05 41250 8192000000 10230551872 10690117632 1303271056 13.725 97 11 22 0 1312824880 7086592 2467232
19:34:06 69149 8192000000 10260299008 10716573696 1357396224 14.214 100 12 23 0 1367876320 7833920 2646176
19:36:15 68174 8192000000 10253591504 10716078080 1354424960 14.187 99 15 29 3 1373522848 15195040 3902848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-05-2022 19:39:59 to 16-05-2022 19:40:14.

Exceptions occured during test was in run

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

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

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          244790
MON$OLDEST_ACTIVE               245319
MON$OLDEST_SNAPSHOT             245319
MON$NEXT_TRANSACTION            245319
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                       2035424
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.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
--- end of diff output ---

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

16.05.22 19:40:18. 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-05-16 19:40:20 No severe PSQL-related problems occured
16.05.22 19:40:21. End of report.