Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.12.2021 22: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 16512196 kB
MemAvailable 31356392 kB
Buffers 0 kB
Cached 15130648 kB
SwapCached 444 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-23 22:37:59
DTS_END                         2021-12-23 22:37:59.6050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51454
TRN_ID                          245768
ATT_ID                          642
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: 9429.35 23621 1131522
customer order: creation 171.46 317 20575
customer order: refuse 14.56 338 1747
order to supplier: creation 45.88 1204 5506
order to supplier: removal 3.24 113187 389
invoice (draft): creation 47.44 3251 5693
invoice (draft): removal 53.74 840 6449
invoice accept: apply 42.40 18162 5088
invoice accept: cancel 48.83 733 5859
customer reserve: creation 3932.35 178 471882
customer reserve: removal 4762.28 59 571474
realization accept: apply 85.46 184 10255
realization accept: cancel 88.73 127 10647
payment from customer: creation 60.36 121 7243
payment from customer: removal 5.82 99 698
payment to supplier: creation 50.64 147 6077
payment to supplier: removal 3.68 65 441
service: total inventory turnovers 4.68 1110 562
service: total monetary turnovers 6.91 29 829
service: aggregate perf. data 0.87 19546 104
service: refresh index statistics 0.03 312732 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-12-2021 22:38:32 to 23-12-2021 22:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3096
TEST_TIME 2 2998
TEST_TIME 3 3511
TEST_TIME 4 3450
TEST_TIME 5 3109
TEST_TIME 6 3552
TEST_TIME 7 4218
TEST_TIME 8 4692
TEST_TIME 9 4929
TEST_TIME 10 5254
TEST_TIME 11 5680
TEST_TIME 12 5930
TEST_TIME 13 6094
TEST_TIME 14 6116
TEST_TIME 15 6237
TEST_TIME 16 6621
TEST_TIME 17 6895
TEST_TIME 18 7320
TEST_TIME 19 7365
TEST_TIME 20 7587
TEST_TIME 21 8000
TEST_TIME 22 8452
TEST_TIME 23 8562
TEST_TIME 24 8546
TEST_TIME 25 8968
TEST_TIME 26 9380
TEST_TIME 27 9492
TEST_TIME 28 9573
TEST_TIME 29 9640
TEST_TIME 30 10615
TEST_TIME 31 10926
TEST_TIME 32 11041
TEST_TIME 33 10751
TEST_TIME 34 10524
TEST_TIME 35 10692
TEST_TIME 36 10456
TEST_TIME 37 10453
TEST_TIME 38 10413
TEST_TIME 39 10284
TEST_TIME 40 10420
TEST_TIME 41 10248
TEST_TIME 42 10121
TEST_TIME 43 10058
TEST_TIME 44 10187
TEST_TIME 45 10094
TEST_TIME 46 10061
TEST_TIME 47 9993
TEST_TIME 48 9940
TEST_TIME 49 9872
TEST_TIME 50 9827
TEST_TIME 51 9771
TEST_TIME 52 9680
TEST_TIME 53 9652
TEST_TIME 54 9740
TEST_TIME 55 9679
TEST_TIME 56 9640
TEST_TIME 57 9608
TEST_TIME 58 9572
TEST_TIME 59 9494
TEST_TIME 60 9618
TEST_TIME 61 9597
TEST_TIME 62 9631
TEST_TIME 63 9696
TEST_TIME 64 9728
TEST_TIME 65 9759
TEST_TIME 66 9813
TEST_TIME 67 9763
TEST_TIME 68 9752
TEST_TIME 69 9752
TEST_TIME 70 9723
TEST_TIME 71 9741
TEST_TIME 72 9776
TEST_TIME 73 9745
TEST_TIME 74 9647
TEST_TIME 75 9609
TEST_TIME 76 9614
TEST_TIME 77 9585
TEST_TIME 78 9575
TEST_TIME 79 9554
TEST_TIME 80 9494
TEST_TIME 81 9468
TEST_TIME 82 9498
TEST_TIME 83 9422
TEST_TIME 84 9448
TEST_TIME 85 9422
TEST_TIME 86 9407
TEST_TIME 87 9386
TEST_TIME 88 9409
TEST_TIME 89 9341
TEST_TIME 90 9512
TEST_TIME 91 9486
TEST_TIME 92 9530
TEST_TIME 93 9544
TEST_TIME 94 9599
TEST_TIME 95 9583
TEST_TIME 96 9547
TEST_TIME 97 9631
TEST_TIME 98 9604
TEST_TIME 99 9546
TEST_TIME 100 9510
TEST_TIME 101 9507
TEST_TIME 102 9449
TEST_TIME 103 9434
TEST_TIME 104 9388
TEST_TIME 105 9382
TEST_TIME 106 9383
TEST_TIME 107 9354
TEST_TIME 108 9308
TEST_TIME 109 9342
TEST_TIME 110 9300
TEST_TIME 111 9349
TEST_TIME 112 9324
TEST_TIME 113 9329
TEST_TIME 114 9374
TEST_TIME 115 9407
TEST_TIME 116 9404
TEST_TIME 117 9381
TEST_TIME 118 9409
TEST_TIME 119 9445
TEST_TIME 120 9439

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-12-2021 22:38:35 to 23-12-2021 22:38:39.

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 1566900 1566900 0 0.00 0 22586 7 0 0
doc_list_biud 1566899 1566899 0 0.00 0 65253 28 0 0
sp_add_invoice_to_stock 5206 5088 118 2.26 17 154249 18162 0 118
sp_cancel_adding_invoice 5859 5859 0 0.00 4 96082 734 0 0
sp_cancel_client_order 1747 1747 0 0.00 4 9636 339 0 0
sp_cancel_customer_reserve 571474 571474 0 0.00 1 52819 59 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 9126 100 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 3146 65 0 0
sp_cancel_supplier_invoice 6454 6449 5 0.07 1 65269 841 0 5
sp_cancel_supplier_order 389 389 0 0.00 8 1164528 113188 0 0
sp_cancel_write_off 10647 10647 0 0.00 1 21430 128 0 0
sp_client_order 20575 20575 0 0.00 4 27964 318 0 0
sp_customer_reserve 471882 471882 0 0.00 0 80884 179 0 0
sp_fill_shopping_cart 31769 31769 0 0.00 1 17049 43 0 0
sp_fill_shopping_cart_clo_res 471869 471869 0 0.00 0 19532 13 0 0
sp_get_clo_for_invoice 5073 5073 0 0.00 0 22803 310 0 0
sp_kill_cost_storno 17645 17645 0 0.00 0 12043 60 0 0
sp_kill_qstorno_ret_qs2qd 1160433 1160433 0 0.00 0 65244 32 0 0
sp_kill_qty_storno 611893 611893 0 0.00 0 65249 70 0 0
sp_lock_selected_doc 21055 21055 0 0.00 0 10685 6 0 0
sp_make_cost_storno 28648 28648 0 0.00 0 14291 58 0 0
sp_multiply_rows_for_pdistr 28648 28648 0 0.00 0 11995 30 0 0
sp_multiply_rows_for_qdistr 20575 20575 0 0.00 0 26552 155 0 0
sp_pay_from_customer 7243 7243 0 0.00 4 13872 122 0 0
sp_pay_to_supplier 6077 6077 0 0.00 5 14426 148 0 0
sp_payment_common 13320 13320 0 0.00 1 13867 36 0 0
sp_qd_handle_on_cancel_clo 1747 1747 0 0.00 0 9630 304 0 0
sp_qd_handle_on_invoice_upd_sts 10932 10932 0 0.00 0 39264 332 0 0
sp_qd_handle_on_reserve_upd_sts 10255 10255 0 0.00 0 14029 55 0 0
sp_reserve_write_off 10255 10255 0 0.00 6 15067 185 0 0
sp_supplier_invoice 5693 5693 0 0.00 6 99294 3252 0 0
sp_supplier_order 5506 5506 0 0.00 6 68927 1205 0 0
srv_aggregate_perf_data 104 104 0 0.00 3116 285613 19547 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1259 169170 48844 0 0
srv_find_qd_qs_mism 1082171 1082171 0 0.00 0 22520 6 0 0
srv_make_invnt_saldo 562 562 0 0.00 56 76196 1110 0 0
srv_make_money_saldo 829 829 0 0.00 1 2069 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 234716 483960 312733 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5088 5088 0 0.00 0 9547 144 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 4064 65 0 0
t$perf-norm:sp_cancel_client_order 1747 1747 0 0.00 0 3068 12 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 647 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 275 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 3130 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 719 11 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 30585 1775 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 2175 9 0 0
t$perf-norm:sp_client_order 20575 20575 0 0.00 0 11195 16 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 2769 13 0 0
t$perf-norm:sp_pay_from_customer 7243 7243 0 0.00 0 4469 9 0 0
t$perf-norm:sp_pay_to_supplier 6077 6077 0 0.00 0 6093 9 0 0
t$perf-norm:sp_reserve_write_off 10255 10255 0 0.00 0 15452 10 0 0
t$perf-norm:sp_supplier_invoice 5693 5693 0 0.00 0 3393 16 0 0
t$perf-norm:sp_supplier_order 5506 5506 0 0.00 0 7284 17 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 732 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 5123 85 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 1341 25 0 0
t$perf-norm:srv_make_money_saldo 829 829 0 0.00 0 2600 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10382 10146 236 2.27 0 11604 24 0 236
v_all_customers 37582 37582 0 0.00 0 16 1 0 0
v_all_suppliers 31098 31098 0 0.00 0 63 1 0 0
v_all_wares 41148 41148 0 0.00 0 61 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 3759 12 0 0
v_cancel_client_order 3494 3494 0 0.00 0 4005 8 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 2388 8 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 1597 6 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 1118 6 0 0
v_cancel_supplier_invoice 734 724 10 1.36 0 2568 26 0 10
v_cancel_supplier_order 778 778 0 0.00 0 1970 8 0 0
v_cancel_write_off 1980 1980 0 0.00 0 12992 22 0 0
v_max_id_clo_ord 5506 5506 0 0.00 0 652 1 0 0
v_max_id_clo_res 993 993 0 0.00 0 4039 14 0 0
v_max_id_ord_sup 5689 5689 0 0.00 0 8074 7 0 0
v_max_non_paid_invoice 1723 1723 0 0.00 0 1414 3 0 0
v_max_non_paid_realizn 3830 3830 0 0.00 0 8465 9 0 0
v_min_id_clo_ord 5506 5506 0 0.00 0 3424 7 0 0
v_min_id_clo_res 993 993 0 0.00 0 5496 16 0 0
v_min_id_ord_sup 5689 5689 0 0.00 0 7035 11 0 0
v_min_non_paid_invoice 6077 6077 0 0.00 0 10740 28 0 0
v_min_non_paid_realizn 7243 7243 0 0.00 0 13864 26 0 0
v_our_firm 10392 10392 0 0.00 0 18 1 0 0
v_reserve_write_off 20510 20510 0 0.00 0 14203 9 0 0
x_lock_depdocs_on_canc_invoice 11946 11946 0 0.00 0 24964 115 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 7897 383 0 0
x_make_qstorno_1000_1200 5506 5506 0 0.00 2 68816 1116 0 0
x_make_qstorno_1200_2000 5693 5693 0 0.00 2 99251 3101 0 0
x_make_qstorno_2100_3300 471879 471879 0 0.00 0 80882 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2021 22:38:40 to 23-12-2021 22:38:44.

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
20:08:03 13 8192000000 8805976928 8819937280 9416720 0.114 3 2 3 0 9559712 68032 74960
20:09:04 20392 8192000000 9904080288 10216243200 987828224 10.760 100 88 176 1 1016596896 6122176 22646496
20:10:31 26897 8192000000 9950756400 10273177600 1030065968 11.169 100 91 182 0 1062189328 9044448 23078912
20:12:04 15677 8192000000 9992451120 10326618112 1072393856 11.575 100 84 168 0 1103003776 9632416 20977504
20:13:26 14414 8192000000 10052597280 10401660928 1134793504 12.167 100 78 156 1 1163779200 10676144 18309552
20:14:44 7042 8192000000 10105653632 10465296384 1186815728 12.654 100 85 168 4 1217996528 12239040 18941760
20:15:54 5391 8192000000 10138572784 10511101952 1227614288 13.032 100 89 178 1 1261932048 14717072 19600688
20:17:00 2220 8192000000 10179709968 10559184896 1268009824 13.403 100 83 166 1 1299698368 13919040 17769504
20:18:05 1791 8192000000 10218686688 10602143744 1300491824 13.700 100 92 184 1 1335867152 14941632 20433696
20:19:07 12399 8192000000 10195129152 10607513600 1308628656 13.774 100 35 70 1 1324972384 8514944 7828784
20:20:21 4649 8192000000 10202787040 10617303040 1317384448 13.853 100 22 44 0 1329582304 6797632 5400224
20:21:29 9939 8192000000 10204734048 10624716800 1324357216 13.916 100 16 30 2 1333204176 5361264 3485696
20:22:43 57160 8192000000 10244118800 10649792512 1330626224 13.973 100 65 132 3 1352942368 6167200 16148944
20:24:40 6068 8192000000 10226820016 10648002560 1334282416 14.006 100 28 56 1 1348148000 7402416 6463168
20:25:47 43233 8192000000 10231480048 10653286400 1338370864 14.043 100 31 60 2 1351210720 6382784 6457072
20:27:34 5490 8192000000 10205390992 10621943808 1299243280 13.688 98 32 63 4 1311847920 5661824 6942816
20:28:42 6228 8192000000 10167336848 10579869696 1239446720 13.141 95 18 35 2 1249051072 5121472 4482880
20:29:50 56791 8192000000 10127484064 10536210432 1187519408 12.660 94 31 62 0 1200110384 5240208 7350768
20:31:51 8805 8192000000 10161611808 10561036288 1254782880 13.282 99 29 58 0 1267724016 6644672 6296464
20:33:01 11451 8192000000 10157230112 10564669440 1267071696 13.395 100 25 49 1 1278412288 5279696 6060896
20:34:13 17062 8192000000 10180103296 10590248960 1288207024 13.588 100 19 37 2 1298573552 4889664 5476864
20:35:31 9233 8192000000 10184694400 10597699584 1292557200 13.628 100 25 50 1 1305443744 7399072 5487472
20:36:42 6331 8192000000 10196873728 10612404224 1308445008 13.772 100 21 42 2 1319009696 5885792 4678896
20:37:49 9278 8192000000 10209277312 10625286144 1316683472 13.847 100 19 39 1 1327110320 6210656 4216192
20:39:06 27326 8192000000 10249984624 10660016128 1343337840 14.087 100 39 78 0 1359884864 8067888 8479136
20:40:49 24660 8192000000 10267190832 10685743104 1366730048 14.298 100 33 64 1 1385118384 10545200 7843136
20:42:19 42505 8192000000 10278379904 10700099584 1373928560 14.362 100 36 72 0 1396286352 13871792 8486000
20:44:09 9523 8192000000 10285300960 10703003648 1366576144 14.296 99 39 78 1 1391544544 15356096 9612304
20:45:28 6101 8192000000 10312045936 10728017920 1389261488 14.499 100 65 130 0 1423684656 19084640 15338528
20:46:45 3492 8192000000 10324576368 10740232192 1391790240 14.522 100 60 118 2 1432512256 26701712 14020304
20:47:53 5222 8192000000 10337108496 10757627904 1406162096 14.650 100 57 112 2 1446797072 27037888 13597088
20:49:03 3488 8192000000 10338585824 10752909312 1395607600 14.556 99 64 127 0 1437397008 26595680 15193728
20:50:11 6349 8192000000 10331275920 10743947264 1385513520 14.466 99 67 134 0 1429920528 27959792 16447216
20:51:24 4695 8192000000 10332360880 10739593216 1391341952 14.518 100 68 134 2 1438469184 30613264 16513968
20:52:33 1802 8192000000 10350032000 10756005888 1394855888 14.549 100 89 175 1 1453906896 37362176 21688832
20:53:40 2650 8192000000 10350097872 10759450624 1398588992 14.582 100 79 158 1 1456783136 38536224 19657920
20:54:48 4527 8192000000 10360251040 10768756736 1399187312 14.588 100 82 165 0 1465469024 45103360 21178352
20:55:58 3809 8192000000 10334827088 10745602048 1378086960 14.399 100 72 142 0 1439506672 43286336 18133376
20:57:06 2180 8192000000 10365408128 10770427904 1389699456 14.503 100 83 167 1 1464276160 51901040 22675664
20:58:11 17979 8192000000 10351450864 10782232576 1389714096 14.503 100 40 79 1 1458118208 56956592 11447520
20:59:39 1258 8192000000 10354877184 10766336000 1383829696 14.451 100 89 173 4 1454800576 48016832 22954048
21:00:43 11882 8192000000 10337002672 10764337152 1382674384 14.440 100 41 82 0 1445227168 50978576 11574208
21:01:58 14368 8192000000 10322523504 10765594624 1376408480 14.384 100 44 88 1 1441747728 52750560 12588688
21:03:19 13101 8192000000 10332499840 10761502720 1368569216 14.314 99 39 77 2 1425897600 46980016 10348368
21:04:34 20871 8192000000 10331270816 10770841600 1389977104 14.506 100 25 50 1 1433781680 37300896 6503680
21:07:05 40043 8192000000 10292386320 10743713792 1342724464 14.082 99 20 40 1 1404203776 55538560 5940752
21:08:46 78901 8192000000 10272168864 10723086336 1330918560 13.975 98 13 25 0 1352195696 18603760 2673376
21:11:06 169170 8192000000 10277820448 10741223424 1341963664 14.075 98 11 20 2 1368545088 24024640 2556784
21:14:56 70597 8192000000 10303122736 10756329472 1387898944 14.487 100 22 43 0 1409481296 16988064 4594288
21:17:08 111023 8192000000 10301018400 10769584128 1381931408 14.434 99 11 22 0 1404124912 19709616 2483888
21:19:59 75198 8192000000 10301557088 10767884288 1381321968 14.428 99 9 18 0 1403268976 19535952 2411056
21:22:15 61212 8192000000 10309460864 10764734464 1380790832 14.424 98 26 50 1 1399814160 13380096 5643232
21:24:21 64868 8192000000 10279632592 10739363840 1370439168 14.331 98 6 12 0 1384301584 12599248 1263168
21:26:36 24362 8192000000 10309684912 10753867776 1392397120 14.527 100 46 91 1 1411282000 8108512 10776368
21:28:04 71917 8192000000 10307468576 10768867328 1388448912 14.492 99 16 32 1 1403409056 11380736 3579408
21:30:16 47724 8192000000 10301811120 10769559552 1391106208 14.516 99 19 38 0 1410005968 14283568 4616192
21:32:46 16132 8192000000 10266593200 10733723648 1369265904 14.320 98 8 16 1 1379897152 8897472 1733776
21:34:04 79137 8192000000 10275907488 10742472704 1379131856 14.409 99 20 40 0 1392359616 8628208 4599552
21:36:32 69094 8192000000 10293196288 10770550784 1361366016 14.250 97 15 29 1 1381682832 16728800 3588016
21:38:45 84779 8192000000 10272004656 10728837120 1292256896 13.625 92 29 58 1 1309274896 10530336 6487664
21:42:27 47207 8192000000 10264568736 10717859840 1327906992 13.948 97 16 32 0 1339993808 8861008 3225808
21:44:20 9785 8192000000 10271262656 10701885440 1329478608 13.962 98 50 100 3 1348301168 7246880 11575680
21:45:37 29717 8192000000 10276236160 10716672000 1329942560 13.967 97 31 62 1 1344612208 8092480 6577168
21:47:18 100457 8192000000 10271553248 10718351360 1293983616 13.641 95 20 40 0 1309372816 10952368 4436832
21:49:59 42958 8192000000 10271384784 10702745600 1330518800 13.972 98 33 66 1 1348036240 10377936 7139504
21:51:42 81087 8192000000 10256374496 10702901248 1290999856 13.613 95 20 40 1 1307026304 11823648 4202800
21:54:07 57257 8192000000 10270503760 10718740480 1343419264 14.088 99 29 58 1 1362605584 12867584 6318736
21:56:04 53561 8192000000 10246324000 10703073280 1344686048 14.100 99 19 38 2 1358460960 9004288 4770624
21:58:05 56005 8192000000 10263209104 10717724672 1340714496 14.064 98 13 26 1 1355962816 11798032 3450288
22:00:02 62879 8192000000 10251386992 10724487168 1347250960 14.123 99 8 16 0 1361668272 12392112 2025200
22:02:41 81964 8192000000 10283383824 10739941376 1346972496 14.120 98 24 48 0 1365149792 12663440 5513856
22:05:03 53915 8192000000 10286305824 10750267392 1362897344 14.263 99 12 24 0 1385526448 19827232 2801872
22:07:19 62853 8192000000 10290030112 10731798528 1371415824 14.340 100 14 28 0 1389058320 14289456 3353040
22:09:22 70226 8192000000 10291274832 10734583808 1373501088 14.358 99 22 44 0 1387268176 9189424 4577664
22:11:33 101440 8192000000 10290780176 10739724288 1340016512 14.058 96 9 18 0 1352963872 11190400 1756960
22:14:31 66189 8192000000 10306445920 10765430784 1365838256 14.290 98 19 38 1 1391193792 21151408 4204128
22:16:40 97153 8192000000 10276599920 10734612480 1336451424 14.025 96 21 42 1 1349384848 8664704 4268720
22:19:22 70522 8192000000 10257628912 10717626368 1337059904 14.031 97 3 6 0 1348543776 10851280 632592
22:21:39 88747 8192000000 10263265584 10725801984 1323664496 13.910 96 18 36 2 1341404736 14121936 3618304
22:24:15 62468 8192000000 10269158336 10726944768 1343780400 14.091 98 11 22 1 1356295520 10025776 2489344
22:26:18 69422 8192000000 10278140192 10729385984 1342315664 14.078 97 17 34 0 1356725200 10854512 3555024
22:28:31 111278 8192000000 10290807744 10737537024 1332159136 13.987 96 19 38 0 1349696816 13462192 4075488
22:31:24 103887 8192000000 10273605664 10738765824 1324221184 13.915 96 18 34 2 1338427648 10237696 3968768
22:34:08 21494 8192000000 10278035488 10716663808 1358923840 14.228 100 36 72 0 1379881840 13260256 7697744
22:35:30 84971 8192000000 10263557504 10713702400 1294236384 13.643 95 29 58 0 1322193904 21745872 6211648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-12-2021 22:38:46 to 23-12-2021 22:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-12-2021 22:39:01 to 23-12-2021 22:39:03.

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          245478
MON$OLDEST_ACTIVE               245880
MON$OLDEST_SNAPSHOT             245880
MON$NEXT_TRANSACTION            245880
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                       2036576
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.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Thu Dec 23 22:38:04 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Thu Dec 23 22:38:04 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Thu Dec 23 22:38:04 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
--- end of diff output ---

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

23.12.21 22:39:04. 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-23 22:39:07 No severe PSQL-related problems occured
23.12.21 22:39:08. End of report.