[Concurrent] Future.get: timeout trop long

**Jidefix** · 21/07/2015, 10h55

Bonjour,
je suis actuellement en train d'essayer de timeouter une opération longue et je test donc le code suivant:

Code :

Sélectionner tout - Visualiser dans une fenêtre à part

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
 
import java.util.concurrent.Callable;
import java.util.concurrent.ExecutorService;
import java.util.concurrent.Executors;
import java.util.concurrent.Future;
import java.util.concurrent.TimeUnit;
import java.util.concurrent.TimeoutException;
 
public class Test {
    public static void main(String[] args) throws Exception {
        ExecutorService executor = Executors.newSingleThreadExecutor();
        Future<String> future = executor.submit(new Task());
 
        try {
            System.out.println("Début");
            System.out.println(future.get(3, TimeUnit.SECONDS));
            System.out.println("Fin");
        } catch (TimeoutException e) {
            future.cancel(true);
            System.out.println("Annulée");
        }
 
        executor.shutdownNow();
    }
}
 
class Task implements Callable<String> {
    //@Override
    public String call() throws Exception {
    	int max=20;
    	for(int i=0; i<max; i++){
    		System.out.println("Tic "+i);
    		TimeUnit.SECONDS.sleep(1);
    	}
        return "Task terminée";
    }
}

Qui, en théorie, devrait afficher dans la console environ 3 tics, en fonction du bon vouloir de la JVM.
Or actuellement le résultat est prévisible mais bizarre: la boucle tourne systématiquement environ 2.5 fois plus longtemps que prévu:
Avec un timeout à 2 secondes, j'attends environ 5 secondes
Avec un timeout à 3 secondes, j'attends environ 8 secondes
Avec un timeout à 4 secondes, j'attends environ 11 secondes
Avec un timeout à 5 secondes, j'attends environ 13 secondes

Dans l'absolu je peux me contenter de mettre la moitié du timeout que je veux vraiment, de toute façon je ne suis pas à la seconde près. Mais j'aurai bien voulu comprendre un écart aussi important entre le temps attendu et le temps réel (qui est, je le rappelle, systématique et pas du tout aléatoire)

Jidéfix

**adiGuba** · 21/07/2015, 13h50

Salut,

Bizarre ton affaire... En plus je n'arrive pas à le reproduire.

Tu tournes sous quelles version de Java ?
Que te donne le code suivant :

Code :

Sélectionner tout - Visualiser dans une fenêtre à part

1
2
	System.out.println(System.getProperty("java.version"));
	System.out.println(System.getProperty("java.runtime.version"));

a++

**eulbobo** · 21/07/2015, 14h00

Quand tu fais un thread.sleep, tu arrêtes l'exécution du thread pendant AU MOINS le temps indiqué dans le timeout. Tu n'as aucune garantie que ça ne sera pas plus (au bon vouloir de la JVM justement).

Si tu veux exactement une seconde entre chaque exécution, il faudra opter pour une autre approche

**Jidefix** · 21/07/2015, 14h34

Eulbobo: je suis bien d'accord qu'on ne peut pas attendre une précision millisecondesque, mais la j'attends plus du double du temps demandé quand même! En plus le "sleep" marche bien, c'est juste le timeout qui est -très- en retard

J'ai testé avec java 6 et 8:

1.6.0_27
1.6.0_27-b07
et
1.8.0_51
1.8.0_51-b16

Et j'ai le même comportement: le timeout n'intervient qu'au bout de neuf tours de boucles.
Pour info je suis sous Windows XP
Par contre je viens de tester sur java 6 (1.6.0_29-b11) sur un serveur Solaris 10, et j'ai bien le bon temps d'attente.

Bref, ça vient de ma machine...

**tchize_** · 21/07/2015, 15h43

Pour info, je viens tester ce code légèrement modifié sous seven

Code :

Sélectionner tout - Visualiser dans une fenêtre à part

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
import java.util.concurrent.Callable;
import java.util.concurrent.ExecutorService;
import java.util.concurrent.Executors;
import java.util.concurrent.Future;
import java.util.concurrent.TimeUnit;
import java.util.concurrent.TimeoutException;
 
