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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16688188 kB
MemAvailable 31605464 kB
Buffers 0 kB
Cached 14997708 kB
SwapCached 2140 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-03-26 19:38:10
DTS_END                         2022-03-26 19:38:10.8120
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/59374
TRN_ID                          248436
ATT_ID                          717
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: 9353.99 19315 1122479
customer order: creation 184.86 323 22183
customer order: refuse 15.73 338 1887
order to supplier: creation 47.04 1284 5645
order to supplier: removal 3.46 97178 415
invoice (draft): creation 48.48 3343 5818
invoice (draft): removal 51.62 869 6194
invoice accept: apply 44.38 17251 5325
invoice accept: cancel 46.52 802 5582
customer reserve: creation 4084.85 171 490182
customer reserve: removal 4516.58 58 541989
realization accept: apply 87.68 171 10521
realization accept: cancel 86.39 113 10367
payment from customer: creation 61.73 121 7408
payment from customer: removal 6.18 94 741
payment to supplier: creation 51.78 114 6214
payment to supplier: removal 3.58 73 429
service: total inventory turnovers 4.97 1142 596
service: total monetary turnovers 7.18 30 861
service: aggregate perf. data 0.98 16392 118
service: refresh index statistics 0.03 246440 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-03-2022 19:39:04 to 26-03-2022 19:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5029
TEST_TIME 2 6055
TEST_TIME 3 5792
TEST_TIME 4 5793
TEST_TIME 5 5865
TEST_TIME 6 5738
TEST_TIME 7 5947
TEST_TIME 8 6215
TEST_TIME 9 6212
TEST_TIME 10 6360
TEST_TIME 11 6949
TEST_TIME 12 7209
TEST_TIME 13 7181
TEST_TIME 14 7399
TEST_TIME 15 7414
TEST_TIME 16 8078
TEST_TIME 17 7923
TEST_TIME 18 8070
TEST_TIME 19 9262
TEST_TIME 20 9527
TEST_TIME 21 9331
TEST_TIME 22 9641
TEST_TIME 23 9750
TEST_TIME 24 10121
TEST_TIME 25 10562
TEST_TIME 26 10453
TEST_TIME 27 10753
TEST_TIME 28 11842
TEST_TIME 29 11588
TEST_TIME 30 11246
TEST_TIME 31 10934
TEST_TIME 32 10749
TEST_TIME 33 10723
TEST_TIME 34 10599
TEST_TIME 35 10554
TEST_TIME 36 10421
TEST_TIME 37 10259
TEST_TIME 38 10158
TEST_TIME 39 10109
TEST_TIME 40 10133
TEST_TIME 41 9999
TEST_TIME 42 9905
TEST_TIME 43 9890
TEST_TIME 44 9822
TEST_TIME 45 9988
TEST_TIME 46 9921
TEST_TIME 47 9949
TEST_TIME 48 9947
TEST_TIME 49 9897
TEST_TIME 50 9895
TEST_TIME 51 9855
TEST_TIME 52 9913
TEST_TIME 53 9890
TEST_TIME 54 9936
TEST_TIME 55 9892
TEST_TIME 56 9916
TEST_TIME 57 9851
TEST_TIME 58 9732
TEST_TIME 59 9864
TEST_TIME 60 9760
TEST_TIME 61 9754
TEST_TIME 62 9747
TEST_TIME 63 9654
TEST_TIME 64 9591
TEST_TIME 65 9547
TEST_TIME 66 9535
TEST_TIME 67 9475
TEST_TIME 68 9533
TEST_TIME 69 9577
TEST_TIME 70 9602
TEST_TIME 71 9565
TEST_TIME 72 9578
TEST_TIME 73 9594
TEST_TIME 74 9540
TEST_TIME 75 9557
TEST_TIME 76 9577
TEST_TIME 77 9557
TEST_TIME 78 9639
TEST_TIME 79 9654
TEST_TIME 80 9571
TEST_TIME 81 9488
TEST_TIME 82 9522
TEST_TIME 83 9457
TEST_TIME 84 9440
TEST_TIME 85 9451
TEST_TIME 86 9423
TEST_TIME 87 9362
TEST_TIME 88 9367
TEST_TIME 89 9339
TEST_TIME 90 9343
TEST_TIME 91 9326
TEST_TIME 92 9302
TEST_TIME 93 9404
TEST_TIME 94 9370
TEST_TIME 95 9420
TEST_TIME 96 9449
TEST_TIME 97 9414
TEST_TIME 98 9399
TEST_TIME 99 9562
TEST_TIME 100 9587
TEST_TIME 101 9559
TEST_TIME 102 9537
TEST_TIME 103 9559
TEST_TIME 104 9513
TEST_TIME 105 9465
TEST_TIME 106 9468
TEST_TIME 107 9418
TEST_TIME 108 9422
TEST_TIME 109 9422
TEST_TIME 110 9370
TEST_TIME 111 9362
TEST_TIME 112 9333
TEST_TIME 113 9316
TEST_TIME 114 9301
TEST_TIME 115 9303
TEST_TIME 116 9317
TEST_TIME 117 9323
TEST_TIME 118 9373
TEST_TIME 119 9420
TEST_TIME 120 9357

