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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16647640 kB
MemAvailable 31445852 kB
Buffers 0 kB
Cached 15053024 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.9
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-19 19:37:58
DTS_END                         2021-12-19 19:37:58.4570
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/43800
TRN_ID                          235540
ATT_ID                          643
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: 9293.22 21991 1115186
customer order: creation 171.98 377 20637
customer order: refuse 14.44 428 1733
order to supplier: creation 45.40 1319 5448
order to supplier: removal 3.63 105388 436
invoice (draft): creation 46.80 3538 5616
invoice (draft): removal 53.12 795 6374
invoice accept: apply 40.99 18220 4919
invoice accept: cancel 48.33 783 5800
customer reserve: creation 3835.46 176 460255
customer reserve: removal 4725.82 63 567098
realization accept: apply 84.42 211 10130
realization accept: cancel 94.00 118 11280
payment from customer: creation 58.66 131 7039
payment from customer: removal 5.79 63 695
payment to supplier: creation 48.94 151 5873
payment to supplier: removal 3.21 79 385
service: total inventory turnovers 4.69 1034 563
service: total monetary turnovers 6.57 31 788
service: aggregate perf. data 0.94 15067 113
service: refresh index statistics 0.03 291846 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-12-2021 19:39:48 to 19-12-2021 19:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2117
TEST_TIME 2 3875
TEST_TIME 3 4725
TEST_TIME 4 4529
TEST_TIME 5 5800
TEST_TIME 6 5242
TEST_TIME 7 5029
TEST_TIME 8 4820
TEST_TIME 9 4895
TEST_TIME 10 5171
TEST_TIME 11 5733
TEST_TIME 12 5617
TEST_TIME 13 5939
TEST_TIME 14 5845
TEST_TIME 15 6135
TEST_TIME 16 6737
TEST_TIME 17 6903
TEST_TIME 18 7415
TEST_TIME 19 7835
TEST_TIME 20 7817
TEST_TIME 21 8422
TEST_TIME 22 8780
TEST_TIME 23 8961
TEST_TIME 24 9221
TEST_TIME 25 9109
TEST_TIME 26 9329
TEST_TIME 27 9355
TEST_TIME 28 9473
TEST_TIME 29 10183
TEST_TIME 30 10858
TEST_TIME 31 10931
TEST_TIME 32 10717
TEST_TIME 33 10438
TEST_TIME 34 10399
TEST_TIME 35 10448
TEST_TIME 36 10337
TEST_TIME 37 10191
TEST_TIME 38 10174
TEST_TIME 39 10031
TEST_TIME 40 10017
TEST_TIME 41 9966
TEST_TIME 42 9899
TEST_TIME 43 9817
TEST_TIME 44 9708
TEST_TIME 45 9633
TEST_TIME 46 9589
TEST_TIME 47 9418
TEST_TIME 48 9581
TEST_TIME 49 9638
TEST_TIME 50 9584
TEST_TIME 51 9463
TEST_TIME 52 9669
TEST_TIME 53 9612
TEST_TIME 54 9557
TEST_TIME 55 9539
TEST_TIME 56 9607
TEST_TIME 57 9636
TEST_TIME 58 9576
TEST_TIME 59 9724
TEST_TIME 60 9663
TEST_TIME 61 9653
TEST_TIME 62 9724
TEST_TIME 63 9755
TEST_TIME 64 9664
TEST_TIME 65 9563
TEST_TIME 66 9491
TEST_TIME 67 9494
TEST_TIME 68 9393
TEST_TIME 69 9375
TEST_TIME 70 9323
TEST_TIME 71 9225
TEST_TIME 72 9245
TEST_TIME 73 9243
TEST_TIME 74 9226
TEST_TIME 75 9188
TEST_TIME 76 9240
TEST_TIME 77 9278
TEST_TIME 78 9260
TEST_TIME 79 9269
TEST_TIME 80 9223
TEST_TIME 81 9261
TEST_TIME 82 9300
TEST_TIME 83 9376
TEST_TIME 84 9340
TEST_TIME 85 9383
TEST_TIME 86 9409
TEST_TIME 87 9362
TEST_TIME 88 9311
TEST_TIME 89 9372
TEST_TIME 90 9302
TEST_TIME 91 9254
TEST_TIME 92 9208
TEST_TIME 93 9216
TEST_TIME 94 9219
TEST_TIME 95 9244
TEST_TIME 96 9266
TEST_TIME 97 9275
TEST_TIME 98 9244
TEST_TIME 99 9303
TEST_TIME 100 9338
TEST_TIME 101 9312
TEST_TIME 102 9280
TEST_TIME 103 9392
TEST_TIME 104 9404
TEST_TIME 105 9393
TEST_TIME 106 9402
TEST_TIME 107 9426
TEST_TIME 108 9506
TEST_TIME 109 9478
TEST_TIME 110 9420
TEST_TIME 111 9392
TEST_TIME 112 9359
TEST_TIME 113 9360
TEST_TIME 114 9363
TEST_TIME 115 9320
TEST_TIME 116 9326
TEST_TIME 117 9353
TEST_TIME 118 9298
TEST_TIME 119 9279
TEST_TIME 120 9296

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-12-2021 19:39:52 to 19-12-2021 19:39:55.

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 1541344 1541344 0 0.00 0 22232 7 0 0
doc_list_biud 1541342 1541342 0 0.00 0 71538 30 0 0
sp_add_invoice_to_stock 4985 4919 66 1.32 24 220502 18221 0 66
sp_cancel_adding_invoice 5800 5800 0 0.00 4 65243 784 0 0
sp_cancel_client_order 1733 1733 0 0.00 4 13839 429 0 0
sp_cancel_customer_reserve 567098 567098 0 0.00 1 71539 64 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 2416 64 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 1955 80 0 0
sp_cancel_supplier_invoice 6381 6374 7 0.10 2 15474 795 0 7
sp_cancel_supplier_order 436 436 0 0.00 5 1134533 105388 0 0
sp_cancel_write_off 11280 11280 0 0.00 1 17205 119 0 0
sp_client_order 20637 20637 0 0.00 3 57562 377 0 0
sp_customer_reserve 460255 460255 0 0.00 0 74454 176 0 0
sp_fill_shopping_cart 31671 31671 0 0.00 1 23427 37 0 0
sp_fill_shopping_cart_clo_res 460244 460244 0 0.00 0 18932 14 0 0
sp_get_clo_for_invoice 4908 4908 0 0.00 0 22594 322 0 0
sp_kill_cost_storno 18160 18160 0 0.00 0 22222 64 0 0
sp_kill_qstorno_ret_qs2qd 1152286 1152286 0 0.00 0 71536 34 0 0
sp_kill_qty_storno 607759 607759 0 0.00 0 71538 74 0 0
sp_lock_selected_doc 20761 20761 0 0.00 0 6252 6 0 0
sp_make_cost_storno 27950 27950 0 0.00 0 16170 62 0 0
sp_multiply_rows_for_pdistr 27950 27950 0 0.00 0 15967 30 0 0
sp_multiply_rows_for_qdistr 20637 20637 0 0.00 0 57549 156 0 0
sp_pay_from_customer 7039 7039 0 0.00 4 16036 132 0 0
sp_pay_to_supplier 5873 5873 0 0.00 4 15974 152 0 0
sp_payment_common 12912 12912 0 0.00 1 10287 26 0 0
sp_qd_handle_on_cancel_clo 1733 1733 0 0.00 0 13833 380 0 0
sp_qd_handle_on_invoice_upd_sts 10708 10708 0 0.00 0 39015 363 0 0
sp_qd_handle_on_reserve_upd_sts 10130 10130 0 0.00 0 19789 71 0 0
sp_reserve_write_off 10130 10130 0 0.00 6 19824 211 0 0
sp_supplier_invoice 5616 5616 0 0.00 5 81377 3539 0 0
sp_supplier_order 5448 5448 0 0.00 6 26768 1320 0 0
srv_aggregate_perf_data 113 113 0 0.00 2095 128424 15068 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1765 166109 43274 0 0
srv_find_qd_qs_mism 1067570 1067570 0 0.00 0 12690 5 0 0
srv_make_invnt_saldo 563 563 0 0.00 42 49034 1034 0 0
srv_make_money_saldo 788 788 0 0.00 1 4988 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 117897 414968 291847 0 0
t$perf-abend:sp_add_invoice_to_stock 66 66 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 4919 4919 0 0.00 0 19186 209 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 3905 117 0 0
t$perf-norm:sp_cancel_client_order 1733 1733 0 0.00 0 10752 16 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 3445 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1427 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 422 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 12123 47 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 24509 1773 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 10629 17 0 0
t$perf-norm:sp_client_order 20637 20637 0 0.00 0 19723 21 0 0
t$perf-norm:sp_customer_reserve 1037 1037 0 0.00 0 4510 10 0 0
t$perf-norm:sp_pay_from_customer 7039 7039 0 0.00 0 18333 11 0 0
t$perf-norm:sp_pay_to_supplier 5873 5873 0 0.00 0 8138 10 0 0
t$perf-norm:sp_reserve_write_off 10130 10130 0 0.00 0 16365 14 0 0
t$perf-norm:sp_supplier_invoice 5616 5616 0 0.00 0 13951 28 0 0
t$perf-norm:sp_supplier_order 5448 5448 0 0.00 0 3243 16 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 925 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 421 8 0 0
t$perf-norm:srv_make_invnt_saldo 563 563 0 0.00 0 1483 20 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 606 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 14 6 0 0
v_add_invoice_to_stock 9948 9816 132 1.32 0 5483 24 0 132
v_all_customers 37950 37950 0 0.00 0 16 1 0 0
v_all_suppliers 30580 30580 0 0.00 0 26 1 0 0
v_all_wares 41274 41274 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 3259 11 0 0
v_cancel_client_order 3466 3466 0 0.00 0 7122 12 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 1167 5 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 2641 8 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 1648 13 0 0
v_cancel_supplier_invoice 812 798 14 1.72 0 2059 19 0 14
v_cancel_supplier_order 872 872 0 0.00 0 4051 13 0 0
v_cancel_write_off 2012 2012 0 0.00 0 599 11 0 0
v_max_id_clo_ord 5448 5448 0 0.00 0 3447 2 0 0
v_max_id_clo_res 1037 1037 0 0.00 0 2246 13 0 0
v_max_id_ord_sup 5586 5586 0 0.00 0 6080 4 0 0
v_max_non_paid_invoice 1617 1617 0 0.00 0 387 2 0 0
v_max_non_paid_realizn 3523 3523 0 0.00 0 3618 5 0 0
v_min_id_clo_ord 5448 5448 0 0.00 0 2376 7 0 0
v_min_id_clo_res 1037 1037 0 0.00 0 7540 13 0 0
v_min_id_ord_sup 5586 5586 0 0.00 0 6280 10 0 0
v_min_non_paid_invoice 5873 5873 0 0.00 0 8345 17 0 0
v_min_non_paid_realizn 7039 7039 0 0.00 0 10283 21 0 0
v_our_firm 10356 10356 0 0.00 0 13 1 0 0
v_reserve_write_off 20260 20260 0 0.00 0 4784 9 0 0
x_lock_depdocs_on_canc_invoice 11775 11775 0 0.00 0 23504 133 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 23890 453 0 0
x_make_qstorno_1000_1200 5448 5448 0 0.00 1 26757 1236 0 0
x_make_qstorno_1200_2000 5616 5616 0 0.00 2 81368 3408 0 0
x_make_qstorno_2100_3300 460255 460255 0 0.00 0 74453 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2021 19:39:56 to 19-12-2021 19:40:00.

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:02 20 8192000000 8810997136 8826490880 15596192 0.190 4 4 5 0 15822928 87456 139280
17:09:03 24970 8192000000 9896281920 10208690176 974838272 10.634 100 85 170 0 1003777552 6184064 22755216
17:10:42 21927 8192000000 9949843072 10275188736 1031208784 11.180 100 89 178 0 1063198944 8938912 23051248
17:12:07 16644 8192000000 9974566032 10311397376 1064792832 11.502 100 89 177 1 1097541440 9917344 22831264
17:13:36 9796 8192000000 10041686896 10391588864 1127385008 12.097 100 80 160 1 1157732864 10825520 19522336
17:14:54 8827 8192000000 10103149552 10464763904 1189557920 12.679 100 79 158 0 1219583904 11578048 18447936
17:16:08 4480 8192000000 10137191360 10505719808 1223763008 12.996 100 89 178 2 1259519600 16393680 19362912
17:17:18 4092 8192000000 10162093120 10542206976 1254317328 13.278 100 79 158 0 1282965616 11106496 17541792
17:18:23 2307 8192000000 10205673360 10588192768 1288578592 13.591 100 90 180 0 1323388976 14602320 20208064
17:19:28 1228 8192000000 10218541344 10603294720 1301555072 13.709 100 95 191 1 1337266160 14763968 20947120
17:20:30 2696 8192000000 10216822944 10611159040 1313270256 13.816 100 75 153 0 1338512928 8336960 16905712
17:21:35 6091 8192000000 10189085808 10610335744 1312088864 13.805 100 22 44 0 1324501904 7411776 5001264
17:22:43 9139 8192000000 10202308112 10621681664 1320262752 13.879 100 22 44 1 1330952416 5899392 4790272
17:23:53 64065 8192000000 10247434336 10648395776 1330477920 13.971 100 54 106 1 1349614144 6329904 12806320
17:25:58 5205 8192000000 10231983664 10652164096 1337318864 14.033 100 24 48 1 1347873808 5655840 4899104
17:27:06 6661 8192000000 10229901328 10652536832 1339635152 14.054 100 21 42 2 1350439616 5617728 5186736
17:28:14 20662 8192000000 10221873040 10645467136 1313464368 13.817 98 35 70 1 1327552672 5580416 8507888
17:29:44 6817 8192000000 10186955824 10597535744 1282818464 13.539 98 25 50 0 1295404496 6599184 5986848
17:30:53 11430 8192000000 10169577232 10572718080 1246248576 13.204 98 20 39 1 1255704848 4600512 4855760
17:32:06 57409 8192000000 10111942688 10503430144 1173036480 12.525 94 23 46 0 1183270720 4690656 5543584
17:34:06 6440 8192000000 10166792080 10568196096 1273133968 13.450 100 32 64 1 1286154560 5610800 7409792
17:35:13 7914 8192000000 10171280992 10582355968 1284529888 13.554 100 26 52 0 1296186704 5389456 6267360
17:36:22 12424 8192000000 10183624336 10601299968 1300635024 13.701 100 16 32 1 1309759104 5392880 3731200
17:37:40 71321 8192000000 10223991312 10632400896 1310739472 13.793 100 42 84 0 1326060416 5756080 9564864
17:39:56 4164 8192000000 10252232816 10662973440 1345010320 14.103 100 52 104 1 1369223088 12476192 11736576
17:41:03 38508 8192000000 10265373904 10682114048 1356734960 14.208 100 52 104 1 1384693056 15122608 12835488
17:42:50 46766 8192000000 10286775536 10711556096 1374390048 14.366 100 28 56 0 1399444864 17483232 7571584
17:44:43 19746 8192000000 10309641040 10733539328 1391916352 14.523 100 43 86 0 1421382656 18656640 10809664
17:46:06 4031 8192000000 10338930368 10753622016 1406928816 14.657 100 70 139 1 1446002896 22263392 16810688
17:47:14 3395 8192000000 10343681200 10767826944 1416406368 14.741 100 50 100 0 1451783472 23308752 12068352
17:48:21 6616 8192000000 10355711024 10775429120 1411007472 14.693 99 62 124 2 1459321184 32523888 15789824
17:49:33 4897 8192000000 10360266592 10782711808 1422514016 14.795 100 73 145 0 1470985280 30106000 18365264
17:50:46 5272 8192000000 10359450192 10784628736 1417579584 14.751 100 60 120 3 1468476544 36049488 14847472
17:51:54 3700 8192000000 10357922304 10773950464 1392117552 14.525 99 71 140 2 1455573808 45042784 18413472
17:53:05 1763 8192000000 10357671680 10768453632 1395748176 14.557 99 86 172 2 1463172992 46085360 21339456
17:54:12 4198 8192000000 10353650512 10767273984 1391525664 14.519 99 80 159 0 1452103536 39747744 20830128
17:55:25 3685 8192000000 10365344736 10774020096 1394862160 14.549 100 85 168 0 1466273840 50018992 21392688
17:56:39 3779 8192000000 10354896656 10769498112 1391570080 14.520 100 71 143 0 1456881008 47083344 18227584
17:57:49 2132 8192000000 10380444128 10792861696 1408328464 14.669 100 84 167 1 1480472192 51060944 21082784
17:59:01 7232 8192000000 10366552544 10793459712 1411648192 14.699 100 49 98 1 1473065408 47743360 13673856
18:00:14 11723 8192000000 10339925504 10777104384 1397575696 14.573 100 37 74 0 1449778512 42194128 10008688
18:01:29 19541 8192000000 10333996720 10768793600 1399026704 14.586 100 29 58 1 1443508128 36614288 7867136
18:02:59 23990 8192000000 10330689120 10764754944 1380373104 14.420 100 29 56 0 1432677728 44393280 7911344
18:04:40 18734 8192000000 10336257216 10773729280 1391539888 14.520 100 29 58 1 1440949120 41268912 8140320
18:06:01 37472 8192000000 10300709104 10757820416 1359142176 14.230 100 13 26 0 1409420896 46282976 3995744
18:08:26 48552 8192000000 10282735520 10717954048 1350527152 14.152 99 18 35 1 1363632800 8718128 4387520
18:10:20 92897 8192000000 10273747120 10708963328 1305078368 13.741 95 40 80 1 1321932400 7722672 9131360
18:13:09 16631 8192000000 10268439360 10705526784 1337110560 14.031 98 25 50 0 1350921808 8087136 5724112
18:14:30 13271 8192000000 10265621520 10704064512 1357628496 14.216 100 19 38 0 1368965888 6885968 4451424
18:16:41 35838 8192000000 10295654528 10737823744 1384611344 14.458 100 43 86 2 1403857648 9407520 9838784
18:18:28 34384 8192000000 10275840256 10727841792 1376487728 14.385 100 34 66 1 1391568944 7258928 7822288
18:20:03 37016 8192000000 10285912272 10740867072 1384114272 14.453 100 9 18 0 1397104608 10916272 2074064
18:21:41 27335 8192000000 10289511456 10750767104 1384818816 14.460 100 9 18 0 1405128448 17835744 2473888
18:24:12 79835 8192000000 10279607600 10746142720 1369215328 14.320 99 23 44 0 1397066832 22497808 5353696
18:26:33 75035 8192000000 10286225920 10761719808 1369277232 14.321 99 11 22 0 1389503792 17942720 2283840
18:28:53 101182 8192000000 10298153088 10773372928 1393466128 14.537 100 23 46 0 1418048208 19165120 5416960
18:31:35 58695 8192000000 10310156176 10762694656 1374675984 14.369 98 40 79 1 1397490928 13635504 9179440
18:34:07 48221 8192000000 10270208592 10712961024 1289716336 13.602 93 16 33 0 1307923552 14449984 3757232
18:35:55 74889 8192000000 10270701056 10718371840 1333037904 13.995 97 15 30 0 1347220048 11169200 3012944
18:38:18 21546 8192000000 10287685328 10732470272 1362370992 14.259 99 35 70 0 1380588688 10902912 7314784
18:39:40 70702 8192000000 10276856224 10727460864 1324018240 13.913 96 16 32 1 1338572336 11138112 3415984
18:41:54 66104 8192000000 10262268592 10704715776 1350276720 14.150 98 29 58 1 1363973776 7208880 6488176
18:44:05 43678 8192000000 10269380736 10734612480 1363402224 14.268 99 33 64 1 1380714384 10112496 7199664
18:45:50 60242 8192000000 10270150160 10745774080 1375446144 14.376 100 6 12 0 1390180272 13261424 1472704
18:47:52 72826 8192000000 10285021520 10745651200 1386951760 14.479 100 19 38 2 1401318880 10070544 4296576
18:50:29 31975 8192000000 10276899312 10732699648 1356485040 14.206 98 10 18 1 1367159296 8466736 2207520
18:52:05 38004 8192000000 10274085648 10722242560 1344282688 14.096 98 31 62 0 1362263264 10717104 7263472
18:53:44 39320 8192000000 10289268592 10727297024 1347077568 14.121 98 31 62 2 1365051104 10332304 7641232
18:55:24 49704 8192000000 10278236368 10724921344 1305565536 13.746 95 15 30 0 1316461568 7634432 3261600
18:57:24 75219 8192000000 10270712992 10719166464 1276744288 13.483 93 21 42 2 1294651136 13003296 4903552
18:59:40 60865 8192000000 10288426016 10738221056 1327884368 13.948 97 24 48 0 1353611456 20476016 5251072
19:01:47 23776 8192000000 10262644096 10705047552 1311973696 13.804 96 39 77 2 1329683200 7969568 9739936
19:03:15 166109 8192000000 10280199888 10722684928 1323656240 13.910 97 17 34 0 1339574400 11934960 3983200
19:07:19 24592 8192000000 10272148752 10746204160 1365910416 14.290 100 12 24 1 1389935168 21680624 2344128
19:08:52 47365 8192000000 10270175376 10724323328 1375028032 14.372 100 9 18 1 1385001072 8212864 1760176
19:10:47 76271 8192000000 10289333408 10748592128 1375673312 14.378 99 8 16 0 1387745104 10503664 1568128
19:13:32 71814 8192000000 10302697280 10746454016 1344375712 14.097 96 31 62 0 1369803024 18291760 7135552
19:15:53 55094 8192000000 10300428752 10765869056 1383812960 14.451 99 39 78 1 1405179584 12629280 8737344
19:18:37 49017 8192000000 10307509936 10758909952 1363151648 14.266 97 23 47 0 1382755200 14348208 5255344
19:20:26 61100 8192000000 10307435936 10760953856 1383553088 14.448 99 11 22 0 1398160496 12449760 2157648
19:22:33 86266 8192000000 10294165616 10743463936 1323823200 13.911 95 17 34 0 1337804096 10095024 3885872
19:25:02 113339 8192000000 10285707984 10737033216 1341777248 14.073 97 9 18 0 1359808064 16233264 1797552
19:28:26 72750 8192000000 10293345312 10750951424 1348707328 14.136 97 16 32 2 1359521568 7694352 3119888
19:31:52 53566 8192000000 10291514464 10756186112 1388918128 14.496 100 6 12 0 1404097456 13636000 1543328
19:33:45 56821 8192000000 10293758176 10751131648 1370096160 14.328 98 18 36 0 1387902224 13711792 4094272
19:37:27 51676 8192000000 9407823744 9722257408 562423488 6.424 40 5 10 0 569948960 6376464 1149008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-12-2021 19:40:02 to 19-12-2021 19:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 66 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 19-12-2021 19:40:18 to 19-12-2021 19:40:20.

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          236115
MON$OLDEST_ACTIVE               236116
MON$OLDEST_SNAPSHOT             236116
MON$NEXT_TRANSACTION            236116
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                       2028384
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 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 ---
:135: 
:136: mscs-test-161.budzdorov.ru    Sun Dec 19 19:38:23 2021
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:138: 
--- end of diff output ---

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

19.12.21 19:40:21. 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-19 19:40:23 No severe PSQL-related problems occured
19.12.21 19:40:24. End of report.