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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16232040 kB
MemAvailable 31225692 kB
Buffers 0 kB
Cached 15444680 kB
SwapCached 2444 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-17 01:38:23
DTS_END                         2022-06-17 01:38:23.6920
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/60878
TRN_ID                          245460
ATT_ID                          690
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: 9249.52 25611 1109942
customer order: creation 173.96 375 20875
customer order: refuse 15.43 326 1851
order to supplier: creation 47.76 1343 5731
order to supplier: removal 3.45 104683 414
invoice (draft): creation 46.58 3484 5590
invoice (draft): removal 51.73 830 6207
invoice accept: apply 43.32 18976 5198
invoice accept: cancel 46.65 823 5598
customer reserve: creation 3969.03 189 476283
customer reserve: removal 4551.59 62 546191
realization accept: apply 84.43 195 10131
realization accept: cancel 83.18 110 9981
payment from customer: creation 60.98 110 7318
payment from customer: removal 5.88 87 706
payment to supplier: creation 49.43 127 5931
payment to supplier: removal 3.88 69 466
service: total inventory turnovers 4.54 1077 545
service: total monetary turnovers 6.77 31 812
service: aggregate perf. data 0.92 16876 110
service: refresh index statistics 0.03 362442 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4458
TEST_TIME 2 3011
TEST_TIME 3 3933
TEST_TIME 4 3745
TEST_TIME 5 5012
TEST_TIME 6 5854
TEST_TIME 7 5676
TEST_TIME 8 5993
TEST_TIME 9 6251
TEST_TIME 10 6422
TEST_TIME 11 6653
TEST_TIME 12 6824
TEST_TIME 13 6979
TEST_TIME 14 7001
TEST_TIME 15 7138
TEST_TIME 16 7473
TEST_TIME 17 7691
TEST_TIME 18 7719
TEST_TIME 19 7680
TEST_TIME 20 7727
TEST_TIME 21 8286
TEST_TIME 22 8263
TEST_TIME 23 8213
TEST_TIME 24 8718
TEST_TIME 25 8910
TEST_TIME 26 9126
TEST_TIME 27 9836
TEST_TIME 28 9766
TEST_TIME 29 10495
TEST_TIME 30 10818
TEST_TIME 31 10745
TEST_TIME 32 10548
TEST_TIME 33 10338
TEST_TIME 34 10337
TEST_TIME 35 10199
TEST_TIME 36 10036
TEST_TIME 37 9983
TEST_TIME 38 9935
TEST_TIME 39 9861
TEST_TIME 40 9832
TEST_TIME 41 9828
TEST_TIME 42 9852
TEST_TIME 43 9858
TEST_TIME 44 9769
TEST_TIME 45 9613
TEST_TIME 46 9613
TEST_TIME 47 9459
TEST_TIME 48 9416
TEST_TIME 49 9397
TEST_TIME 50 9442
TEST_TIME 51 9365
TEST_TIME 52 9398
TEST_TIME 53 9482
TEST_TIME 54 9528
TEST_TIME 55 9577
TEST_TIME 56 9627
TEST_TIME 57 9644
TEST_TIME 58 9582
TEST_TIME 59 9742
TEST_TIME 60 9708
TEST_TIME 61 9694
TEST_TIME 62 9556
TEST_TIME 63 9515
TEST_TIME 64 9495
TEST_TIME 65 9480
TEST_TIME 66 9403
TEST_TIME 67 9410
TEST_TIME 68 9389
TEST_TIME 69 9360
TEST_TIME 70 9461
TEST_TIME 71 9532
TEST_TIME 72 9657
TEST_TIME 73 9679
TEST_TIME 74 9652
TEST_TIME 75 9767
TEST_TIME 76 9710
TEST_TIME 77 9673
TEST_TIME 78 9674
TEST_TIME 79 9777
TEST_TIME 80 9728
TEST_TIME 81 9688
TEST_TIME 82 9637
TEST_TIME 83 9558
TEST_TIME 84 9533
TEST_TIME 85 9472
TEST_TIME 86 9422
TEST_TIME 87 9423
TEST_TIME 88 9403
TEST_TIME 89 9440
TEST_TIME 90 9476
TEST_TIME 91 9485
TEST_TIME 92 9490
TEST_TIME 93 9520
TEST_TIME 94 9492
TEST_TIME 95 9559
TEST_TIME 96 9527
TEST_TIME 97 9547
TEST_TIME 98 9514
TEST_TIME 99 9544
TEST_TIME 100 9548
TEST_TIME 101 9544
TEST_TIME 102 9590
TEST_TIME 103 9582
TEST_TIME 104 9544
TEST_TIME 105 9475
TEST_TIME 106 9449
TEST_TIME 107 9472
TEST_TIME 108 9430
TEST_TIME 109 9421
TEST_TIME 110 9412
TEST_TIME 111 9393
TEST_TIME 112 9365
TEST_TIME 113 9343
TEST_TIME 114 9324
TEST_TIME 115 9267
TEST_TIME 116 9300
TEST_TIME 117 9272
TEST_TIME 118 9275
TEST_TIME 119 9316
TEST_TIME 120 9253

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-06-2022 01:39:44 to 17-06-2022 01: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 1550690 1550690 0 0.00 0 29040 7 0 0
doc_list_biud 1550689 1550689 0 0.00 0 40311 28 0 0
sp_add_invoice_to_stock 5347 5198 149 2.78 23 176779 18977 0 149
sp_cancel_adding_invoice 5598 5598 0 0.00 5 68713 823 0 0
sp_cancel_client_order 1851 1851 0 0.00 4 8673 326 0 0
sp_cancel_customer_reserve 546191 546191 0 0.00 1 21876 62 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 2 6102 88 0 0
sp_cancel_pay_to_supplier 466 466 0 0.00 3 4811 69 0 0
sp_cancel_supplier_invoice 6218 6207 11 0.17 1 20113 831 0 11
sp_cancel_supplier_order 414 414 0 0.00 6 1201659 104683 0 0
sp_cancel_write_off 9981 9981 0 0.00 1 7410 111 0 0
sp_client_order 20875 20875 0 0.00 4 18735 375 0 0
sp_customer_reserve 476283 476283 0 0.00 0 92989 189 0 0
sp_fill_shopping_cart 32190 32190 0 0.00 1 12600 38 0 0
sp_fill_shopping_cart_clo_res 476281 476281 0 0.00 0 10484 13 0 0
sp_get_clo_for_invoice 5194 5194 0 0.00 0 33054 319 0 0
sp_kill_cost_storno 16751 16751 0 0.00 0 6123 57 0 0
sp_kill_qstorno_ret_qs2qd 1108984 1108984 0 0.00 0 21872 34 0 0
sp_kill_qty_storno 585566 585566 0 0.00 0 40311 73 0 0
sp_lock_selected_doc 21374 21374 0 0.00 0 7157 6 0 0
sp_make_cost_storno 28573 28573 0 0.00 0 17487 55 0 0
sp_multiply_rows_for_pdistr 28573 28573 0 0.00 0 8686 28 0 0
sp_multiply_rows_for_qdistr 20875 20875 0 0.00 0 12357 168 0 0
sp_pay_from_customer 7318 7318 0 0.00 4 10714 110 0 0
sp_pay_to_supplier 5931 5931 0 0.00 5 15552 127 0 0
sp_payment_common 13249 13249 0 0.00 1 9053 29 0 0
sp_qd_handle_on_cancel_clo 1851 1851 0 0.00 0 8664 303 0 0
sp_qd_handle_on_invoice_upd_sts 10791 10791 0 0.00 0 40310 345 0 0
sp_qd_handle_on_reserve_upd_sts 10131 10131 0 0.00 0 25542 67 0 0
sp_reserve_write_off 10131 10131 0 0.00 6 25563 196 0 0
sp_supplier_invoice 5590 5590 0 0.00 6 94229 3485 0 0
sp_supplier_order 5731 5731 0 0.00 5 73884 1343 0 0
srv_aggregate_perf_data 110 110 0 0.00 2451 109276 16876 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1190 112101 44537 0 0
srv_find_qd_qs_mism 1060920 1060920 0 0.00 0 29024 6 0 0
srv_make_invnt_saldo 545 545 0 0.00 34 20925 1078 0 0
srv_make_money_saldo 812 812 0 0.00 1 3169 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 258069 538795 362442 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5200 5200 0 0.00 0 16185 192 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 7054 103 0 0
t$perf-norm:sp_cancel_client_order 1851 1851 0 0.00 0 18208 25 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 2794 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 5997 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 466 466 0 0.00 0 6833 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 1320 17 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 25168 1655 0 0
t$perf-norm:sp_cancel_write_off 1069 1069 0 0.00 0 3175 10 0 0
t$perf-norm:sp_client_order 20876 20876 0 0.00 0 16739 21 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 512 4 0 0
t$perf-norm:sp_pay_from_customer 7318 7318 0 0.00 0 8641 13 0 0
t$perf-norm:sp_pay_to_supplier 5931 5931 0 0.00 0 15540 13 0 0
t$perf-norm:sp_reserve_write_off 10131 10131 0 0.00 0 14105 12 0 0
t$perf-norm:sp_supplier_invoice 5590 5590 0 0.00 0 4007 17 0 0
t$perf-norm:sp_supplier_order 5731 5731 0 0.00 0 7416 19 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 440 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 545 545 0 0.00 0 987 22 0 0
t$perf-norm:srv_make_money_saldo 812 812 0 0.00 0 1173 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 176 45 0 0
v_add_invoice_to_stock 10684 10386 298 2.78 0 18945 21 0 298
v_all_customers 38448 38448 0 0.00 0 69 1 0 0
v_all_suppliers 30926 30926 0 0.00 0 21 1 0 0
v_all_wares 41750 41750 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 5058 15 0 0
v_cancel_client_order 3702 3702 0 0.00 0 3209 5 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 5026 11 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 2805 9 0 0
v_cancel_payment_to_supplier 932 932 0 0.00 0 2785 6 0 0
v_cancel_supplier_invoice 876 854 22 2.51 0 5195 25 0 22
v_cancel_supplier_order 828 828 0 0.00 0 321 3 0 0
v_cancel_write_off 2138 2138 0 0.00 0 5024 16 0 0
v_max_id_clo_ord 5731 5731 0 0.00 0 2920 2 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 3452 17 0 0
v_max_id_ord_sup 5584 5584 0 0.00 0 2257 2 0 0
v_max_non_paid_invoice 1783 1783 0 0.00 0 734 3 0 0
v_max_non_paid_realizn 3749 3749 0 0.00 0 1209 4 0 0
v_min_id_clo_ord 5731 5731 0 0.00 0 8318 8 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 10981 32 0 0
v_min_id_ord_sup 5584 5584 0 0.00 0 6327 5 0 0
v_min_non_paid_invoice 5931 5931 0 0.00 0 5892 18 0 0
v_min_non_paid_realizn 7318 7318 0 0.00 0 9031 25 0 0
v_our_firm 10440 10440 0 0.00 0 17 1 0 0
v_reserve_write_off 20262 20262 0 0.00 0 4379 6 0 0
x_lock_depdocs_on_canc_invoice 11378 11378 0 0.00 0 34888 137 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 16016 404 0 0
x_make_qstorno_1000_1200 5731 5731 0 0.00 2 66104 1254 0 0
x_make_qstorno_1200_2000 5590 5590 0 0.00 3 94126 3360 0 0
x_make_qstorno_2100_3300 476283 476283 0 0.00 0 92986 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-06-2022 01:39:48 to 17-06-2022 01: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
23:08:27 6 8192000000 8806583536 8820264960 11343312 0.138 3 3 4 0 11527232 76800 107120
23:09:28 31947 8192000000 9902634048 10209685504 978882000 10.673 100 92 184 0 1009176128 6134368 24159760
23:11:02 26133 8192000000 9949243344 10272247808 1032043424 11.188 100 90 180 0 1063159136 8278064 22837648
23:12:30 11896 8192000000 9972044672 10303655936 1057851456 11.436 100 80 160 0 1087192576 9157856 20183264
23:13:49 16150 8192000000 10019473568 10365685760 1108769792 11.921 100 77 155 1 1137923952 10594448 18559712
23:15:10 9151 8192000000 10081871968 10439888896 1164444288 12.445 100 80 160 0 1195752416 13195120 18113008
23:16:21 4843 8192000000 10118768192 10488053760 1211520800 12.883 100 79 158 1 1242897488 14196912 17179776
23:17:27 2872 8192000000 10151900560 10526224384 1245038928 13.193 100 89 176 0 1276794992 11732256 20023808
23:18:33 1522 8192000000 10192119696 10571780096 1276447456 13.481 100 93 185 1 1310222624 13768128 20007040
23:19:36 1322 8192000000 10212065616 10598072320 1298162976 13.679 100 83 166 0 1329236704 11893216 19180512
23:20:39 1129 8192000000 10222149264 10609319936 1305203072 13.743 100 98 197 1 1340212672 12525600 22484000
23:21:41 4871 8192000000 10203510608 10612121600 1312510672 13.809 100 32 65 0 1330307760 10753392 7043696
23:22:47 7083 8192000000 10207798128 10621267968 1320550432 13.882 100 29 58 0 1334748992 7376160 6822400
23:23:57 9818 8192000000 10211413552 10627141632 1324208912 13.915 100 24 48 1 1336396096 6379200 5807984
23:25:08 63034 8192000000 10217017488 10635026432 1324628688 13.919 100 31 62 0 1338532800 6451984 7452128
23:27:14 5361 8192000000 10213465712 10626801664 1311422624 13.799 99 28 56 1 1323100592 5385520 6292448
23:28:21 7540 8192000000 10211232064 10631061504 1323933168 13.912 100 25 51 3 1335650224 5703776 6013280
23:29:36 57763 8192000000 10178510112 10578894848 1239852256 13.145 96 54 109 0 1258381120 4805520 13723344
23:31:39 6614 8192000000 10137783648 10534187008 1228576960 13.041 98 47 94 2 1247426256 7992704 10856592
23:32:46 9519 8192000000 10141824640 10542571520 1234016480 13.091 98 24 48 1 1244943184 4964224 5962480
23:33:57 12447 8192000000 10166349216 10571108352 1256251024 13.296 98 24 48 1 1266967760 5176400 5540336
23:35:51 5257 8192000000 10176397728 10589110272 1289061024 13.596 100 25 50 1 1301202736 6464384 5677328
23:36:57 9975 8192000000 10185550960 10593910784 1280044400 13.513 99 24 48 0 1291428416 5669872 5714144
23:38:07 10576 8192000000 10200459744 10616610816 1313091120 13.814 100 18 36 0 1321907344 4580816 4235408
23:39:23 33089 8192000000 10233140768 10648305664 1337647360 14.036 100 39 77 0 1353389232 6604288 9137584
23:41:00 65070 8192000000 10244382064 10675310592 1350977248 14.156 100 30 60 0 1368204224 10411168 6815808
23:43:19 29066 8192000000 10271792096 10688393216 1357690752 14.217 99 37 73 0 1379998144 13005504 9301888
23:44:55 12947 8192000000 10297947728 10718261248 1379250448 14.410 100 61 123 3 1405821920 12807184 13764288
23:46:10 7568 8192000000 10318038352 10735169536 1391949104 14.523 100 61 122 2 1426646752 20492736 14204912
23:47:22 2590 8192000000 10332348192 10755350528 1408292736 14.669 100 67 133 0 1443603632 19550160 15760736
23:48:27 3312 8192000000 10352698912 10766000128 1414610960 14.725 100 90 179 1 1460217120 24480816 21125344
23:49:41 6081 8192000000 10340772112 10762350592 1409287648 14.678 100 59 118 2 1451748592 28037984 14422960
23:50:53 7384 8192000000 10316710944 10734440448 1385544640 14.466 100 56 113 2 1427084368 28065536 13474192
23:52:05 4483 8192000000 10313226880 10724839424 1375661456 14.378 100 65 130 2 1420737280 29546816 15529008
23:53:17 6373 8192000000 10343802624 10761224192 1400785200 14.602 100 65 130 1 1451033872 34098432 16150240
23:54:30 3230 8192000000 10323096432 10732335104 1377018464 14.390 100 67 133 1 1428810400 35744304 16047632
23:55:37 1190 8192000000 10339190064 10752180224 1390306288 14.509 100 74 148 1 1444654112 37053264 17294560
23:56:46 3159 8192000000 10322988544 10731986944 1363207248 14.266 99 77 153 1 1423405280 40906272 19291760
23:57:53 1990 8192000000 10358910064 10762444800 1388344384 14.491 100 95 190 0 1458036048 45874400 23817264
23:59:02 1589 8192000000 10359369184 10757451776 1383774512 14.450 100 84 169 0 1458677360 53573408 21329440
00:00:06 8210 8192000000 10333164560 10751381504 1380778240 14.424 100 47 95 2 1441079616 46747936 13553440
00:01:37 7215 8192000000 10347382688 10764681216 1383733248 14.450 100 57 114 1 1453179184 52741888 16704048
00:02:47 7204 8192000000 10340912256 10767634432 1383326112 14.446 100 48 97 0 1447158592 50083488 13748992
00:03:56 9534 8192000000 10324711904 10758561792 1371377760 14.339 100 27 54 1 1428435920 48914016 8144144
00:05:07 30389 8192000000 10303999584 10748919808 1357872112 14.218 99 17 34 2 1411603648 48615440 5116096
00:06:40 57196 8192000000 10279534800 10730594304 1335861040 14.020 98 15 30 1 1375975840 35527152 4587648
00:08:52 98999 8192000000 10273172944 10712358912 1324175776 13.914 96 10 18 0 1338320320 12364288 1780256
00:11:32 61359 8192000000 10262567744 10699902976 1343406848 14.088 98 44 89 2 1364784416 11330448 10047120
00:13:34 94106 8192000000 10287529344 10728263680 1366479312 14.295 99 41 81 5 1385078672 8906928 9692432
00:16:16 21497 8192000000 10315226976 10754351104 1394268848 14.544 100 53 107 2 1418819248 12563392 11987008
00:17:46 54800 8192000000 10302525216 10757050368 1385965088 14.470 99 14 28 1 1406917488 16838064 4114336
00:19:44 27823 8192000000 10300097472 10759847936 1401717136 14.610 100 9 16 1 1414925456 11145600 2062720
00:21:14 65279 8192000000 10295607968 10764357632 1394177728 14.543 100 23 46 0 1410776848 10235120 6364000
00:24:23 74374 8192000000 10288302608 10761633792 1400598736 14.600 100 9 18 0 1411156816 8419584 2138496
00:26:40 48086 8192000000 10292992768 10752503808 1385884976 14.469 99 14 28 0 1397057152 8293408 2878768
00:28:29 67738 8192000000 10293485408 10745298944 1344930512 14.102 96 20 40 0 1370896768 21723648 4242608
00:30:37 57707 8192000000 10305246304 10767282176 1384957680 14.461 99 22 44 0 1401701664 11674496 5069488
00:32:47 110070 8192000000 10294019232 10754662400 1318716560 13.865 94 12 24 0 1337680384 16235472 2728352
00:35:39 39430 8192000000 10301683120 10745704448 1365523136 14.287 98 44 86 0 1385215568 9061776 10630656
00:37:24 93894 8192000000 10294169328 10749288448 1349778512 14.145 98 12 24 1 1373086448 20778144 2529792
00:39:58 75285 8192000000 10248590288 10685308928 1287046000 13.577 94 31 62 1 1307642064 13490864 7105200
00:42:20 29929 8192000000 10246667552 10697830400 1310470336 13.790 96 12 24 0 1324261824 11358512 2432976
00:43:50 51946 8192000000 10257093088 10714673152 1359143168 14.230 100 11 22 1 1369329328 7479808 2706352
00:45:43 65500 8192000000 10282432544 10722508800 1315612576 13.837 96 25 51 1 1334985776 13759840 5613360
00:47:52 65087 8192000000 10288159792 10744967168 1357292000 14.213 99 20 40 0 1382619136 20100992 5226144
00:50:13 39210 8192000000 10262186560 10719072256 1346828752 14.119 99 9 18 0 1370941952 21654000 2459200
00:51:56 34434 8192000000 10274248400 10709508096 1338981600 14.048 98 36 72 1 1355383184 8274192 8127392
00:53:34 52469 8192000000 10279414336 10733711360 1355839664 14.200 99 12 24 0 1376533808 18129120 2565024
00:55:27 30768 8192000000 10283305104 10720710656 1360915648 14.246 100 48 96 1 1386294208 15016128 10362432
00:56:58 29801 8192000000 10287237264 10728288256 1365990784 14.291 100 35 70 1 1394012912 20237904 7784224
00:58:45 112101 8192000000 10258954384 10713767936 1337597472 14.036 98 19 38 0 1352098368 10209360 4291536
01:01:38 60075 8192000000 10270387824 10725388288 1370520736 14.332 100 32 64 0 1388911248 11271552 7118960
01:03:44 51270 8192000000 10268814656 10717073408 1346976176 14.120 98 12 24 1 1365553536 15092096 3485264
01:06:31 49650 8192000000 10297268224 10746925056 1381672864 14.432 100 15 30 1 1402384992 17038976 3673152
01:08:24 84223 8192000000 10287950656 10744926208 1384855104 14.460 100 14 28 1 1400873168 12846912 3171152
01:11:14 67984 8192000000 10288822736 10739277824 1350302816 14.150 98 11 22 0 1361536320 9108432 2125072
01:13:22 73553 8192000000 10305829520 10742423552 1372630416 14.351 99 12 23 1 1386579728 11769856 2179456
01:15:37 45137 8192000000 10301805200 10746540032 1373797904 14.361 99 34 68 3 1392352096 10957488 7596704
01:17:31 90843 8192000000 10297174672 10748243968 1340612032 14.063 96 26 52 3 1360711936 14466880 5633024
01:20:01 58255 8192000000 10279071888 10730983424 1311758016 13.802 95 29 57 1 1332392592 14623760 6010816
01:22:06 38923 8192000000 10283371520 10717802496 1361092976 14.247 99 18 34 0 1371557360 6810640 3653744
01:23:46 108368 8192000000 10271497296 10721513472 1299578688 13.691 94 30 60 0 1319443728 13269600 6595440
01:27:36 61256 8192000000 10249714592 10705903616 1341827216 14.074 98 12 24 0 1352295456 7933488 2534752
01:29:37 34045 8192000000 10265089232 10716418048 1355587920 14.198 99 9 17 2 1367225744 10068272 1569552
01:31:11 51761 8192000000 10269333904 10720989184 1376424176 14.385 100 21 41 1 1387954304 6063648 5466480
01:33:17 71963 8192000000 10261126320 10721067008 1354960640 14.192 98 28 56 1 1376390000 15265424 6163936
01:35:31 24184 8192000000 10263143344 10707369984 1310507584 13.791 96 17 34 1 1323352096 8908752 3935760
01:36:57 68233 8192000000 10268097824 10720014336 1334318640 14.006 98 18 35 0 1351559536 13404864 3836032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-06-2022 01:39:54 to 17-06-2022 01:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 149 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-06-2022 01:40:10 to 17-06-2022 01: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          244903
MON$OLDEST_ACTIVE               246671
MON$OLDEST_SNAPSHOT             246671
MON$NEXT_TRANSACTION            246671
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                       2033568
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:753: 
:754: mscs-test-161.budzdorov.ru    Fri Jun 17 01:38:30 2022
:755:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:756: 
:757: 
:758: mscs-test-161.budzdorov.ru    Fri Jun 17 01:38:32 2022
:759:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:760: 
--- end of diff output ---

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

17.06.22 01: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-17 01:40:15 No severe PSQL-related problems occured
17.06.22 01:40:16. End of report.