Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.05.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 16678276 kB
MemAvailable 31487140 kB
Buffers 0 kB
Cached 15010348 kB
SwapCached 2536 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-05-05 16:38:16
DTS_END                         2022-05-05 16:38:16.3620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60166
TRN_ID                          246777
ATT_ID                          660
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: 9263.28 25013 1111594
customer order: creation 181.08 339 21729
customer order: refuse 15.36 318 1843
order to supplier: creation 49.53 1224 5944
order to supplier: removal 3.42 87619 410
invoice (draft): creation 48.37 3228 5804
invoice (draft): removal 50.38 814 6046
invoice accept: apply 44.24 16582 5309
invoice accept: cancel 45.73 798 5487
customer reserve: creation 4083.15 164 489978
customer reserve: removal 4439.04 52 532685
realization accept: apply 87.57 174 10508
realization accept: cancel 80.28 114 9634
payment from customer: creation 61.98 104 7437
payment from customer: removal 5.80 95 696
payment to supplier: creation 51.76 135 6211
payment to supplier: removal 3.64 81 437
service: total inventory turnovers 4.57 1174 548
service: total monetary turnovers 6.51 21 781
service: aggregate perf. data 0.86 20808 103
service: refresh index statistics 0.03 366407 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-05-2022 16:39:30 to 05-05-2022 16:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3710
TEST_TIME 2 4319
TEST_TIME 3 4474
TEST_TIME 4 4491
TEST_TIME 5 4632
TEST_TIME 6 4771
TEST_TIME 7 5291
TEST_TIME 8 6083
TEST_TIME 9 6471
TEST_TIME 10 6891
TEST_TIME 11 7325
TEST_TIME 12 7610
TEST_TIME 13 8088
TEST_TIME 14 8230
TEST_TIME 15 8089
TEST_TIME 16 8831
TEST_TIME 17 9593
TEST_TIME 18 9589
TEST_TIME 19 9545
TEST_TIME 20 9713
TEST_TIME 21 9678
TEST_TIME 22 10355
TEST_TIME 23 10104
TEST_TIME 24 10374
TEST_TIME 25 10564
TEST_TIME 26 11736
TEST_TIME 27 11483
TEST_TIME 28 11134
TEST_TIME 29 11154
TEST_TIME 30 10937
TEST_TIME 31 10718
TEST_TIME 32 10542
TEST_TIME 33 10656
TEST_TIME 34 10661
TEST_TIME 35 10727
TEST_TIME 36 10584
TEST_TIME 37 10466
TEST_TIME 38 10373
TEST_TIME 39 10233
TEST_TIME 40 10163
TEST_TIME 41 10154
TEST_TIME 42 10016
TEST_TIME 43 9930
TEST_TIME 44 9788
TEST_TIME 45 9684
TEST_TIME 46 9744
TEST_TIME 47 9677
TEST_TIME 48 9703
TEST_TIME 49 9672
TEST_TIME 50 9593
TEST_TIME 51 9785
TEST_TIME 52 9784
TEST_TIME 53 9778
TEST_TIME 54 9786
TEST_TIME 55 9760
TEST_TIME 56 9819
TEST_TIME 57 9931
TEST_TIME 58 9856
TEST_TIME 59 9871
TEST_TIME 60 10084
TEST_TIME 61 10063
TEST_TIME 62 9985
TEST_TIME 63 9852
TEST_TIME 64 9771
TEST_TIME 65 9742
TEST_TIME 66 9735
TEST_TIME 67 9678
TEST_TIME 68 9646
TEST_TIME 69 9580
TEST_TIME 70 9562
TEST_TIME 71 9523
TEST_TIME 72 9427
TEST_TIME 73 9413
TEST_TIME 74 9372
TEST_TIME 75 9358
TEST_TIME 76 9462
TEST_TIME 77 9557
TEST_TIME 78 9529
TEST_TIME 79 9517
TEST_TIME 80 9623
TEST_TIME 81 9584
TEST_TIME 82 9626
TEST_TIME 83 9665
TEST_TIME 84 9629
TEST_TIME 85 9669
TEST_TIME 86 9592
TEST_TIME 87 9540
TEST_TIME 88 9493
TEST_TIME 89 9452
TEST_TIME 90 9479
TEST_TIME 91 9382
TEST_TIME 92 9356
TEST_TIME 93 9390
TEST_TIME 94 9443
TEST_TIME 95 9424
TEST_TIME 96 9474
TEST_TIME 97 9459
TEST_TIME 98 9418
TEST_TIME 99 9501
TEST_TIME 100 9490
TEST_TIME 101 9459
TEST_TIME 102 9475
TEST_TIME 103 9526
TEST_TIME 104 9502
TEST_TIME 105 9533
TEST_TIME 106 9559
TEST_TIME 107 9578
TEST_TIME 108 9527
TEST_TIME 109 9462
TEST_TIME 110 9455
TEST_TIME 111 9428
TEST_TIME 112 9423
TEST_TIME 113 9399
TEST_TIME 114 9372
TEST_TIME 115 9361
TEST_TIME 116 9311
TEST_TIME 117 9296
TEST_TIME 118 9306
TEST_TIME 119 9324
TEST_TIME 120 9267

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-05-2022 16:39:34 to 05-05-2022 16:39:37.

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 1562825 1562825 0 0.00 0 15627 6 0 0
doc_list_biud 1562825 1562825 0 0.00 0 80938 24 0 0
sp_add_invoice_to_stock 5405 5309 96 1.77 32 145215 16582 0 96
sp_cancel_adding_invoice 5487 5487 0 0.00 3 58427 799 0 0
sp_cancel_client_order 1843 1843 0 0.00 4 8760 318 0 0
sp_cancel_customer_reserve 532685 532685 0 0.00 1 51637 53 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 2 5375 96 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 2 2872 82 0 0
sp_cancel_supplier_invoice 6059 6046 13 0.21 1 80941 814 0 13
sp_cancel_supplier_order 410 410 0 0.00 15 1031372 87619 0 0
sp_cancel_write_off 9634 9634 0 0.00 1 12822 114 0 0
sp_client_order 21729 21729 0 0.00 3 49803 339 0 0
sp_customer_reserve 489978 489978 0 0.00 0 57983 164 0 0
sp_fill_shopping_cart 33475 33475 0 0.00 1 12875 40 0 0
sp_fill_shopping_cart_clo_res 489975 489975 0 0.00 0 14793 14 0 0
sp_get_clo_for_invoice 5305 5305 0 0.00 0 34154 326 0 0
sp_kill_cost_storno 16254 16254 0 0.00 0 9113 53 0 0
sp_kill_qstorno_ret_qs2qd 1081460 1081460 0 0.00 0 80936 29 0 0
sp_kill_qty_storno 571918 571918 0 0.00 0 80937 64 0 0
sp_lock_selected_doc 21925 21925 0 0.00 0 12240 6 0 0
sp_make_cost_storno 29461 29461 0 0.00 0 12702 51 0 0
sp_multiply_rows_for_pdistr 29461 29461 0 0.00 0 8262 29 0 0
sp_multiply_rows_for_qdistr 21729 21729 0 0.00 0 49796 142 0 0
sp_pay_from_customer 7437 7437 0 0.00 3 11268 105 0 0
sp_pay_to_supplier 6211 6211 0 0.00 4 10304 136 0 0
sp_payment_common 13648 13648 0 0.00 2 9368 28 0 0
sp_qd_handle_on_cancel_clo 1843 1843 0 0.00 0 8754 282 0 0
sp_qd_handle_on_invoice_upd_sts 10792 10792 0 0.00 0 27133 306 0 0
sp_qd_handle_on_reserve_upd_sts 10508 10508 0 0.00 0 14750 55 0 0
sp_reserve_write_off 10508 10508 0 0.00 6 15436 175 0 0
sp_supplier_invoice 5804 5804 0 0.00 5 81008 3228 0 0
sp_supplier_order 5944 5944 0 0.00 6 38436 1225 0 0
srv_aggregate_perf_data 103 103 0 0.00 2339 358934 20809 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2412 102379 45701 0 0
srv_find_qd_qs_mism 1059982 1059982 0 0.00 0 15626 5 0 0
srv_make_invnt_saldo 548 548 0 0.00 40 149367 1174 0 0
srv_make_money_saldo 781 781 0 0.00 0 622 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 185752 595502 366408 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5309 5309 0 0.00 0 25000 173 0 0
t$perf-norm:sp_cancel_adding_invoice 465 465 0 0.00 0 7537 120 0 0
t$perf-norm:sp_cancel_client_order 1843 1843 0 0.00 0 4373 11 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 6433 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 1103 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 2193 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 8566 36 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 23198 1439 0 0
t$perf-norm:sp_cancel_write_off 1105 1105 0 0.00 0 5699 12 0 0
t$perf-norm:sp_client_order 21729 21729 0 0.00 0 13089 16 0 0
t$perf-norm:sp_customer_reserve 1069 1069 0 0.00 0 10622 23 0 0
t$perf-norm:sp_pay_from_customer 7437 7437 0 0.00 0 12856 14 0 0
t$perf-norm:sp_pay_to_supplier 6211 6211 0 0.00 0 6648 15 0 0
t$perf-norm:sp_reserve_write_off 10508 10508 0 0.00 0 10183 12 0 0
t$perf-norm:sp_supplier_invoice 5804 5804 0 0.00 0 5352 18 0 0
t$perf-norm:sp_supplier_order 5944 5944 0 0.00 0 12486 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 35 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1008 17 0 0
t$perf-norm:srv_make_invnt_saldo 548 548 0 0.00 0 12709 35 0 0
t$perf-norm:srv_make_money_saldo 781 781 0 0.00 0 4432 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10796 10604 192 1.77 0 5187 20 0 192
v_all_customers 39378 39378 0 0.00 0 17 0 0 0
v_all_suppliers 32428 32428 0 0.00 0 16 1 0 0
v_all_wares 43458 43458 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 930 930 0 0.00 0 1610 12 0 0
v_cancel_client_order 3686 3686 0 0.00 0 4170 10 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 2971 9 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 2119 6 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 2856 18 0 0
v_cancel_supplier_invoice 916 890 26 2.83 0 3225 22 0 26
v_cancel_supplier_order 820 820 0 0.00 0 4501 13 0 0
v_cancel_write_off 2210 2210 0 0.00 0 4496 22 0 0
v_max_id_clo_ord 5944 5944 0 0.00 0 1680 2 0 0
v_max_id_clo_res 1069 1069 0 0.00 0 1257 13 0 0
v_max_id_ord_sup 5802 5802 0 0.00 0 6454 3 0 0
v_max_non_paid_invoice 1742 1742 0 0.00 0 2247 6 0 0
v_max_non_paid_realizn 3979 3979 0 0.00 0 1506 4 0 0
v_min_id_clo_ord 5944 5944 0 0.00 0 11017 9 0 0
v_min_id_clo_res 1069 1069 0 0.00 0 9413 16 0 0
v_min_id_ord_sup 5802 5802 0 0.00 0 3271 4 0 0
v_min_non_paid_invoice 6211 6211 0 0.00 0 8647 22 0 0
v_min_non_paid_realizn 7437 7437 0 0.00 0 9363 21 0 0
v_our_firm 10996 10996 0 0.00 0 17 1 0 0
v_reserve_write_off 21016 21016 0 0.00 0 5614 8 0 0
x_lock_depdocs_on_canc_invoice 11088 11088 0 0.00 0 16295 118 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 5776 305 0 0
x_make_qstorno_1000_1200 5944 5944 0 0.00 2 38264 1146 0 0
x_make_qstorno_1200_2000 5804 5804 0 0.00 2 80992 3089 0 0
x_make_qstorno_2100_3300 489978 489978 0 0.00 0 57982 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-05-2022 16:39:38 to 05-05-2022 16:39:42.

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:20 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:21 14058 8192000000 9881010016 10188492800 966936288 10.557 100 82 164 0 994303264 5420512 21946464
14:10:39 19986 8192000000 9934776496 10252980224 1014172096 11.016 100 89 178 0 1044938992 7606896 23160000
14:12:12 20789 8192000000 9975275248 10304307200 1056317840 11.421 100 85 170 0 1087603872 9595200 21690832
14:13:41 10123 8192000000 10016895776 10364219392 1105973040 11.894 100 71 142 2 1132480416 9699632 16807744
14:14:57 14407 8192000000 10084341712 10445209600 1168838768 12.486 100 79 158 0 1198658784 11607072 18212944
14:16:13 5761 8192000000 10125755104 10495516672 1214131984 12.907 100 75 150 1 1243388160 12754000 16502176
14:17:21 2564 8192000000 10167589680 10540179456 1249062000 13.230 100 85 166 1 1281499632 14265392 18172240
14:18:25 2177 8192000000 10204222752 10583654400 1285502928 13.563 100 95 192 1 1319559248 13744720 20311600
14:19:29 4484 8192000000 10209530272 10609147904 1304912896 13.740 100 68 136 2 1333734096 14218800 14602400
14:20:34 5707 8192000000 10196579376 10611474432 1310256416 13.788 100 32 65 1 1325639664 8441552 6941696
14:21:41 8546 8192000000 10203992384 10617716736 1316072128 13.841 100 21 42 1 1327962368 6990976 4899264
14:22:53 16750 8192000000 10216563792 10631442432 1323277344 13.906 100 28 57 0 1336297904 5928336 7092224
14:24:10 17491 8192000000 10214782912 10639126528 1327744976 13.947 100 36 75 2 1341690480 5761168 8184336
14:25:28 7370 8192000000 10233933840 10650116096 1335543584 14.017 100 30 60 1 1349572064 7506064 6522416
14:26:38 7557 8192000000 10224673056 10643308544 1320718304 13.883 99 18 36 0 1335377488 9839904 4819280
14:27:47 11577 8192000000 10219603232 10629623808 1282934704 13.540 96 27 54 1 1294979264 5012688 7031872
14:29:01 17313 8192000000 10201269456 10625253376 1301709712 13.711 99 20 40 0 1311851744 5256976 4885056
14:30:19 10251 8192000000 10145383488 10552397824 1193358224 12.715 93 28 56 0 1205635376 5491088 6786064
14:31:29 11978 8192000000 10136834992 10531782656 1220193040 12.963 98 22 44 2 1230417648 5001920 5222688
14:32:43 44272 8192000000 10135616512 10541793280 1225765136 13.015 97 25 50 0 1236914160 4935248 6213776
14:34:29 5935 8192000000 10170095104 10571124736 1269586288 13.418 100 22 43 1 1280277216 5935232 4755696
14:35:36 5172 8192000000 10183163232 10588729344 1284726640 13.556 100 18 36 0 1294662400 5861136 4074624
14:36:44 7790 8192000000 10197128944 10604965888 1300620032 13.701 100 32 63 4 1314245264 6072960 7552272
14:37:54 34608 8192000000 10202263216 10617585664 1305681568 13.747 100 39 78 0 1320816944 5937472 9197904
14:39:41 7977 8192000000 10251357968 10659770368 1340832912 14.065 100 52 103 0 1365003968 13041856 11129200
14:40:53 16728 8192000000 10277050272 10691825664 1366765152 14.298 100 53 106 1 1395257632 15776672 12715808
14:42:30 28692 8192000000 10287404752 10711019520 1379393296 14.411 100 49 98 2 1408469488 17358912 11717280
14:44:04 10780 8192000000 10314149184 10733559808 1395396832 14.554 100 59 116 0 1429462112 20468336 13596944
14:45:21 9522 8192000000 10312793760 10737037312 1397143296 14.570 100 45 88 0 1428445664 20137968 11164400
14:46:39 3916 8192000000 10323980528 10742120448 1397929888 14.577 100 68 134 0 1437213088 23730864 15552336
14:47:46 4609 8192000000 10340612048 10755977216 1403009280 14.622 100 70 140 2 1444688928 25237696 16441952
14:48:59 3415 8192000000 10338454736 10755727360 1396621872 14.565 99 79 158 0 1442015728 26810656 18583200
14:50:06 6682 8192000000 10336874560 10766299136 1399504960 14.591 99 48 95 1 1444641312 33705040 11431312
14:51:18 2411 8192000000 10351211120 10765287424 1400880816 14.603 100 88 176 1 1454578176 31651456 22045904
14:52:23 13628 8192000000 10318455376 10748850176 1382851792 14.442 99 35 70 1 1421447536 28917792 9677952
14:53:47 8489 8192000000 10345303648 10763739136 1391880928 14.523 100 61 122 2 1448906640 41526992 15498720
14:55:02 4102 8192000000 10340999312 10763227136 1393164480 14.534 100 59 118 3 1447160160 39158464 14837216
14:56:12 16294 8192000000 10295679152 10732953600 1351770256 14.163 98 20 40 1 1387169056 29803328 5595472
14:57:33 19005 8192000000 10281071072 10712907776 1338085056 14.040 98 26 52 1 1375054176 29488528 7480592
14:58:53 18375 8192000000 10282001040 10702352384 1335330048 14.015 99 34 66 3 1381095728 36668832 9096848
15:00:16 12839 8192000000 10283278672 10722209792 1356808544 14.209 100 29 59 3 1405686304 41379232 7498528
15:01:33 25362 8192000000 10298141008 10730860544 1366074768 14.292 100 24 48 0 1404585776 31506976 7004032
15:03:03 28194 8192000000 10291143184 10727759872 1344635792 14.099 100 22 44 0 1387562480 36001792 6924896
15:04:38 11331 8192000000 10262858576 10696249344 1328857280 13.957 99 36 71 0 1361631344 24411792 8362272
15:05:50 61707 8192000000 10263102656 10685829120 1328121488 13.950 99 52 105 0 1348857968 8217040 12519440
15:07:55 93795 8192000000 10268355744 10711597056 1358743568 14.226 100 25 50 0 1374744144 10125216 5875360
15:10:30 20277 8192000000 10330899488 10753404928 1380794960 14.424 99 58 116 2 1412978528 18490192 13693376
15:11:50 71939 8192000000 10285653216 10752802816 1368385360 14.313 99 14 28 1 1397529440 25453264 3690816
15:14:03 34215 8192000000 10290522976 10739240960 1386916656 14.478 100 23 46 0 1399954992 7262848 5775488
15:15:38 34455 8192000000 10282843664 10745843712 1378098352 14.400 99 8 16 1 1390279984 10305136 1876496
15:17:14 69292 8192000000 10295712080 10751442944 1390965760 14.514 100 20 40 1 1402647776 7113968 4568048
15:20:52 37579 8192000000 10291415760 10753708032 1404186176 14.632 100 37 74 0 1418539872 5904304 8449392
15:22:38 61722 8192000000 10300156560 10758311936 1399586864 14.591 100 24 47 2 1420570464 15341472 5642128
15:26:28 45590 8192000000 10290060128 10758197248 1397963584 14.577 100 24 48 0 1415809984 11956368 5890032
15:28:28 68729 8192000000 10307866592 10780110848 1396899568 14.567 99 17 34 0 1421105024 20218112 3987344
15:30:38 93699 8192000000 10308707984 10774724608 1372545024 14.350 97 21 42 0 1390965472 13785376 4635072
15:33:12 36492 8192000000 10302159888 10758197248 1357949952 14.219 96 33 66 1 1375166480 10157312 7059216
15:34:53 38314 8192000000 10303807680 10762874880 1402632432 14.618 100 12 24 0 1415578080 10174192 2771456
15:36:34 87329 8192000000 10335805792 10795487232 1394476464 14.546 98 17 34 0 1418990656 20196384 4317808
15:39:14 38393 8192000000 10308665872 10753216512 1386590272 14.475 99 34 68 1 1403832848 9979456 7263120
15:40:57 66442 8192000000 10224310640 10661974016 1286463360 13.572 93 25 50 0 1297858544 5503024 5892160
15:43:06 44896 8192000000 10257359168 10713628672 1361306576 14.249 99 5 10 0 1369353296 7117632 929088
15:46:18 75153 8192000000 10229201824 10683949056 1292726464 13.629 95 7 14 0 1301311360 7374560 1210336
15:48:50 55097 8192000000 10247224560 10701357056 1342293456 14.078 99 14 28 1 1356319696 10874288 3151952
15:50:46 49464 8192000000 10265946656 10719285248 1349877920 14.146 99 13 26 0 1365600816 13196624 2526272
15:52:42 84012 8192000000 10261764160 10698862592 1292493120 13.627 95 22 44 1 1312877568 15083584 5300864
15:55:37 50749 8192000000 10257505744 10704834560 1336256832 14.024 99 17 34 1 1358756592 18838096 3661664
15:57:36 75772 8192000000 10256141872 10712879104 1335151776 14.014 98 31 62 0 1356301152 13760464 7388912
15:59:52 26560 8192000000 10287409360 10717650944 1364325488 14.276 100 30 60 1 1379808160 8982288 6500384
16:01:20 91678 8192000000 10294337440 10726289408 1326177696 13.933 97 17 34 2 1341863056 11568992 4116368
16:03:52 102378 8192000000 10253555840 10691727360 1315949200 13.840 96 26 52 3 1329358048 7637456 5771392
16:06:35 37631 8192000000 10285826304 10722713600 1374459936 14.367 100 44 88 0 1392023440 7083456 10480048
16:08:16 69629 8192000000 10262226464 10717945856 1350949008 14.156 98 11 22 0 1363871696 10589824 2332864
16:10:26 87209 8192000000 10284533728 10753396736 1382200032 14.436 100 11 22 1 1400959120 16366880 2392208
16:12:57 66235 8192000000 10289947680 10742468608 1341162064 14.068 96 16 32 0 1352735600 7963696 3609840
16:15:04 58622 8192000000 10312394448 10762387456 1403238480 14.624 100 21 42 1 1419333280 11118096 4976704
16:17:05 84353 8192000000 10306879056 10761113600 1368790128 14.316 97 13 26 1 1384911888 12974032 3147728
16:19:31 64680 8192000000 10301816800 10751037440 1375976352 14.381 98 25 50 0 1398954816 17638416 5340048
16:21:38 52381 8192000000 10298464624 10751426560 1380669408 14.423 99 19 38 0 1397417200 12609232 4138560
16:23:30 50734 8192000000 10295851936 10734645248 1347436224 14.124 97 36 72 0 1367805312 11750400 8618688
16:25:21 60947 8192000000 10269557440 10698993664 1261568480 13.344 92 19 39 1 1274656096 8791680 4295936
16:27:23 81306 8192000000 10244611648 10683564032 1287778720 13.584 95 23 46 0 1300211440 7355168 5077552
16:29:58 32516 8192000000 10239625696 10688974848 1324966016 13.922 98 21 42 0 1337835840 8067712 4802112
16:31:40 73091 8192000000 10253988080 10701041664 1354196816 14.185 100 13 26 0 1363994448 6940640 2856992
16:33:54 57947 8192000000 10243820480 10697207808 1344635712 14.099 99 13 27 0 1361817040 14511392 2669936
16:35:54 99005 8192000000 10244490416 10683539456 1275911984 13.476 94 15 30 0 1296323792 16885648 3526160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-05-2022 16:39:44 to 05-05-2022 16:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

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

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          245817
MON$OLDEST_ACTIVE               246921
MON$OLDEST_SNAPSHOT             246921
MON$NEXT_TRANSACTION            246921
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                       2018496
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.33585 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33585 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33585 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33585 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 ---
:700: 
:701: mscs-test-161.budzdorov.ru    Thu May  5 16:38:20 2022
:702:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:703: 
:704: 
:705: mscs-test-161.budzdorov.ru    Thu May  5 16:38:23 2022
:706:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:707: 
--- end of diff output ---

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

05.05.22 16:40:03. 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-05-05 16:40:05 No severe PSQL-related problems occured
05.05.22 16:40:07. End of report.