Performance per minute, chart


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

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 1575493 1575493 0 0.00 0 22702 6 0 0
doc_list_biud 1575493 1575493 0 0.00 0 45506 26 0 0
sp_add_invoice_to_stock 5454 5325 129 2.36 23 197453 17251 0 129
sp_cancel_adding_invoice 5582 5582 0 0.00 3 54964 803 0 0
sp_cancel_client_order 1887 1887 0 0.00 4 8858 338 0 0
sp_cancel_customer_reserve 541989 541989 0 0.00 1 45508 58 0 0
sp_cancel_pay_from_customer 741 741 0 0.00 3 17897 94 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 2 7660 74 0 0
sp_cancel_supplier_invoice 6204 6194 10 0.16 1 39173 869 0 10
sp_cancel_supplier_order 415 415 0 0.00 6 1121287 97178 0 0
sp_cancel_write_off 10367 10367 0 0.00 1 17999 114 0 0
sp_client_order 22183 22183 0 0.00 3 62250 323 0 0
sp_customer_reserve 490182 490182 0 0.00 0 62399 171 0 0
sp_fill_shopping_cart 33627 33627 0 0.00 1 18253 40 0 0
sp_fill_shopping_cart_clo_res 490174 490174 0 0.00 0 31372 13 0 0
sp_get_clo_for_invoice 5313 5313 0 0.00 0 46548 347 0 0
sp_kill_cost_storno 17119 17119 0 0.00 0 17923 58 0 0
sp_kill_qstorno_ret_qs2qd 1100954 1100954 0 0.00 0 45504 32 0 0
sp_kill_qty_storno 582268 582268 0 0.00 0 45506 70 0 0
sp_lock_selected_doc 21986 21986 0 0.00 0 9285 5 0 0
sp_make_cost_storno 29456 29456 0 0.00 0 15118 49 0 0
sp_multiply_rows_for_pdistr 29456 29456 0 0.00 0 18558 32 0 0
sp_multiply_rows_for_qdistr 22183 22183 0 0.00 0 62240 152 0 0
sp_pay_from_customer 7408 7408 0 0.00 3 72529 122 0 0
sp_pay_to_supplier 6214 6214 0 0.00 4 18863 115 0 0
sp_payment_common 13622 13622 0 0.00 1 72519 37 0 0
sp_qd_handle_on_cancel_clo 1887 1887 0 0.00 0 8837 315 0 0
sp_qd_handle_on_invoice_upd_sts 10895 10895 0 0.00 0 40583 323 0 0
sp_qd_handle_on_reserve_upd_sts 10521 10521 0 0.00 0 28941 55 0 0
sp_reserve_write_off 10521 10521 0 0.00 6 28978 172 0 0
sp_supplier_invoice 5818 5818 0 0.00 7 91681 3344 0 0
sp_supplier_order 5645 5645 0 0.00 5 57195 1284 0 0
srv_aggregate_perf_data 118 118 0 0.00 2686 198126 16393 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2120 121702 43653 0 0
srv_find_qd_qs_mism 1071496 1071496 0 0.00 0 10675 5 0 0
srv_make_invnt_saldo 596 596 0 0.00 27 155620 1142 0 0
srv_make_money_saldo 861 861 0 0.00 1 4201 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 128343 309702 246440 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5326 5326 0 0.00 0 10658 157 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 3684 87 0 0
t$perf-norm:sp_cancel_client_order 1887 1887 0 0.00 0 11325 16 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 5911 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 741 741 0 0.00 0 4007 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 1735 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 565 10 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 18445 1549 0 0
t$perf-norm:sp_cancel_write_off 1114 1114 0 0.00 0 4922 9 0 0
t$perf-norm:sp_client_order 22183 22183 0 0.00 0 11135 15 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 4160 7 0 0
t$perf-norm:sp_pay_from_customer 7408 7408 0 0.00 0 2953 8 0 0
t$perf-norm:sp_pay_to_supplier 6214 6214 0 0.00 0 13699 12 0 0
t$perf-norm:sp_reserve_write_off 10521 10521 0 0.00 0 19228 12 0 0
t$perf-norm:sp_supplier_invoice 5818 5818 0 0.00 0 8837 22 0 0
t$perf-norm:sp_supplier_order 5645 5645 0 0.00 0 10115 18 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 690 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1779 29 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 3457 28 0 0
t$perf-norm:srv_make_money_saldo 861 861 0 0.00 0 751 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10882 10624 258 2.37 0 7866 14 0 258
v_all_customers 39922 39922 0 0.00 0 16 1 0 0
v_all_suppliers 31582 31582 0 0.00 0 18 1 0 0
v_all_wares 44366 44366 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 176 5 0 0
v_cancel_client_order 3774 3774 0 0.00 0 3015 3 0 0
v_cancel_customer_prepayment 1482 1482 0 0.00 0 10313 10 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 529 4 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 709 3 0 0
v_cancel_supplier_invoice 876 856 20 2.28 0 1156 13 0 20
v_cancel_supplier_order 830 830 0 0.00 0 99 3 0 0
v_cancel_write_off 2228 2228 0 0.00 0 862 11 0 0
v_max_id_clo_ord 5645 5645 0 0.00 0 191 1 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 3768 24 0 0
v_max_id_ord_sup 5798 5798 0 0.00 0 3608 6 0 0
v_max_non_paid_invoice 1866 1866 0 0.00 0 6020 5 0 0
v_max_non_paid_realizn 4130 4130 0 0.00 0 6045 4 0 0
v_min_id_clo_ord 5645 5645 0 0.00 0 7105 7 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 9785 27 0 0
v_min_id_ord_sup 5798 5798 0 0.00 0 7099 9 0 0
v_min_non_paid_invoice 6214 6214 0 0.00 0 18453 22 0 0
v_min_non_paid_realizn 7408 7408 0 0.00 0 47850 27 0 0
v_our_firm 11000 11000 0 0.00 0 17 1 0 0
v_reserve_write_off 21042 21042 0 0.00 0 4612 4 0 0
x_lock_depdocs_on_canc_invoice 11348 11348 0 0.00 0 12978 106 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 4037 274 0 0
x_make_qstorno_1000_1200 5645 5645 0 0.00 1 56320 1202 0 0
x_make_qstorno_1200_2000 5818 5818 0 0.00 3 91648 3193 0 0
x_make_qstorno_2100_3300 490182 490182 0 0.00 0 62399 156 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:14 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:15 18812 8192000000 9890217488 10195419136 970444000 10.591 100 91 182 0 1000301120 5592112 24265008
17:10:38 18648 8192000000 9934571616 10253025280 1019252448 11.065 100 84 168 0 1048805984 7736752 21816784
17:12:03 19002 8192000000 9969358000 10298310656 1056417568 11.422 100 83 164 2 1086187280 9390832 20378880
17:13:37 12541 8192000000 10037138464 10385784832 1122031280 12.046 100 77 154 0 1150635056 10053424 18550352
17:14:51 7274 8192000000 10088405488 10449686528 1170793808 12.504 100 75 150 0 1199155280 11510688 16850784
17:16:02 4735 8192000000 10130187776 10498740224 1217983296 12.943 100 79 159 0 1249197888 13705104 17509488
17:17:09 7242 8192000000 10163524800 10540691456 1255665280 13.290 100 82 162 1 1287768896 14461648 17641968
17:18:17 1871 8192000000 10196834336 10577661952 1281501312 13.527 100 92 183 0 1316097376 14535744 20060320
17:19:21 28173 8192000000 10187780864 10589958144 1293363696 13.635 100 46 92 1 1313684176 10359328 9961152
17:20:50 5504 8192000000 10196902496 10605461504 1305254192 13.743 100 32 64 0 1319798736 7710768 6833776
17:21:56 15717 8192000000 10198501424 10616098816 1316186176 13.842 100 14 27 1 1325237168 5971168 3079824
17:23:16 38728 8192000000 10223305648 10632871936 1324968864 13.922 100 26 50 1 1336996144 5345024 6682256
17:24:59 7051 8192000000 10241448224 10650439680 1334494016 14.008 100 49 99 1 1353971904 8727408 10750480
17:26:07 7038 8192000000 10230893936 10649395200 1334981984 14.012 100 27 52 1 1347415904 5876688 6557232
17:27:15 15644 8192000000 10215835584 10628276224 1315232016 13.834 99 22 44 3 1325503696 5100704 5170976
17:28:51 5767 8192000000 10192707520 10606723072 1261948832 13.348 96 27 53 1 1278513488 10581376 5983280
17:29:58 8174 8192000000 10143389712 10546089984 1225253088 13.010 97 21 42 2 1235548256 5128352 5166816
17:31:09 7317 8192000000 10117882848 10517549056 1229549168 13.050 99 20 40 0 1239651616 5624544 4477904
17:32:20 77975 8192000000 10156460832 10563887104 1221035424 12.971 96 23 46 2 1236014688 9699792 5279472
17:34:38 3396 8192000000 10188227552 10589179904 1283078640 13.541 100 46 92 1 1301145504 6898192 11168672
17:35:42 10911 8192000000 10199855360 10614460416 1297480880 13.672 100 26 52 1 1313164496 9477664 6205952
17:36:53 9311 8192000000 10197085584 10618212352 1310508480 13.791 100 22 44 1 1321159920 5706272 4945168
17:38:03 81237 8192000000 10231909376 10640576512 1323772128 13.911 100 42 81 1 1340735696 8508064 8455504
17:40:28 10621 8192000000 10266201984 10675470336 1351534560 14.161 100 59 118 0 1379011632 13800608 13676464
17:41:42 4445 8192000000 10282712112 10695901184 1366275280 14.294 100 55 108 0 1392489776 13687344 12527152
17:42:49 23791 8192000000 10301012624 10720878592 1381397328 14.429 100 41 82 2 1409822896 18776016 9649552
17:44:16 8730 8192000000 10301618080 10726535168 1390126848 14.507 100 49 98 2 1421800352 18657136 13016368
17:45:35 5577 8192000000 10319916848 10740264960 1393897632 14.541 100 70 137 4 1431486304 21368544 16220128
17:46:48 6444 8192000000 10328013088 10751381504 1403335344 14.625 100 57 113 2 1440237488 22317968 14584176
17:48:03 8749 8192000000 10341954944 10766503936 1411822160 14.700 100 61 119 1 1453914016 27871248 14220608
17:49:15 2484 8192000000 10351421264 10764795904 1404049472 14.631 100 81 162 0 1458827648 34537136 20241040
17:50:24 7886 8192000000 10313012048 10722091008 1361402352 14.250 99 68 136 2 1408403904 31095824 15905728
17:51:37 2117 8192000000 10316352832 10721501184 1367177792 14.302 99 73 143 0 1417856448 32621696 18056960
17:52:43 4675 8192000000 10323860960 10730901504 1363370256 14.268 99 83 164 1 1420619696 36926464 20322976
17:53:53 2528 8192000000 10313650480 10717679616 1351635680 14.162 99 67 133 1 1416318432 47600384 17082368
17:55:05 2933 8192000000 10331823552 10743103488 1370540880 14.332 100 69 138 1 1433637440 44243312 18853248
17:56:17 5554 8192000000 10347964512 10760179712 1376963024 14.389 100 67 132 1 1452549472 58023440 17563008
17:57:26 7046 8192000000 10337822640 10761703424 1388928672 14.496 100 48 96 0 1445715664 44306608 12480384
17:58:48 13796 8192000000 10300727536 10734874624 1364336096 14.276 100 28 56 1 1410992880 39029680 7627104
18:00:04 18577 8192000000 10325007904 10752811008 1373448400 14.358 100 33 66 0 1425580496 43153680 8978416
18:01:25 16139 8192000000 10321278736 10748157952 1373541536 14.359 100 34 68 2 1422074768 39160896 9372336
18:02:48 20517 8192000000 10333197424 10770022400 1391819312 14.522 100 32 64 1 1442303408 40940128 9543968
18:04:15 31328 8192000000 10323491776 10769481728 1392667088 14.530 100 21 42 1 1431121392 32292288 6162016
18:06:22 63972 8192000000 10271330064 10729635840 1332956832 13.994 97 9 18 0 1360653952 25567376 2129744
18:08:26 83952 8192000000 10269020256 10703974400 1335442400 14.016 97 38 77 1 1349837248 5942000 8452848
18:10:53 49763 8192000000 10288335008 10736431104 1357055856 14.211 98 33 65 0 1371352832 6829200 7467776
18:12:46 69078 8192000000 10275345120 10733383680 1363523824 14.269 99 10 20 0 1376452192 10667696 2260672
18:14:56 41952 8192000000 10297713696 10752491520 1391896848 14.523 100 19 38 0 1408260400 11353168 5010384
18:16:55 24524 8192000000 10299014128 10766454784 1397104496 14.569 100 14 29 1 1411731024 11325408 3301120
18:19:09 48339 8192000000 10295726864 10764427264 1392907136 14.532 100 9 17 1 1406082288 11494944 1680208
18:21:00 73785 8192000000 10281513152 10746015744 1384717920 14.459 99 12 24 0 1395108064 7531216 2858928
18:23:15 65109 8192000000 10305910928 10787962880 1402709888 14.619 100 10 20 0 1419315184 14220752 2384544
18:25:26 55793 8192000000 10301197712 10762002432 1361180384 14.248 97 11 22 0 1372621248 8837504 2603360
18:27:23 67007 8192000000 10314676128 10769895424 1381228912 14.428 98 25 50 1 1397569968 10788640 5552416
18:29:31 17161 8192000000 10320522048 10768969728 1401547200 14.609 100 49 98 2 1423075264 10209312 11318752
18:31:17 57708 8192000000 10301506992 10748801024 1346967472 14.120 96 18 36 0 1363444912 12433088 4044352
18:33:17 70873 8192000000 10328288896 10777190400 1374629648 14.369 98 23 46 0 1396360848 16540928 5190272
18:35:36 121702 8192000000 10269125424 10721214464 1337960960 14.039 96 25 50 1 1363489248 19078224 6450064
18:38:40 18557 8192000000 10300634768 10737356800 1367995808 14.309 99 47 95 0 1388121920 9911712 10214400
18:40:03 73736 8192000000 10270607408 10742173696 1357412224 14.214 98 14 28 0 1371604496 10801040 3391232
18:42:18 28705 8192000000 10246730384 10707582976 1326443872 13.935 97 2 4 0 1333617264 7088320 85072
18:43:46 49704 8192000000 10249222448 10705612800 1297532400 13.673 95 11 22 0 1310375824 10482528 2360896
18:45:39 39045 8192000000 10275542976 10722533376 1351652176 14.162 99 43 86 1 1373009456 11115056 10242224
18:48:18 49725 8192000000 10242174896 10686119936 1300880864 13.703 95 14 28 0 1313748224 9440416 3426944
18:50:08 59495 8192000000 10262848480 10713911296 1332464672 13.989 98 28 56 1 1350299760 11671664 6163424
18:52:11 15843 8192000000 10282305440 10722717696 1370249536 14.329 100 28 56 1 1383792112 6815888 6726688
18:53:28 17737 8192000000 10289446384 10724499456 1366249776 14.293 100 45 88 1 1388846816 12901296 9695744
18:54:46 41502 8192000000 10279852688 10731540480 1350772288 14.154 98 13 26 0 1361583024 8236816 2573920
18:56:33 96114 8192000000 10285189744 10737176576 1343345600 14.088 98 10 20 0 1360991376 15681936 1963840
18:59:14 87396 8192000000 10277135744 10724630528 1351256336 14.159 98 28 56 0 1368940624 11059920 6624368
19:01:42 31810 8192000000 10246303600 10686574592 1328037312 13.949 97 26 52 2 1342820384 8876000 5907072
19:03:14 64465 8192000000 10253905280 10726916096 1367111072 14.301 100 16 32 0 1382744880 11713872 3919936
19:05:21 61874 8192000000 10277268528 10732081152 1364399008 14.277 99 19 37 0 1379097984 9762848 4936128
19:07:44 88044 8192000000 10296927616 10752593920 1362541696 14.260 98 25 50 1 1381561776 12758512 6261568
19:10:12 39266 8192000000 10305673664 10760847360 1384091328 14.453 99 33 66 1 1405041600 13279568 7670704
19:11:57 69842 8192000000 10286633424 10747150336 1344499344 14.098 97 19 38 1 1363024064 14233792 4290928
19:14:17 43458 8192000000 10302850608 10746552320 1371480352 14.340 99 35 66 8 1388183904 8097088 8606464
19:16:21 96817 8192000000 10316728880 10787500032 1381646784 14.431 99 21 42 0 1408321264 22284624 4389856
19:18:59 57811 8192000000 10285771216 10738106368 1327066112 13.941 95 18 36 0 1344796064 13698064 4031888
19:21:17 46962 8192000000 10311777264 10766733312 1383016512 14.444 99 32 64 1 1400890816 10977200 6897104
19:23:04 118913 8192000000 10281287760 10732048384 1348538736 14.134 97 20 40 0 1363599744 10077488 4983520
19:26:11 11297 8192000000 10290460880 10744655872 1368456416 14.313 99 26 52 1 1389061056 14881776 5722864
19:27:27 94978 8192000000 10270117008 10729877504 1308922336 13.776 95 9 18 0 1324235168 13621264 1691568
19:30:08 78452 8192000000 10263698832 10717925376 1346303776 14.114 98 18 36 1 1358893872 8140832 4449264
19:32:42 51405 8192000000 10272508752 10728583168 1360810480 14.245 99 8 16 0 1374588096 12085168 1692448
19:34:36 48592 8192000000 10261646384 10715471872 1324851680 13.921 97 27 54 0 1344240784 12881712 6507392
19:36:29 59971 8192000000 10272913536 10730225664 1352244144 14.168 99 13 26 0 1370613824 15411120 2958560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-03-2022 19:39:18 to 26-03-2022 19:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-03-2022 19:39:34 to 26-03-2022 19:39:36.

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          248385
MON$OLDEST_ACTIVE               248798
MON$OLDEST_SNAPSHOT             248798
MON$NEXT_TRANSACTION            248798
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                       2032416
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:491: 
:492: mscs-test-161.budzdorov.ru    Sat Mar 26 19:38:14 2022
:493:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:494: 
--- end of diff output ---

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

26.03.22 19:39:37. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-03-26 19:39:39 No severe PSQL-related problems occured
26.03.22 19:39:41. End of report.