Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.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 16670184 kB
MemAvailable 31238556 kB
Buffers 0 kB
Cached 14977528 kB
SwapCached 2292 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-01-11 19:38:01
DTS_END                         2022-01-11 19:38:01.3100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34238
TRN_ID                          240751
ATT_ID                          662
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: 9181.14 22098 1101737
customer order: creation 168.82 340 20258
customer order: refuse 14.55 329 1746
order to supplier: creation 44.67 1200 5360
order to supplier: removal 3.43 92176 412
invoice (draft): creation 46.34 3363 5561
invoice (draft): removal 52.47 715 6296
invoice accept: apply 42.71 17682 5125
invoice accept: cancel 46.64 775 5597
customer reserve: creation 3879.54 177 465545
customer reserve: removal 4596.92 55 551630
realization accept: apply 82.42 162 9890
realization accept: cancel 74.66 108 8959
payment from customer: creation 58.69 104 7043
payment from customer: removal 6.14 74 737
payment to supplier: creation 47.85 126 5742
payment to supplier: removal 3.35 56 402
service: total inventory turnovers 4.78 1076 573
service: total monetary turnovers 6.42 54 770
service: aggregate perf. data 0.73 27436 87
service: refresh index statistics 0.03 295960 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2022 19:39:15 to 11-01-2022 19:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9614
TEST_TIME 2 6334
TEST_TIME 3 6339
TEST_TIME 4 5542
TEST_TIME 5 5310
TEST_TIME 6 5166
TEST_TIME 7 5311
TEST_TIME 8 5766
TEST_TIME 9 5589
TEST_TIME 10 5695
TEST_TIME 11 5973
TEST_TIME 12 6142
TEST_TIME 13 6163
TEST_TIME 14 6648
TEST_TIME 15 6758
TEST_TIME 16 7239
TEST_TIME 17 7602
TEST_TIME 18 8133
TEST_TIME 19 8705
TEST_TIME 20 9035
TEST_TIME 21 9117
TEST_TIME 22 9327
TEST_TIME 23 10533
TEST_TIME 24 10379
TEST_TIME 25 10497
TEST_TIME 26 10361
TEST_TIME 27 10268
TEST_TIME 28 10190
TEST_TIME 29 10192
TEST_TIME 30 10007
TEST_TIME 31 9852
TEST_TIME 32 9915
TEST_TIME 33 9721
TEST_TIME 34 9574
TEST_TIME 35 9559
TEST_TIME 36 9410
TEST_TIME 37 9330
TEST_TIME 38 9413
TEST_TIME 39 9260
TEST_TIME 40 9147
TEST_TIME 41 9106
TEST_TIME 42 8982
TEST_TIME 43 9273
TEST_TIME 44 9278
TEST_TIME 45 9230
TEST_TIME 46 9239
TEST_TIME 47 9267
TEST_TIME 48 9320
TEST_TIME 49 9305
TEST_TIME 50 9375
TEST_TIME 51 9375
TEST_TIME 52 9299
TEST_TIME 53 9416
TEST_TIME 54 9466
TEST_TIME 55 9508
TEST_TIME 56 9513
TEST_TIME 57 9359
TEST_TIME 58 9400
TEST_TIME 59 9293
TEST_TIME 60 9258
TEST_TIME 61 9250
TEST_TIME 62 9209
TEST_TIME 63 9237
TEST_TIME 64 9213
TEST_TIME 65 9177
TEST_TIME 66 9129
TEST_TIME 67 9163
TEST_TIME 68 9207
TEST_TIME 69 9367
TEST_TIME 70 9468
TEST_TIME 71 9411
TEST_TIME 72 9457
TEST_TIME 73 9501
TEST_TIME 74 9523
TEST_TIME 75 9502
TEST_TIME 76 9490
TEST_TIME 77 9509
TEST_TIME 78 9500
TEST_TIME 79 9418
TEST_TIME 80 9400
TEST_TIME 81 9353
TEST_TIME 82 9289
TEST_TIME 83 9301
TEST_TIME 84 9366
TEST_TIME 85 9358
TEST_TIME 86 9281
TEST_TIME 87 9258
TEST_TIME 88 9286
TEST_TIME 89 9396
TEST_TIME 90 9378
TEST_TIME 91 9397
TEST_TIME 92 9423
TEST_TIME 93 9490
TEST_TIME 94 9466
TEST_TIME 95 9521
TEST_TIME 96 9500
TEST_TIME 97 9524
TEST_TIME 98 9554
TEST_TIME 99 9571
TEST_TIME 100 9543
TEST_TIME 101 9590
TEST_TIME 102 9528
TEST_TIME 103 9467
TEST_TIME 104 9448
TEST_TIME 105 9412
TEST_TIME 106 9382
TEST_TIME 107 9405
TEST_TIME 108 9361
TEST_TIME 109 9374
TEST_TIME 110 9339
TEST_TIME 111 9303
TEST_TIME 112 9271
TEST_TIME 113 9242
TEST_TIME 114 9196
TEST_TIME 115 9191
TEST_TIME 116 9203
TEST_TIME 117 9238
TEST_TIME 118 9228
TEST_TIME 119 9239
TEST_TIME 120 9184

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2022 19:39:19 to 11-01-2022 19:39:22.

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 1530400 1530400 0 0.00 0 24473 6 0 0
doc_list_biud 1530398 1530398 0 0.00 0 28548 25 0 0
sp_add_invoice_to_stock 5199 5125 74 1.42 36 160006 17683 0 74
sp_cancel_adding_invoice 5597 5597 0 0.00 5 63201 776 0 0
sp_cancel_client_order 1746 1746 0 0.00 4 9634 329 0 0
sp_cancel_customer_reserve 551630 551630 0 0.00 1 28549 55 0 0
sp_cancel_pay_from_customer 737 737 0 0.00 2 7078 74 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 1140 56 0 0
sp_cancel_supplier_invoice 6301 6296 5 0.07 1 22998 715 0 5
sp_cancel_supplier_order 412 412 0 0.00 8 948577 92176 0 0
sp_cancel_write_off 8959 8959 0 0.00 1 15379 108 0 0
sp_client_order 20258 20258 0 0.00 3 48959 341 0 0
sp_customer_reserve 465545 465545 0 0.00 0 68329 178 0 0
sp_fill_shopping_cart 31174 31174 0 0.00 1 34710 41 0 0
sp_fill_shopping_cart_clo_res 465541 465541 0 0.00 0 17009 14 0 0
sp_get_clo_for_invoice 5121 5121 0 0.00 0 23782 274 0 0
sp_kill_cost_storno 15695 15695 0 0.00 0 5534 50 0 0
sp_kill_qstorno_ret_qs2qd 1118927 1118927 0 0.00 0 24272 30 0 0
sp_kill_qty_storno 589650 589650 0 0.00 0 28548 65 0 0
sp_lock_selected_doc 20813 20813 0 0.00 0 9649 8 0 0
sp_make_cost_storno 27796 27796 0 0.00 0 24466 46 0 0
sp_multiply_rows_for_pdistr 27795 27795 0 0.00 0 7023 25 0 0
sp_multiply_rows_for_qdistr 20258 20258 0 0.00 0 20709 158 0 0
sp_pay_from_customer 7043 7043 0 0.00 4 21719 104 0 0
sp_pay_to_supplier 5742 5742 0 0.00 5 24516 127 0 0
sp_payment_common 12784 12784 0 0.00 1 21714 29 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 9627 300 0 0
sp_qd_handle_on_invoice_upd_sts 10718 10718 0 0.00 0 15608 310 0 0
sp_qd_handle_on_reserve_upd_sts 9889 9889 0 0.00 0 17305 56 0 0
sp_reserve_write_off 9890 9890 0 0.00 6 17357 163 0 0
sp_supplier_invoice 5561 5561 0 0.00 7 111064 3364 0 0
sp_supplier_order 5360 5360 0 0.00 5 20714 1201 0 0
srv_aggregate_perf_data 87 87 0 0.00 3126 826503 27437 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2021 144925 48980 0 0
srv_find_qd_qs_mism 1053066 1053066 0 0.00 0 20556 6 0 0
srv_make_invnt_saldo 573 573 0 0.00 36 59770 1076 0 0
srv_make_money_saldo 770 770 0 0.00 1 21183 54 0 0
srv_recalc_idx_stat 4 4 0 0.00 112521 411908 295960 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5125 5125 0 0.00 0 23371 174 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 10185 129 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 5251 17 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 939 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 737 737 0 0.00 0 4676 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 162 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 662 16 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 27280 1474 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 9927 15 0 0
t$perf-norm:sp_client_order 20258 20258 0 0.00 0 19942 17 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 299 2 0 0
t$perf-norm:sp_pay_from_customer 7043 7043 0 0.00 0 19810 14 0 0
t$perf-norm:sp_pay_to_supplier 5742 5742 0 0.00 0 8861 10 0 0
t$perf-norm:sp_reserve_write_off 9890 9890 0 0.00 0 12697 13 0 0
t$perf-norm:sp_supplier_invoice 5561 5561 0 0.00 0 4962 15 0 0
t$perf-norm:sp_supplier_order 5360 5360 0 0.00 0 3226 11 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 75 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 31 1 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 2397 23 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 929 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 251 65 0 0
v_add_invoice_to_stock 10390 10242 148 1.42 0 9531 21 0 148
v_all_customers 36688 36688 0 0.00 0 23 0 0 0
v_all_suppliers 29926 29926 0 0.00 0 15 1 0 0
v_all_wares 40512 40512 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 1390 9 0 0
v_cancel_client_order 3492 3492 0 0.00 0 1964 5 0 0
v_cancel_customer_prepayment 1474 1474 0 0.00 0 7071 9 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 1395 4 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 1019 5 0 0
v_cancel_supplier_invoice 842 832 10 1.18 0 2669 20 0 10
v_cancel_supplier_order 824 824 0 0.00 0 150 3 0 0
v_cancel_write_off 2014 2014 0 0.00 0 5674 18 0 0
v_max_id_clo_ord 5359 5359 0 0.00 0 1982 2 0 0
v_max_id_clo_res 964 964 0 0.00 0 2867 15 0 0
v_max_id_ord_sup 5556 5556 0 0.00 0 8263 5 0 0
v_max_non_paid_invoice 1693 1693 0 0.00 0 292 2 0 0
v_max_non_paid_realizn 3866 3866 0 0.00 0 2022 4 0 0
v_min_id_clo_ord 5359 5359 0 0.00 0 6474 12 0 0
v_min_id_clo_res 964 964 0 0.00 0 1911 10 0 0
v_min_id_ord_sup 5556 5556 0 0.00 0 9056 13 0 0
v_min_non_paid_invoice 5742 5742 0 0.00 0 18981 23 0 0
v_min_non_paid_realizn 7042 7042 0 0.00 0 11703 20 0 0
v_our_firm 10176 10176 0 0.00 0 16 1 0 0
v_reserve_write_off 19776 19776 0 0.00 0 5538 6 0 0
x_lock_depdocs_on_canc_invoice 11477 11477 0 0.00 0 20363 106 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 16593 342 0 0
x_make_qstorno_1000_1200 5360 5360 0 0.00 1 20709 1118 0 0
x_make_qstorno_1200_2000 5561 5561 0 0.00 2 110698 3214 0 0
x_make_qstorno_2100_3300 465545 465545 0 0.00 0 68303 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-01-2022 19:39:23 to 11-01-2022 19:39:27.

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:05 11 8192000000 8803286640 8816070656 8208864 0.100 3 1 2 0 8346272 62880 74528
17:09:06 24379 8192000000 9901809280 10214477824 987787520 10.760 100 88 174 2 1016974944 6066592 23120832
17:10:37 16042 8192000000 9954086544 10275446784 1032640736 11.194 100 87 174 0 1063083776 8205680 22237360
17:12:09 21279 8192000000 9989618160 10327797760 1073060016 11.581 100 86 172 0 1104497200 10007712 21429472
17:13:38 12389 8192000000 10053900464 10404761600 1132647472 12.146 100 72 144 0 1160541216 10966720 16927024
17:14:56 7204 8192000000 10103851536 10467930112 1187935968 12.664 100 77 154 0 1217792704 11869952 17986784
17:16:06 3992 8192000000 10131083488 10505465856 1227943584 13.035 100 81 162 1 1259075712 12688448 18443680
17:17:11 3004 8192000000 10172635136 10553307136 1257572480 13.308 100 81 162 1 1289945456 14242608 18130368
17:18:17 1939 8192000000 10205935760 10591711232 1290635856 13.610 100 85 169 2 1321433344 12599200 18198288
17:19:20 42869 8192000000 10215291776 10609430528 1307584736 13.764 100 60 120 5 1330954144 9073504 14295904
17:21:04 10618 8192000000 10198320848 10617139200 1315333312 13.834 100 13 24 1 1323463776 5631424 2499040
17:22:19 20665 8192000000 10204752576 10627043328 1323604944 13.909 100 22 41 2 1335226768 6090240 5531584
17:23:40 8345 8192000000 10216011008 10639982592 1332980512 13.994 100 18 36 0 1342524928 5248912 4295504
17:24:53 66033 8192000000 10235841840 10658390016 1339576160 14.054 100 44 88 2 1354897504 5285136 10036208
17:27:01 4960 8192000000 10235176448 10656141312 1337291504 14.033 100 24 48 0 1348122800 5369200 5462096
17:28:10 6239 8192000000 10183661680 10589020160 1233087680 13.083 93 17 34 0 1242514208 5270512 4156016
17:29:19 80640 8192000000 10103999952 10506014720 1125977008 12.083 87 26 52 2 1137071824 4677680 6417136
17:31:40 6509 8192000000 10133691440 10531622912 1228186096 13.037 98 22 44 0 1239315088 5953632 5175360
17:32:48 7051 8192000000 10170728736 10569031680 1270324976 13.425 100 24 48 0 1281544880 5707552 5512352
17:33:56 13965 8192000000 10162336928 10576908288 1277626736 13.491 100 13 26 0 1285502896 4918720 2957440
17:35:10 13892 8192000000 10208384560 10600136704 1291840368 13.621 100 66 132 3 1314612832 5522560 17249904
17:36:25 5403 8192000000 10189764416 10606256128 1300599200 13.701 100 27 54 0 1313100448 6388768 6112480
17:37:31 8483 8192000000 10195876080 10613612544 1307517728 13.764 100 21 42 2 1318682176 6729984 4434464
17:38:46 15733 8192000000 10246108896 10653835264 1336862272 14.029 100 46 92 1 1354496800 7264544 10369984
17:40:07 50482 8192000000 10273282672 10693062656 1361658848 14.252 100 39 78 1 1381638800 11483120 8496832
17:42:23 27882 8192000000 10291635840 10715635712 1382208032 14.436 100 52 102 2 1410428192 16187600 12032560
17:44:22 8600 8192000000 10310832336 10733166592 1391272976 14.517 100 46 92 1 1424333184 22174576 10885632
17:45:35 31662 8192000000 10320323344 10752598016 1398025840 14.577 100 38 76 0 1434827408 27316160 9485408
17:47:13 5099 8192000000 10323705008 10749677568 1390043664 14.506 99 49 93 2 1428086320 26293568 11749088
17:48:23 7490 8192000000 10330504928 10744393728 1388253184 14.490 99 71 141 0 1437560656 32508304 16799168
17:49:35 6855 8192000000 10342582032 10767958016 1412516112 14.706 100 54 108 1 1458820240 33343312 12960816
17:50:47 4448 8192000000 10349373456 10769334272 1406466800 14.653 100 73 144 3 1463279952 38930304 17882848
17:51:58 2537 8192000000 10360970064 10768564224 1389918144 14.505 98 87 172 3 1455331984 44035040 21378800
17:53:04 5220 8192000000 10344783696 10757902336 1392747984 14.530 100 70 140 0 1452245216 42022752 17474480
17:54:21 3074 8192000000 10348085248 10761498624 1394823952 14.549 100 76 151 1 1453801312 39737568 19239792
17:55:27 4589 8192000000 10352552272 10761871360 1395088976 14.551 100 82 162 1 1455159968 39686976 20384016
17:56:38 2018 8192000000 10359193744 10769342464 1396720512 14.566 100 85 171 0 1460854016 42695808 21437696
17:57:43 14399 8192000000 10324301408 10757672960 1383682272 14.449 99 29 57 3 1423390912 31585680 8122960
17:59:00 36344 8192000000 10310914128 10747273216 1377623136 14.395 99 24 48 1 1414682656 30133904 6925616
18:00:46 17069 8192000000 10316407648 10753232896 1373827312 14.361 100 23 46 0 1419998144 39116224 7054608
18:02:06 35485 8192000000 10275397040 10707771392 1348653712 14.135 99 22 44 1 1367505536 13473488 5378336
18:03:48 14311 8192000000 10257691872 10689814528 1338122688 14.040 99 20 40 0 1351728928 9001792 4604448
18:05:04 28157 8192000000 10247575584 10693353472 1340052160 14.058 99 15 31 2 1353296000 10062256 3181584
18:06:34 48640 8192000000 10253720192 10702454784 1344910224 14.102 99 25 48 2 1358294608 8338128 5046256
18:08:40 144925 8192000000 10262139744 10708959232 1302976400 13.722 95 29 58 1 1324803728 15109744 6717584
18:12:21 111544 8192000000 10250656480 10697695232 1312334512 13.807 96 18 36 0 1322995136 6744720 3915904
18:15:14 32965 8192000000 10274848512 10714370048 1369524736 14.323 100 26 52 1 1384043120 8643888 5874496
18:16:55 58736 8192000000 10299847456 10740678656 1386706752 14.476 100 21 42 1 1406137456 14413184 5017520
18:19:02 66093 8192000000 10296298032 10752921600 1398218608 14.579 100 17 34 0 1418156592 15712192 4225792
18:21:52 52204 8192000000 10295696960 10736529408 1374877040 14.371 99 18 36 1 1390963760 11858032 4228688
18:23:55 54715 8192000000 10291839808 10740256768 1391853376 14.522 100 42 84 2 1414396944 12537216 10006352
18:26:58 62660 8192000000 10295763664 10740899840 1369974080 14.327 98 16 32 0 1384787680 11292640 3520960
18:29:05 62831 8192000000 10270449712 10713694208 1295174768 13.651 93 19 37 2 1306870384 7984864 3710752
18:31:18 71560 8192000000 10307001792 10754936832 1367678256 14.306 99 20 39 2 1386357776 14319616 4359904
18:33:46 139258 8192000000 10274664320 10718937088 1334336192 14.006 97 20 40 0 1350593408 11743328 4513888
18:37:07 45801 8192000000 10272081584 10719756288 1358276992 14.222 99 26 53 1 1377747504 13262976 6207536
18:39:01 58849 8192000000 10266567408 10721226752 1350091904 14.148 98 11 22 1 1366905984 14390128 2423952
18:41:03 24479 8192000000 10262668432 10723000320 1357841312 14.218 99 7 14 1 1374240592 15094432 1304848
18:42:47 40554 8192000000 10271096624 10710593536 1324448240 13.917 96 18 36 0 1335742640 7508816 3785584
18:44:30 40118 8192000000 10271936080 10713358336 1324950912 13.922 96 28 57 0 1345400336 13549504 6899920
18:46:34 81327 8192000000 10270416384 10730921984 1316989152 13.849 96 14 28 0 1341139840 20542640 3608048
18:48:58 37145 8192000000 10276695248 10712363008 1338440032 14.043 98 41 82 1 1361465632 13491968 9533632
18:50:37 67958 8192000000 10280862400 10731188224 1339354864 14.052 98 39 77 1 1370222192 22041008 8826320
18:53:22 60746 8192000000 10289704448 10718654464 1361456016 14.250 99 31 62 0 1375636960 7234640 6946304
18:55:23 35293 8192000000 10282827056 10731655168 1375224336 14.374 100 19 38 0 1394057968 14633104 4200528
18:56:59 55680 8192000000 10255457200 10695110656 1294307088 13.643 94 33 66 0 1308271616 6919360 7045168
18:58:57 29189 8192000000 10269011328 10717020160 1351351056 14.160 98 19 38 0 1364885568 8795456 4739056
19:00:44 100191 8192000000 10284645536 10745942016 1366853840 14.299 99 20 40 1 1393586192 21509760 5222592
19:03:32 110856 8192000000 10289928512 10745720832 1357544800 14.215 98 13 26 0 1382829792 22143744 3141248
19:06:23 50006 8192000000 10286931152 10740330496 1373998848 14.363 100 14 28 0 1397934512 20405808 3529856
19:08:19 43294 8192000000 10313896336 10762133504 1401780352 14.611 100 36 72 0 1422704464 12851232 8072880
19:10:07 43429 8192000000 10316555600 10760626176 1387753472 14.486 99 28 56 0 1405766640 12489664 5523504
19:11:51 68437 8192000000 10326962480 10771972096 1393972496 14.541 99 18 36 0 1406431216 8911056 3547664
19:14:01 61402 8192000000 10326714992 10775912448 1391922016 14.523 98 19 38 1 1409784384 13789904 4072464
19:16:04 22880 8192000000 10311207744 10750271488 1383463536 14.448 99 33 66 0 1401186896 10530512 7192848
19:17:54 65833 8192000000 10314737728 10749730816 1358925952 14.228 97 25 50 1 1373546944 9252848 5368144
19:20:02 124298 8192000000 10277985664 10722697216 1351178976 14.158 97 21 42 1 1366892304 11179872 4533456
19:23:13 69910 8192000000 10279923120 10725838848 1360448688 14.241 99 19 38 0 1380599248 15531200 4619360
19:25:32 58984 8192000000 10273503552 10737111040 1358731120 14.226 99 8 16 0 1374619488 14279424 1608944
19:27:32 64962 8192000000 10278465584 10727698432 1354163792 14.185 98 11 22 0 1366165120 9397008 2604320
19:29:38 67905 8192000000 10284823728 10738057216 1376083120 14.382 99 15 28 0 1385900656 6335408 3482128
19:31:55 109334 8192000000 10270869520 10722488320 1336403456 14.025 97 13 26 1 1349607808 10129264 3075088
19:34:49 86218 8192000000 10273682480 10747559936 1344423760 14.097 98 8 16 0 1362124416 15962432 1738224
19:37:16 35005 8192000000 10263937040 10706010112 1312126608 13.805 96 23 46 0 1328938768 11806320 5005840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-01-2022 19:39:29 to 11-01-2022 19:39:43.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-01-2022 19:39:44 to 11-01-2022 19:39:46.

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          239273
MON$OLDEST_ACTIVE               240879
MON$OLDEST_SNAPSHOT             240879
MON$NEXT_TRANSACTION            240879
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                       2001920
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:811: 
:812: mscs-test-161.budzdorov.ru    Tue Jan 11 19:38:03 2022
:813:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:814: 
:815: 
:816: mscs-test-161.budzdorov.ru    Tue Jan 11 19:38:06 2022
:817:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:818: 
:819: 
:820: mscs-test-161.budzdorov.ru    Tue Jan 11 19:38:08 2022
:821:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:822: 
--- end of diff output ---

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

11.01.22 19:39:47. 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-01-11 19:39:50 No severe PSQL-related problems occured
11.01.22 19:39:51. End of report.