Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.06.2022 16: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 15984528 kB
MemAvailable 31230132 kB
Buffers 0 kB
Cached 15706644 kB
SwapCached 2460 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-06-19 16:38:22
DTS_END                         2022-06-19 16:38:22.6030
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/44358
TRN_ID                          242400
ATT_ID                          629
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: 9478.19 21165 1137383
customer order: creation 182.67 348 21920
customer order: refuse 15.56 349 1867
order to supplier: creation 47.96 1372 5755
order to supplier: removal 3.48 106716 417
invoice (draft): creation 47.96 3523 5755
invoice (draft): removal 53.09 972 6371
invoice accept: apply 42.78 18058 5133
invoice accept: cancel 48.38 862 5806
customer reserve: creation 3954.48 178 474538
customer reserve: removal 4775.04 59 573005
realization accept: apply 87.25 182 10470
realization accept: cancel 83.03 109 9964
payment from customer: creation 62.73 122 7528
payment from customer: removal 5.79 107 695
payment to supplier: creation 51.53 131 6183
payment to supplier: removal 3.83 78 459
service: total inventory turnovers 4.78 1197 574
service: total monetary turnovers 6.90 35 828
service: aggregate perf. data 0.93 18823 111
service: refresh index statistics 0.03 270084 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-06-2022 16:39:40 to 19-06-2022 16:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4139
TEST_TIME 2 3197
TEST_TIME 3 4820
TEST_TIME 4 4403
TEST_TIME 5 5528
TEST_TIME 6 5692
TEST_TIME 7 6283
TEST_TIME 8 6381
TEST_TIME 9 7093
TEST_TIME 10 7449
TEST_TIME 11 7339
TEST_TIME 12 7183
TEST_TIME 13 7505
TEST_TIME 14 7476
TEST_TIME 15 7423
TEST_TIME 16 7581
TEST_TIME 17 7564
TEST_TIME 18 7516
TEST_TIME 19 7664
TEST_TIME 20 7609
TEST_TIME 21 7650
TEST_TIME 22 7616
TEST_TIME 23 7853
TEST_TIME 24 7883
TEST_TIME 25 8210
TEST_TIME 26 8241
TEST_TIME 27 8165
TEST_TIME 28 8120
TEST_TIME 29 8342
TEST_TIME 30 9061
TEST_TIME 31 9091
TEST_TIME 32 9468
TEST_TIME 33 9969
TEST_TIME 34 10404
TEST_TIME 35 10532
TEST_TIME 36 10653
TEST_TIME 37 10533
TEST_TIME 38 10381
TEST_TIME 39 10229
TEST_TIME 40 10284
TEST_TIME 41 10191
TEST_TIME 42 10377
TEST_TIME 43 10211
TEST_TIME 44 10228
TEST_TIME 45 10305
TEST_TIME 46 10236
TEST_TIME 47 10191
TEST_TIME 48 10132
TEST_TIME 49 9956
TEST_TIME 50 10009
TEST_TIME 51 9913
TEST_TIME 52 9963
TEST_TIME 53 9975
TEST_TIME 54 9856
TEST_TIME 55 9839
TEST_TIME 56 9813
TEST_TIME 57 9793
TEST_TIME 58 9952
TEST_TIME 59 9957
TEST_TIME 60 9903
TEST_TIME 61 9857
TEST_TIME 62 9744
TEST_TIME 63 9711
TEST_TIME 64 9807
TEST_TIME 65 9769
TEST_TIME 66 9875
TEST_TIME 67 9806
TEST_TIME 68 9944
TEST_TIME 69 9938
TEST_TIME 70 9937
TEST_TIME 71 9869
TEST_TIME 72 9860
TEST_TIME 73 9991
TEST_TIME 74 9936
TEST_TIME 75 9950
TEST_TIME 76 10056
TEST_TIME 77 9992
TEST_TIME 78 9888
TEST_TIME 79 9862
TEST_TIME 80 9823
TEST_TIME 81 9777
TEST_TIME 82 9735
TEST_TIME 83 9700
TEST_TIME 84 9654
TEST_TIME 85 9614
TEST_TIME 86 9604
TEST_TIME 87 9552
TEST_TIME 88 9550
TEST_TIME 89 9601
TEST_TIME 90 9591
TEST_TIME 91 9619
TEST_TIME 92 9644
TEST_TIME 93 9644
TEST_TIME 94 9601
TEST_TIME 95 9672
TEST_TIME 96 9684
TEST_TIME 97 9655
TEST_TIME 98 9684
TEST_TIME 99 9665
TEST_TIME 100 9715
TEST_TIME 101 9799
TEST_TIME 102 9853
TEST_TIME 103 9829
TEST_TIME 104 9788
TEST_TIME 105 9754
TEST_TIME 106 9708
TEST_TIME 107 9702
TEST_TIME 108 9651
TEST_TIME 109 9665
TEST_TIME 110 9650
TEST_TIME 111 9615
TEST_TIME 112 9581
TEST_TIME 113 9586
TEST_TIME 114 9534
TEST_TIME 115 9496
TEST_TIME 116 9555
TEST_TIME 117 9536
TEST_TIME 118 9536
TEST_TIME 119 9533
TEST_TIME 120 9479

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-06-2022 16:39:43 to 19-06-2022 16:39:47.

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 1578910 1578910 0 0.00 0 19017 6 0 0
doc_list_biud 1578909 1578909 0 0.00 0 55954 28 0 0
sp_add_invoice_to_stock 5218 5133 85 1.62 38 204116 18058 0 85
sp_cancel_adding_invoice 5806 5806 0 0.00 5 56055 862 0 0
sp_cancel_client_order 1867 1867 0 0.00 4 8880 350 0 0
sp_cancel_customer_reserve 573005 573005 0 0.00 1 55956 60 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 2 12968 107 0 0
sp_cancel_pay_to_supplier 459 459 0 0.00 3 4456 79 0 0
sp_cancel_supplier_invoice 6379 6371 8 0.12 2 20226 973 0 8
sp_cancel_supplier_order 417 417 0 0.00 7 1172682 106717 0 0
sp_cancel_write_off 9964 9964 0 0.00 1 6777 110 0 0
sp_client_order 21920 21920 0 0.00 3 20142 348 0 0
sp_customer_reserve 474538 474538 0 0.00 0 37613 179 0 0
sp_fill_shopping_cart 33423 33423 0 0.00 1 13710 39 0 0
sp_fill_shopping_cart_clo_res 474536 474536 0 0.00 0 12858 13 0 0
sp_get_clo_for_invoice 5131 5131 0 0.00 0 14494 264 0 0
sp_kill_cost_storno 16924 16924 0 0.00 0 18936 62 0 0
sp_kill_qstorno_ret_qs2qd 1162762 1162762 0 0.00 0 55951 33 0 0
sp_kill_qty_storno 613031 613031 0 0.00 0 55953 72 0 0
sp_lock_selected_doc 21505 21505 0 0.00 0 10549 6 0 0
sp_make_cost_storno 29312 29312 0 0.00 0 15155 58 0 0
sp_multiply_rows_for_pdistr 29312 29312 0 0.00 0 17696 29 0 0
sp_multiply_rows_for_qdistr 21920 21920 0 0.00 0 11603 150 0 0
sp_pay_from_customer 7528 7528 0 0.00 4 17706 123 0 0
sp_pay_to_supplier 6183 6183 0 0.00 5 29207 131 0 0
sp_payment_common 13711 13711 0 0.00 1 29183 29 0 0
sp_qd_handle_on_cancel_clo 1867 1867 0 0.00 0 7667 316 0 0
sp_qd_handle_on_invoice_upd_sts 10937 10937 0 0.00 0 12895 340 0 0
sp_qd_handle_on_reserve_upd_sts 10470 10470 0 0.00 0 13065 61 0 0
sp_reserve_write_off 10470 10470 0 0.00 6 15285 183 0 0
sp_supplier_invoice 5755 5755 0 0.00 6 79343 3523 0 0
sp_supplier_order 5755 5755 0 0.00 5 27030 1373 0 0
srv_aggregate_perf_data 111 111 0 0.00 3117 156757 18824 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1320 144600 40031 0 0
srv_find_qd_qs_mism 1088542 1088542 0 0.00 0 16038 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 35 108850 1197 0 0
srv_make_money_saldo 828 828 0 0.00 0 4108 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 132950 481690 270084 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5133 5133 0 0.00 0 15541 176 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 1 1693 77 0 0
t$perf-norm:sp_cancel_client_order 1867 1867 0 0.00 0 2084 13 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 3814 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 611 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 459 459 0 0.00 0 2465 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1869 31 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 30095 1802 0 0
t$perf-norm:sp_cancel_write_off 947 947 0 0.00 0 4809 32 0 0
t$perf-norm:sp_client_order 21920 21920 0 0.00 0 15797 19 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 3810 15 0 0
t$perf-norm:sp_pay_from_customer 7528 7528 0 0.00 0 16134 14 0 0
t$perf-norm:sp_pay_to_supplier 6183 6183 0 0.00 0 18161 10 0 0
t$perf-norm:sp_reserve_write_off 10470 10470 0 0.00 0 7372 13 0 0
t$perf-norm:sp_supplier_invoice 5755 5755 0 0.00 0 4451 23 0 0
t$perf-norm:sp_supplier_order 5755 5755 0 0.00 0 2861 15 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 556 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 1647 26 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 2948 27 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 1749 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10430 10260 170 1.62 0 12203 22 0 170
v_all_customers 39998 39998 0 0.00 0 19 1 0 0
v_all_suppliers 32038 32038 0 0.00 0 59 1 0 0
v_all_wares 43840 43840 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 2859 15 0 0
v_cancel_client_order 3734 3734 0 0.00 0 4507 7 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 2000 6 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 4205 15 0 0
v_cancel_payment_to_supplier 918 918 0 0.00 0 4446 9 0 0
v_cancel_supplier_invoice 806 790 16 1.98 0 2076 16 0 16
v_cancel_supplier_order 834 834 0 0.00 0 2560 7 0 0
v_cancel_write_off 1894 1894 0 0.00 0 4842 18 0 0
v_max_id_clo_ord 5754 5754 0 0.00 0 913 2 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 1324 9 0 0
v_max_id_ord_sup 5749 5749 0 0.00 0 6650 6 0 0
v_max_non_paid_invoice 1667 1667 0 0.00 0 760 4 0 0
v_max_non_paid_realizn 3877 3877 0 0.00 0 5662 6 0 0
v_min_id_clo_ord 5754 5754 0 0.00 0 3655 7 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 4458 9 0 0
v_min_id_ord_sup 5749 5749 0 0.00 0 4610 6 0 0
v_min_non_paid_invoice 6183 6183 0 0.00 0 29180 21 0 0
v_min_non_paid_realizn 7528 7528 0 0.00 0 11011 20 0 0
v_our_firm 11144 11144 0 0.00 0 19 1 0 0
v_reserve_write_off 20940 20940 0 0.00 0 6582 7 0 0
x_lock_depdocs_on_canc_invoice 11782 11782 0 0.00 0 24662 114 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 7348 377 0 0
x_make_qstorno_1000_1200 5755 5755 0 0.00 1 26912 1280 0 0
x_make_qstorno_1200_2000 5755 5755 0 0.00 2 79161 3390 0 0
x_make_qstorno_2100_3300 474538 474538 0 0.00 0 37609 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-06-2022 16:39:48 to 19-06-2022 16:39:52.

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
14:08:26 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:27 19613 8192000000 9893339856 10203660288 979223856 10.677 100 82 164 0 1006321376 5521568 21575952
14:10:51 32044 8192000000 9953007360 10273075200 1030608816 11.174 100 91 182 0 1062581872 8372656 23600400
14:12:27 17684 8192000000 9978967280 10311680000 1064630816 11.501 100 83 166 1 1095023632 9418112 20974704
14:13:55 11653 8192000000 10030570880 10379575296 1117298368 12.001 100 76 152 1 1146078016 10648976 18130672
14:15:13 7195 8192000000 10088677520 10447491072 1171312080 12.509 100 83 164 0 1202672576 12323936 19036560
14:16:25 6072 8192000000 10132496160 10500628480 1216523472 12.930 100 80 160 2 1247066944 12678352 17865120
14:17:33 4529 8192000000 10158616048 10533093376 1246778128 13.209 100 81 162 4 1278723024 14116944 17827952
14:18:39 2306 8192000000 10197666432 10576338944 1282584240 13.537 100 90 180 0 1315023216 12909920 19529056
14:19:42 1421 8192000000 10220623984 10604134400 1300922080 13.704 100 89 181 2 1333922448 13293296 19707072
14:20:44 3460 8192000000 10207998944 10613088256 1313995904 13.822 100 39 77 0 1329123456 7163504 7964048
14:21:50 5592 8192000000 10210729232 10620416000 1322763008 13.902 100 38 76 4 1337860416 6685568 8411840
14:22:57 14742 8192000000 10218864816 10623696896 1325340736 13.925 100 21 42 0 1336312816 6387952 4584128
14:24:12 42713 8192000000 10235403376 10638454784 1330451664 13.971 100 52 102 3 1348465728 6354816 11659248
14:25:55 5815 8192000000 10227644064 10645450752 1336327600 14.024 100 23 45 1 1346662608 5200064 5134944
14:27:01 51938 8192000000 10222233888 10649788416 1337603472 14.036 100 34 68 1 1351813520 6857712 7352336
14:28:58 6960 8192000000 10193752320 10613587968 1273860880 13.457 96 27 54 1 1285957872 6324800 5772192
14:30:06 5897 8192000000 10162233616 10576437248 1263309840 13.360 98 33 66 1 1277887040 7015488 7561712
14:31:12 41584 8192000000 10133343248 10538729472 1189061472 12.675 93 23 46 0 1199656736 4871344 5723920
14:32:57 5568 8192000000 10158228768 10553638912 1245893232 13.200 99 33 66 0 1259991360 6371728 7726400
14:34:05 6688 8192000000 10170653920 10571476992 1261433584 13.343 99 36 72 0 1275801536 4994848 9373104
14:35:14 71731 8192000000 10174120448 10589351936 1289824784 13.603 100 27 55 0 1301536496 5474048 6237664
14:37:26 9384 8192000000 10197637424 10607124480 1300303856 13.698 100 21 41 1 1310604544 5236608 5064080
14:38:38 31728 8192000000 10234696304 10641666048 1329828688 13.966 100 34 68 0 1343277568 5238560 8210320
14:40:15 26919 8192000000 10253380928 10666254336 1347631920 14.126 100 50 100 2 1369013936 9899104 11482912
14:41:49 75648 8192000000 10265909232 10691305472 1360097808 14.238 100 28 56 0 1379192192 12183600 6910784
14:44:11 18346 8192000000 10272946560 10695520256 1366017424 14.291 100 29 57 0 1383520432 10917104 6585904
14:45:36 5937 8192000000 10304267312 10718105600 1381003152 14.426 100 63 126 2 1409304000 14070288 14230560
14:46:45 13395 8192000000 10307434464 10726232064 1385398448 14.465 100 77 154 2 1422135408 18880560 17856400
14:48:02 13421 8192000000 10308012384 10733842432 1393959936 14.541 100 54 108 2 1420143872 14331792 11852144
14:49:22 2428 8192000000 10325681680 10746400768 1405070608 14.640 100 68 134 0 1434865696 13682272 16112816
14:50:28 4814 8192000000 10330816576 10753515520 1412449776 14.706 100 64 125 1 1442119840 15485744 14184320
14:51:39 1316 8192000000 10320955616 10734567424 1387393968 14.483 100 91 185 2 1425439984 16869392 21176624
14:52:44 1767 8192000000 10307227440 10720407552 1375046576 14.372 99 67 134 2 1412883056 21746848 16089632
14:53:54 5223 8192000000 10328035024 10749386752 1399112256 14.587 100 49 97 1 1437473872 26171984 12189632
14:55:05 3532 8192000000 10316667808 10726469632 1344867024 14.101 96 72 144 1 1388311312 24698912 18745376
14:56:13 2414 8192000000 10327895824 10737541120 1385178288 14.463 99 72 138 1 1432268864 29913216 17177360
14:57:22 12461 8192000000 10340501104 10760708096 1390549200 14.511 99 53 103 1 1438647968 34846832 13251936
14:58:39 2748 8192000000 10369208288 10781679616 1415181888 14.730 100 84 168 3 1470401568 33543696 21675984
14:59:49 2500 8192000000 10375941536 10784301056 1414300480 14.722 100 74 148 3 1475171632 42469840 18401312
15:01:06 2355 8192000000 10372656016 10783424512 1410617888 14.689 100 80 162 2 1479234416 47417552 21198976
15:02:15 4896 8192000000 10345650848 10761052160 1393668048 14.539 100 53 106 0 1451923584 43994864 14260672
15:03:37 10177 8192000000 10344017168 10778406912 1394918576 14.550 100 38 75 1 1453631520 48552736 10160208
15:04:56 10320 8192000000 10360207488 10785640448 1401859056 14.612 99 42 84 2 1461697104 48628208 11209840
15:06:13 13742 8192000000 10366166016 10794790912 1405993392 14.648 100 39 78 1 1475747088 58551392 11202304
15:07:31 8786 8192000000 10384399696 10811977728 1402273840 14.615 100 43 86 0 1487093088 72119824 12699424
15:08:53 16426 8192000000 10346954816 10783109120 1396806912 14.567 100 30 58 2 1451090512 46465376 7818224
15:10:11 26741 8192000000 10349198992 10794065920 1393724176 14.539 100 26 52 3 1468393520 66758576 7910768
15:11:47 14551 8192000000 10338664544 10774122496 1390683904 14.512 100 47 91 6 1437147024 35540672 10922448
15:13:03 28958 8192000000 10293330768 10739191808 1385394416 14.465 100 22 42 2 1404431168 14055600 4981152
15:14:52 77365 8192000000 10284339584 10719395840 1328783600 13.956 95 11 22 0 1350274912 19515712 1975600
15:17:10 35992 8192000000 10293544736 10738515968 1359949312 14.237 98 40 81 2 1382986944 13618272 9419360
15:18:56 69653 8192000000 10295818592 10742243328 1372451568 14.349 99 24 49 0 1392495760 14773600 5270592
15:21:17 46785 8192000000 10303455056 10744909824 1383557520 14.448 100 28 54 1 1402500928 12552624 6390784
15:23:07 134101 8192000000 10275719600 10724921344 1284414144 13.553 93 5 10 0 1298213024 12867584 931296
15:26:22 102017 8192000000 10306729056 10750763008 1377201008 14.392 99 23 46 0 1398267216 15984704 5081504
15:29:06 44239 8192000000 10300038864 10741358592 1371425456 14.340 99 37 74 0 1392971440 13028784 8517200
15:30:50 61525 8192000000 10309228576 10765819904 1391352560 14.518 100 45 89 2 1419433520 17850912 10230048
15:32:59 12113 8192000000 10302195232 10749763584 1394184848 14.543 100 14 28 0 1416611776 19009744 3417184
15:34:12 65336 8192000000 10310683344 10759766016 1365197264 14.284 97 23 46 0 1388718400 17901472 5619664
15:36:24 34835 8192000000 10300393824 10772860928 1386575232 14.475 99 29 58 0 1408410672 14996608 6838832
15:38:07 31783 8192000000 10290798112 10749091840 1373560992 14.359 98 13 26 0 1385003456 8618528 2823936
15:39:40 30147 8192000000 10301925328 10764574720 1389235088 14.499 99 12 25 0 1397904592 6238992 2430512
15:41:17 59999 8192000000 10310085824 10783285248 1407436624 14.661 100 19 38 2 1425508000 13708784 4362592
15:43:17 43775 8192000000 10294480832 10754269184 1356185600 14.203 97 23 46 0 1375024112 13425744 5412768
15:45:07 61586 8192000000 10312997968 10763677696 1377191616 14.391 99 23 46 1 1399721632 17072992 5457024
15:47:09 70658 8192000000 10305679616 10761195520 1358188784 14.221 97 19 38 0 1374080720 11583792 4308144
15:49:22 19549 8192000000 10290846256 10727936000 1367118528 14.301 99 49 98 0 1389857040 10945296 11793216
15:50:47 85432 8192000000 10274663520 10736443392 1340754944 14.064 97 11 22 0 1361261808 18013632 2493232
15:53:20 62625 8192000000 10286052928 10736775168 1339047184 14.049 97 25 48 2 1358976528 14328288 5601056
15:55:25 44136 8192000000 10252653456 10691039232 1321065024 13.886 96 23 46 0 1334522416 8400432 5056960
15:57:13 54752 8192000000 10266504848 10718863360 1338276160 14.042 98 25 50 1 1352682016 8347536 6058320
15:59:08 42683 8192000000 10264887152 10719866880 1354179152 14.185 99 23 45 1 1371604992 12544752 4881088
16:00:53 53741 8192000000 10273991760 10727809024 1361203440 14.248 99 15 30 0 1378179072 13262224 3713408
16:02:54 57965 8192000000 10293603776 10745978880 1376521408 14.385 99 27 54 0 1396185664 13247968 6416288
16:04:57 73199 8192000000 10277447584 10733977600 1357086128 14.211 98 11 22 0 1371435280 11912368 2436784
16:07:10 53145 8192000000 10266276192 10720743424 1310074128 13.787 95 18 36 1 1325960064 11866336 4019600
16:09:03 39494 8192000000 10263235328 10715066368 1335980096 14.021 97 16 32 0 1349261872 9846112 3435664
16:10:43 44987 8192000000 10277760880 10734751744 1356747552 14.208 98 24 48 1 1371880944 9475888 5657504
16:12:29 34609 8192000000 10304037216 10743713792 1373647952 14.360 99 42 84 0 1394250576 11365424 9237200
16:14:04 63583 8192000000 10293632224 10749587456 1388305984 14.491 100 17 33 2 1401033152 9194848 3532320
16:16:16 42569 8192000000 10293723712 10745593856 1362203392 14.257 98 19 38 0 1375931728 9662832 4065504
16:18:46 72041 8192000000 10297935968 10757234688 1361150176 14.248 98 25 48 1 1384659264 17909552 5599536
16:20:58 49315 8192000000 10287127168 10738077696 1371525136 14.341 99 24 48 2 1387756944 10668032 5563776
16:22:55 144600 8192000000 10285792928 10736500736 1348251136 14.132 97 22 44 1 1361305744 8196368 4858240
16:26:21 25521 8192000000 10308100432 10757722112 1392883520 14.532 100 15 30 1 1406597584 10166784 3547280
16:27:48 52755 8192000000 10281099344 10757263360 1372085104 14.346 99 6 13 0 1389211520 15875952 1250464
16:29:41 41833 8192000000 10291771136 10741841920 1364061040 14.274 98 17 34 0 1378515712 10776304 3678368
16:31:26 74254 8192000000 10277066544 10739273728 1339877264 14.056 97 14 28 3 1355200112 12083376 3239472
16:34:00 79251 8192000000 10269788464 10741047296 1347294144 14.123 98 10 20 0 1369269136 19649728 2325264
16:36:23 66513 8192000000 10253053440 10708647936 1296226192 13.661 95 18 36 0 1308333504 8260656 3846656

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-06-2022 16:39:54 to 19-06-2022 16:40:09.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-06-2022 16:40:10 to 19-06-2022 16:40:12.

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          241362
MON$OLDEST_ACTIVE               243161
MON$OLDEST_SNAPSHOT             243161
MON$NEXT_TRANSACTION            243161
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                       2060768
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:102: 
:103: mscs-test-161.budzdorov.ru    Sun Jun 19 16:38:24 2022
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:105: 
:106: 
:107: mscs-test-161.budzdorov.ru    Sun Jun 19 16:38:25 2022
:108:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:109: 
:110: 
:111: mscs-test-161.budzdorov.ru    Sun Jun 19 16:38:31 2022
:112:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:113: 
:114: 
:115: mscs-test-161.budzdorov.ru    Sun Jun 19 16:38:31 2022
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:117: 
:118: 
:119: mscs-test-161.budzdorov.ru    Sun Jun 19 16:38:31 2022
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:121: 
--- end of diff output ---

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

19.06.22 16:40:13. 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-06-19 16:40:15 No severe PSQL-related problems occured
19.06.22 16:40:17. End of report.