Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.03.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 16796108 kB
MemAvailable 31612024 kB
Buffers 0 kB
Cached 14897816 kB
SwapCached 2156 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-03-25 19:38:09
DTS_END                         2022-03-25 19:38:09.3250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45420
TRN_ID                          240610
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: 9436.95 23769 1132434
customer order: creation 171.93 378 20631
customer order: refuse 15.15 296 1818
order to supplier: creation 46.62 1324 5594
order to supplier: removal 3.68 90160 442
invoice (draft): creation 46.66 3278 5599
invoice (draft): removal 56.48 761 6777
invoice accept: apply 41.47 17600 4976
invoice accept: cancel 50.48 745 6058
customer reserve: creation 3800.75 177 456090
customer reserve: removal 4895.79 53 587495
realization accept: apply 83.43 175 10012
realization accept: cancel 91.84 104 11021
payment from customer: creation 60.51 126 7261
payment from customer: removal 5.73 124 688
payment to supplier: creation 49.85 107 5982
payment to supplier: removal 3.56 69 427
service: total inventory turnovers 5.28 998 633
service: total monetary turnovers 6.83 77 819
service: aggregate perf. data 0.89 16620 107
service: refresh index statistics 0.03 342215 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-03-2022 19:38:52 to 25-03-2022 19:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4736
TEST_TIME 2 4996
TEST_TIME 3 5223
TEST_TIME 4 5012
TEST_TIME 5 5261
TEST_TIME 6 5511
TEST_TIME 7 5660
TEST_TIME 8 5766
TEST_TIME 9 5997
TEST_TIME 10 6139
TEST_TIME 11 6213
TEST_TIME 12 6220
TEST_TIME 13 6631
TEST_TIME 14 6976
TEST_TIME 15 7084
TEST_TIME 16 7649
TEST_TIME 17 7793
TEST_TIME 18 8353
TEST_TIME 19 8387
TEST_TIME 20 8389
TEST_TIME 21 8522
TEST_TIME 22 8589
TEST_TIME 23 8609
TEST_TIME 24 9191
TEST_TIME 25 9462
TEST_TIME 26 10051
TEST_TIME 27 10915
TEST_TIME 28 10812
TEST_TIME 29 10668
TEST_TIME 30 10568
TEST_TIME 31 10379
TEST_TIME 32 10376
TEST_TIME 33 10277
TEST_TIME 34 10201
TEST_TIME 35 10185
TEST_TIME 36 10072
TEST_TIME 37 10156
TEST_TIME 38 9982
TEST_TIME 39 9924
TEST_TIME 40 9925
TEST_TIME 41 9905
TEST_TIME 42 10028
TEST_TIME 43 9906
TEST_TIME 44 9875
TEST_TIME 45 9832
TEST_TIME 46 10143
TEST_TIME 47 10145
TEST_TIME 48 10121
TEST_TIME 49 10061
TEST_TIME 50 10083
TEST_TIME 51 10133
TEST_TIME 52 10078
TEST_TIME 53 10034
TEST_TIME 54 10025
TEST_TIME 55 9881
TEST_TIME 56 9901
TEST_TIME 57 9805
TEST_TIME 58 9808
TEST_TIME 59 9727
TEST_TIME 60 9627
TEST_TIME 61 9625
TEST_TIME 62 9548
TEST_TIME 63 9515
TEST_TIME 64 9490
TEST_TIME 65 9571
TEST_TIME 66 9579
TEST_TIME 67 9572
TEST_TIME 68 9516
TEST_TIME 69 9663
TEST_TIME 70 9616
TEST_TIME 71 9691
TEST_TIME 72 9703
TEST_TIME 73 9643
TEST_TIME 74 9765
TEST_TIME 75 9760
TEST_TIME 76 9737
TEST_TIME 77 9821
TEST_TIME 78 9762
TEST_TIME 79 9667
TEST_TIME 80 9587
TEST_TIME 81 9575
TEST_TIME 82 9612
TEST_TIME 83 9545
TEST_TIME 84 9486
TEST_TIME 85 9476
TEST_TIME 86 9450
TEST_TIME 87 9487
TEST_TIME 88 9526
TEST_TIME 89 9529
TEST_TIME 90 9470
TEST_TIME 91 9587
TEST_TIME 92 9575
TEST_TIME 93 9665
TEST_TIME 94 9630
TEST_TIME 95 9654
TEST_TIME 96 9667
TEST_TIME 97 9627
TEST_TIME 98 9621
TEST_TIME 99 9654
TEST_TIME 100 9610
TEST_TIME 101 9537
TEST_TIME 102 9505
TEST_TIME 103 9472
TEST_TIME 104 9441
TEST_TIME 105 9414
TEST_TIME 106 9393
TEST_TIME 107 9352
TEST_TIME 108 9375
TEST_TIME 109 9351
TEST_TIME 110 9369
TEST_TIME 111 9349
TEST_TIME 112 9380
TEST_TIME 113 9388
TEST_TIME 114 9393
TEST_TIME 115 9388
TEST_TIME 116 9404
TEST_TIME 117 9438
TEST_TIME 118 9422
TEST_TIME 119 9488
TEST_TIME 120 9437

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-03-2022 19:38:56 to 25-03-2022 19:38:59.

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 1557148 1557148 0 0.00 0 20807 6 0 0
doc_list_biud 1557146 1557146 0 0.00 0 21566 26 0 0
sp_add_invoice_to_stock 5051 4976 75 1.48 40 158565 17600 0 75
sp_cancel_adding_invoice 6058 6058 0 0.00 5 73177 746 0 0
sp_cancel_client_order 1818 1818 0 0.00 4 9594 297 0 0
sp_cancel_customer_reserve 587495 587495 0 0.00 1 17307 53 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 2 12329 124 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 7735 70 0 0
sp_cancel_supplier_invoice 6784 6777 7 0.10 2 16084 762 0 7
sp_cancel_supplier_order 442 442 0 0.00 10 823663 90160 0 0
sp_cancel_write_off 11021 11021 0 0.00 1 16891 105 0 0
sp_client_order 20631 20631 0 0.00 3 17043 378 0 0
sp_customer_reserve 456090 456090 0 0.00 0 107565 177 0 0
sp_fill_shopping_cart 31815 31815 0 0.00 1 22926 41 0 0
sp_fill_shopping_cart_clo_res 456089 456089 0 0.00 0 22503 12 0 0
sp_get_clo_for_invoice 4975 4975 0 0.00 0 22983 280 0 0
sp_kill_cost_storno 18194 18194 0 0.00 0 13898 52 0 0
sp_kill_qstorno_ret_qs2qd 1193230 1193230 0 0.00 0 17270 29 0 0
sp_kill_qty_storno 628597 628597 0 0.00 0 21565 63 0 0
sp_lock_selected_doc 20918 20918 0 0.00 0 8341 5 0 0
sp_make_cost_storno 28230 28230 0 0.00 0 14956 53 0 0
sp_multiply_rows_for_pdistr 28228 28228 0 0.00 0 15022 33 0 0
sp_multiply_rows_for_qdistr 20631 20631 0 0.00 0 10047 161 0 0
sp_pay_from_customer 7261 7261 0 0.00 4 19679 126 0 0
sp_pay_to_supplier 5982 5982 0 0.00 5 7544 108 0 0
sp_payment_common 13242 13242 0 0.00 1 19546 27 0 0
sp_qd_handle_on_cancel_clo 1818 1818 0 0.00 0 9522 273 0 0
sp_qd_handle_on_invoice_upd_sts 11033 11033 0 0.00 0 21564 282 0 0
sp_qd_handle_on_reserve_upd_sts 10011 10011 0 0.00 0 15714 59 0 0
sp_reserve_write_off 10012 10012 0 0.00 6 19660 176 0 0
sp_supplier_invoice 5599 5599 0 0.00 6 86932 3279 0 0
sp_supplier_order 5594 5594 0 0.00 4 36677 1325 0 0
srv_aggregate_perf_data 107 107 0 0.00 2933 211873 16620 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1387 143381 49505 0 0
srv_find_qd_qs_mism 1085697 1085697 0 0.00 0 20797 6 0 0
srv_make_invnt_saldo 633 633 0 0.00 37 38836 999 0 0
srv_make_money_saldo 819 819 0 0.00 1 41339 77 0 0
srv_recalc_idx_stat 4 4 0 0.00 310728 371320 342216 0 0
t$perf-abend:sp_add_invoice_to_stock 75 75 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 4976 4976 0 0.00 0 15355 138 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 6931 117 0 0
t$perf-norm:sp_cancel_client_order 1818 1818 0 0.00 0 1434 10 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 3323 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 2407 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 5055 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 558 13 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 28255 1249 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 4385 11 0 0
t$perf-norm:sp_client_order 20631 20631 0 0.00 0 23786 19 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 879 3 0 0
t$perf-norm:sp_pay_from_customer 7261 7261 0 0.00 0 5055 11 0 0
t$perf-norm:sp_pay_to_supplier 5982 5982 0 0.00 0 6074 7 0 0
t$perf-norm:sp_reserve_write_off 10012 10012 0 0.00 0 4646 10 0 0
t$perf-norm:sp_supplier_invoice 5599 5599 0 0.00 0 22358 22 0 0
t$perf-norm:sp_supplier_order 5594 5594 0 0.00 0 7503 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 506 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 633 633 0 0.00 0 588 12 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 2229 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10098 9948 150 1.48 0 7954 15 0 150
v_all_customers 38002 38002 0 0.00 0 14 0 0 0
v_all_suppliers 31112 31112 0 0.00 0 17 1 0 0
v_all_wares 41262 41262 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 2974 14 0 0
v_cancel_client_order 3636 3636 0 0.00 0 2618 5 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 4180 8 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 1046 3 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 547 3 0 0
v_cancel_supplier_invoice 898 884 14 1.55 0 6130 19 0 14
v_cancel_supplier_order 884 884 0 0.00 0 1675 6 0 0
v_cancel_write_off 2004 2004 0 0.00 0 3005 14 0 0
v_max_id_clo_ord 5591 5591 0 0.00 0 1850 2 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 6401 21 0 0
v_max_id_ord_sup 5593 5593 0 0.00 0 5571 5 0 0
v_max_non_paid_invoice 1609 1609 0 0.00 0 2501 4 0 0
v_max_non_paid_realizn 3744 3744 0 0.00 0 3254 6 0 0
v_min_id_clo_ord 5591 5591 0 0.00 0 7207 9 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 4234 13 0 0
v_min_id_ord_sup 5593 5593 0 0.00 0 5738 8 0 0
v_min_non_paid_invoice 5982 5982 0 0.00 0 6102 13 0 0
v_min_non_paid_realizn 7260 7260 0 0.00 0 14979 27 0 0
v_our_firm 10292 10292 0 0.00 0 12 1 0 0
v_reserve_write_off 20022 20022 0 0.00 0 7817 6 0 0
x_lock_depdocs_on_canc_invoice 12393 12393 0 0.00 0 26991 109 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 10967 283 0 0
x_make_qstorno_1000_1200 5594 5594 0 0.00 1 36665 1241 0 0
x_make_qstorno_1200_2000 5599 5599 0 0.00 3 79503 3134 0 0
x_make_qstorno_2100_3300 456090 456090 0 0.00 0 107563 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-03-2022 19:39:00 to 25-03-2022 19:39:04.

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:12 6 8192000000 8803478768 8816398336 8777344 0.107 3 2 3 0 8919792 67504 74944
17:09:13 13325 8192000000 9898940176 10209255424 986082496 10.743 100 85 170 0 1014178080 6114768 21980816
17:10:34 18793 8192000000 9943906576 10263379968 1022379376 11.095 100 90 180 0 1054527120 9030608 23117136
17:11:58 23417 8192000000 9966900928 10296213504 1050295008 11.364 100 87 174 0 1082583008 10241792 22046208
17:13:31 12587 8192000000 10019356736 10362847232 1099855776 11.836 100 75 150 1 1128359664 10047072 18456816
17:14:56 14196 8192000000 10085843184 10441400320 1159131888 12.395 100 77 154 1 1188063200 11851232 17080080
17:16:13 5600 8192000000 10121264544 10486304768 1204096224 12.814 100 88 177 0 1237328096 13576960 19654912
17:17:21 5827 8192000000 10146519600 10515918848 1226254176 13.019 100 75 148 1 1256716480 13815600 16646704
17:18:29 2922 8192000000 10177685344 10560036864 1268332208 13.406 100 88 177 0 1301579232 13260816 19986208
17:19:33 8377 8192000000 10182656944 10583044096 1283725088 13.547 100 69 138 2 1311773696 12623328 15425280
17:20:44 8940 8192000000 10193173744 10594091008 1296283408 13.661 100 51 99 2 1317116144 10118144 10714592
17:21:54 6823 8192000000 10203537168 10612768768 1307835488 13.766 100 34 69 0 1326250512 10794928 7620096
17:23:01 5782 8192000000 10211031440 10626949120 1322671568 13.901 100 26 52 2 1334706848 6324208 5711072
17:24:13 17570 8192000000 10210134368 10633134080 1330567408 13.972 100 34 67 0 1344964496 5844496 8552592
17:25:31 30402 8192000000 10231059424 10653028352 1336878032 14.029 100 32 65 0 1350874448 6194624 7801792
17:27:03 5631 8192000000 10230028736 10652155904 1341633792 14.072 100 36 73 0 1356253296 5883696 8735808
17:28:10 7012 8192000000 10209842880 10625003520 1290368032 13.608 97 15 30 0 1299775712 6112576 3295104
17:29:17 9059 8192000000 10190180928 10597203968 1248511248 13.225 95 9 18 0 1255398768 4898080 1989440
17:30:26 16203 8192000000 10151136064 10558586880 1158738208 12.391 89 23 46 1 1168897760 4848704 5310848
17:31:46 4648 8192000000 10154987248 10552434688 1257900672 13.311 100 47 94 2 1275876880 6943008 11033200
17:32:55 8217 8192000000 10152143568 10549616640 1228988816 13.045 97 19 39 0 1238521312 5175632 4356864
17:34:03 17009 8192000000 10190402896 10582630400 1269718208 13.419 99 28 56 0 1282667728 5543424 7406096
17:35:23 17173 8192000000 10188582816 10598551552 1292360048 13.626 100 25 50 1 1304563008 6524528 5678432
17:36:43 4942 8192000000 10203031040 10614906880 1309513856 13.782 100 33 65 0 1322904832 5767040 7623936
17:37:49 7439 8192000000 10209016992 10626195456 1313260464 13.816 100 15 30 0 1322148528 5542688 3345376
17:39:03 14863 8192000000 10255495168 10665062400 1345532944 14.107 100 44 88 1 1363350416 7720480 10096992
17:40:30 64394 8192000000 10259783488 10686189568 1357448768 14.214 100 37 74 1 1374750848 8696960 8605120
17:43:35 12460 8192000000 10312151456 10729443328 1390913008 14.514 100 61 121 3 1420367680 15047552 14407120
17:44:49 12304 8192000000 10315586352 10737954816 1399384064 14.590 100 51 95 1 1424380960 14226592 10770304
17:46:05 13211 8192000000 10319118112 10748207104 1405872928 14.647 100 48 96 1 1432998944 15591824 11534192
17:47:22 5277 8192000000 10337346992 10757287936 1411623120 14.698 100 63 125 0 1445579344 18450864 15505360
17:48:31 8446 8192000000 10321860480 10746036224 1394198048 14.543 98 43 86 0 1423034992 17462496 11374448
17:49:43 5658 8192000000 10340105856 10756460544 1403855728 14.629 100 70 140 2 1444982816 24463584 16663504
17:50:52 8445 8192000000 10372310656 10786832384 1428826992 14.851 100 76 151 0 1476463264 28019616 19616656
17:52:04 1386 8192000000 10388159344 10810867712 1445386240 14.997 100 78 156 1 1497818896 32659056 19773600
17:53:10 3504 8192000000 10372899104 10787938304 1417519024 14.751 99 74 148 0 1475048896 38529904 18999968
17:54:18 3711 8192000000 10367530864 10781704192 1406714112 14.655 100 73 146 2 1469979136 45062784 18202240
17:55:26 13499 8192000000 10349663168 10775068672 1394595360 14.547 99 40 80 0 1445141472 39415536 11130576
17:56:48 20090 8192000000 10338908672 10773209088 1375686176 14.378 98 36 72 0 1435388496 49644752 10057568
17:58:10 16447 8192000000 10358328912 10789339136 1408245744 14.668 100 44 88 0 1467547376 47619856 11681776
17:59:29 22308 8192000000 10345042512 10778288128 1387448864 14.483 99 28 56 3 1445599728 50523808 7627056
18:01:04 37048 8192000000 10336788256 10774659072 1376832848 14.388 100 26 52 1 1442936288 58266000 7837440
18:02:51 28771 8192000000 10320741632 10760855552 1351041760 14.157 98 28 56 1 1411961344 52733392 8186192
18:04:32 53335 8192000000 10279344288 10718097408 1293905200 13.640 95 25 50 1 1341893904 41120512 6868192
18:06:26 40758 8192000000 10260145312 10695172096 1316548432 13.845 97 26 51 2 1332614112 10219456 5846224
18:08:25 143381 8192000000 10270156992 10715787264 1338760928 14.046 98 29 56 3 1353962016 9163024 6038064
18:11:51 28366 8192000000 10293559520 10731016192 1378663328 14.405 100 40 79 1 1397488192 9805008 9019856
18:13:20 34843 8192000000 10319047264 10762711040 1401586528 14.609 100 41 81 0 1424965600 13848592 9530480
18:14:57 39029 8192000000 10295223264 10751688704 1365424272 14.286 98 25 50 2 1393416768 21237280 6755216
18:16:44 67901 8192000000 10306802224 10765676544 1398211104 14.579 100 21 42 2 1422709696 19436128 5062464
18:19:05 105824 8192000000 10291962608 10764992512 1372365488 14.348 98 12 24 3 1391859408 16483104 3010816
18:21:52 49022 8192000000 10313642944 10774147072 1406483248 14.653 100 27 54 0 1427172560 14422848 6266464
18:23:46 60997 8192000000 10336191408 10796064768 1410011200 14.684 100 18 36 0 1437608768 22973712 4623856
18:25:55 100536 8192000000 10315446352 10772287488 1399845952 14.594 99 14 28 0 1413849424 10766528 3236944
18:28:49 37153 8192000000 10318349360 10772647936 1396463664 14.563 99 18 36 0 1414788528 14221968 4102896
18:30:29 52205 8192000000 10333392512 10778460160 1387308928 14.482 98 16 34 0 1401960448 10605248 4046272
18:32:25 96803 8192000000 10300098496 10748211200 1367833696 14.308 98 31 62 0 1390106672 14608608 7664368
18:35:04 30533 8192000000 10305206416 10752937984 1383741152 14.450 100 46 93 1 1405661200 11671728 10248320
18:36:38 59902 8192000000 10279782560 10746187776 1376621040 14.386 99 7 14 0 1385283968 7267008 1395920
18:38:38 66343 8192000000 10270754496 10728439808 1351960528 14.165 97 12 24 1 1362907536 8106672 2840336
18:40:49 57305 8192000000 10268564976 10729029632 1331393936 13.980 96 10 20 1 1343940880 10474560 2072384
18:42:51 54853 8192000000 10250690400 10705981440 1330074864 13.968 97 13 26 0 1343861600 10854064 2932672
18:44:46 23217 8192000000 10266191312 10709557248 1345510880 14.107 99 31 62 0 1364189824 11445776 7233168
18:46:10 72441 8192000000 10271303536 10715025408 1338540608 14.044 98 25 50 0 1361754976 16811104 6403264
18:48:36 76940 8192000000 10278262768 10732064768 1349848256 14.146 98 29 58 0 1365958608 9508016 6602336
18:50:56 38949 8192000000 10291559440 10734669824 1345265152 14.105 98 20 40 0 1364292064 14550992 4475920
18:53:01 69386 8192000000 10282187120 10724663296 1313927984 13.822 96 10 20 0 1326796208 10839376 2028848
18:55:11 95405 8192000000 10273727120 10740842496 1353041664 14.175 99 9 18 2 1370302448 15414592 1846192
18:57:50 79785 8192000000 10281509264 10722222080 1345476304 14.107 98 26 52 1 1363016320 11976160 5563856
19:00:10 73081 8192000000 10268582768 10731229184 1330613440 13.973 97 10 20 0 1346866544 13746672 2506432
19:03:02 64885 8192000000 10275190528 10734944256 1361002944 14.246 99 22 44 0 1386627248 19565056 6059248
19:05:08 78683 8192000000 10265105696 10726428672 1328556000 13.954 97 11 22 0 1347774944 15936880 3282064
19:07:30 53272 8192000000 10293803872 10743545856 1376902288 14.389 100 7 14 0 1393197744 14651328 1644128
19:09:38 39677 8192000000 10295243120 10734743552 1358077440 14.220 98 27 54 0 1370900208 6831808 5990960
19:11:22 81769 8192000000 10306743632 10761093120 1394470320 14.546 100 16 32 1 1414471600 17086784 2914496
19:13:44 89747 8192000000 10291988784 10748399616 1351221408 14.158 97 16 32 0 1365633008 11099808 3311792
19:16:27 42708 8192000000 10315420096 10767437824 1386860768 14.478 99 36 72 1 1406419744 11786912 7772064
19:19:38 86561 8192000000 10313187120 10765778944 1379525360 14.412 98 16 32 0 1390693376 7565200 3602816
19:22:59 61609 8192000000 10293158992 10765029376 1393038384 14.533 100 8 16 0 1406513744 11428816 2046544
19:25:03 80966 8192000000 10262703680 10732343296 1326547664 13.936 96 17 35 2 1341991744 10573728 4870352
19:27:24 58842 8192000000 10283790544 10750803968 1354875648 14.191 98 14 27 0 1372899952 14890752 3133552
19:29:23 63112 8192000000 10282534800 10745929728 1349677600 14.145 97 15 30 1 1363526864 10762016 3087248
19:31:27 38847 8192000000 10277487168 10730504192 1323588544 13.909 96 38 74 2 1345378368 13680160 8109664
19:33:08 75129 8192000000 10271334544 10727575552 1310787760 13.793 96 18 36 0 1325207152 10388928 4030464
19:35:36 81109 8192000000 10263789648 10714312704 1321375232 13.889 97 17 33 1 1343194624 17901680 3917712
19:37:58 22716 8192000000 8965518800 9143033856 130842368 1.572 10 1 2 0 133768208 2883488 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-03-2022 19:39:06 to 25-03-2022 19:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 75 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 25-03-2022 19:39:22 to 25-03-2022 19:39:24.

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          240898
MON$OLDEST_ACTIVE               240899
MON$OLDEST_SNAPSHOT             240899
MON$NEXT_TRANSACTION            240899
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                       2020160
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:447: 
:448: mscs-test-161.budzdorov.ru    Fri Mar 25 19:38:22 2022
:449:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:450: 
:451: 
:452: mscs-test-161.budzdorov.ru    Fri Mar 25 19:38:22 2022
:453:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:454: 
--- end of diff output ---

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

25.03.22 19:39:25. 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-03-25 19:39:27 No severe PSQL-related problems occured
25.03.22 19:39:28. End of report.