public class HelloWorld {
    public static void main(String[] args) throws Exception {
        ExecutorService executor = Executors.newSingleThreadExecutor();
        for (int time =2; time < 15; time++){
            System.out.println("timing: "+time);
            Future<String> future = executor.submit(new Task());
 
            try {
                System.out.println("Debut "+new java.util.Date());
                System.out.println(future.get(time, TimeUnit.SECONDS));
                System.out.println("Fin "+new java.util.Date());
            } catch (TimeoutException e) {
                //future.cancel(true);
                System.out.println("Annulee "+new java.util.Date());
            }
            future.get(); //wait termination
            System.out.println("NEXT! ");
        }
        executor.shutdownNow();
    }
}
 
class Task implements Callable<String> {
    //@Override
    public String call() throws Exception {
    	int max=20;
    	for(int i=0; i<max; i++){
    		System.out.println("Tic "+i);
    		TimeUnit.SECONDS.sleep(1);
    	}
        return "Task terminee";
    }
}

avec des java 7/8 32 et 64 bits et j'ai un comportement correct. Pareil via le site coding ground sous java 8

Code :

Sélectionner tout - Visualiser dans une fenêtre à part

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
Tic 16                                                                                                                                                                                      
Tic 17                                                                                                                                                                                      
Tic 18                                                                                                                                                                                      
Tic 19                                                                                                                                                                                      
NEXT!                                                                                                                                                                                       
timing: 13                                                                                                                                                                                  
Tic 0                                                                                                                                                                                       
Debut Tue Jul 21 13:37:05 UTC 2015                                                                                                                                                          
Tic 1                                                                                                                                                                                       
Tic 2                                                                                                                                                                                       
Tic 3                                                                                                                                                                                       
Tic 4                                                                                                                                                                                       
Tic 5                                                                                                                                                                                       
Tic 6                                                                                                                                                                                       
Tic 7                                                                                                                                                                                       
Tic 8                                                                                                                                                                                       
Tic 9                                                                                                                                                                                       
Tic 10                                                                                                                                                                                      
Tic 11                                                                                                                                                                                      
Tic 12                                                                                                                                                                                      
Annulee Tue Jul 21 13:37:18 UTC 2015                                                                                                                                                        
Tic 13                                                                                                                                                                                      
Tic 14                                                                                                                                                                                      
Tic 15                                                                                                                                                                                      
Tic 16                                                                                                                                                                                      
Tic 17                                                                                                                                                                                      
Tic 18                                                                                                                                                                                      
Tic 19                                                                                                                                                                                      
NEXT!                                                                                                                                                                                       
timing: 14                                                                                                                                                                                  
Tic 0                                                                                                                                                                                       
Debut Tue Jul 21 13:37:25 UTC 2015                                                                                                                                                          
Tic 1                                                                                                                                                                                       
Tic 2                                                                                                                                                                                       
Tic 3                                                                                                                                                                                       
Tic 4                                                                                                                                                                                       
Tic 5                                                                                                                                                                                       
Tic 6                                                                                                                                                                                       
Tic 7                                                                                                                                                                                       
Tic 8                                                                                                                                                                                       
Tic 9                                                                                                                                                                                       
Tic 10                                                                                                                                                                                      
Tic 11                                                                                                                                                                                      
Tic 12

**Jidefix** · 23/07/2015, 10h19

Bon j'ai fait un petit tour sur les postes autour de moi, et le résultat est assez clair: mes collègues qui ont le même type de poste que moi ont tous le même problème, les autres qui sont sur d'autres machines (plus récentes, autre OS...) fonctionnent tous normalement.

Bref, le problème vient bien de ma machine. Ce ne serait pas le premier bug: un de mes collègues a son horloge qui compte les secondes trop vite! résultat elle avance de 2h toutes les 24h. Chez lui le timeout attend 17 secondes au lieu de 3 avant de se déclencher.

La vérité est ailleurs... en tout cas ça ne vient pas de java. Peut être une défaillance de la pile de BIOS?

**tchize_** · 24/07/2015, 02h26

Envoyé par Jidefix

Ce ne serait pas le premier bug: un de mes collègues a son horloge qui compte les secondes trop vite! résultat elle avance de 2h toutes les 24h. Chez lui le timeout attend 17 secondes au lieu de 3 avant de se déclencher.

La vérité est ailleurs... en tout cas ça ne vient pas de java. Peut être une défaillance de la pile de BIOS?

Je dirais plutot des horloges pourries ou des machines bien verollées jusqu'à la moelle.

[Concurrent] Future.get: timeout trop long

Collection et Stream Java

Discussions similaires

Partager

Partager