Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.09.2021 07: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 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 15890140 kB
MemAvailable 30940732 kB
Buffers 0 kB
Cached 15835888 kB
SwapCached 340 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-09-04 07:37:06
DTS_END                         2021-09-04 07:37:06.9900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36808
TRN_ID                          236520
ATT_ID                          684
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: 9156.96 26955 1098835
customer order: creation 166.49 393 19979
customer order: refuse 14.61 404 1753
order to supplier: creation 46.03 1423 5524
order to supplier: removal 3.48 124979 417
invoice (draft): creation 44.43 3901 5332
invoice (draft): removal 51.54 987 6185
invoice accept: apply 41.38 19728 4965
invoice accept: cancel 47.21 919 5665
customer reserve: creation 3855.06 192 462607
customer reserve: removal 4601.11 73 552133
realization accept: apply 80.71 221 9685
realization accept: cancel 77.88 136 9345
payment from customer: creation 58.02 131 6962
payment from customer: removal 6.03 83 723
payment to supplier: creation 48.74 159 5849
payment to supplier: removal 3.20 105 384
service: total inventory turnovers 3.68 4062 442
service: total monetary turnovers 6.48 568 777
service: aggregate perf. data 0.87 17365 104
service: refresh index statistics 0.03 363269 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-09-2021 07:39:13 to 04-09-2021 07:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6895
TEST_TIME 2 4775
TEST_TIME 3 4344
TEST_TIME 4 4443
TEST_TIME 5 4489
TEST_TIME 6 4827
TEST_TIME 7 4986
TEST_TIME 8 5486
TEST_TIME 9 5387
TEST_TIME 10 6086
TEST_TIME 11 6343
TEST_TIME 12 6252
TEST_TIME 13 6265
TEST_TIME 14 6313
TEST_TIME 15 6518
TEST_TIME 16 6502
TEST_TIME 17 6802
TEST_TIME 18 6847
TEST_TIME 19 7012
TEST_TIME 20 7394
TEST_TIME 21 7461
TEST_TIME 22 7666
TEST_TIME 23 8314
TEST_TIME 24 8279
TEST_TIME 25 8687
TEST_TIME 26 8756
TEST_TIME 27 9121
TEST_TIME 28 9518
TEST_TIME 29 10104
TEST_TIME 30 10819
TEST_TIME 31 10557
TEST_TIME 32 10702
TEST_TIME 33 10518
TEST_TIME 34 10469
TEST_TIME 35 10255
TEST_TIME 36 10362
TEST_TIME 37 10511
TEST_TIME 38 10491
TEST_TIME 39 10575
TEST_TIME 40 10473
TEST_TIME 41 10227
TEST_TIME 42 10118
TEST_TIME 43 10239
TEST_TIME 44 10054
TEST_TIME 45 9977
TEST_TIME 46 9944
TEST_TIME 47 9884
TEST_TIME 48 9888
TEST_TIME 49 9865
TEST_TIME 50 9795
TEST_TIME 51 9854
TEST_TIME 52 9822
TEST_TIME 53 9893
TEST_TIME 54 9792
TEST_TIME 55 9732
TEST_TIME 56 9673
TEST_TIME 57 9568
TEST_TIME 58 9496
TEST_TIME 59 9465
TEST_TIME 60 9429
TEST_TIME 61 9403
TEST_TIME 62 9517
TEST_TIME 63 9470
TEST_TIME 64 9427
TEST_TIME 65 9672
TEST_TIME 66 9638
TEST_TIME 67 9577
TEST_TIME 68 9593
TEST_TIME 69 9738
TEST_TIME 70 9736
TEST_TIME 71 9772
TEST_TIME 72 9763
TEST_TIME 73 9653
TEST_TIME 74 9644
TEST_TIME 75 9577
TEST_TIME 76 9569
TEST_TIME 77 9484
TEST_TIME 78 9452
TEST_TIME 79 9456
TEST_TIME 80 9492
TEST_TIME 81 9463
TEST_TIME 82 9437
TEST_TIME 83 9464
TEST_TIME 84 9445
TEST_TIME 85 9444
TEST_TIME 86 9541
TEST_TIME 87 9521
TEST_TIME 88 9528
TEST_TIME 89 9554
TEST_TIME 90 9551
TEST_TIME 91 9576
TEST_TIME 92 9482
TEST_TIME 93 9416
TEST_TIME 94 9379
TEST_TIME 95 9398
TEST_TIME 96 9346
TEST_TIME 97 9297
TEST_TIME 98 9295
TEST_TIME 99 9259
TEST_TIME 100 9276
TEST_TIME 101 9264
TEST_TIME 102 9271
TEST_TIME 103 9294
TEST_TIME 104 9305
TEST_TIME 105 9345
TEST_TIME 106 9319
TEST_TIME 107 9319
TEST_TIME 108 9342
TEST_TIME 109 9377
TEST_TIME 110 9407
TEST_TIME 111 9431
TEST_TIME 112 9426
TEST_TIME 113 9363
TEST_TIME 114 9306
TEST_TIME 115 9277
TEST_TIME 116 9247
TEST_TIME 117 9284
TEST_TIME 118 9267
TEST_TIME 119 9230
TEST_TIME 120 9160

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-09-2021 07:39:17 to 04-09-2021 07:39:21.

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 1525164 1525164 0 0.00 0 20582 8 0 0
doc_list_biud 1525164 1525164 0 0.00 0 75072 33 0 0
sp_add_invoice_to_stock 5090 4965 125 2.45 36 223626 19729 0 125
sp_cancel_adding_invoice 5665 5665 0 0.00 6 87932 920 0 0
sp_cancel_client_order 1753 1753 0 0.00 5 41565 404 0 0
sp_cancel_customer_reserve 552133 552133 0 0.00 1 21275 74 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 3 4953 84 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 5702 105 0 0
sp_cancel_supplier_invoice 6195 6185 10 0.16 2 75079 987 0 10
sp_cancel_supplier_order 417 417 0 0.00 6 1340257 124979 0 0
sp_cancel_write_off 9345 9345 0 0.00 1 20734 136 0 0
sp_client_order 19979 19979 0 0.00 4 19257 393 0 0
sp_customer_reserve 462607 462607 0 0.00 0 67659 193 0 0
sp_fill_shopping_cart 30823 30823 0 0.00 1 23274 46 0 0
sp_fill_shopping_cart_clo_res 462603 462603 0 0.00 0 20787 15 0 0
sp_get_clo_for_invoice 4961 4961 0 0.00 0 28924 318 0 0
sp_kill_cost_storno 16117 16117 0 0.00 0 6630 70 0 0
sp_kill_qstorno_ret_qs2qd 1120213 1120213 0 0.00 0 75071 39 0 0
sp_kill_qty_storno 590144 590144 0 0.00 0 75072 85 0 0
sp_lock_selected_doc 20468 20468 0 0.00 0 11222 6 0 0
sp_make_cost_storno 27457 27457 0 0.00 0 20557 62 0 0
sp_multiply_rows_for_pdistr 27457 27457 0 0.00 0 12356 42 0 0
sp_multiply_rows_for_qdistr 19979 19979 0 0.00 0 11745 190 0 0
sp_pay_from_customer 6962 6962 0 0.00 4 14461 132 0 0
sp_pay_to_supplier 5849 5849 0 0.00 4 17627 160 0 0
sp_payment_common 12811 12811 0 0.00 1 10364 28 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 0 41558 359 0 0
sp_qd_handle_on_invoice_upd_sts 10626 10626 0 0.00 0 39721 412 0 0
sp_qd_handle_on_reserve_upd_sts 9685 9685 0 0.00 0 39649 64 0 0
sp_reserve_write_off 9685 9685 0 0.00 7 39669 222 0 0
sp_supplier_invoice 5332 5332 0 0.00 7 100018 3901 0 0
sp_supplier_order 5524 5524 0 0.00 6 30754 1424 0 0
srv_aggregate_perf_data 104 104 0 0.00 2976 179441 17365 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1015 135712 39277 0 0
srv_find_qd_qs_mism 1050700 1050700 0 0.00 0 14211 7 0 0
srv_make_invnt_saldo 442 442 0 0.00 52 114213 4063 0 0
srv_make_money_saldo 777 777 0 0.00 1 13664 568 0 0
srv_recalc_idx_stat 4 4 0 0.00 135336 640429 363269 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4965 4965 0 0.00 0 16418 169 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 3384 100 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 4445 13 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 4571 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 3428 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 745 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 808 19 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 22938 1782 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 2155 5 0 0
t$perf-norm:sp_client_order 19979 19979 0 0.00 0 19461 20 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 3780 14 0 0
t$perf-norm:sp_pay_from_customer 6962 6962 0 0.00 0 4563 9 0 0
t$perf-norm:sp_pay_to_supplier 5849 5849 0 0.00 0 4503 9 0 0
t$perf-norm:sp_reserve_write_off 9685 9685 0 0.00 0 4281 10 0 0
t$perf-norm:sp_supplier_invoice 5332 5332 0 0.00 0 2337 17 0 0
t$perf-norm:sp_supplier_order 5524 5524 0 0.00 0 12219 20 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 514 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 765 13 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 1168 8 0 0
t$perf-norm:srv_make_money_saldo 777 777 0 0.00 0 682 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 10172 9922 250 2.45 0 6773 19 0 250
v_all_customers 36810 36810 0 0.00 0 17 1 0 0
v_all_suppliers 30420 30420 0 0.00 0 18 1 0 0
v_all_wares 39958 39958 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 1273 9 0 0
v_cancel_client_order 3506 3506 0 0.00 0 5620 9 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 845 6 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 6591 12 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 2414 7 0 0
v_cancel_supplier_invoice 808 788 20 2.47 0 3650 30 0 20
v_cancel_supplier_order 834 834 0 0.00 0 1764 12 0 0
v_cancel_write_off 2070 2070 0 0.00 0 1118 16 0 0
v_max_id_clo_ord 5521 5521 0 0.00 0 972 2 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 3529 18 0 0
v_max_id_ord_sup 5322 5322 0 0.00 0 5711 5 0 0
v_max_non_paid_invoice 1482 1482 0 0.00 0 149 2 0 0
v_max_non_paid_realizn 3643 3643 0 0.00 0 751 3 0 0
v_min_id_clo_ord 5521 5521 0 0.00 0 4847 10 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 3288 10 0 0
v_min_id_ord_sup 5322 5322 0 0.00 0 5655 5 0 0
v_min_non_paid_invoice 5849 5849 0 0.00 0 10358 22 0 0
v_min_non_paid_realizn 6962 6962 0 0.00 0 9346 21 0 0
v_our_firm 9786 9786 0 0.00 0 36 1 0 0
v_reserve_write_off 19370 19370 0 0.00 0 5620 6 0 0
x_lock_depdocs_on_canc_invoice 11456 11456 0 0.00 0 24048 141 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 3721 384 0 0
x_make_qstorno_1000_1200 5524 5524 0 0.00 2 30728 1326 0 0
x_make_qstorno_1200_2000 5332 5332 0 0.00 3 91218 3739 0 0
x_make_qstorno_2100_3300 462607 462607 0 0.00 0 67657 175 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-09-2021 07:39:21 to 04-09-2021 07:39:26.

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
05:07:10 1042 8192000000 8964141632 9030942720 34612128 0.420 7 4 8 0 35430944 268560 550256
05:08:13 17684 8192000000 9724549968 10019766272 787580240 8.770 100 87 174 0 816487024 7572464 21334320
05:09:35 15445 8192000000 9754863984 10058731520 819331744 9.092 100 83 166 1 851221968 11903856 19986368
05:10:58 18368 8192000000 9800479312 10118795264 857596960 9.476 100 85 169 1 901026240 24126624 19302656
05:12:22 13747 8192000000 9848056800 10176765952 896948416 9.868 100 75 150 0 947589008 33762736 16877856
05:13:42 10941 8192000000 9917287152 10258472960 942897856 10.321 100 81 162 0 1017277264 57376288 17003120
05:14:55 9024 8192000000 9953126448 10307764224 975342320 10.639 100 74 146 1 1054595632 64568208 14685104
05:16:11 3616 8192000000 10017983664 10377469952 1005873712 10.935 100 87 173 1 1125307008 102091584 17341712
05:17:18 5294 8192000000 10011335488 10383409152 1031548240 11.183 100 77 154 1 1118270944 72112064 14610640
05:18:26 2666 8192000000 10025835856 10397536256 1055865856 11.417 100 85 172 2 1127827808 54214640 17747312
05:19:31 5003 8192000000 10052049232 10425397248 1066878880 11.522 100 79 159 0 1146538688 63086976 16572832
05:20:37 3840 8192000000 10010915312 10415951872 1070221984 11.554 100 30 60 1 1119927952 44010064 5695904
05:21:42 5909 8192000000 10001419888 10396106752 1076583568 11.615 100 33 66 0 1104663104 21332880 6746656
05:22:49 4936 8192000000 10012459536 10414202880 1084086224 11.686 100 34 69 1 1119645376 28752128 6807024
05:23:57 47263 8192000000 10050376752 10463109120 1092937760 11.771 100 43 87 0 1125033328 23442528 8653040
05:25:45 6386 8192000000 10014723936 10418728960 1092307872 11.765 100 36 72 1 1113339024 13307616 7723536
05:26:56 5036 8192000000 10023982624 10429743104 1087383792 11.718 100 41 82 1 1123147392 26770288 8993312
05:28:03 6399 8192000000 10008533312 10412298240 1058787808 11.445 98 36 71 2 1076905072 10268288 7848976
05:29:12 12333 8192000000 10016676512 10422177792 1032685024 11.194 96 28 56 0 1070152544 31898192 5569328
05:30:25 6309 8192000000 9961144368 10343497728 1034052064 11.207 98 44 88 0 1066134032 21832000 10249968
05:31:34 4408 8192000000 9972216848 10348781568 1022890608 11.100 98 48 94 1 1064613728 31839232 9883888
05:32:39 8671 8192000000 9960812128 10348281856 1021179680 11.083 98 42 80 4 1041605328 11485440 8940208
05:33:51 8581 8192000000 9952083968 10338201600 1042633424 11.290 100 45 92 3 1078264144 25157536 10473184
05:35:05 4061 8192000000 9971756448 10368716800 1052549344 11.385 100 43 84 5 1088572768 26831040 9192384
05:36:11 7948 8192000000 9983207408 10384883712 1065698720 11.511 100 35 70 1 1093104912 19306864 8099328
05:37:21 46634 8192000000 10014414896 10409058304 1084332896 11.689 100 48 96 4 1107301568 12523680 10444992
05:39:10 83912 8192000000 10048256304 10459144192 1101665760 11.853 100 42 84 2 1169747616 57858480 10223376
05:41:47 22298 8192000000 10083822128 10498142208 1120841216 12.035 100 40 78 3 1213030256 83271552 8917488
05:43:16 26022 8192000000 10165284560 10589556736 1134857488 12.167 100 56 111 2 1254685328 107768976 12058864
05:44:45 21027 8192000000 10180173952 10597171200 1139430112 12.210 100 39 78 0 1279583200 131021584 9131504
05:46:18 6492 8192000000 10207091840 10610876416 1151047936 12.319 100 66 132 0 1308730816 142232656 15450224
05:47:27 6274 8192000000 10195306896 10603352064 1164312080 12.444 100 63 124 0 1302737488 124247504 14177904
05:48:38 5373 8192000000 10225514224 10621612032 1163042960 12.432 99 75 148 1 1312353296 134307168 15003168
05:49:59 3360 8192000000 10264503072 10668376064 1177602896 12.568 100 85 166 4 1358367248 162580992 18183360
05:51:11 5851 8192000000 10309254288 10726932480 1186076352 12.647 100 79 158 0 1407382992 203507408 17799232
05:52:25 2411 8192000000 10343862064 10765758464 1178455072 12.576 99 80 160 1 1441143984 244447712 18241200
05:53:32 2079 8192000000 10372080688 10780569600 1192178256 12.704 100 84 167 0 1471760336 260056720 19525360
05:54:40 2123 8192000000 10413118832 10823983104 1186909776 12.655 100 88 175 1 1513836576 306338448 20588352
05:55:48 5448 8192000000 10403574224 10816757760 1168134880 12.479 100 69 138 0 1497770752 313189008 16446864
05:56:59 1013 8192000000 10453554576 10857775104 1184614784 12.633 100 86 168 1 1542960896 338439392 19906720
05:58:03 11480 8192000000 10476992160 10927775744 1185011360 12.637 100 57 113 1 1575631424 376419776 14200288
05:59:21 1020 8192000000 10513096160 10929025024 1196494320 12.744 100 83 166 0 1612001776 394773264 20734192
06:00:28 3274 8192000000 10479145360 10910076928 1156024592 12.366 100 63 123 1 1584342704 413112144 15205968
06:01:42 9115 8192000000 10448185008 10883854336 1156663824 12.372 100 45 90 2 1536780384 368468912 11647648
06:02:57 18440 8192000000 10446064032 10904784896 1159131392 12.395 100 29 58 0 1550037984 382819680 8086912
06:04:38 19620 8192000000 10395477328 10857222144 1131078016 12.132 99 22 44 0 1493171344 355699136 6394192
06:05:59 37712 8192000000 10264921616 10726318080 1123054704 12.056 99 28 55 2 1379128160 248899440 7174016
06:07:40 47854 8192000000 10076986464 10521878528 1103704400 11.873 98 12 25 2 1157861936 51585936 2571600
06:09:30 82531 8192000000 10072733136 10518601728 1097636784 11.815 97 11 22 1 1132545504 32243600 2665120
06:12:57 64516 8192000000 10126707776 10594963456 1115296560 11.983 99 20 40 0 1207135680 87383824 4455296
06:15:24 97691 8192000000 10094036224 10579587072 1104740032 11.883 98 10 20 1 1161627712 54985600 1902080
06:18:02 106776 8192000000 10112647088 10545065984 1129293872 12.115 100 41 81 0 1207835248 69102976 9438400
06:21:04 35022 8192000000 10054763616 10513989632 1099855088 11.836 97 16 31 3 1131938512 29052944 3030480
06:22:42 23009 8192000000 10115385744 10554523648 1143729680 12.251 100 50 100 4 1196461264 42519424 10212160
06:24:07 33518 8192000000 10139895248 10597310464 1148244064 12.293 99 44 85 1 1240850272 82546304 10059904
06:25:44 17952 8192000000 10185673200 10661085184 1157253568 12.378 100 27 54 0 1263831312 100804288 5773456
06:27:03 32614 8192000000 10165123504 10639077376 1140056592 12.216 99 11 21 0 1242396896 99861360 2478944
06:28:36 51356 8192000000 10113776960 10592333824 1146125488 12.273 100 13 26 3 1231188384 82277184 2785712
06:30:29 46633 8192000000 10093335568 10574864384 1147607024 12.287 100 14 28 0 1185704992 34923344 3174624
06:32:26 14870 8192000000 10106558816 10563006464 1144545296 12.258 100 32 65 1 1190274560 39310496 6418768
06:33:52 46883 8192000000 10081814704 10560724992 1132137168 12.142 99 7 14 0 1178641216 45091312 1412736
06:35:58 61011 8192000000 10082379168 10576183296 1116825296 11.997 98 11 22 1 1193328672 74348528 2154848
06:38:03 44195 8192000000 10109860480 10561720320 1117108304 12.000 98 28 56 1 1180885808 58146640 5630864
06:39:48 37161 8192000000 10102454272 10560376832 1123794800 12.063 98 24 48 0 1187715328 58571120 5349408
06:41:26 74508 8192000000 10074047552 10604593152 1117516384 12.004 97 17 34 1 1248072480 126343936 4212160
06:44:46 18534 8192000000 10155560448 10610573312 1137810880 12.195 99 50 99 3 1241859136 93396464 10651792
06:46:08 46750 8192000000 10082630192 10554241024 1126625808 12.090 99 17 34 0 1171026032 41036496 3363728
06:47:55 19010 8192000000 10122399760 10566823936 1139213904 12.208 100 28 56 1 1190744208 46206368 5323936
06:49:15 71400 8192000000 10099383600 10561888256 1119539440 12.023 98 19 38 1 1180243264 57132000 3571824
06:52:22 8228 8192000000 10052269584 10485923840 1117879344 12.007 99 31 62 1 1144373984 20565840 5928800
06:53:32 60399 8192000000 10101154800 10579308544 1136672112 12.184 100 8 16 0 1203003632 64341168 1990352
06:55:33 67807 8192000000 10112714912 10576666624 1106730704 11.901 97 10 20 0 1183490576 74403904 2355968
06:57:41 43052 8192000000 10070545968 10532048896 1109191952 11.925 98 23 46 0 1141067888 27222464 4653472
06:59:32 24334 8192000000 10088702928 10526842880 1124939184 12.074 100 49 99 1 1176135584 41780800 9415600
07:01:04 46333 8192000000 10086627136 10529853440 1099624832 11.834 97 30 60 0 1175994688 70463056 5906800
07:02:50 107269 8192000000 10076447456 10543992832 1075105440 11.601 95 24 48 0 1146314224 66449904 4758880
07:05:44 70113 8192000000 10082212816 10561142784 1117962800 12.008 99 18 36 3 1222895760 100859360 4073600
07:07:55 135712 8192000000 10023122016 10460651520 1084447280 11.690 96 21 42 1 1143446880 54632784 4366816
07:11:11 24962 8192000000 10097070256 10526777344 1101687184 11.854 98 41 82 0 1163564192 53808864 8068144
07:12:39 26146 8192000000 10026029520 10491662336 1105764320 11.892 99 4 8 1 1126480112 19960304 755488
07:14:21 34509 8192000000 10091595424 10541228032 1131115424 12.132 100 17 34 0 1184499328 49624272 3759632
07:16:23 55305 8192000000 10060869920 10538475520 1099886464 11.837 97 10 20 0 1156921056 54611536 2423056
07:18:20 40772 8192000000 10090802800 10534739968 1134337648 12.162 99 21 42 0 1167005776 28426560 4241568
07:20:04 21586 8192000000 10126137360 10577362944 1137396272 12.191 99 26 52 1 1212178816 69189376 5593168
07:21:31 58476 8192000000 10094366816 10565005312 1129903200 12.120 98 17 35 2 1193657824 60058496 3696128
07:23:30 19514 8192000000 10110941776 10548436992 1139938336 12.215 99 31 62 1 1186226288 40228096 6059856
07:24:51 12148 8192000000 10148717776 10572144640 1163176864 12.433 100 67 134 3 1221933680 45171584 13585232
07:26:04 70547 8192000000 10125802112 10614968320 1136307296 12.181 98 22 44 1 1218521824 77587360 4627168
07:28:22 86984 8192000000 10088292896 10574262272 1100162208 11.839 95 14 28 0 1152766256 49912352 2691696
07:30:49 121025 8192000000 10112654208 10581192704 1110975824 11.942 97 26 52 0 1164414640 47966304 5472512
07:33:52 61910 8192000000 10073998960 10591678464 1095662928 11.796 96 17 34 0 1158223680 58853360 3707392
07:36:04 19628 8192000000 10039751472 10501644288 1114857360 11.978 99 25 50 4 1138398368 17947824 5593184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-09-2021 07:39:27 to 04-09-2021 07:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-09-2021 07:39:44 to 04-09-2021 07:39:46.

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          235078
MON$OLDEST_ACTIVE               236638
MON$OLDEST_SNAPSHOT             236638
MON$NEXT_TRANSACTION            236638
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                       2069600
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             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2580 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2580 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2580 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2580 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 ---
:42: 
:43: mscs-test-161.budzdorov.ru    Sat Sep  4 07:37:27 2021
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:45: 
:46: 
:47: mscs-test-161.budzdorov.ru    Sat Sep  4 07:37:28 2021
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
--- end of diff output ---

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

04.09.21 07:39:47. 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-09-04 07:39:49 No severe PSQL-related problems occured
04.09.21 07:39:50. End of report.