Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2021 10:37
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15783456 kB
MemAvailable 30661260 kB
Buffers 0 kB
Cached 15901424 kB
SwapCached 3784 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2021-10-15 10:37:08
DTS_END                         2021-10-15 10:37:08.7620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57322
TRN_ID                          240369
ATT_ID                          663
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: 9154.28 17121 1098514
customer order: creation 180.32 403 21638
customer order: refuse 14.84 308 1781
order to supplier: creation 46.74 1292 5609
order to supplier: removal 3.53 109443 423
invoice (draft): creation 48.16 3375 5779
invoice (draft): removal 50.85 976 6102
invoice accept: apply 42.45 17118 5094
invoice accept: cancel 45.90 806 5508
customer reserve: creation 3914.57 170 469748
customer reserve: removal 4501.25 68 540150
realization accept: apply 87.36 175 10483
realization accept: cancel 86.59 112 10391
payment from customer: creation 60.84 100 7301
payment from customer: removal 5.74 61 689
payment to supplier: creation 50.57 122 6068
payment to supplier: removal 3.25 73 390
service: total inventory turnovers 4.04 3129 485
service: total monetary turnovers 6.38 458 766
service: aggregate perf. data 0.88 18382 105
service: refresh index statistics 0.03 185856 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-10-2021 10:38:06 to 15-10-2021 10:38:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3686
TEST_TIME 2 5107
TEST_TIME 3 4857
TEST_TIME 4 5023
TEST_TIME 5 5038
TEST_TIME 6 5593
TEST_TIME 7 5495
TEST_TIME 8 5459
TEST_TIME 9 5475
TEST_TIME 10 5456
TEST_TIME 11 5627
TEST_TIME 12 5585
TEST_TIME 13 5867
TEST_TIME 14 5927
TEST_TIME 15 6381
TEST_TIME 16 6739
TEST_TIME 17 7744
TEST_TIME 18 7980
TEST_TIME 19 8016
TEST_TIME 20 8156
TEST_TIME 21 8345
TEST_TIME 22 8955
TEST_TIME 23 9278
TEST_TIME 24 9754
TEST_TIME 25 11157
TEST_TIME 26 11124
TEST_TIME 27 11067
TEST_TIME 28 10922
TEST_TIME 29 10688
TEST_TIME 30 10620
TEST_TIME 31 10718
TEST_TIME 32 10775
TEST_TIME 33 10652
TEST_TIME 34 10506
TEST_TIME 35 10823
TEST_TIME 36 10842
TEST_TIME 37 10734
TEST_TIME 38 10826
TEST_TIME 39 10810
TEST_TIME 40 10619
TEST_TIME 41 10477
TEST_TIME 42 10280
TEST_TIME 43 10199
TEST_TIME 44 10168
TEST_TIME 45 10065
TEST_TIME 46 10065
TEST_TIME 47 10152
TEST_TIME 48 10080
TEST_TIME 49 10066
TEST_TIME 50 10013
TEST_TIME 51 10020
TEST_TIME 52 9928
TEST_TIME 53 9798
TEST_TIME 54 9683
TEST_TIME 55 9675
TEST_TIME 56 9603
TEST_TIME 57 9525
TEST_TIME 58 9556
TEST_TIME 59 9636
TEST_TIME 60 9583
TEST_TIME 61 9661
TEST_TIME 62 9612
TEST_TIME 63 9664
TEST_TIME 64 9644
TEST_TIME 65 9626
TEST_TIME 66 9869
TEST_TIME 67 9885
TEST_TIME 68 9874
TEST_TIME 69 9910
TEST_TIME 70 9865
TEST_TIME 71 9777
TEST_TIME 72 9736
TEST_TIME 73 9655
TEST_TIME 74 9629
TEST_TIME 75 9593
TEST_TIME 76 9536
TEST_TIME 77 9463
TEST_TIME 78 9489
TEST_TIME 79 9465
TEST_TIME 80 9527
TEST_TIME 81 9526
TEST_TIME 82 9559
TEST_TIME 83 9531
TEST_TIME 84 9586
TEST_TIME 85 9620
TEST_TIME 86 9590
TEST_TIME 87 9638
TEST_TIME 88 9591
TEST_TIME 89 9720
TEST_TIME 90 9661
TEST_TIME 91 9578
TEST_TIME 92 9572
TEST_TIME 93 9535
TEST_TIME 94 9479
TEST_TIME 95 9424
TEST_TIME 96 9419
TEST_TIME 97 9391
TEST_TIME 98 9390
TEST_TIME 99 9418
TEST_TIME 100 9399
TEST_TIME 101 9399
TEST_TIME 102 9371
TEST_TIME 103 9396
TEST_TIME 104 9411
TEST_TIME 105 9377
TEST_TIME 106 9451
TEST_TIME 107 9458
TEST_TIME 108 9520
TEST_TIME 109 9441
TEST_TIME 110 9408
TEST_TIME 111 9368
TEST_TIME 112 9315
TEST_TIME 113 9296
TEST_TIME 114 9278
TEST_TIME 115 9275
TEST_TIME 116 9253
TEST_TIME 117 9233
TEST_TIME 118 9240
TEST_TIME 119 9228
TEST_TIME 120 9157

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-10-2021 10:38:10 to 15-10-2021 10:38:14.

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 1533769 1533769 0 0.00 0 20410 7 0 0
doc_list_biud 1533766 1533766 0 0.00 0 49638 30 0 0
sp_add_invoice_to_stock 5178 5094 84 1.62 44 167445 17118 0 84
sp_cancel_adding_invoice 5508 5508 0 0.00 6 76077 806 0 0
sp_cancel_client_order 1781 1781 0 0.00 4 9407 309 0 0
sp_cancel_customer_reserve 540150 540150 0 0.00 1 30798 68 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 3757 61 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 4 6346 74 0 0
sp_cancel_supplier_invoice 6107 6102 5 0.08 2 49647 977 0 5
sp_cancel_supplier_order 423 423 0 0.00 5 1025490 109444 0 0
sp_cancel_write_off 10391 10391 0 0.00 1 10784 112 0 0
sp_client_order 21638 21638 0 0.00 4 26429 404 0 0
sp_customer_reserve 469748 469748 0 0.00 0 60525 170 0 0
sp_fill_shopping_cart 33019 33019 0 0.00 1 18584 41 0 0
sp_fill_shopping_cart_clo_res 469740 469740 0 0.00 0 13596 16 0 0
sp_get_clo_for_invoice 5086 5086 0 0.00 0 27887 348 0 0
sp_kill_cost_storno 16978 16978 0 0.00 0 5665 57 0 0
sp_kill_qstorno_ret_qs2qd 1097216 1097216 0 0.00 0 49635 37 0 0
sp_kill_qty_storno 579922 579922 0 0.00 0 49637 78 0 0
sp_lock_selected_doc 21375 21375 0 0.00 0 7456 6 0 0
sp_make_cost_storno 28937 28937 0 0.00 0 13015 52 0 0
sp_multiply_rows_for_pdistr 28937 28937 0 0.00 0 6862 29 0 0
sp_multiply_rows_for_qdistr 21638 21638 0 0.00 0 22204 213 0 0
sp_pay_from_customer 7301 7301 0 0.00 5 13714 100 0 0
sp_pay_to_supplier 6068 6068 0 0.00 5 10057 123 0 0
sp_payment_common 13368 13368 0 0.00 1 6433 20 0 0
sp_qd_handle_on_cancel_clo 1781 1781 0 0.00 0 9396 271 0 0
sp_qd_handle_on_invoice_upd_sts 10592 10592 0 0.00 0 19099 315 0 0
sp_qd_handle_on_reserve_upd_sts 10483 10483 0 0.00 0 13437 51 0 0
sp_reserve_write_off 10483 10483 0 0.00 6 13456 176 0 0
sp_supplier_invoice 5779 5779 0 0.00 7 112943 3375 0 0
sp_supplier_order 5609 5609 0 0.00 6 33635 1293 0 0
srv_aggregate_perf_data 105 105 0 0.00 2935 411543 18382 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 3257 116712 43534 0 0
srv_find_qd_qs_mism 1049627 1049627 0 0.00 0 20410 6 0 0
srv_make_invnt_saldo 485 485 0 0.00 38 129588 3130 0 0
srv_make_money_saldo 766 766 0 0.00 1 12408 459 0 0
srv_recalc_idx_stat 4 4 0 0.00 149293 220099 185857 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 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 5095 5095 0 0.00 0 14071 136 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 2797 91 0 0
t$perf-norm:sp_cancel_client_order 1781 1781 0 0.00 0 2462 8 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 2697 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 807 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 188 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 1195 18 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 16942 1317 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 3168 7 0 0
t$perf-norm:sp_client_order 21638 21638 0 0.00 0 10504 15 0 0
t$perf-norm:sp_customer_reserve 1085 1085 0 0.00 0 11001 17 0 0
t$perf-norm:sp_pay_from_customer 7301 7301 0 0.00 0 19364 12 0 0
t$perf-norm:sp_pay_to_supplier 6068 6068 0 0.00 0 7049 10 0 0
t$perf-norm:sp_reserve_write_off 10484 10484 0 0.00 0 7041 9 0 0
t$perf-norm:sp_supplier_invoice 5779 5779 0 0.00 0 8022 17 0 0
t$perf-norm:sp_supplier_order 5609 5609 0 0.00 0 6963 11 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 249 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 39 2 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 3515 15 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 1101 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10336 10168 168 1.62 0 6351 18 0 168
v_all_customers 39580 39580 0 0.00 0 58 1 0 0
v_all_suppliers 31822 31822 0 0.00 0 35 1 0 0
v_all_wares 43276 43276 0 0.00 0 58 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 3827 12 0 0
v_cancel_client_order 3562 3562 0 0.00 0 5756 7 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 2660 7 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 1329 4 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 1487 6 0 0
v_cancel_supplier_invoice 830 820 10 1.20 0 4757 19 0 10
v_cancel_supplier_order 846 846 0 0.00 0 1700 7 0 0
v_cancel_write_off 2090 2090 0 0.00 0 4543 17 0 0
v_max_id_clo_ord 5608 5608 0 0.00 0 322 1 0 0
v_max_id_clo_res 1085 1085 0 0.00 0 11207 21 0 0
v_max_id_ord_sup 5771 5771 0 0.00 0 8613 8 0 0
v_max_non_paid_invoice 1536 1536 0 0.00 0 95 1 0 0
v_max_non_paid_realizn 3738 3738 0 0.00 0 4520 6 0 0
v_min_id_clo_ord 5608 5608 0 0.00 0 3293 4 0 0
v_min_id_clo_res 1085 1085 0 0.00 0 8216 10 0 0
v_min_id_ord_sup 5771 5771 0 0.00 0 5404 9 0 0
v_min_non_paid_invoice 6068 6068 0 0.00 0 6429 13 0 0
v_min_non_paid_realizn 7300 7300 0 0.00 0 5276 12 0 0
v_our_firm 10818 10818 0 0.00 0 19 1 0 0
v_reserve_write_off 20965 20965 0 0.00 0 5431 8 0 0
x_lock_depdocs_on_canc_invoice 11200 11200 0 0.00 0 21695 102 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 19946 303 0 0
x_make_qstorno_1000_1200 5609 5609 0 0.00 2 33619 1229 0 0
x_make_qstorno_1200_2000 5779 5779 0 0.00 3 112928 3210 0 0
x_make_qstorno_2100_3300 469748 469748 0 0.00 0 58068 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-10-2021 10:38:15 to 15-10-2021 10:38:19.

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
08:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 22107 8192000000 9716181520 10009354240 789507312 8.790 100 86 172 0 817704624 7312528 20884784
08:09:40 18476 8192000000 9761912976 10065137664 834738128 9.247 100 96 192 0 870385808 12975216 22672464
08:11:01 25501 8192000000 9793464096 10110300160 861164608 9.512 100 84 168 0 901678528 20490464 20023456
08:12:30 14329 8192000000 9853623104 10184585216 908217520 9.980 100 78 156 1 965452096 39712176 17522400
08:13:47 10974 8192000000 9921637616 10262663168 957360080 10.463 100 80 157 1 1024015168 50440592 16214496
08:15:01 4424 8192000000 9963312768 10315714560 994694208 10.827 100 86 173 1 1074911008 62882640 17334160
08:16:08 3556 8192000000 10005415040 10367500288 1027668000 11.146 100 87 174 2 1123927328 78417232 17842096
08:17:13 2439 8192000000 10036298704 10400796672 1051674064 11.377 100 90 180 0 1151500080 81260208 18565808
08:18:17 16689 8192000000 10027885376 10406825984 1065915648 11.513 100 66 132 0 1144364416 65181040 13267728
08:19:35 4348 8192000000 9994527728 10381611008 1077346720 11.622 100 54 107 0 1107582432 18866336 11369376
08:20:40 6180 8192000000 9983096016 10385264640 1084438928 11.690 100 41 82 2 1109167856 16071104 8657824
08:21:49 7995 8192000000 10010734352 10409672704 1094900912 11.789 100 25 48 2 1127332848 27675392 4756544
08:22:57 53216 8192000000 10009324880 10405801984 1099313008 11.831 100 40 81 0 1119288992 11601824 8374160
08:24:51 4499 8192000000 10014338336 10423435264 1103538720 11.871 100 47 92 1 1135398560 21577904 10281936
08:25:56 5502 8192000000 10036297904 10430799872 1103004016 11.866 100 46 94 0 1136608960 22992272 10612672
08:27:02 5222 8192000000 10011452288 10411356160 1098190672 11.820 100 41 83 0 1122076576 14925200 8960704
08:28:08 55640 8192000000 9984817504 10394509312 1028579664 11.155 94 33 64 0 1052533104 17002112 6951328
08:30:05 4507 8192000000 9952538560 10339176448 1021930784 11.091 98 30 59 0 1063366944 35072528 6363632
08:31:10 3737 8192000000 9944657808 10324058112 1038247872 11.248 100 53 106 5 1057615632 7872192 11495568
08:32:16 10446 8192000000 9929155680 10312515584 991782448 10.799 96 31 60 1 1025208896 26321328 7105120
08:33:28 40504 8192000000 9960927568 10358767616 1038201264 11.247 99 32 64 2 1058638832 14542976 5894592
08:35:10 7711 8192000000 9983008016 10374160384 1061180304 11.468 99 23 45 0 1087499952 21225712 5093936
08:36:19 5223 8192000000 9995842032 10392080384 1076840640 11.617 100 41 80 2 1102981728 16419344 9721744
08:37:28 9246 8192000000 10044283696 10430492672 1104296896 11.878 100 65 130 0 1143396240 25675264 13424080
08:38:38 60194 8192000000 10077241536 10488131584 1112809104 11.959 100 50 97 2 1187152064 64565616 9777344
08:40:42 3503 8192000000 10100358896 10502549504 1133846336 12.158 100 62 124 1 1213854176 67808592 12199248
08:41:55 32825 8192000000 10161609552 10571886592 1146728192 12.279 100 57 114 2 1261121520 102868656 11524672
08:43:31 16597 8192000000 10171680768 10599456768 1158210160 12.386 100 48 94 2 1288882608 120038704 10633744
08:44:55 24714 8192000000 10218828736 10639798272 1167969376 12.478 100 47 94 1 1325416064 146292336 11154352
08:46:34 7194 8192000000 10244448272 10660081664 1193915184 12.720 100 51 100 0 1365285856 159480016 11890656
08:47:46 5171 8192000000 10317644800 10722869248 1197683344 12.755 100 73 145 2 1412519808 198111456 16725008
08:48:56 6748 8192000000 10380233920 10792312832 1198802640 12.765 99 65 128 0 1467117408 253414736 14900032
08:50:07 4094 8192000000 10458139728 10872459264 1206074752 12.833 100 70 140 0 1555232768 332046176 17111840
08:51:16 3339 8192000000 10474191392 10888675328 1188311488 12.668 98 70 138 0 1562464256 356836416 17316352
08:52:25 3256 8192000000 10513945008 10928156672 1202988624 12.804 100 84 168 0 1614146672 391205456 19952592
08:53:33 6125 8192000000 10481791472 10908614656 1130951280 12.130 96 67 133 0 1555665472 408262768 16451424
08:54:44 6844 8192000000 10419023792 10852982784 1160048864 12.404 100 52 103 0 1513750048 341649856 12051328
08:56:03 9506 8192000000 10425739728 10845523968 1172148000 12.517 100 46 92 0 1518688944 335339472 11201472
08:57:27 17164 8192000000 10467461472 10914918400 1168626848 12.484 100 35 68 2 1565444384 387845136 8972400
08:59:02 13004 8192000000 10422124032 10880417792 1154296032 12.350 99 39 78 0 1552700192 388060656 10343504
09:00:18 23394 8192000000 10401500992 10856034304 1124739712 12.072 99 26 52 0 1484508304 352611232 7157360
09:01:52 38012 8192000000 10217487712 10781917184 1115357072 11.983 99 25 50 1 1435175792 313551888 6266832
09:03:39 90418 8192000000 10048029296 10528272384 1086849360 11.713 96 18 36 0 1131580896 40174704 4556832
09:06:11 57131 8192000000 10086997024 10505756672 1096031584 11.800 98 30 60 0 1135586400 33792208 5762608
09:08:12 62718 8192000000 10062148800 10520485888 1090442432 11.747 98 17 33 2 1156918144 62778832 3696880
09:10:15 51672 8192000000 10068718016 10490732544 1056973648 11.428 94 12 24 0 1083462976 23899760 2589568
09:12:10 65992 8192000000 10066998688 10557804544 1061083456 11.467 95 5 11 0 1132397872 70088496 1225920
09:14:17 116711 8192000000 10053657792 10522083328 1086444384 11.709 97 13 27 0 1141750256 52767584 2538288
09:18:53 69294 8192000000 10087424736 10521645056 1100545328 11.843 97 44 87 0 1154700880 45212320 8943232
09:21:08 29800 8192000000 10118873024 10567815168 1126730928 12.091 100 11 23 0 1208764416 79574736 2458752
09:22:38 58165 8192000000 10138412528 10599796736 1133115664 12.151 100 34 66 1 1242660432 102184448 7360320
09:24:46 54338 8192000000 10096033200 10557575168 1112104960 11.952 97 14 28 1 1178020416 62537856 3377600
09:26:44 42531 8192000000 10084716768 10550681600 1126319760 12.087 99 23 46 1 1193831808 62126016 5386032
09:28:30 34866 8192000000 10058342736 10524065792 1129636176 12.118 100 10 20 0 1164640480 32630784 2373520
09:30:08 99692 8192000000 10070542320 10523860992 1142230128 12.237 100 23 46 0 1177743552 30275504 5237920
09:32:47 69531 8192000000 10058672848 10541789184 1139939728 12.215 100 7 14 0 1167755504 26572864 1242912
09:35:07 64697 8192000000 10107703408 10605670400 1150275024 12.312 100 18 36 1 1211762128 57715840 3771264
09:37:12 78833 8192000000 10108110240 10571137024 1125158304 12.076 98 27 54 0 1195481456 64846176 5476976
09:39:32 20185 8192000000 10095520432 10529689600 1135089248 12.169 99 34 67 0 1180026384 38238352 6698784
09:40:57 52255 8192000000 10194054528 10618646528 1134193648 12.161 98 33 66 0 1229915520 88860160 6861712
09:42:52 83357 8192000000 10108399760 10619076608 1075066400 11.600 94 15 30 0 1150615328 72588368 2960560
09:45:16 56167 8192000000 10095031840 10523250688 1100920256 11.846 98 25 51 0 1155865008 49867280 5077472
09:47:16 21874 8192000000 10068127760 10499153920 1081716624 11.664 97 47 88 3 1146528832 54981952 9830256
09:48:38 65855 8192000000 10033252800 10488795136 1074750032 11.597 96 26 52 1 1109158896 28500960 5907904
09:50:47 7843 8192000000 10030268144 10454638592 1088400992 11.727 98 44 88 0 1121982384 24127888 9453504
09:52:02 36575 8192000000 10063387696 10511486976 1104589872 11.881 99 21 42 0 1161311040 52307296 4413872
09:53:48 48025 8192000000 10063007216 10501578752 1078470432 11.633 97 19 38 1 1134136832 51387168 4279232
09:55:36 93181 8192000000 10078706160 10534731776 1092262688 11.764 98 13 26 0 1177605616 82039296 3303632
09:58:12 50559 8192000000 10092399008 10538180608 1100314640 11.841 98 19 38 1 1182555344 78151248 4089456
10:00:09 28637 8192000000 10083502640 10510790656 1089430192 11.737 98 29 58 2 1148569504 52925136 6214176
10:01:42 41897 8192000000 10061462688 10510692352 1082441504 11.671 97 20 40 0 1140434128 53900592 4092032
10:03:32 47319 8192000000 10074949008 10519339008 1084557792 11.691 98 26 52 0 1140336032 50467552 5310688
10:05:23 31320 8192000000 10121822336 10549121024 1112668592 11.958 100 42 84 3 1168789984 46527888 9593504
10:06:57 95133 8192000000 10059193136 10486734848 1080915024 11.656 97 22 44 0 1132752688 47542384 4295280
10:09:47 24108 8192000000 10019317408 10466603008 1107488944 11.909 100 15 30 0 1147306896 36801712 3016240
10:11:11 41658 8192000000 10042314432 10497056768 1115584736 11.985 99 13 27 0 1148516944 30563088 2369120
10:12:54 54581 8192000000 10058097360 10523054080 1126601824 12.089 100 10 21 0 1161386880 32540112 2244944
10:14:49 51895 8192000000 10092424336 10530615296 1137234144 12.190 100 23 44 3 1206326096 63586288 5505664
10:16:43 40245 8192000000 10057643248 10510794752 1130404976 12.125 99 20 40 0 1160867232 26154992 4307264
10:18:26 43087 8192000000 10083481312 10527420416 1133118464 12.151 99 27 54 0 1180476144 42036608 5321072
10:20:13 45689 8192000000 10111665264 10552791040 1134438240 12.163 99 28 56 0 1198768928 58849760 5480928
10:22:09 71908 8192000000 10092382528 10613567488 1150505232 12.314 100 13 26 0 1206165936 53197248 2463456
10:24:21 74661 8192000000 10107530032 10606391296 1131600592 12.136 98 21 42 0 1192120896 56516640 4003664
10:26:36 50736 8192000000 10076567952 10512949248 1140623072 12.221 99 41 80 0 1167154080 18457216 8073792
10:28:33 59258 8192000000 10089350928 10559451136 1150341536 12.313 100 13 26 0 1201874672 48560160 2972976
10:30:45 69864 8192000000 10038773168 10486726656 1086205536 11.707 95 13 25 1 1115627072 26734576 2686960
10:32:56 41909 8192000000 10043842544 10490118144 1090331504 11.746 97 19 38 0 1122097584 27345712 4420368
10:34:48 55000 8192000000 10046037712 10490114048 1062724032 11.483 94 24 48 1 1105666320 38142272 4800016
10:36:47 31038 8192000000 9312145392 9619521536 417899040 4.853 38 15 29 0 457802576 36567680 3335856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-10-2021 10:38:21 to 15-10-2021 10:38:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 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 15-10-2021 10:38:37 to 15-10-2021 10:38:39.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240632
MON$OLDEST_ACTIVE               240633
MON$OLDEST_SNAPSHOT             240633
MON$NEXT_TRANSACTION            240633
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-30 07:30:08.6030 GMT
MON$PAGES                       2049312
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             813
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:131: 
:132: mscs-test-161.budzdorov.ru    Fri Oct 15 10:37:24 2021
:133:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:134: 
:135: 
:136: mscs-test-161.budzdorov.ru    Fri Oct 15 10:37:25 2021
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:138: 
--- end of diff output ---

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

15.10.21 10:38:40. There are 3 logs in /var/tmp/logs-oltp40 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
2021-10-15 10:38:42 No severe PSQL-related problems occured
15.10.21 10:38:43. End of report.