Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.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 16503624 kB
MemAvailable 31467124 kB
Buffers 0 kB
Cached 15201660 kB
SwapCached 2592 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-11 19:38:17
DTS_END                         2022-05-11 19:38:17.1500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40494
TRN_ID                          245840
ATT_ID                          631
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: 9370.08 18348 1124410
customer order: creation 181.15 368 21738
customer order: refuse 15.16 326 1819
order to supplier: creation 48.23 1254 5787
order to supplier: removal 3.53 98072 424
invoice (draft): creation 47.87 3425 5744
invoice (draft): removal 52.25 809 6270
invoice accept: apply 44.18 17289 5301
invoice accept: cancel 47.22 816 5666
customer reserve: creation 3995.78 175 479493
customer reserve: removal 4628.63 58 555436
realization accept: apply 88.10 174 10572
realization accept: cancel 82.06 104 9847
payment from customer: creation 61.76 108 7411
payment from customer: removal 5.77 94 692
payment to supplier: creation 51.62 114 6194
payment to supplier: removal 3.83 68 460
service: total inventory turnovers 4.98 1446 598
service: total monetary turnovers 7.12 25 854
service: aggregate perf. data 0.83 18738 100
service: refresh index statistics 0.03 223501 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-05-2022 19:39:38 to 11-05-2022 19:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5424
TEST_TIME 2 4869
TEST_TIME 3 7262
TEST_TIME 4 6318
TEST_TIME 5 5705
TEST_TIME 6 5632
TEST_TIME 7 6020
TEST_TIME 8 5993
TEST_TIME 9 6112
TEST_TIME 10 5921
TEST_TIME 11 6095
TEST_TIME 12 6278
TEST_TIME 13 6859
TEST_TIME 14 7093
TEST_TIME 15 7319
TEST_TIME 16 7439
TEST_TIME 17 7837
TEST_TIME 18 8497
TEST_TIME 19 8916
TEST_TIME 20 9048
TEST_TIME 21 9288
TEST_TIME 22 9624
TEST_TIME 23 9569
TEST_TIME 24 9406
TEST_TIME 25 9512
TEST_TIME 26 10092
TEST_TIME 27 10582
TEST_TIME 28 11481
TEST_TIME 29 11242
TEST_TIME 30 11062
TEST_TIME 31 11088
TEST_TIME 32 10913
TEST_TIME 33 10759
TEST_TIME 34 10682
TEST_TIME 35 10833
TEST_TIME 36 10680
TEST_TIME 37 10575
TEST_TIME 38 10405
TEST_TIME 39 10343
TEST_TIME 40 10583
TEST_TIME 41 10504
TEST_TIME 42 10396
TEST_TIME 43 10354
TEST_TIME 44 10298
TEST_TIME 45 10107
TEST_TIME 46 10032
TEST_TIME 47 9946
TEST_TIME 48 9912
TEST_TIME 49 9746
TEST_TIME 50 9750
TEST_TIME 51 9643
TEST_TIME 52 9597
TEST_TIME 53 9598
TEST_TIME 54 9663
TEST_TIME 55 9671
TEST_TIME 56 9632
TEST_TIME 57 9671
TEST_TIME 58 9778
TEST_TIME 59 9740
TEST_TIME 60 9833
TEST_TIME 61 9773
TEST_TIME 62 9820
TEST_TIME 63 9860
TEST_TIME 64 9922
TEST_TIME 65 9874
TEST_TIME 66 9745
TEST_TIME 67 9820
TEST_TIME 68 9728
TEST_TIME 69 9691
TEST_TIME 70 9684
TEST_TIME 71 9649
TEST_TIME 72 9577
TEST_TIME 73 9645
TEST_TIME 74 9625
TEST_TIME 75 9611
TEST_TIME 76 9612
TEST_TIME 77 9569
TEST_TIME 78 9654
TEST_TIME 79 9623
TEST_TIME 80 9596
TEST_TIME 81 9682
TEST_TIME 82 9705
TEST_TIME 83 9676
TEST_TIME 84 9698
TEST_TIME 85 9703
TEST_TIME 86 9703
TEST_TIME 87 9657
TEST_TIME 88 9627
TEST_TIME 89 9578
TEST_TIME 90 9541
TEST_TIME 91 9475
TEST_TIME 92 9430
TEST_TIME 93 9456
TEST_TIME 94 9491
TEST_TIME 95 9503
TEST_TIME 96 9471
TEST_TIME 97 9493
TEST_TIME 98 9566
TEST_TIME 99 9549
TEST_TIME 100 9550
TEST_TIME 101 9584
TEST_TIME 102 9566
TEST_TIME 103 9697
TEST_TIME 104 9684
TEST_TIME 105 9655
TEST_TIME 106 9691
TEST_TIME 107 9697
TEST_TIME 108 9679
TEST_TIME 109 9603
TEST_TIME 110 9640
TEST_TIME 111 9590
TEST_TIME 112 9582
TEST_TIME 113 9555
TEST_TIME 114 9537
TEST_TIME 115 9515
TEST_TIME 116 9464
TEST_TIME 117 9461
TEST_TIME 118 9432
TEST_TIME 119 9436
TEST_TIME 120 9374

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-05-2022 19:39:42 to 11-05-2022 19:39:45.

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 1568740 1568740 0 0.00 0 25149 6 0 0
doc_list_biud 1568739 1568739 0 0.00 0 44083 26 0 0
sp_add_invoice_to_stock 5403 5301 102 1.88 18 152444 17289 0 102
sp_cancel_adding_invoice 5666 5666 0 0.00 5 95205 816 0 0
sp_cancel_client_order 1819 1819 0 0.00 4 9428 327 0 0
sp_cancel_customer_reserve 555436 555436 0 0.00 1 37593 59 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 2 11332 94 0 0
sp_cancel_pay_to_supplier 460 460 0 0.00 3 6300 68 0 0
sp_cancel_supplier_invoice 6282 6270 12 0.19 2 20347 809 0 12
sp_cancel_supplier_order 424 424 0 0.00 8 1090223 98073 0 0
sp_cancel_write_off 9847 9847 0 0.00 1 9964 105 0 0
sp_client_order 21738 21738 0 0.00 3 27005 368 0 0
sp_customer_reserve 479493 479493 0 0.00 0 31560 175 0 0
sp_fill_shopping_cart 33259 33259 0 0.00 1 17402 39 0 0
sp_fill_shopping_cart_clo_res 479480 479480 0 0.00 0 14356 13 0 0
sp_get_clo_for_invoice 5287 5287 0 0.00 0 22386 278 0 0
sp_kill_cost_storno 16665 16665 0 0.00 0 19673 55 0 0
sp_kill_qstorno_ret_qs2qd 1127413 1127413 0 0.00 0 36275 32 0 0
sp_kill_qty_storno 595321 595321 0 0.00 0 44081 69 0 0
sp_lock_selected_doc 21841 21841 0 0.00 0 7172 4 0 0
sp_make_cost_storno 29464 29464 0 0.00 0 25135 50 0 0
sp_multiply_rows_for_pdistr 29464 29464 0 0.00 0 14733 26 0 0
sp_multiply_rows_for_qdistr 21738 21738 0 0.00 0 19502 170 0 0
sp_pay_from_customer 7411 7411 0 0.00 4 18205 109 0 0
sp_pay_to_supplier 6194 6194 0 0.00 4 25160 114 0 0
sp_payment_common 13605 13605 0 0.00 1 15434 22 0 0
sp_qd_handle_on_cancel_clo 1819 1819 0 0.00 0 6872 300 0 0
sp_qd_handle_on_invoice_upd_sts 10953 10953 0 0.00 0 44079 341 0 0
sp_qd_handle_on_reserve_upd_sts 10572 10572 0 0.00 0 15830 57 0 0
sp_reserve_write_off 10572 10572 0 0.00 6 23075 175 0 0
sp_supplier_invoice 5744 5744 0 0.00 7 93245 3426 0 0
sp_supplier_order 5787 5787 0 0.00 4 34913 1254 0 0
srv_aggregate_perf_data 100 100 0 0.00 2964 169091 18738 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1313 157816 45914 0 0
srv_find_qd_qs_mism 1074658 1074658 0 0.00 0 18787 6 0 0
srv_make_invnt_saldo 598 598 0 0.00 43 249395 1446 0 0
srv_make_money_saldo 854 854 0 0.00 0 1505 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 129313 345577 223501 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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 5301 5301 0 0.00 0 25573 179 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 0 4989 112 0 0
t$perf-norm:sp_cancel_client_order 1819 1819 0 0.00 0 3846 11 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 1528 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 1067 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 460 460 0 0.00 0 809 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 9810 66 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 29318 1660 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 3065 8 0 0
t$perf-norm:sp_client_order 21738 21738 0 0.00 0 8385 17 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 1552 8 0 0
t$perf-norm:sp_pay_from_customer 7411 7411 0 0.00 0 8148 8 0 0
t$perf-norm:sp_pay_to_supplier 6194 6194 0 0.00 0 4789 7 0 0
t$perf-norm:sp_reserve_write_off 10572 10572 0 0.00 0 4523 10 0 0
t$perf-norm:sp_supplier_invoice 5744 5744 0 0.00 0 4576 19 0 0
t$perf-norm:sp_supplier_order 5787 5787 0 0.00 0 8289 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 572 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 5194 100 0 0
t$perf-norm:srv_make_invnt_saldo 598 598 0 0.00 0 798 17 0 0
t$perf-norm:srv_make_money_saldo 854 854 0 0.00 0 2006 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10758 10554 204 1.89 0 18363 22 0 204
v_all_customers 39486 39486 0 0.00 0 17 1 0 0
v_all_suppliers 31738 31738 0 0.00 0 67 1 0 0
v_all_wares 43476 43476 0 0.00 0 99 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 1405 8 0 0
v_cancel_client_order 3638 3638 0 0.00 0 3230 6 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 11285 19 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 3454 7 0 0
v_cancel_payment_to_supplier 920 920 0 0.00 0 6280 10 0 0
v_cancel_supplier_invoice 844 820 24 2.84 0 2270 18 0 24
v_cancel_supplier_order 848 848 0 0.00 0 456 4 0 0
v_cancel_write_off 2050 2050 0 0.00 0 1452 14 0 0
v_max_id_clo_ord 5786 5786 0 0.00 0 1314 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 3956 13 0 0
v_max_id_ord_sup 5735 5735 0 0.00 0 6304 6 0 0
v_max_non_paid_invoice 1846 1846 0 0.00 0 190 2 0 0
v_max_non_paid_realizn 3941 3941 0 0.00 0 835 4 0 0
v_min_id_clo_ord 5786 5786 0 0.00 0 2876 6 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 3252 5 0 0
v_min_id_ord_sup 5735 5735 0 0.00 0 6052 5 0 0
v_min_non_paid_invoice 6194 6194 0 0.00 0 4849 11 0 0
v_min_non_paid_realizn 7411 7411 0 0.00 0 15428 17 0 0
v_our_firm 10930 10930 0 0.00 0 19 1 0 0
v_reserve_write_off 21144 21144 0 0.00 0 23010 10 0 0
x_lock_depdocs_on_canc_invoice 11526 11526 0 0.00 0 23497 111 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 7655 308 0 0
x_make_qstorno_1000_1200 5787 5787 0 0.00 1 34621 1175 0 0
x_make_qstorno_1200_2000 5744 5744 0 0.00 3 93231 3281 0 0
x_make_qstorno_2100_3300 479493 479493 0 0.00 0 31559 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-05-2022 19:39:46 to 11-05-2022 19:39:50.

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 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:21 25301 8192000000 9896184160 10200600576 975408784 10.639 100 89 178 0 1005474560 6176576 23889200
17:10:53 16781 8192000000 9929041424 10244395008 1012635072 11.001 100 91 182 1 1044292688 8174384 23483232
17:12:24 21201 8192000000 9974355744 10305015808 1051156208 11.372 100 78 155 1 1079714800 9525504 19033088
17:13:55 15704 8192000000 10033447216 10381508608 1121429568 12.040 100 81 163 2 1151686368 10938672 19318128
17:15:15 7782 8192000000 10095753248 10456363008 1183742320 12.625 100 75 150 0 1213168384 12454240 16971824
17:16:25 4939 8192000000 10140937856 10510237696 1228986816 13.045 100 88 176 1 1262622176 14137456 19497904
17:17:32 2001 8192000000 10172668304 10552303616 1260230112 13.332 100 85 168 1 1295944352 17660464 18053776
17:18:35 1795 8192000000 10204468960 10590380032 1290465664 13.608 100 85 170 1 1322065056 12418960 19180432
17:19:38 17455 8192000000 10206339680 10604961792 1301232608 13.706 100 71 143 0 1331961600 14095520 16633472
17:20:57 3527 8192000000 10207930080 10609782784 1307743552 13.766 100 45 91 1 1327796624 10313648 9739424
17:22:04 5458 8192000000 10205124656 10617229312 1316696352 13.847 100 30 60 2 1331069184 7752272 6620560
17:23:12 47204 8192000000 10237697952 10637692928 1324930016 13.921 100 47 96 2 1341487936 5189344 11368576
17:24:59 6920 8192000000 10217691904 10640187392 1330763184 13.974 100 25 50 1 1342974592 6926416 5284992
17:26:09 6144 8192000000 10221307696 10630488064 1311614400 13.801 98 20 40 2 1321357648 5334688 4408560
17:27:16 67604 8192000000 10216440368 10637332480 1303142608 13.724 98 34 68 1 1321501312 10436992 7921712
17:29:26 5507 8192000000 10185558912 10596052992 1265896384 13.384 97 31 62 1 1279254176 6164480 7193312
17:30:32 6312 8192000000 10153359216 10553110528 1212922368 12.896 95 30 60 1 1225774800 5663520 7188912
17:31:40 69547 8192000000 10151663696 10560610304 1189812256 12.682 94 31 62 1 1202584864 4718272 8054336
17:33:53 8898 8192000000 10146986784 10554601472 1258884624 13.320 100 35 70 0 1274112896 7247744 7980528
17:35:02 5985 8192000000 10187078528 10598338560 1290454464 13.608 100 18 36 4 1303980608 9471952 4054192
17:36:09 13137 8192000000 10195569872 10613379072 1308810880 13.775 100 16 32 0 1317786560 5602032 3373648
17:37:22 26077 8192000000 10204126352 10622005248 1311692800 13.801 100 17 34 0 1321398992 6182416 3523776
17:38:53 10759 8192000000 10242968496 10651140096 1337380752 14.034 100 62 123 0 1360014368 8613456 14020160
17:40:10 14619 8192000000 10260063344 10675929088 1352362496 14.169 100 61 122 2 1378997216 11967616 14667104
17:41:30 5891 8192000000 10296524176 10710032384 1373020032 14.354 100 69 138 2 1409029200 20076352 15932816
17:42:48 46632 8192000000 10298008624 10726445056 1384363408 14.456 100 24 48 0 1411332880 20879680 6089792
17:44:42 18495 8192000000 10320574384 10744741888 1395744832 14.557 100 53 105 0 1434768256 26361648 12661776
17:46:15 9575 8192000000 10335999696 10761609216 1403710000 14.628 100 56 112 3 1448248864 30072912 14465952
17:47:36 3532 8192000000 10348673616 10772869120 1413155520 14.712 100 58 115 2 1456633728 29706416 13771792
17:48:53 4474 8192000000 10349208336 10768613376 1410765728 14.691 100 55 110 1 1458775488 34408816 13600944
17:50:04 2036 8192000000 10361761664 10773622784 1410029696 14.684 100 84 168 0 1468297568 36860368 21407504
17:51:08 7561 8192000000 10369319392 10790301696 1420571728 14.778 100 70 141 1 1478287264 39940304 17775232
17:52:21 10756 8192000000 10363440000 10777116672 1396798256 14.566 99 62 122 0 1455952400 44044832 15109312
17:53:35 6584 8192000000 10347591680 10762014720 1378849136 14.406 99 65 128 1 1445979200 51147184 15982880
17:54:48 3083 8192000000 10374642080 10778918912 1388272752 14.490 99 89 178 0 1470336608 59558752 22505104
17:55:59 5623 8192000000 10370014656 10781880320 1387951136 14.488 100 68 136 2 1468477680 62727456 17799088
17:57:12 6425 8192000000 10339023264 10751045632 1360576336 14.243 99 68 136 2 1432687616 54613600 17497680
17:58:22 10479 8192000000 10312723184 10733449216 1352465168 14.170 100 37 75 0 1413939312 50946480 10527664
17:59:40 14926 8192000000 10324733856 10749079552 1368115920 14.310 100 36 72 2 1428478336 50320688 10041728
18:01:05 27817 8192000000 10326105728 10757271552 1371607600 14.341 99 32 64 0 1420637200 39705936 9323664
18:02:38 19674 8192000000 10335149888 10766745600 1362785456 14.262 98 31 62 2 1421258944 49994112 8479376
18:04:10 31344 8192000000 10321548800 10765938688 1362786400 14.262 100 28 55 1 1435161776 64550944 7824432
18:05:51 48854 8192000000 10263187472 10730917888 1329935632 13.967 98 10 20 0 1379110000 46199984 2974384
18:07:46 107617 8192000000 10288693344 10731995136 1345306928 14.105 98 25 52 1 1366011376 14612432 6092016
18:10:34 80720 8192000000 10296690272 10731298816 1341116208 14.067 97 22 43 0 1357856160 12308368 4431584
18:12:59 57482 8192000000 10286036320 10746810368 1372760448 14.352 100 15 30 0 1393214896 17168112 3286336
18:14:57 1312 8192000000 10294085552 10734071808 1380457840 14.421 100 35 68 1 1400226432 12029872 7738720
18:16:00 37737 8192000000 10318211872 10753765376 1380122320 14.418 99 19 38 0 1402364976 17785904 4456752
18:17:42 41789 8192000000 10292297120 10756608000 1383370880 14.447 100 29 58 1 1413127456 22958992 6797584
18:19:44 50231 8192000000 10290036720 10751483904 1390795664 14.513 100 11 22 0 1401509168 8413184 2300320
18:21:36 58855 8192000000 10286323392 10752471040 1380746672 14.423 99 14 28 0 1393442784 9539088 3157024
18:23:38 76109 8192000000 10286914400 10753671168 1393703680 14.539 100 23 46 1 1405165280 6317104 5144496
18:25:58 48605 8192000000 10278910368 10745561088 1370316400 14.330 98 10 20 0 1381178112 8853472 2008240
18:27:53 83248 8192000000 10283078448 10750869504 1366894272 14.299 98 14 28 0 1378977328 9273920 2809136
18:30:18 48956 8192000000 10295973504 10753585152 1382411424 14.438 99 25 50 1 1400930512 12591808 5927280
18:32:08 63441 8192000000 10284380672 10746806272 1379159792 14.409 99 12 24 1 1392866816 11061584 2645440
18:34:12 43006 8192000000 10301516848 10751205376 1380426400 14.420 99 28 56 0 1397538304 10825232 6286672
18:35:57 64154 8192000000 10290746224 10747289600 1355149904 14.194 97 12 24 0 1371443504 13443680 2849920
18:38:02 108219 8192000000 10284637872 10730078208 1300257760 13.698 93 14 28 0 1311557920 8680928 2619232
18:40:50 60068 8192000000 10281507648 10725007360 1328201520 13.951 96 20 40 2 1342695760 9647136 4847104
18:42:54 24634 8192000000 10270920480 10706309120 1312229664 13.806 96 35 71 0 1332534912 12081648 8223600
18:44:19 157815 8192000000 10269245424 10720051200 1297843936 13.676 95 26 53 0 1320380784 16173136 6363712
18:48:05 56267 8192000000 10254253088 10713645056 1343374064 14.088 99 7 14 0 1367519152 22467328 1677760
18:50:12 64611 8192000000 10250709008 10708873216 1319026048 13.868 97 11 22 0 1333168880 11418048 2724784
18:52:17 29147 8192000000 10269384208 10707992576 1339189152 14.050 98 23 46 1 1352068592 7332624 5546816
18:53:47 44306 8192000000 10274420304 10709544960 1332782640 13.992 98 27 54 0 1347522528 8783728 5956160
18:55:34 81257 8192000000 10257545792 10712276992 1322158768 13.896 97 15 30 1 1338534688 13003856 3372064
18:58:00 21973 8192000000 10258438688 10693156864 1331289872 13.979 98 46 92 0 1353495376 12627968 9577536
18:59:45 97363 8192000000 10275390256 10729893888 1313945952 13.822 96 10 20 0 1329946048 13595968 2404128
19:02:24 51390 8192000000 10298840752 10748010496 1367249328 14.302 99 46 93 0 1394803504 17185328 10368848
19:04:23 114987 8192000000 10287884464 10747400192 1384943088 14.461 100 19 38 0 1400861952 11460304 4458560
19:07:20 18790 8192000000 10269623456 10724384768 1330852880 13.975 96 25 50 2 1347068160 10731568 5483712
19:08:40 41475 8192000000 10278096784 10740981760 1374675264 14.369 99 7 14 0 1387452080 11281728 1495088
19:10:22 51450 8192000000 10272639872 10732306432 1359551680 14.233 98 15 30 1 1377349104 14530096 3267328
19:12:22 46323 8192000000 10260331680 10711703552 1328548448 13.954 96 18 36 1 1342118736 9114144 4456144
19:14:08 45842 8192000000 10294757584 10748157952 1364910880 14.281 98 19 36 0 1379433920 10551104 3971936
19:16:08 46923 8192000000 10298409904 10750644224 1363113712 14.265 98 20 40 0 1378941728 10555248 5272768
19:17:56 14021 8192000000 10279850112 10726768640 1355526400 14.197 98 25 50 4 1369212912 8335904 5350608
19:19:10 56323 8192000000 10309241456 10771976192 1402280960 14.615 100 37 73 1 1425193488 14463696 8448832
19:21:25 117329 8192000000 10298435936 10739716096 1320693392 13.883 95 8 16 0 1337319696 15164624 1461680
19:24:29 60724 8192000000 10283192224 10734301184 1328342240 13.952 96 22 44 1 1345546528 12576448 4627840
19:26:37 118389 8192000000 10274733056 10730409984 1344555168 14.098 98 14 28 0 1361212960 13258912 3398880
19:31:07 53124 8192000000 10264861072 10730278912 1354065216 14.184 99 8 16 0 1369391648 13142352 2184080
19:33:03 70795 8192000000 10253728672 10707677184 1318715200 13.865 96 6 12 0 1329544912 9512352 1317360
19:35:17 80523 8192000000 10246173872 10704433152 1264320144 13.370 93 12 24 0 1273711568 6858480 2532944
19:37:38 31905 8192000000 10281104272 10728964096 1379800928 14.415 100 30 59 0 1393718768 7476256 6441584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-05-2022 19:39:52 to 11-05-2022 19:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 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 11-05-2022 19:40:08 to 11-05-2022 19:40:10.

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          244652
MON$OLDEST_ACTIVE               246079
MON$OLDEST_SNAPSHOT             246079
MON$NEXT_TRANSACTION            246079
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                       2035680
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 ---
:167: 
:168: mscs-test-161.budzdorov.ru    Wed May 11 19:38:19 2022
:169:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:170: 
:171: 
:172: mscs-test-161.budzdorov.ru    Wed May 11 19:38:21 2022
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:174: 
:175: 
:176: mscs-test-161.budzdorov.ru    Wed May 11 19:38:22 2022
:177:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:178: 
:179: 
:180: mscs-test-161.budzdorov.ru    Wed May 11 19:38:22 2022
:181:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:182: 
--- end of diff output ---

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

11.05.22 19:40:11. 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-11 19:40:13 No severe PSQL-related problems occured
11.05.22 19:40:14. End of report.