Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.12.2021 01: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 16910524 kB
MemAvailable 31519712 kB
Buffers 0 kB
Cached 14796900 kB
SwapCached 0 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.8
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 2021-12-06 01:37:58
DTS_END                         2021-12-06 01:37:58.0030
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/55664
TRN_ID                          237598
ATT_ID                          711
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: 9189.78 22318 1102773
customer order: creation 171.27 358 20552
customer order: refuse 14.22 362 1706
order to supplier: creation 45.40 1219 5448
order to supplier: removal 3.36 101074 403
invoice (draft): creation 45.15 3172 5418
invoice (draft): removal 51.65 720 6198
invoice accept: apply 41.63 19123 4996
invoice accept: cancel 47.23 722 5668
customer reserve: creation 3829.40 192 459528
customer reserve: removal 4638.47 58 556616
realization accept: apply 83.07 174 9968
realization accept: cancel 89.78 109 10774
payment from customer: creation 58.50 127 7020
payment from customer: removal 5.75 99 690
payment to supplier: creation 48.81 128 5857
payment to supplier: removal 3.67 191 440
service: total inventory turnovers 4.71 1058 565
service: total monetary turnovers 6.83 39 820
service: aggregate perf. data 0.85 21220 102
service: refresh index statistics 0.03 296215 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-12-2021 01:40:08 to 06-12-2021 01:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1898
TEST_TIME 2 2947
TEST_TIME 3 3823
TEST_TIME 4 3982
TEST_TIME 5 4624
TEST_TIME 6 4580
TEST_TIME 7 5015
TEST_TIME 8 5254
TEST_TIME 9 5191
TEST_TIME 10 5615
TEST_TIME 11 5801
TEST_TIME 12 5606
TEST_TIME 13 5678
TEST_TIME 14 6114
TEST_TIME 15 6323
TEST_TIME 16 6183
TEST_TIME 17 6548
TEST_TIME 18 7185
TEST_TIME 19 7527
TEST_TIME 20 7770
TEST_TIME 21 7894
TEST_TIME 22 8152
TEST_TIME 23 8693
TEST_TIME 24 9205
TEST_TIME 25 9152
TEST_TIME 26 10028
TEST_TIME 27 10617
TEST_TIME 28 10331
TEST_TIME 29 10310
TEST_TIME 30 10197
TEST_TIME 31 10134
TEST_TIME 32 10046
TEST_TIME 33 9862
TEST_TIME 34 9918
TEST_TIME 35 9854
TEST_TIME 36 9671
TEST_TIME 37 9527
TEST_TIME 38 9608
TEST_TIME 39 9370
TEST_TIME 40 9610
TEST_TIME 41 9558
TEST_TIME 42 9445
TEST_TIME 43 9514
TEST_TIME 44 9514
TEST_TIME 45 9606
TEST_TIME 46 9549
TEST_TIME 47 9499
TEST_TIME 48 9517
TEST_TIME 49 9594
TEST_TIME 50 9589
TEST_TIME 51 9512
TEST_TIME 52 9556
TEST_TIME 53 9564
TEST_TIME 54 9416
TEST_TIME 55 9347
TEST_TIME 56 9274
TEST_TIME 57 9297
TEST_TIME 58 9239
TEST_TIME 59 9267
TEST_TIME 60 9221
TEST_TIME 61 9203
TEST_TIME 62 9128
TEST_TIME 63 9105
TEST_TIME 64 9119
TEST_TIME 65 9119
TEST_TIME 66 9097
TEST_TIME 67 9144
TEST_TIME 68 9185
TEST_TIME 69 9316
TEST_TIME 70 9281
TEST_TIME 71 9279
TEST_TIME 72 9269
TEST_TIME 73 9292
TEST_TIME 74 9286
TEST_TIME 75 9285
TEST_TIME 76 9314
TEST_TIME 77 9337
TEST_TIME 78 9352
TEST_TIME 79 9264
TEST_TIME 80 9194
TEST_TIME 81 9139
TEST_TIME 82 9138
TEST_TIME 83 9066
TEST_TIME 84 9073
TEST_TIME 85 9053
TEST_TIME 86 9139
TEST_TIME 87 9093
TEST_TIME 88 9079
TEST_TIME 89 9109
TEST_TIME 90 9186
TEST_TIME 91 9232
TEST_TIME 92 9248
TEST_TIME 93 9225
TEST_TIME 94 9264
TEST_TIME 95 9272
TEST_TIME 96 9314
TEST_TIME 97 9301
TEST_TIME 98 9307
TEST_TIME 99 9306
TEST_TIME 100 9254
TEST_TIME 101 9219
TEST_TIME 102 9187
TEST_TIME 103 9140
TEST_TIME 104 9165
TEST_TIME 105 9122
TEST_TIME 106 9106
TEST_TIME 107 9087
TEST_TIME 108 9092
TEST_TIME 109 9079
TEST_TIME 110 9063
TEST_TIME 111 9059
TEST_TIME 112 9073
TEST_TIME 113 9138
TEST_TIME 114 9194
TEST_TIME 115 9190
TEST_TIME 116 9210
TEST_TIME 117 9183
TEST_TIME 118 9199
TEST_TIME 119 9226
TEST_TIME 120 9191

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-12-2021 01:40:12 to 06-12-2021 01:40: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 1527774 1527774 0 0.00 0 35027 7 0 0
doc_list_biud 1527774 1527774 0 0.00 0 25470 26 0 0
sp_add_invoice_to_stock 5122 4996 126 2.45 38 224698 19123 0 126
sp_cancel_adding_invoice 5668 5668 0 0.00 5 67572 723 0 0
sp_cancel_client_order 1706 1706 0 0.00 4 39132 363 0 0
sp_cancel_customer_reserve 556616 556616 0 0.00 1 18062 59 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 2 7848 99 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 11537 191 0 0
sp_cancel_supplier_invoice 6206 6198 8 0.12 2 13817 721 0 8
sp_cancel_supplier_order 403 403 0 0.00 11 1148306 101074 0 0
sp_cancel_write_off 10774 10774 0 0.00 1 25491 110 0 0
sp_client_order 20552 20552 0 0.00 3 34097 358 0 0
sp_customer_reserve 459528 459528 0 0.00 0 98845 192 0 0
sp_fill_shopping_cart 31396 31396 0 0.00 1 12656 34 0 0
sp_fill_shopping_cart_clo_res 459516 459516 0 0.00 0 15734 13 0 0
sp_get_clo_for_invoice 4984 4984 0 0.00 0 27022 313 0 0
sp_kill_cost_storno 17572 17572 0 0.00 0 7650 50 0 0
sp_kill_qstorno_ret_qs2qd 1130607 1130607 0 0.00 0 25466 31 0 0
sp_kill_qty_storno 596315 596315 0 0.00 0 25470 67 0 0
sp_lock_selected_doc 20763 20763 0 0.00 0 8339 5 0 0
sp_make_cost_storno 27827 27827 0 0.00 0 13775 52 0 0
sp_multiply_rows_for_pdistr 27827 27827 0 0.00 0 9846 26 0 0
sp_multiply_rows_for_qdistr 20552 20552 0 0.00 0 14274 159 0 0
sp_pay_from_customer 7020 7020 0 0.00 4 23398 127 0 0
sp_pay_to_supplier 5857 5857 0 0.00 4 14617 128 0 0
sp_payment_common 12877 12877 0 0.00 1 15910 28 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 0 13077 304 0 0
sp_qd_handle_on_invoice_upd_sts 10650 10650 0 0.00 0 19861 297 0 0
sp_qd_handle_on_reserve_upd_sts 9968 9968 0 0.00 0 15621 47 0 0
sp_reserve_write_off 9968 9968 0 0.00 6 33138 174 0 0
sp_supplier_invoice 5418 5418 0 0.00 7 64314 3172 0 0
sp_supplier_order 5448 5448 0 0.00 5 35484 1220 0 0
srv_aggregate_perf_data 102 102 0 0.00 3062 248720 21220 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2040 149567 50313 0 0
srv_find_qd_qs_mism 1055058 1055058 0 0.00 0 35026 6 0 0
srv_make_invnt_saldo 565 565 0 0.00 32 36579 1059 0 0
srv_make_money_saldo 820 820 0 0.00 0 13348 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 152408 541054 296216 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4996 4996 0 0.00 0 12960 150 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 8968 105 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 8675 15 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 1288 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 285 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 15843 56 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 2523 27 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 22519 1565 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 2693 8 0 0
t$perf-norm:sp_client_order 20552 20552 0 0.00 0 15815 17 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 3289 7 0 0
t$perf-norm:sp_pay_from_customer 7020 7020 0 0.00 0 3424 7 0 0
t$perf-norm:sp_pay_to_supplier 5857 5857 0 0.00 0 6034 8 0 0
t$perf-norm:sp_reserve_write_off 9968 9968 0 0.00 0 6494 8 0 0
t$perf-norm:sp_supplier_invoice 5418 5418 0 0.00 0 10458 16 0 0
t$perf-norm:sp_supplier_order 5448 5448 0 0.00 0 9051 15 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 360 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 12090 42 0 0
t$perf-norm:srv_make_money_saldo 820 820 0 0.00 0 7134 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10214 9962 252 2.46 0 32780 26 0 252
v_all_customers 37570 37570 0 0.00 0 15 0 0 0
v_all_suppliers 30366 30366 0 0.00 0 23 1 0 0
v_all_wares 41104 41104 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 1316 6 0 0
v_cancel_client_order 3412 3412 0 0.00 0 33956 21 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 6252 16 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 7588 9 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 11524 60 0 0
v_cancel_supplier_invoice 872 856 16 1.83 0 7580 23 0 16
v_cancel_supplier_order 806 806 0 0.00 0 7584 14 0 0
v_cancel_write_off 1984 1984 0 0.00 0 9972 24 0 0
v_max_id_clo_ord 5448 5448 0 0.00 0 7015 3 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 1643 14 0 0
v_max_id_ord_sup 5395 5395 0 0.00 0 3824 3 0 0
v_max_non_paid_invoice 1517 1517 0 0.00 0 818 3 0 0
v_max_non_paid_realizn 3713 3713 0 0.00 0 5857 6 0 0
v_min_id_clo_ord 5448 5448 0 0.00 0 4532 5 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 4202 9 0 0
v_min_id_ord_sup 5395 5395 0 0.00 0 6709 6 0 0
v_min_non_paid_invoice 5857 5857 0 0.00 0 12757 17 0 0
v_min_non_paid_realizn 7020 7020 0 0.00 0 15904 24 0 0
v_our_firm 10148 10148 0 0.00 0 14 1 0 0
v_reserve_write_off 19936 19936 0 0.00 0 32811 15 0 0
x_lock_depdocs_on_canc_invoice 11438 11438 0 0.00 0 25508 121 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 2672 305 0 0
x_make_qstorno_1000_1200 5448 5448 0 0.00 2 35466 1149 0 0
x_make_qstorno_1200_2000 5418 5418 0 0.00 3 59323 3047 0 0
x_make_qstorno_2100_3300 459528 459528 0 0.00 0 98845 177 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 06-12-2021 01:40:16 to 06-12-2021 01:40:21.

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
23:08:01 7 8192000000 8799747232 8811610112 5012192 0.061 2 1 2 0 5111792 56816 42784
23:09:02 31522 8192000000 9912741968 10225893376 990036848 10.782 100 90 180 0 1019825200 6199968 23588384
23:10:35 20380 8192000000 9947752064 10268737536 1021877552 11.090 100 90 179 0 1057755904 12938784 22939568
23:11:59 18774 8192000000 9976971824 10310057984 1057424608 11.432 100 81 162 0 1087765168 9982992 20357568
23:13:27 10781 8192000000 10033583488 10381832192 1119005680 12.018 100 82 163 1 1148923216 10188752 19728784
23:14:44 8889 8192000000 10093217856 10457473024 1177891568 12.571 100 76 152 1 1208388400 12548224 17948608
23:15:57 5185 8192000000 10135774304 10507440128 1221938384 12.980 100 80 161 1 1253338256 12678048 18721824
23:17:04 6928 8192000000 10168793952 10548174848 1257442528 13.307 100 83 167 0 1288692144 13891584 17358032
23:18:13 2755 8192000000 10200373504 10586140672 1283230688 13.543 100 86 173 0 1316600880 13693712 19676480
23:19:19 2728 8192000000 10222262768 10612129792 1307217424 13.761 100 93 185 1 1341904816 13387120 21300272
23:20:36 3226 8192000000 10219297904 10621177856 1319224864 13.870 100 61 121 0 1341696096 8823792 13647440
23:21:41 7611 8192000000 10221267344 10624921600 1325066544 13.923 100 39 76 1 1340267056 6635168 8565344
23:22:54 5779 8192000000 10221183408 10640482304 1336698080 14.028 100 23 45 1 1347393264 5278176 5417008
23:24:04 20291 8192000000 10260633888 10662055936 1341225040 14.068 100 57 113 2 1360517440 5566368 13726032
23:25:25 8130 8192000000 10237333888 10658254848 1344747376 14.100 100 30 60 1 1357677008 5886944 7042688
23:26:34 7129 8192000000 10231530752 10658836480 1344584784 14.099 100 23 46 2 1355960832 6441968 4934080
23:27:47 15701 8192000000 10204308064 10617692160 1251132784 13.249 93 41 80 0 1267141808 5204416 10804608
23:29:04 11324 8192000000 10202461200 10620739584 1270131936 13.423 96 39 76 2 1287058992 8770208 8156848
23:30:18 6160 8192000000 10152323104 10555691008 1235542704 13.105 96 23 46 0 1246805712 5688928 5574080
23:31:30 7535 8192000000 10154422336 10549100544 1242773104 13.172 99 28 56 2 1255420848 5436368 7211376
23:32:42 62767 8192000000 10135324800 10542551040 1218543648 12.948 97 29 58 2 1230852496 5224432 7084416
23:34:52 4958 8192000000 10178269104 10580766720 1279975968 13.513 100 34 66 0 1295641040 6874016 8791056
23:35:58 10917 8192000000 10179474064 10597789696 1295329568 13.653 100 14 28 0 1304217632 5532928 3355136
23:37:09 16088 8192000000 10189402176 10606034944 1306354800 13.753 100 35 71 0 1320070784 5362016 8353968
23:38:55 22513 8192000000 10231614656 10641993728 1328594000 13.954 100 47 94 1 1346815808 7784160 10437648
23:40:23 12132 8192000000 10246661616 10661490688 1344324464 14.096 100 42 84 1 1364380688 10444096 9612128
23:41:39 20934 8192000000 10278077952 10691371008 1363224208 14.266 100 63 126 0 1390189184 12397760 14567216
23:43:08 7203 8192000000 10294513536 10710142976 1377636352 14.395 100 55 110 2 1405209712 14573952 12999408
23:44:19 13237 8192000000 10297173776 10717642752 1381999936 14.434 100 56 112 1 1412974592 17560992 13413664
23:45:42 11903 8192000000 10305151744 10724679680 1379427952 14.411 99 51 101 1 1408371040 16840384 12102704
23:47:00 13833 8192000000 10300294576 10722205696 1358201152 14.221 97 60 121 1 1394911312 20816224 15893936
23:48:19 13507 8192000000 10329997312 10754838528 1408814656 14.673 100 53 102 2 1440741984 19184176 12743152
23:49:42 8132 8192000000 10356726272 10772267008 1419316528 14.767 100 73 147 0 1463334544 26451600 17566416
23:50:56 6119 8192000000 10367737616 10793771008 1431261344 14.872 100 62 124 0 1479284928 32173632 15849952
23:52:07 3868 8192000000 10378957904 10795823104 1432040320 14.879 100 73 145 4 1482718784 32056448 18622016
23:53:14 2040 8192000000 10387665616 10809532416 1440039680 14.950 100 76 150 1 1497487680 38019104 19428896
23:54:21 2404 8192000000 10389975184 10802868224 1424526128 14.813 100 91 182 0 1492281152 45086064 22668960
23:55:28 2298 8192000000 10367913904 10775154688 1408050960 14.667 99 86 172 2 1474417712 43952384 22414368
23:56:34 11904 8192000000 10359559216 10784477184 1412317328 14.705 100 37 74 1 1462145840 39722416 10106096
23:57:52 2070 8192000000 10335613808 10745860096 1362722976 14.262 98 69 140 4 1422621760 41952112 17946672
23:58:57 13317 8192000000 10314626288 10738663424 1343951120 14.093 98 35 70 1 1401812672 47843424 10018128
00:00:17 20191 8192000000 10309135248 10748989440 1352951200 14.174 99 32 64 1 1416283984 54120944 9211840
00:01:40 18834 8192000000 10278682032 10710122496 1332297536 13.988 98 39 77 1 1376364592 33920016 10147040
00:03:00 20223 8192000000 10280473120 10714202112 1326071216 13.932 99 15 30 1 1376537808 45756688 4709904
00:04:23 64504 8192000000 10229082592 10651283456 1279517584 13.509 96 27 54 2 1297549840 11871552 6160704
00:06:40 109614 8192000000 10251281504 10687692800 1310295920 13.789 98 28 56 0 1330231136 13047584 6887632
00:09:42 81861 8192000000 10279713936 10715480064 1348199264 14.131 99 23 47 0 1362482848 8983136 5300448
00:13:05 114784 8192000000 10281987008 10728099840 1363512784 14.269 98 14 28 0 1378264512 11373744 3377984
00:16:21 45993 8192000000 10273028528 10718220288 1358679600 14.225 99 21 42 0 1379747744 15171008 5897136
00:18:08 69047 8192000000 10291355152 10736082944 1356784816 14.208 99 22 45 2 1367934352 6451264 4698272
00:20:27 67353 8192000000 10299507440 10747117568 1388293888 14.491 100 29 58 1 1406530064 11855952 6380224
00:22:36 91905 8192000000 10300897328 10751623168 1373287024 14.356 99 13 26 0 1389743696 13379888 3076784
00:25:09 36047 8192000000 10313554640 10759540736 1400154544 14.596 100 34 68 0 1418289136 10835296 7299296
00:26:49 65404 8192000000 10300992512 10744258560 1373066256 14.355 98 8 16 0 1386223584 11575120 1582208
00:28:59 11926 8192000000 10325937408 10764521472 1404121008 14.632 100 55 110 2 1427960336 11873680 11965648
00:30:14 149567 8192000000 10290441744 10746638336 1334996240 14.012 95 26 51 1 1348750096 8403632 5350224
00:33:46 65000 8192000000 10312595536 10773270528 1403846432 14.629 100 41 82 2 1430382112 17334144 9201536
00:35:58 41369 8192000000 10307720624 10769793024 1400346048 14.598 100 21 42 0 1419506544 14111440 5049056
00:37:40 66409 8192000000 10300386896 10761195520 1362800352 14.262 97 16 32 1 1377359632 10934976 3624304
00:39:56 53188 8192000000 10302517168 10775568384 1408647008 14.672 100 15 29 1 1423213152 11017184 3548960
00:41:52 78849 8192000000 10287915776 10756526080 1349469168 14.143 96 10 20 0 1361857200 10409408 1978624
00:44:13 51520 8192000000 10300522864 10762792960 1380744096 14.423 99 18 36 2 1398500800 13379808 4376896
00:46:08 53794 8192000000 10264499472 10725580800 1304598496 13.737 95 15 28 1 1326770752 18567952 3604304
00:48:11 57958 8192000000 10277434448 10715127808 1331008288 13.976 98 15 29 0 1343870608 9752320 3110000
00:50:12 45314 8192000000 10271346960 10716303360 1344079040 14.094 99 27 54 0 1362783776 12922320 5782416
00:52:09 20715 8192000000 10264969888 10693738496 1300703136 13.702 96 39 78 1 1320774224 11525888 8545200
00:53:34 19980 8192000000 10281182448 10706251776 1351384480 14.160 100 21 42 2 1363059360 6986720 4688160
00:55:03 51007 8192000000 10257163872 10700292096 1316134608 13.842 97 18 36 1 1334752112 14509952 4107552
00:56:54 122499 8192000000 10250958032 10699046912 1292666112 13.629 95 23 46 1 1310745648 12446512 5633024
01:00:11 69313 8192000000 10247344528 10698686464 1344451104 14.098 99 16 32 0 1362559872 14496496 3612272
01:02:44 87803 8192000000 10245046496 10699931648 1311645040 13.801 97 7 14 0 1322865088 10031168 1188880
01:05:12 48918 8192000000 10251439760 10695413760 1300305824 13.698 95 17 34 0 1317683552 13556016 3821712
01:07:01 76422 8192000000 10261053984 10708426752 1337109952 14.031 99 15 30 1 1358887664 18170912 3606800
01:09:29 76408 8192000000 10287420752 10725969920 1334436000 14.007 97 18 36 1 1346395168 8164848 3794320
01:11:48 73775 8192000000 10304035248 10750664704 1375286080 14.374 99 25 49 1 1393201040 12405232 5509728
01:14:14 62512 8192000000 10300806112 10739539968 1344988128 14.102 97 16 32 0 1360386672 10671712 4726832
01:16:17 117787 8192000000 10302787104 10746023936 1382101968 14.435 100 19 38 0 1402151056 15705232 4343856
01:19:27 69775 8192000000 10291895760 10753441792 1391718864 14.521 100 15 30 0 1405666608 10869424 3078320
01:21:37 65451 8192000000 10271769680 10726735872 1322752416 13.902 95 7 14 0 1333192864 8846448 1594000
01:23:44 65347 8192000000 10283197008 10752598016 1374668160 14.369 99 10 20 1 1389133296 11880208 2584928
01:26:32 49140 8192000000 10283655328 10740076544 1345982800 14.111 96 16 32 1 1363217424 13830256 3404368
01:28:24 67281 8192000000 10294558016 10749370368 1364960272 14.282 98 18 36 0 1387128848 17632944 4535632
01:30:32 111249 8192000000 10296541296 10778251264 1336648800 14.027 96 10 20 1 1364562352 24962544 2951008
01:33:24 69617 8192000000 10275673936 10717503488 1315811488 13.839 95 25 49 0 1329744960 8557872 5375600
01:35:54 95955 8192000000 10282015440 10735824896 1299019696 13.686 94 21 42 0 1317983168 14631680 4331792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 06-12-2021 01:40:23 to 06-12-2021 01:40:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-12-2021 01:40:38 to 06-12-2021 01:40: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          236782
MON$OLDEST_ACTIVE               238020
MON$OLDEST_SNAPSHOT             238020
MON$NEXT_TRANSACTION            238020
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                       2011328
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33541 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33541 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33541 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33541 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 ---
:85: 
:86: mscs-test-161.budzdorov.ru    Mon Dec  6 01:38:24 2021
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:88: 
--- end of diff output ---

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

06.12.21 01:40: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
2021-12-06 01:40:44 No severe PSQL-related problems occured
06.12.21 01:40:45. End of report.