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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16696520 kB
MemAvailable 31497652 kB
Buffers 0 kB
Cached 14992564 kB
SwapCached 2688 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-04-13 19:38:11
DTS_END                         2022-04-13 19:38:11.1580
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/39478
TRN_ID                          242798
ATT_ID                          654
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: 9350.26 20324 1122031
customer order: creation 174.19 377 20903
customer order: refuse 14.94 334 1793
order to supplier: creation 47.28 1275 5673
order to supplier: removal 3.33 107400 399
invoice (draft): creation 46.95 3535 5634
invoice (draft): removal 53.58 830 6430
invoice accept: apply 42.24 16977 5069
invoice accept: cancel 48.29 793 5795
customer reserve: creation 3876.93 169 465231
customer reserve: removal 4744.25 59 569310
realization accept: apply 85.24 158 10229
realization accept: cancel 83.26 122 9991
payment from customer: creation 58.72 99 7046
payment from customer: removal 5.55 69 666
payment to supplier: creation 49.63 118 5955
payment to supplier: removal 3.55 80 426
service: total inventory turnovers 5.02 933 602
service: total monetary turnovers 6.43 20 772
service: aggregate perf. data 0.86 21608 103
service: refresh index statistics 0.03 251515 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-04-2022 19:39:08 to 13-04-2022 19:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6779
TEST_TIME 2 6183
TEST_TIME 3 5595
TEST_TIME 4 5858
TEST_TIME 5 5578
TEST_TIME 6 5310
TEST_TIME 7 5052
TEST_TIME 8 5094
TEST_TIME 9 5077
TEST_TIME 10 5205
TEST_TIME 11 5391
TEST_TIME 12 5683
TEST_TIME 13 5877
TEST_TIME 14 5914
TEST_TIME 15 6201
TEST_TIME 16 6818
TEST_TIME 17 7491
TEST_TIME 18 7741
TEST_TIME 19 8147
TEST_TIME 20 8285
TEST_TIME 21 8328
TEST_TIME 22 8650
TEST_TIME 23 8918
TEST_TIME 24 9455
TEST_TIME 25 9979
TEST_TIME 26 10496
TEST_TIME 27 10328
TEST_TIME 28 11139
TEST_TIME 29 10814
TEST_TIME 30 10788
TEST_TIME 31 10533
TEST_TIME 32 10591
TEST_TIME 33 10397
TEST_TIME 34 10303
TEST_TIME 35 10286
TEST_TIME 36 10181
TEST_TIME 37 10137
TEST_TIME 38 10157
TEST_TIME 39 10073
TEST_TIME 40 9987
TEST_TIME 41 9874
TEST_TIME 42 9831
TEST_TIME 43 9781
TEST_TIME 44 9711
TEST_TIME 45 9640
TEST_TIME 46 9620
TEST_TIME 47 9485
TEST_TIME 48 9491
TEST_TIME 49 9486
TEST_TIME 50 9427
TEST_TIME 51 9484
TEST_TIME 52 9479
TEST_TIME 53 9620
TEST_TIME 54 9612
TEST_TIME 55 9608
TEST_TIME 56 9724
TEST_TIME 57 9747
TEST_TIME 58 9669
TEST_TIME 59 9830
TEST_TIME 60 9861
TEST_TIME 61 9813
TEST_TIME 62 9772
TEST_TIME 63 9681
TEST_TIME 64 9595
TEST_TIME 65 9572
TEST_TIME 66 9573
TEST_TIME 67 9527
TEST_TIME 68 9483
TEST_TIME 69 9459
TEST_TIME 70 9516
TEST_TIME 71 9495
TEST_TIME 72 9494
TEST_TIME 73 9510
TEST_TIME 74 9465
TEST_TIME 75 9501
TEST_TIME 76 9578
TEST_TIME 77 9525
TEST_TIME 78 9577
TEST_TIME 79 9639
TEST_TIME 80 9630
TEST_TIME 81 9597
TEST_TIME 82 9498
TEST_TIME 83 9521
TEST_TIME 84 9454
TEST_TIME 85 9441
TEST_TIME 86 9379
TEST_TIME 87 9349
TEST_TIME 88 9315
TEST_TIME 89 9388
TEST_TIME 90 9417
TEST_TIME 91 9432
TEST_TIME 92 9423
TEST_TIME 93 9437
TEST_TIME 94 9429
TEST_TIME 95 9390
TEST_TIME 96 9492
TEST_TIME 97 9456
TEST_TIME 98 9501
TEST_TIME 99 9504
TEST_TIME 100 9543
TEST_TIME 101 9505
TEST_TIME 102 9531
TEST_TIME 103 9536
TEST_TIME 104 9559
TEST_TIME 105 9479
TEST_TIME 106 9431
TEST_TIME 107 9410
TEST_TIME 108 9428
TEST_TIME 109 9395
TEST_TIME 110 9364
TEST_TIME 111 9342
TEST_TIME 112 9284
TEST_TIME 113 9284
TEST_TIME 114 9304
TEST_TIME 115 9308
TEST_TIME 116 9284
TEST_TIME 117 9250
TEST_TIME 118 9265
TEST_TIME 119 9424
TEST_TIME 120 9352

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-04-2022 19:39:12 to 13-04-2022 19:39:15.

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 1552043 1552043 0 0.00 0 40172 6 0 0
doc_list_biud 1552043 1552043 0 0.00 0 40176 28 0 0
sp_add_invoice_to_stock 5155 5069 86 1.66 33 144121 16977 0 86
sp_cancel_adding_invoice 5795 5795 0 0.00 5 67552 793 0 0
sp_cancel_client_order 1793 1793 0 0.00 3 16558 334 0 0
sp_cancel_customer_reserve 569310 569310 0 0.00 1 22113 59 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 4706 70 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 2 5403 80 0 0
sp_cancel_supplier_invoice 6436 6430 6 0.09 2 22664 831 0 6
sp_cancel_supplier_order 399 399 0 0.00 9 1096176 107400 0 0
sp_cancel_write_off 9991 9991 0 0.00 1 40183 123 0 0
sp_client_order 20903 20903 0 0.00 3 41357 377 0 0
sp_customer_reserve 465231 465231 0 0.00 0 85331 169 0 0
sp_fill_shopping_cart 32175 32175 0 0.00 1 14120 36 0 0
sp_fill_shopping_cart_clo_res 465227 465227 0 0.00 0 18745 16 0 0
sp_get_clo_for_invoice 5064 5064 0 0.00 0 15406 320 0 0
sp_kill_cost_storno 16878 16878 0 0.00 0 13665 55 0 0
sp_kill_qstorno_ret_qs2qd 1155440 1155440 0 0.00 0 40174 33 0 0
sp_kill_qty_storno 609010 609010 0 0.00 0 40175 70 0 0
sp_lock_selected_doc 21026 21026 0 0.00 0 8267 4 0 0
sp_make_cost_storno 28293 28293 0 0.00 0 14346 46 0 0
sp_multiply_rows_for_pdistr 28293 28293 0 0.00 0 26185 27 0 0
sp_multiply_rows_for_qdistr 20903 20903 0 0.00 0 41337 181 0 0
sp_pay_from_customer 7046 7046 0 0.00 4 26204 100 0 0
sp_pay_to_supplier 5955 5955 0 0.00 4 17836 118 0 0
sp_payment_common 13001 13001 0 0.00 1 11540 21 0 0
sp_qd_handle_on_cancel_clo 1793 1793 0 0.00 0 16548 298 0 0
sp_qd_handle_on_invoice_upd_sts 10858 10858 0 0.00 0 27829 302 0 0
sp_qd_handle_on_reserve_upd_sts 10229 10229 0 0.00 0 12354 59 0 0
sp_reserve_write_off 10229 10229 0 0.00 7 14890 159 0 0
sp_supplier_invoice 5634 5634 0 0.00 6 88011 3535 0 0
sp_supplier_order 5673 5673 0 0.00 5 25922 1276 0 0
srv_aggregate_perf_data 103 103 0 0.00 2918 359956 21609 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1541 146378 46141 0 0
srv_find_qd_qs_mism 1073499 1073499 0 0.00 0 40170 5 0 0
srv_make_invnt_saldo 602 602 0 0.00 32 87370 934 0 0
srv_make_money_saldo 772 772 0 0.00 1 1411 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 199245 307399 251515 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5069 5069 0 0.00 0 18552 155 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 1 2829 95 0 0
t$perf-norm:sp_cancel_client_order 1793 1793 0 0.00 0 5847 15 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 3321 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 1437 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 1517 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 2748 19 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 17161 1473 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 2385 10 0 0
t$perf-norm:sp_client_order 20903 20903 0 0.00 0 5479 15 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 13055 19 0 0
t$perf-norm:sp_pay_from_customer 7046 7046 0 0.00 0 7110 7 0 0
t$perf-norm:sp_pay_to_supplier 5955 5955 0 0.00 0 4865 7 0 0
t$perf-norm:sp_reserve_write_off 10229 10229 0 0.00 0 4110 8 0 0
t$perf-norm:sp_supplier_invoice 5634 5634 0 0.00 0 4868 14 0 0
t$perf-norm:sp_supplier_order 5673 5673 0 0.00 0 11693 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 172 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 65 3 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 1227 20 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 681 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10296 10124 172 1.67 0 8454 18 0 172
v_all_customers 37758 37758 0 0.00 0 21 0 0 0
v_all_suppliers 31168 31168 0 0.00 0 21 1 0 0
v_all_wares 41806 41806 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 7879 17 0 0
v_cancel_client_order 3586 3586 0 0.00 0 3452 5 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 4690 8 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 1591 4 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 2051 9 0 0
v_cancel_supplier_invoice 824 812 12 1.45 0 6693 24 0 12
v_cancel_supplier_order 798 798 0 0.00 0 773 5 0 0
v_cancel_write_off 1922 1922 0 0.00 0 9175 22 0 0
v_max_id_clo_ord 5673 5673 0 0.00 0 1169 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 1335 9 0 0
v_max_id_ord_sup 5598 5598 0 0.00 0 8292 6 0 0
v_max_non_paid_invoice 1642 1642 0 0.00 0 1784 3 0 0
v_max_non_paid_realizn 3857 3857 0 0.00 0 3990 5 0 0
v_min_id_clo_ord 5673 5673 0 0.00 0 2675 4 0 0
v_min_id_clo_res 985 985 0 0.00 0 4635 16 0 0
v_min_id_ord_sup 5598 5598 0 0.00 0 7757 6 0 0
v_min_non_paid_invoice 5955 5955 0 0.00 0 6178 14 0 0
v_min_non_paid_realizn 7046 7046 0 0.00 0 11537 14 0 0
v_our_firm 10426 10426 0 0.00 0 18 1 0 0
v_reserve_write_off 20458 20458 0 0.00 0 8434 6 0 0
x_lock_depdocs_on_canc_invoice 11819 11819 0 0.00 0 15154 107 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 7207 374 0 0
x_make_qstorno_1000_1200 5673 5673 0 0.00 1 25910 1202 0 0
x_make_qstorno_1200_2000 5634 5634 0 0.00 3 83164 3393 0 0
x_make_qstorno_2100_3300 465231 465231 0 0.00 0 85187 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-04-2022 19:39:16 to 13-04-2022 19:39:20.

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:14 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:16 30822 8192000000 9897155808 10209857536 976312192 10.648 100 84 168 0 1005105232 5993424 22799616
17:10:56 14219 8192000000 9950567744 10274373632 1033981168 11.207 100 88 176 0 1064628976 8229328 22418480
17:12:16 15467 8192000000 9980621280 10314149888 1068095280 11.534 100 87 174 1 1099797424 9476736 22225408
17:13:35 8835 8192000000 10035315008 10381922304 1122521760 12.051 100 81 161 0 1152312848 10507024 19284064
17:14:47 8257 8192000000 10097296576 10458746880 1184041776 12.628 100 83 165 1 1214962848 12336800 18584272
17:15:59 4019 8192000000 10137872256 10507857920 1226598560 13.023 100 79 157 3 1257184464 13321504 17264400
17:17:04 3138 8192000000 10166521632 10545999872 1253745696 13.273 100 86 170 0 1286596208 14177536 18672976
17:18:09 1909 8192000000 10207613824 10588663808 1287759408 13.584 100 93 186 0 1322412848 13672768 20980672
17:19:11 1224 8192000000 10221244512 10606776320 1303781184 13.730 100 93 185 1 1337208912 11810256 21617472
17:20:13 16417 8192000000 10191689280 10611220480 1310352064 13.789 100 19 37 3 1320368256 5553056 4463136
17:21:31 11464 8192000000 10205253120 10619817984 1319570336 13.873 100 24 48 0 1331042240 5969200 5502704
17:22:47 12306 8192000000 10211173008 10627489792 1325592128 13.927 100 18 37 1 1336084816 6479392 4013296
17:24:01 6301 8192000000 10225637456 10640793600 1333549312 13.999 100 17 33 1 1342823216 5773920 3499984
17:25:10 29050 8192000000 10249247200 10653282304 1335873344 14.020 100 75 152 5 1359930256 6242656 17814256
17:26:39 5698 8192000000 10230174912 10648195072 1338193840 14.041 100 43 85 1 1354773728 5896384 10683504
17:27:46 5942 8192000000 10220014448 10641571840 1328367856 13.952 100 23 46 0 1339997664 6620496 5009312
17:28:57 34696 8192000000 10173291280 10591469568 1239878144 13.145 95 19 38 0 1249204848 5362144 3964560
17:30:33 5879 8192000000 10159705520 10560401408 1242976704 13.174 98 27 53 1 1254474448 5348912 6148832
17:31:41 6470 8192000000 10114717744 10510827520 1218881056 12.951 99 23 46 0 1229942560 5560288 5501216
17:32:49 40373 8192000000 10167218288 10566717440 1264930064 13.375 100 18 35 1 1273892720 4530496 4432160
17:34:47 8029 8192000000 10168597856 10578874368 1278580480 13.500 100 32 64 1 1292520368 6442160 7497728
17:35:55 9232 8192000000 10188562592 10600939520 1297139616 13.669 100 23 43 1 1307791760 5148480 5503664
17:37:05 13940 8192000000 10192301104 10607759360 1304154096 13.733 100 28 55 3 1316741840 5446256 7141488
17:38:22 36962 8192000000 10227172048 10634903552 1317496448 13.854 100 38 74 2 1331814848 5998816 8319584
17:40:03 13716 8192000000 10246547968 10659663872 1340674512 14.063 100 49 98 1 1360595664 8720048 11201104
17:41:24 6000 8192000000 10270581920 10684231680 1357882288 14.218 100 53 106 2 1380758000 10981664 11894048
17:42:39 10048 8192000000 10283001472 10703683584 1370632224 14.333 100 41 80 0 1393102768 13461200 9009344
17:43:56 12038 8192000000 10294482320 10711863296 1371571680 14.341 99 50 100 1 1398717024 15509488 11635856
17:45:14 36419 8192000000 10320367264 10742886400 1395460848 14.555 100 45 90 2 1425038864 18219600 11358416
17:46:55 10507 8192000000 10337975680 10762489856 1413055792 14.711 100 45 90 0 1448869600 24329488 11484320
17:48:09 5958 8192000000 10359827472 10784321536 1430966096 14.870 100 57 114 1 1475307216 30012768 14328352
17:49:22 9115 8192000000 10348211520 10758053888 1381633200 14.431 97 64 126 0 1432073792 34174816 16265776
17:50:36 1538 8192000000 10363510880 10777104384 1416701600 14.743 100 68 134 3 1466234112 32538256 16994256
17:51:43 4824 8192000000 10358511552 10770386944 1408466752 14.670 100 73 146 1 1466666944 39861424 18338768
17:52:53 6034 8192000000 10365630304 10780340224 1408052272 14.667 100 64 128 1 1469731744 44026304 17653168
17:54:05 2878 8192000000 10373555600 10781663232 1401208256 14.606 100 86 171 1 1474568704 50059040 23301408
17:55:12 2797 8192000000 10345991616 10756747264 1372692912 14.351 99 70 140 1 1444942784 54271600 17978272
17:56:19 5127 8192000000 10338743168 10756571136 1379049536 14.408 100 51 102 2 1443332640 50507968 13775136
17:57:31 22022 8192000000 10292472832 10719453184 1317612640 13.855 96 36 71 0 1376934576 49798880 9523056
17:59:00 20200 8192000000 10304552352 10741170176 1347432128 14.124 99 23 46 2 1402178032 48334576 6411328
18:00:30 31346 8192000000 10310362032 10749423616 1361402688 14.250 100 23 46 2 1425561888 57513232 6645968
18:02:27 16620 8192000000 10300207072 10738028544 1338249504 14.042 99 29 59 1 1401464032 55197824 8016704
18:03:48 25455 8192000000 10298701408 10743369728 1352948592 14.174 99 20 40 1 1400448816 42383136 5117088
18:05:22 62429 8192000000 10294131120 10748301312 1336720032 14.028 98 13 26 1 1370197296 29918944 3558320
18:07:26 146378 8192000000 10295456048 10742448128 1373377536 14.357 100 19 38 0 1387832336 10572944 3881856
18:10:54 8502 8192000000 10275817792 10736336896 1367995024 14.309 100 8 16 1 1384154480 14611600 1547856
18:12:04 32741 8192000000 10261384176 10699493376 1345347616 14.106 99 29 58 2 1359951584 8116576 6487392
18:13:39 30948 8192000000 10281486112 10736480256 1370199952 14.329 100 26 52 0 1394211232 17530608 6480672
18:15:10 102165 8192000000 10269214368 10730680320 1368961568 14.318 99 20 40 1 1383313200 9189472 5162160
18:18:13 66876 8192000000 10292741680 10747371520 1388429728 14.492 100 12 24 1 1401451904 9734944 3287232
18:20:20 116616 8192000000 10273476944 10736640000 1381404656 14.429 100 14 28 0 1396696576 12596096 2695824
18:23:19 7133 8192000000 10290114160 10744209408 1390015296 14.506 100 21 42 1 1403537568 8734240 4788032
18:24:27 28834 8192000000 10289222464 10756661248 1396563536 14.564 100 7 14 0 1405541536 7483376 1494624
18:26:16 65228 8192000000 10299731104 10761932800 1397012864 14.568 100 7 14 0 1405632720 7299312 1320544
18:28:58 33052 8192000000 10309278560 10766950400 1398127920 14.578 100 30 60 0 1418955888 13827360 7000608
18:30:34 35847 8192000000 10303269216 10763399168 1397615776 14.574 100 22 42 1 1414523120 12190784 4716560
18:32:45 59235 8192000000 10300247696 10754314240 1369534000 14.323 98 25 49 0 1387496656 12633440 5329216
18:34:59 28622 8192000000 10278229728 10744291328 1355946832 14.201 98 9 17 2 1369594736 11220864 2427040
18:36:28 27126 8192000000 10293591104 10731917312 1338347168 14.043 97 43 86 0 1360929776 12454944 10127664
18:38:03 56261 8192000000 10260333488 10703998976 1305191904 13.742 95 15 30 2 1320610352 12610352 2808096
18:40:03 82557 8192000000 10252250352 10699563008 1307993552 13.768 96 21 42 0 1325486752 12412112 5081088
18:42:40 56510 8192000000 10243496496 10703134720 1341118064 14.067 99 12 24 2 1356882304 12893920 2870320
18:44:37 64967 8192000000 10245089600 10710249472 1334191136 14.005 98 12 25 2 1347527424 10639168 2697120
18:47:09 60829 8192000000 10251398048 10699747328 1281278784 13.525 94 20 40 0 1297847920 11936176 4632960
18:49:19 54069 8192000000 10263458432 10707230720 1325330496 13.925 98 20 39 1 1343586320 13487232 4768592
18:51:16 96005 8192000000 10256884256 10702422016 1308256816 13.770 96 24 48 0 1327917200 14047168 5613216
18:54:11 74369 8192000000 10286692944 10719715328 1353912912 14.183 99 16 32 0 1366938464 9205456 3820096
18:56:26 111922 8192000000 10262936528 10728165376 1288552416 13.591 95 17 34 0 1312202720 19992848 3657456
18:59:56 25412 8192000000 10275379120 10713387008 1365932192 14.291 100 43 86 1 1388859856 13716752 9210912
19:01:53 50274 8192000000 10271644832 10706034688 1343051232 14.085 98 27 52 1 1357095792 8452000 5592560
19:03:44 64729 8192000000 10263216464 10726035456 1366120848 14.292 100 11 22 0 1382171728 13523264 2527616
19:05:48 85081 8192000000 10266292176 10729197568 1357377392 14.214 99 18 37 0 1376640416 14733872 4529152
19:08:17 57101 8192000000 10282862432 10735431680 1381155840 14.427 100 12 23 0 1391475568 8087264 2232464
19:10:19 56866 8192000000 10290714352 10743750656 1360229104 14.239 98 20 40 0 1371154336 6717776 4207456
19:12:17 32267 8192000000 10310903440 10759041024 1393388912 14.536 100 32 63 1 1410088832 9406448 7293472
19:13:50 51732 8192000000 10297709408 10769596416 1386782608 14.477 99 20 42 1 1404319648 12887072 4649968
19:15:42 66719 8192000000 10302130416 10759925760 1375533488 14.377 98 16 32 0 1395777632 16583808 3660336
19:17:55 62108 8192000000 10301942192 10764247040 1384571680 14.457 99 23 45 1 1403028432 13343120 5113632
19:19:57 61076 8192000000 10284393920 10727288832 1333420464 13.998 96 23 46 1 1347596576 8907808 5268304
19:22:01 131798 8192000000 10271450576 10729984000 1293729360 13.638 93 8 16 0 1307602656 12180368 1692928
19:25:18 57695 8192000000 10265604352 10732474368 1355370576 14.196 99 13 26 0 1373352048 15206240 2775232
19:27:23 37960 8192000000 10232106176 10689798144 1324777808 13.920 98 7 15 0 1334061600 7897504 1386288
19:29:02 89324 8192000000 10262686752 10714521600 1336377888 14.025 98 16 32 1 1348778704 8496416 3904400
19:32:36 69527 8192000000 10240424256 10694348800 1286196832 13.570 95 12 24 0 1304680592 15548128 2935632
19:35:05 66690 8192000000 10292990544 10736017408 1346656544 14.117 98 32 64 0 1377370112 23181328 7532240
19:37:18 51655 8192000000 10265020064 10716798976 1342008992 14.076 99 12 24 0 1365987520 21250064 2728464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-04-2022 19:39:22 to 13-04-2022 19:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-04-2022 19:39:38 to 13-04-2022 19:39:40.

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          242111
MON$OLDEST_ACTIVE               242940
MON$OLDEST_SNAPSHOT             242940
MON$NEXT_TRANSACTION            242940
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                       2017984
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:331: 
:332: mscs-test-161.budzdorov.ru    Wed Apr 13 19:38:12 2022
:333:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:334: 
:335: 
:336: mscs-test-161.budzdorov.ru    Wed Apr 13 19:38:12 2022
:337:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:338: 
:339: 
:340: mscs-test-161.budzdorov.ru    Wed Apr 13 19:38:15 2022
:341:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:342: 
--- end of diff output ---

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

13.04.22 19:39:41. 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-04-13 19:39:43 No severe PSQL-related problems occured
13.04.22 19:39:44. End of report.