builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-022 starttime: 1469444428.77 results: success (0) buildid: 20160725032124 builduid: 92a4c734636546e3b1ca4699167f0107 revision: 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:28.774693) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:28.775207) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:28.775584) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:28.792862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:28.793185) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-25 04:00:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.6M=0s 2016-07-25 04:00:29 (69.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075084 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:28.950300) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:28.950763) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021442 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:28.984860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:28.985246) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-25 04:00:30,108 truncating revision to first 12 chars 2016-07-25 04:00:30,108 Setting DEBUG logging. 2016-07-25 04:00:30,108 attempt 1/10 2016-07-25 04:00:30,108 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1bb05a19ff9d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-25 04:00:30,439 unpacking tar archive at: fx-team-1bb05a19ff9d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.666708 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:29.666073) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:29.666688) ========= script_repo_revision: 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:29.667490) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:29.668032) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 04:00:29.678752) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-25 04:00:29.679299) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:00:30 INFO - MultiFileLogger online at 20160725 04:00:30 in /builds/slave/test 04:00:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 04:00:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:00:30 INFO - {'append_to_log': False, 04:00:30 INFO - 'base_work_dir': '/builds/slave/test', 04:00:30 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 04:00:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:00:30 INFO - 'branch': 'Fx-Team-Non-PGO', 04:00:30 INFO - 'buildbot_json_path': 'buildprops.json', 04:00:30 INFO - 'config_files': ('talos/linux_config.py',), 04:00:30 INFO - 'default_actions': ('clobber', 04:00:30 INFO - 'read-buildbot-config', 04:00:30 INFO - 'download-and-extract', 04:00:30 INFO - 'populate-webroot', 04:00:30 INFO - 'create-virtualenv', 04:00:30 INFO - 'install', 04:00:30 INFO - 'run-tests'), 04:00:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:00:30 INFO - 'download_minidump_stackwalk': True, 04:00:30 INFO - 'download_symbols': 'ondemand', 04:00:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:00:30 INFO - 'tooltool.py': '/tools/tooltool.py', 04:00:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:00:30 INFO - '/tools/misc-python/virtualenv.py')}, 04:00:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:00:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:00:30 INFO - 'installer_path': 'installer.exe', 04:00:30 INFO - 'log_level': 'info', 04:00:30 INFO - 'log_name': 'talos', 04:00:30 INFO - 'log_to_console': True, 04:00:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:00:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:00:30 INFO - 'opt_config_files': (), 04:00:30 INFO - 'pip_index': False, 04:00:30 INFO - 'sps_profile': False, 04:00:30 INFO - 'sps_profile_interval': 0, 04:00:30 INFO - 'suite': 'dromaeojs', 04:00:30 INFO - 'system_bits': '32', 04:00:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:00:30 INFO - 'title': 'talos-linux64-ix-022', 04:00:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:00:30 INFO - 'use_talos_json': True, 04:00:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:00:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:00:30 INFO - 'work_dir': 'build'} 04:00:30 INFO - [mozharness: 2016-07-25 11:00:30.900642Z] Running clobber step. 04:00:30 INFO - Running pre-action listener: _resource_record_pre_action 04:00:30 INFO - Running main action method: clobber 04:00:30 INFO - rmtree: /builds/slave/test/build 04:00:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:00:31 INFO - Running post-action listener: _resource_record_post_action 04:00:31 INFO - [mozharness: 2016-07-25 11:00:31.377058Z] Finished clobber step (success) 04:00:31 INFO - [mozharness: 2016-07-25 11:00:31.377158Z] Running read-buildbot-config step. 04:00:31 INFO - Running pre-action listener: _resource_record_pre_action 04:00:31 INFO - Running main action method: read_buildbot_config 04:00:31 INFO - Using buildbot properties: 04:00:31 INFO - { 04:00:31 INFO - "project": "", 04:00:31 INFO - "product": "firefox", 04:00:31 INFO - "script_repo_revision": "production", 04:00:31 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 04:00:31 INFO - "repository": "", 04:00:31 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 04:00:31 INFO - "buildid": "20160725032124", 04:00:31 INFO - "pgo_build": "False", 04:00:31 INFO - "basedir": "/builds/slave/test", 04:00:31 INFO - "buildnumber": 3195, 04:00:31 INFO - "slavename": "talos-linux64-ix-022", 04:00:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:00:31 INFO - "platform": "ubuntu64_hw", 04:00:31 INFO - "scheduler": "tests-fx-team-linux64-talos", 04:00:31 INFO - "branch": "fx-team", 04:00:31 INFO - "repo_path": "integration/fx-team", 04:00:31 INFO - "revision": "1bb05a19ff9d70ed1d858d526fdbe27a8408f19f", 04:00:31 INFO - "stage_platform": "linux64", 04:00:31 INFO - "builduid": "92a4c734636546e3b1ca4699167f0107", 04:00:31 INFO - "slavebuilddir": "test" 04:00:31 INFO - } 04:00:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 04:00:31 INFO - Running post-action listener: _resource_record_post_action 04:00:31 INFO - [mozharness: 2016-07-25 11:00:31.379588Z] Finished read-buildbot-config step (success) 04:00:31 INFO - [mozharness: 2016-07-25 11:00:31.379676Z] Running download-and-extract step. 04:00:31 INFO - Running pre-action listener: _resource_record_pre_action 04:00:31 INFO - Running main action method: download_and_extract 04:00:31 INFO - mkdir: /builds/slave/test/build/tests 04:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:31 INFO - https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:00:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:00:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:00:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:00:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:00:36 INFO - Downloaded 1361 bytes. 04:00:36 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:00:36 INFO - Using the following test package requirements: 04:00:36 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:00:36 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:00:36 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:00:36 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:36 INFO - u'jsshell-linux-x86_64.zip'], 04:00:36 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:00:36 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:00:36 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:00:36 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:00:36 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:00:36 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:00:36 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:00:36 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:36 INFO - https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:00:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:00:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:00:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:00:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:00:40 INFO - Downloaded 22737593 bytes. 04:00:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:00:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:00:41 INFO - Return code: 0 04:00:41 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:00:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:41 INFO - https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:00:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:00:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:00:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:00:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:00:43 INFO - Downloaded 22737593 bytes. 04:00:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:00:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:00:44 INFO - Return code: 0 04:00:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:44 INFO - https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:00:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:00:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:00:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:00:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:00:45 INFO - Downloaded 14403289 bytes. 04:00:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:00:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:00:45 INFO - Return code: 0 04:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:45 INFO - https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:00:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:00:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:00:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:00:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:00:47 INFO - Downloaded 57159329 bytes. 04:00:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:00:47 INFO - mkdir: /builds/slave/test/properties 04:00:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:00:47 INFO - Writing to file /builds/slave/test/properties/build_url 04:00:47 INFO - Contents: 04:00:47 INFO - build_url:https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:00:47 INFO - Running post-action listener: _resource_record_post_action 04:00:47 INFO - Running post-action listener: set_extra_try_arguments 04:00:47 INFO - [mozharness: 2016-07-25 11:00:47.821567Z] Finished download-and-extract step (success) 04:00:47 INFO - [mozharness: 2016-07-25 11:00:47.821888Z] Running populate-webroot step. 04:00:47 INFO - Running pre-action listener: _resource_record_pre_action 04:00:47 INFO - Running main action method: populate_webroot 04:00:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:00:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:00:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:00:47 INFO - '--tppagecycles', 04:00:47 INFO - '7'], 04:00:47 INFO - 'tests': ['tsvgm']}}, 04:00:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:00:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:00:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:00:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:00:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:00:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:00:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:47 INFO - 'talos_options': ['--disable-e10s'], 04:00:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:00:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:00:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:47 INFO - 'talos_options': ['--disable-e10s'], 04:00:47 INFO - 'tests': ['damp', 'tps']}, 04:00:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:47 INFO - 'tests': ['damp', 'tps']}, 04:00:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:00:47 INFO - 'tests': ['dromaeo_dom']}, 04:00:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:00:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:00:47 INFO - 'tests': ['basic_compositor_video']}, 04:00:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:00:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:00:47 INFO - 'tests': ['a11yr', 04:00:47 INFO - 'ts_paint', 04:00:47 INFO - 'tpaint', 04:00:47 INFO - 'sessionrestore', 04:00:47 INFO - 'sessionrestore_no_auto_restore', 04:00:47 INFO - 'tabpaint']}, 04:00:47 INFO - 'other-e10s': {'tests': ['a11yr', 04:00:47 INFO - 'ts_paint', 04:00:47 INFO - 'tpaint', 04:00:47 INFO - 'sessionrestore', 04:00:47 INFO - 'sessionrestore_no_auto_restore', 04:00:47 INFO - 'tabpaint']}, 04:00:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:00:47 INFO - 'ts_paint', 04:00:47 INFO - 'tpaint', 04:00:47 INFO - 'sessionrestore', 04:00:47 INFO - 'sessionrestore_no_auto_restore', 04:00:47 INFO - 'tabpaint']}, 04:00:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:00:47 INFO - 'ts_paint', 04:00:47 INFO - 'tpaint', 04:00:47 INFO - 'sessionrestore', 04:00:47 INFO - 'sessionrestore_no_auto_restore', 04:00:47 INFO - 'tabpaint']}, 04:00:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:00:47 INFO - 'tests': ['a11yr', 04:00:47 INFO - 'ts_paint', 04:00:47 INFO - 'tpaint', 04:00:47 INFO - 'sessionrestore', 04:00:47 INFO - 'sessionrestore_no_auto_restore', 04:00:47 INFO - 'tabpaint']}, 04:00:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:00:47 INFO - 'tests': ['a11yr', 04:00:47 INFO - 'ts_paint', 04:00:47 INFO - 'tpaint', 04:00:47 INFO - 'sessionrestore', 04:00:47 INFO - 'sessionrestore_no_auto_restore', 04:00:47 INFO - 'tabpaint']}, 04:00:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:00:47 INFO - 'tests': ['tsvgx', 04:00:47 INFO - 'tsvgr_opacity', 04:00:47 INFO - 'tart', 04:00:47 INFO - 'tscrollx', 04:00:47 INFO - 'cart']}, 04:00:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:00:47 INFO - 'tsvgr_opacity', 04:00:47 INFO - 'tart', 04:00:47 INFO - 'tscrollx', 04:00:47 INFO - 'cart']}, 04:00:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:47 INFO - 'talos_options': ['--disable-e10s'], 04:00:47 INFO - 'tests': ['tp5o']}, 04:00:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:47 INFO - 'tests': ['tp5o']}, 04:00:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:47 INFO - 'talos_options': ['--disable-e10s', 04:00:47 INFO - '--xperf_path', 04:00:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:00:47 INFO - 'tests': ['tp5n']}, 04:00:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:00:47 INFO - 'talos_options': ['--xperf_path', 04:00:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:00:47 INFO - 'tests': ['tp5n']}}, 04:00:47 INFO - 'talos.zip': {'path': '', 04:00:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:00:47 INFO - Running post-action listener: _resource_record_post_action 04:00:47 INFO - [mozharness: 2016-07-25 11:00:47.842826Z] Finished populate-webroot step (success) 04:00:47 INFO - [mozharness: 2016-07-25 11:00:47.842915Z] Running create-virtualenv step. 04:00:47 INFO - Running pre-action listener: _resource_record_pre_action 04:00:47 INFO - Running main action method: create_virtualenv 04:00:47 INFO - Creating virtualenv /builds/slave/test/build/venv 04:00:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:00:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:00:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:00:47 INFO - Using real prefix '/usr' 04:00:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:00:48 INFO - Installing distribute.............................................................................................................................................................................................done. 04:00:50 INFO - Installing pip.................done. 04:00:50 INFO - Return code: 0 04:00:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2392030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2653180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26513b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2652740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:00:50 INFO - Using env: {'DISPLAY': ':0', 04:00:50 INFO - 'HOME': '/home/cltbld', 04:00:50 INFO - 'LANG': 'en_US.UTF-8', 04:00:50 INFO - 'LANGUAGE': 'en_US:en', 04:00:50 INFO - 'LOGNAME': 'cltbld', 04:00:50 INFO - 'MAIL': '/var/mail/cltbld', 04:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:50 INFO - 'MOZ_NO_REMOTE': '1', 04:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:50 INFO - 'NO_EM_RESTART': '1', 04:00:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:50 INFO - 'PWD': '/builds/slave/test', 04:00:50 INFO - 'SHELL': '/bin/bash', 04:00:50 INFO - 'SHLVL': '1', 04:00:50 INFO - 'TERM': 'linux', 04:00:50 INFO - 'TMOUT': '86400', 04:00:50 INFO - 'USER': 'cltbld', 04:00:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 04:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:50 INFO - '_': '/tools/buildbot/bin/python'} 04:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:00:50 INFO - Cleaning up... 04:00:50 INFO - Return code: 0 04:00:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2392030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2653180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26513b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2652740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:00:50 INFO - Using env: {'DISPLAY': ':0', 04:00:50 INFO - 'HOME': '/home/cltbld', 04:00:50 INFO - 'LANG': 'en_US.UTF-8', 04:00:50 INFO - 'LANGUAGE': 'en_US:en', 04:00:50 INFO - 'LOGNAME': 'cltbld', 04:00:50 INFO - 'MAIL': '/var/mail/cltbld', 04:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:50 INFO - 'MOZ_NO_REMOTE': '1', 04:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:50 INFO - 'NO_EM_RESTART': '1', 04:00:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:50 INFO - 'PWD': '/builds/slave/test', 04:00:50 INFO - 'SHELL': '/bin/bash', 04:00:50 INFO - 'SHLVL': '1', 04:00:50 INFO - 'TERM': 'linux', 04:00:50 INFO - 'TMOUT': '86400', 04:00:50 INFO - 'USER': 'cltbld', 04:00:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 04:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:50 INFO - '_': '/tools/buildbot/bin/python'} 04:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:50 INFO - Downloading/unpacking psutil>=3.1.1 04:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:00:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:52 INFO - Installing collected packages: psutil 04:00:52 INFO - Running setup.py install for psutil 04:00:52 INFO - building 'psutil._psutil_linux' extension 04:00:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:00:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:00:53 INFO - building 'psutil._psutil_posix' extension 04:00:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:00:53 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:00:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:53 INFO - Successfully installed psutil 04:00:53 INFO - Cleaning up... 04:00:53 INFO - Return code: 0 04:00:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2392030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2653180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26513b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2652740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:00:53 INFO - Using env: {'DISPLAY': ':0', 04:00:53 INFO - 'HOME': '/home/cltbld', 04:00:53 INFO - 'LANG': 'en_US.UTF-8', 04:00:53 INFO - 'LANGUAGE': 'en_US:en', 04:00:53 INFO - 'LOGNAME': 'cltbld', 04:00:53 INFO - 'MAIL': '/var/mail/cltbld', 04:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:53 INFO - 'MOZ_NO_REMOTE': '1', 04:00:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:53 INFO - 'NO_EM_RESTART': '1', 04:00:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:53 INFO - 'PWD': '/builds/slave/test', 04:00:53 INFO - 'SHELL': '/bin/bash', 04:00:53 INFO - 'SHLVL': '1', 04:00:53 INFO - 'TERM': 'linux', 04:00:53 INFO - 'TMOUT': '86400', 04:00:53 INFO - 'USER': 'cltbld', 04:00:53 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 04:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:53 INFO - '_': '/tools/buildbot/bin/python'} 04:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:00:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:00:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:00:55 INFO - Installing collected packages: mozsystemmonitor 04:00:55 INFO - Running setup.py install for mozsystemmonitor 04:00:55 INFO - Successfully installed mozsystemmonitor 04:00:55 INFO - Cleaning up... 04:00:55 INFO - Return code: 0 04:00:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2392030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2653180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26513b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2652740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:00:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:00:55 INFO - Using env: {'DISPLAY': ':0', 04:00:55 INFO - 'HOME': '/home/cltbld', 04:00:55 INFO - 'LANG': 'en_US.UTF-8', 04:00:55 INFO - 'LANGUAGE': 'en_US:en', 04:00:55 INFO - 'LOGNAME': 'cltbld', 04:00:55 INFO - 'MAIL': '/var/mail/cltbld', 04:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:55 INFO - 'MOZ_NO_REMOTE': '1', 04:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:55 INFO - 'NO_EM_RESTART': '1', 04:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:55 INFO - 'PWD': '/builds/slave/test', 04:00:55 INFO - 'SHELL': '/bin/bash', 04:00:55 INFO - 'SHLVL': '1', 04:00:55 INFO - 'TERM': 'linux', 04:00:55 INFO - 'TMOUT': '86400', 04:00:55 INFO - 'USER': 'cltbld', 04:00:55 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 04:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:55 INFO - '_': '/tools/buildbot/bin/python'} 04:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:55 INFO - Downloading/unpacking jsonschema==2.5.1 04:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:00:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:58 INFO - Downloading functools32-3.2.3-2.tar.gz 04:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:00:58 INFO - warning: no files found matching '*.txt' 04:00:58 INFO - no previously-included directories found matching 'build' 04:00:58 INFO - no previously-included directories found matching 'dist' 04:00:58 INFO - no previously-included directories found matching '.git*' 04:00:58 INFO - Installing collected packages: jsonschema, functools32 04:00:58 INFO - Running setup.py install for functools32 04:00:58 INFO - warning: no files found matching '*.txt' 04:00:58 INFO - no previously-included directories found matching 'build' 04:00:58 INFO - no previously-included directories found matching 'dist' 04:00:58 INFO - no previously-included directories found matching '.git*' 04:00:58 INFO - Successfully installed jsonschema functools32 04:00:58 INFO - Cleaning up... 04:00:58 INFO - Return code: 0 04:00:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2392030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2653180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26513b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2652740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:00:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:00:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:00:58 INFO - Using env: {'DISPLAY': ':0', 04:00:58 INFO - 'HOME': '/home/cltbld', 04:00:58 INFO - 'LANG': 'en_US.UTF-8', 04:00:58 INFO - 'LANGUAGE': 'en_US:en', 04:00:58 INFO - 'LOGNAME': 'cltbld', 04:00:58 INFO - 'MAIL': '/var/mail/cltbld', 04:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:58 INFO - 'MOZ_NO_REMOTE': '1', 04:00:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:58 INFO - 'NO_EM_RESTART': '1', 04:00:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:58 INFO - 'PWD': '/builds/slave/test', 04:00:58 INFO - 'SHELL': '/bin/bash', 04:00:58 INFO - 'SHLVL': '1', 04:00:58 INFO - 'TERM': 'linux', 04:00:58 INFO - 'TMOUT': '86400', 04:00:58 INFO - 'USER': 'cltbld', 04:00:58 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 04:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:58 INFO - '_': '/tools/buildbot/bin/python'} 04:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:58 INFO - Downloading/unpacking blobuploader==1.2.4 04:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:00:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:00 INFO - Downloading blobuploader-1.2.4.tar.gz 04:01:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:01:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:01:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:01 INFO - Downloading docopt-0.6.1.tar.gz 04:01:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:01:01 INFO - Installing collected packages: blobuploader, requests, docopt 04:01:01 INFO - Running setup.py install for blobuploader 04:01:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:01:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:01:01 INFO - Running setup.py install for requests 04:01:01 INFO - Running setup.py install for docopt 04:01:01 INFO - Successfully installed blobuploader requests docopt 04:01:01 INFO - Cleaning up... 04:01:01 INFO - Return code: 0 04:01:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2392030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2653180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26513b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2652740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:01:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:01:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:01:01 INFO - Using env: {'DISPLAY': ':0', 04:01:01 INFO - 'HOME': '/home/cltbld', 04:01:01 INFO - 'LANG': 'en_US.UTF-8', 04:01:01 INFO - 'LANGUAGE': 'en_US:en', 04:01:01 INFO - 'LOGNAME': 'cltbld', 04:01:01 INFO - 'MAIL': '/var/mail/cltbld', 04:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:01 INFO - 'MOZ_NO_REMOTE': '1', 04:01:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:01 INFO - 'NO_EM_RESTART': '1', 04:01:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:01 INFO - 'PWD': '/builds/slave/test', 04:01:01 INFO - 'SHELL': '/bin/bash', 04:01:01 INFO - 'SHLVL': '1', 04:01:01 INFO - 'TERM': 'linux', 04:01:01 INFO - 'TMOUT': '86400', 04:01:01 INFO - 'USER': 'cltbld', 04:01:01 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 04:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:01 INFO - '_': '/tools/buildbot/bin/python'} 04:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:01:02 INFO - Running setup.py (path:/tmp/pip-ekPSmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:01:02 INFO - Running setup.py (path:/tmp/pip-VjlMGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:01:02 INFO - Running setup.py (path:/tmp/pip-_Qo7hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:01:02 INFO - Running setup.py (path:/tmp/pip-b2omqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:01:02 INFO - Running setup.py (path:/tmp/pip-z0tfIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:01:02 INFO - Running setup.py (path:/tmp/pip-kV9JJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:01:02 INFO - Running setup.py (path:/tmp/pip-NXVisR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:01:02 INFO - Running setup.py (path:/tmp/pip-fSeY_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:01:02 INFO - Running setup.py (path:/tmp/pip-zR6HlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:01:02 INFO - Running setup.py (path:/tmp/pip-P6CQpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:01:02 INFO - Running setup.py (path:/tmp/pip-GBtAia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:01:02 INFO - Running setup.py (path:/tmp/pip-H_tvgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:01:02 INFO - Running setup.py (path:/tmp/pip-I6uD33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:01:02 INFO - Running setup.py (path:/tmp/pip-qKneKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:03 INFO - Running setup.py (path:/tmp/pip-5Iml3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:01:03 INFO - Running setup.py (path:/tmp/pip-gWvAZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:01:03 INFO - Running setup.py (path:/tmp/pip-rfunXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:01:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:01:03 INFO - Running setup.py install for manifestparser 04:01:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:01:03 INFO - Running setup.py install for mozcrash 04:01:03 INFO - Running setup.py install for mozdebug 04:01:03 INFO - Running setup.py install for mozdevice 04:01:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:01:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:01:03 INFO - Running setup.py install for mozfile 04:01:03 INFO - Running setup.py install for mozhttpd 04:01:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:01:03 INFO - Running setup.py install for mozinfo 04:01:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:01:03 INFO - Running setup.py install for mozInstall 04:01:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:01:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:01:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:01:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:01:04 INFO - Running setup.py install for mozleak 04:01:04 INFO - Running setup.py install for mozlog 04:01:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:01:04 INFO - Running setup.py install for moznetwork 04:01:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:01:04 INFO - Running setup.py install for mozprocess 04:01:04 INFO - Running setup.py install for mozprofile 04:01:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:01:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:01:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:01:04 INFO - Running setup.py install for mozrunner 04:01:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:01:04 INFO - Running setup.py install for mozscreenshot 04:01:04 INFO - Running setup.py install for moztest 04:01:04 INFO - Running setup.py install for mozversion 04:01:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:01:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:01:05 INFO - Cleaning up... 04:01:05 INFO - Return code: 0 04:01:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2392030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2653180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26513b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2652740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:01:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:01:05 INFO - Using env: {'DISPLAY': ':0', 04:01:05 INFO - 'HOME': '/home/cltbld', 04:01:05 INFO - 'LANG': 'en_US.UTF-8', 04:01:05 INFO - 'LANGUAGE': 'en_US:en', 04:01:05 INFO - 'LOGNAME': 'cltbld', 04:01:05 INFO - 'MAIL': '/var/mail/cltbld', 04:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:05 INFO - 'MOZ_NO_REMOTE': '1', 04:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:05 INFO - 'NO_EM_RESTART': '1', 04:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:05 INFO - 'PWD': '/builds/slave/test', 04:01:05 INFO - 'SHELL': '/bin/bash', 04:01:05 INFO - 'SHLVL': '1', 04:01:05 INFO - 'TERM': 'linux', 04:01:05 INFO - 'TMOUT': '86400', 04:01:05 INFO - 'USER': 'cltbld', 04:01:05 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 04:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:05 INFO - '_': '/tools/buildbot/bin/python'} 04:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:01:05 INFO - Running setup.py (path:/tmp/pip-eT6bcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:01:05 INFO - Running setup.py (path:/tmp/pip-g1wmDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:01:05 INFO - Running setup.py (path:/tmp/pip-Bp9Mr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:01:05 INFO - Running setup.py (path:/tmp/pip-URNMPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:01:05 INFO - Running setup.py (path:/tmp/pip-h7qRMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:01:05 INFO - Running setup.py (path:/tmp/pip-q1vq5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:01:05 INFO - Running setup.py (path:/tmp/pip-qg1do2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:01:05 INFO - Running setup.py (path:/tmp/pip-YhzgoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:01:05 INFO - Running setup.py (path:/tmp/pip-H_h8Tm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:01:05 INFO - Running setup.py (path:/tmp/pip-1Wxt2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:01:06 INFO - Running setup.py (path:/tmp/pip-qp85Xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:01:06 INFO - Running setup.py (path:/tmp/pip-skZfVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:01:06 INFO - Running setup.py (path:/tmp/pip-ZHflUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:01:06 INFO - Running setup.py (path:/tmp/pip-IcDSDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:06 INFO - Running setup.py (path:/tmp/pip-F0TcSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:01:06 INFO - Running setup.py (path:/tmp/pip-Y2BOX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:01:06 INFO - Running setup.py (path:/tmp/pip-B8THiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:01:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:01:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:01:08 INFO - Downloading blessings-1.6.tar.gz 04:01:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:01:08 INFO - Installing collected packages: blessings 04:01:08 INFO - Running setup.py install for blessings 04:01:08 INFO - Successfully installed blessings 04:01:08 INFO - Cleaning up... 04:01:08 INFO - Return code: 0 04:01:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:01:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:01:08 INFO - Reading from file tmpfile_stdout 04:01:08 INFO - Current package versions: 04:01:08 INFO - argparse == 1.2.1 04:01:08 INFO - blessings == 1.6 04:01:08 INFO - blobuploader == 1.2.4 04:01:08 INFO - docopt == 0.6.1 04:01:08 INFO - functools32 == 3.2.3-2 04:01:08 INFO - jsonschema == 2.5.1 04:01:08 INFO - manifestparser == 1.1 04:01:08 INFO - mozInstall == 1.12 04:01:08 INFO - mozcrash == 0.17 04:01:08 INFO - mozdebug == 0.1 04:01:08 INFO - mozdevice == 0.48 04:01:08 INFO - mozfile == 1.2 04:01:08 INFO - mozhttpd == 0.7 04:01:08 INFO - mozinfo == 0.9 04:01:08 INFO - mozleak == 0.1 04:01:08 INFO - mozlog == 3.3 04:01:08 INFO - moznetwork == 0.27 04:01:08 INFO - mozprocess == 0.23 04:01:08 INFO - mozprofile == 0.28 04:01:08 INFO - mozrunner == 6.12 04:01:08 INFO - mozscreenshot == 0.1 04:01:08 INFO - mozsystemmonitor == 0.3 04:01:08 INFO - moztest == 0.7 04:01:08 INFO - mozversion == 1.4 04:01:08 INFO - psutil == 3.1.1 04:01:08 INFO - requests == 1.2.3 04:01:08 INFO - wsgiref == 0.1.2 04:01:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2392030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2653180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26513b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2652740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:01:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:01:08 INFO - Using env: {'DISPLAY': ':0', 04:01:08 INFO - 'HOME': '/home/cltbld', 04:01:08 INFO - 'LANG': 'en_US.UTF-8', 04:01:08 INFO - 'LANGUAGE': 'en_US:en', 04:01:08 INFO - 'LOGNAME': 'cltbld', 04:01:08 INFO - 'MAIL': '/var/mail/cltbld', 04:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:08 INFO - 'MOZ_NO_REMOTE': '1', 04:01:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:08 INFO - 'NO_EM_RESTART': '1', 04:01:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:08 INFO - 'PWD': '/builds/slave/test', 04:01:08 INFO - 'SHELL': '/bin/bash', 04:01:08 INFO - 'SHLVL': '1', 04:01:08 INFO - 'TERM': 'linux', 04:01:08 INFO - 'TMOUT': '86400', 04:01:08 INFO - 'USER': 'cltbld', 04:01:08 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 04:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:08 INFO - '_': '/tools/buildbot/bin/python'} 04:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:01:09 INFO - Cleaning up... 04:01:09 INFO - Return code: 0 04:01:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:01:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2392030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2653180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26513b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2652740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:01:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:01:09 INFO - Using env: {'DISPLAY': ':0', 04:01:09 INFO - 'HOME': '/home/cltbld', 04:01:09 INFO - 'LANG': 'en_US.UTF-8', 04:01:09 INFO - 'LANGUAGE': 'en_US:en', 04:01:09 INFO - 'LOGNAME': 'cltbld', 04:01:09 INFO - 'MAIL': '/var/mail/cltbld', 04:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:09 INFO - 'MOZ_NO_REMOTE': '1', 04:01:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:09 INFO - 'NO_EM_RESTART': '1', 04:01:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:09 INFO - 'PWD': '/builds/slave/test', 04:01:09 INFO - 'SHELL': '/bin/bash', 04:01:09 INFO - 'SHLVL': '1', 04:01:09 INFO - 'TERM': 'linux', 04:01:09 INFO - 'TMOUT': '86400', 04:01:09 INFO - 'USER': 'cltbld', 04:01:09 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 04:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:09 INFO - '_': '/tools/buildbot/bin/python'} 04:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:01:09 INFO - Cleaning up... 04:01:09 INFO - Return code: 0 04:01:09 INFO - Running post-action listener: _resource_record_post_action 04:01:09 INFO - Running post-action listener: _start_resource_monitoring 04:01:09 INFO - Starting resource monitoring. 04:01:09 INFO - [mozharness: 2016-07-25 11:01:09.342358Z] Finished create-virtualenv step (success) 04:01:09 INFO - [mozharness: 2016-07-25 11:01:09.342833Z] Running install step. 04:01:09 INFO - Running pre-action listener: _resource_record_pre_action 04:01:09 INFO - Running main action method: install 04:01:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:01:09 INFO - Reading from file tmpfile_stdout 04:01:09 INFO - Detecting whether we're running mozinstall >=1.0... 04:01:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:01:09 INFO - Reading from file tmpfile_stdout 04:01:09 INFO - Output received: 04:01:09 INFO - Usage: mozinstall [options] installer 04:01:09 INFO - Options: 04:01:09 INFO - -h, --help show this help message and exit 04:01:09 INFO - -d DEST, --destination=DEST 04:01:09 INFO - Directory to install application into. [default: 04:01:09 INFO - "/builds/slave/test"] 04:01:09 INFO - --app=APP Application being installed. [default: firefox] 04:01:09 INFO - mkdir: /builds/slave/test/build/application 04:01:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:01:26 INFO - Reading from file tmpfile_stdout 04:01:26 INFO - Output received: 04:01:26 INFO - /builds/slave/test/build/application/firefox/firefox 04:01:26 INFO - Running post-action listener: _resource_record_post_action 04:01:26 INFO - [mozharness: 2016-07-25 11:01:26.063735Z] Finished install step (success) 04:01:26 INFO - [mozharness: 2016-07-25 11:01:26.064013Z] Running run-tests step. 04:01:26 INFO - Running pre-action listener: _resource_record_pre_action 04:01:26 INFO - Running main action method: run_tests 04:01:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:01:26 INFO - Python 2.7.3 04:01:26 INFO - Return code: 0 04:01:26 INFO - grabbing minidump binary from tooltool 04:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:01:26 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26513b0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26522b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2652740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:01:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:01:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:01:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:01:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:01:26 INFO - Return code: 0 04:01:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:01:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:01:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:01:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:01:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:01:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:01:26 INFO - Using env: {'DISPLAY': ':0', 04:01:26 INFO - 'HOME': '/home/cltbld', 04:01:26 INFO - 'LANG': 'en_US.UTF-8', 04:01:26 INFO - 'LANGUAGE': 'en_US:en', 04:01:26 INFO - 'LOGNAME': 'cltbld', 04:01:26 INFO - 'MAIL': '/var/mail/cltbld', 04:01:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:01:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:01:26 INFO - 'MOZ_NO_REMOTE': '1', 04:01:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:01:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:01:26 INFO - 'NO_EM_RESTART': '1', 04:01:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:01:26 INFO - 'PWD': '/builds/slave/test', 04:01:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:01:26 INFO - 'SHELL': '/bin/bash', 04:01:26 INFO - 'SHLVL': '1', 04:01:26 INFO - 'TERM': 'linux', 04:01:26 INFO - 'TMOUT': '86400', 04:01:26 INFO - 'USER': 'cltbld', 04:01:26 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148', 04:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:01:26 INFO - '_': '/tools/buildbot/bin/python'} 04:01:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:01:26 INFO - mozversion application_buildid: 20160725032124 04:01:26 INFO - mozversion application_changeset: 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f 04:01:26 INFO - mozversion application_display_name: Nightly 04:01:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:01:26 INFO - mozversion application_name: Firefox 04:01:26 INFO - mozversion application_remotingname: firefox 04:01:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:01:26 INFO - mozversion application_vendor: Mozilla 04:01:26 INFO - mozversion application_version: 50.0a1 04:01:26 INFO - mozversion platform_buildid: 20160725032124 04:01:26 INFO - mozversion platform_changeset: 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f 04:01:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:01:26 INFO - mozversion platform_version: 50.0a1 04:01:26 INFO - using testdate: 1469444486 04:01:26 INFO - actual date: 1469444486 04:01:26 INFO - starting webserver on 'localhost:54768' 04:01:26 INFO - SUITE-START | Running 2 tests 04:01:26 INFO - TEST-START | dromaeo_css 04:01:26 INFO - operating with platform_type : linux_ 04:01:26 INFO - Initialising browser for dromaeo_css test... 04:01:26 INFO - TEST-INFO | started process 2598 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8e9Vx4/profile http://localhost:54768/getInfo.html) 04:01:29 INFO - PROCESS | 2598 | JavaScript error: , line 0: uncaught exception: undefined 04:01:30 INFO - PROCESS | 2598 | __metrics Screen width/height:1600/1200 04:01:30 INFO - PROCESS | 2598 | colorDepth:24 04:01:30 INFO - PROCESS | 2598 | Browser inner width/height: 1024/697 04:01:30 INFO - PROCESS | 2598 | __metrics 04:01:31 INFO - PROCESS | 2598 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:01:31 INFO - PROCESS | 2598 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:01:32 INFO - TEST-INFO | 2598: exit 0 04:01:32 INFO - Browser initialized. 04:01:32 INFO - Running cycle 1/1 for dromaeo_css test... 04:01:32 INFO - TEST-INFO | started process 2678 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8e9Vx4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:01:44 INFO - PROCESS | 2678 | RSS: Main: 159395840 04:01:44 INFO - PROCESS | 2678 | 04:02:36 INFO - PROCESS | 2678 | 1469444556649 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:04:48 INFO - PROCESS | 2678 | Cycle 1(1): loaded http://localhost:54768/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54768/tests/dromaeo/cssquery-ext.html) 04:04:49 INFO - PROCESS | 2678 | RSS: Main: 248926208 04:04:49 INFO - PROCESS | 2678 | 04:07:52 INFO - PROCESS | 2678 | Cycle 1(1): loaded http://localhost:54768/tests/dromaeo/cssquery-ext.html (next: http://localhost:54768/tests/dromaeo/cssquery-jquery.html) 04:07:52 INFO - PROCESS | 2678 | RSS: Main: 240656384 04:07:52 INFO - PROCESS | 2678 | 04:10:54 INFO - PROCESS | 2678 | Cycle 1(1): loaded http://localhost:54768/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54768/tests/dromaeo/cssquery-mootools.html) 04:10:55 INFO - PROCESS | 2678 | RSS: Main: 208203776 04:10:55 INFO - PROCESS | 2678 | 04:13:57 INFO - PROCESS | 2678 | Cycle 1(1): loaded http://localhost:54768/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54768/tests/dromaeo/cssquery-prototype.html) 04:13:58 INFO - PROCESS | 2678 | RSS: Main: 187351040 04:13:58 INFO - PROCESS | 2678 | 04:17:01 INFO - PROCESS | 2678 | Cycle 1(1): loaded http://localhost:54768/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54768/tests/dromaeo/cssquery-yui.html) 04:17:01 INFO - PROCESS | 2678 | RSS: Main: 186249216 04:17:01 INFO - PROCESS | 2678 | 04:20:04 INFO - PROCESS | 2678 | Cycle 1(1): loaded http://localhost:54768/tests/dromaeo/cssquery-yui.html (next: http://localhost:54768/tests/dromaeo/cssquery-dojo.html) 04:20:04 INFO - PROCESS | 2678 | RSS: Main: 276873216 04:20:04 INFO - PROCESS | 2678 | 04:20:04 INFO - PROCESS | 2678 | __start_tp_report 04:20:04 INFO - PROCESS | 2678 | _x_x_mozilla_page_load 04:20:04 INFO - PROCESS | 2678 | _x_x_mozilla_page_load_details 04:20:04 INFO - PROCESS | 2678 | |i|pagename|runs| 04:20:04 INFO - PROCESS | 2678 | |0;dojo.html;2596.8961241550337;2610.151700697274;2637.8812953417096;2645.0874448315326;2650.7084220735715;4609.262517997121;4681;4705.623550115991;4753.025629745903;4768.761561921903;4666.53334666534;4684.391029166203;4731.952680473194;4741;4750.049990001996;4258.7870606469705;4301.741895486265;4325.870223893288;4397.73613583186;4526.864194074183;4434.024514606797;4473.127740090684;4479.664025198123;4489.865304040868;4505.166544189319;4269.765162916038;4314.1371725655;4327.06968001878;4364.672649551265;4398.582134697199;4324.178406102862;4335.588119128678;4343.717658352229;4357.063231405232;4381.671374646914;4319.0713996490595;4370.519242883281;4381.211381951248;4383.605475507188;4421.204183247047;4604.056168485486;4709.952900470986;4763.618910487153;4822.927656085161;4826.0347930414055;5021.899562008776;5090.694558326475;5110.6422550421485;5183.922241166386;5184;4992.40091189056;5019.774110165052;5041.017001684673;5050.520200580939;5071.2139618359215;4605.424321959755;4652.883677908079;4729.858104256878;4734.502877197879;4740.834070807505;5065.341505604321;5073.340465739504;5119.257707632447;5129.948700512947;5143.871403214949;4883.145449546315;4886.560209581155;4889.168841296988;4923.704577725348;4941.110600092018;4914.70511769295;4965.155923493015;4992.051510213048;5028.899422011613;5031.974840125776;4982.327385802942;5038.64729468941;5075.061113693904;5077.416097148801;5082.161443361804;4988.925166122511;4993.300937868701;5000.924986125211;5003;5051.6716413433005;4270.594293542109;4271.188474189894;4274.230638485041;4275.87172384829;4316.050468896838;3671.394219953731;3698.9630103699155;3700.555933287969;3749.6625303722794;3784.129650180474;4348.217320882209;4373.2128216921255;4375.190589740908;4389.078293558388;4416.911661766747;5074.188129899198;5077.035363280965;5081.9745901271;5082.847514574569;5121.667091639031;4262.50981137167;4318.050028993679;4348.412964249849;4365.695135070828;4369.3227549729845;4557.726536407826;4572.0855828834365;4572.908541829145;4610.4697959734385;4619.0530941156985;4760.547747963938;4771.761411929389;4772.0455908817685;4773.689710168828;4818.71087734737;4954.900901981868;5015.799368025384;5033.84898453047;5076.340075790124;5078.136716758234;4765.785539650793;4788.329633851194;4791.425028996543;4821.566059054561;4835.274708793709;4951.3810773653295;4985.651004429655;4988.650794444499;4995.300657907823;5037.899242015213;4916.360873086476;5004.6246531511015;5040.395152581567;5045.31888195089;5062.265971434048;4921.458639549575;4922.950770492249;4940.901181976412;4946.258061290691;4955.628327875496;4956.603471722326;4959.851204463872;4960.70235785854;4964;4994.225894986283;4700.412448443944;4770.045990801784;4782.067496838084;4826.251930950708;4868.464468908488;4951.876203094807;4955.380577427822;4967.776450059829;4976.328195693535;4983.327250821093;4862.246351815475;4866.099771542276;4870.269459581091;4871.585915197248;4894.730789806595 04:20:04 INFO - PROCESS | 2678 | |1;ext.html;552.6125411308954;581.8167277307648;582.2954225387278;589.5283772981613;592.6858764854628;39485.80257098714;40236.39645405321;40307;40355.59644403555;44236.33645495312;40222.39666404999;40636;40668.389974150414;40713.18573628525;40753;7468.962655186717;7639.541627502367;7823.256790604912;7823.687052517893;9808.803823923517;9631.662891798753;9929.553170107327;9950.25373097017;9970.052844979751;9983.051610097029;10044.096031357212;10084.193264538819;10095;10098.495075246208;10108.039736225046;10021.549030293654;10057.044580764816;10066.848997265048;10073.345232559934;10075.697729068139;9864.161546268575;9885.406875587416;9902.851457228147;9919.355241909252;9923.652672156511;38794.22411551781;39131;39131.8043409784;39140.41289380633;39210.41184382237;2978.7021297870256;3005.549167624852;3043.193553708269;3079.7844150909445;3319.5684561007156;29575.408491830258;29921.40157196844;30153.547696784564;30179.245518861986;31031.379372412426;3435.6736110069755;3441.5181874537584;3446.8965931022108;3490.7730997485082;3539.592946811124;246837;247189;247552;247585.9999999982;248003;54187;54475.72762136164;54538.18192727112;54573;54701.45298546964;33108.834455827564;33930.32139357199;57383.713081434325;57415.13877291844;57644.71177644169;15115.244237788067;15528.844711552965;15559.533214003599;15570.610734731723;15572.454964076203;11001;11229.71925701864;11242;11463.598774042868;11809.822852657217;18320.267189312544;18917.810821891606;18982.905085474762;19350.806491935182;19374.70937935932;6825.35159159879;6888.41448476885;6983.371496565232;6996.720131194696;7028.613426261605;813.9674413023531;818.2553875972956;930.6463543853292;934.8550974598948;977.7360112769512;27370.863145684143;28900.42199156005;29007.85496072506;29071.709282907323;29255.268618284714;908.9681861134828;943.8725772020689;952.5618215620892;999.7150812018685;1059.2638116508951;14886.531328520745;15252.237388130548;15272.160031198391;15431.537053888402;15498.690026199638;375543;378336;378875;378936;379422;48719.5128048722;49262.26106608404;49303;49311;49340;1066.0778426660881;1121.5121422181378;1177.8056620657496;1195.7668254690252;1199.4962115911337;25862.482750345265;27182.864085679445;30158.396832063674;30388.84805575958;30652.233694157825;47656.5234347666;49398.753006234736;49478.50521494739;53948.46051539591;54009.45990540044;350027;356005;356260;356623;356655;38344.80827595844;39345.80327098461;40120;41971.58028419677;42141.78929105335;39254.41118383284;41558;42806.78596606997;42813;42931.570684292754;36024;36219.27561448809;36804.26391472209;37122.628773713;38125.237495249385;36493.635063649024;36834.63165368312;36940.26119477649;37412;37504;35701.64298356983;35807;36397.636023639425;36626.08434789152;37131.628683712814;31484.370312594077;33203.66796332102;33509.16227094245;33633.83183084069;33743.3251334967;24988.50022999566;25413.87293063523;25493.98024079016;25584.184540348004;25854 04:20:04 INFO - PROCESS | 2678 | |2;jquery.html;344.6742828027515;365.5394203303836;366.98348574314156;368.78426120719377;381.1044046490747;12257.938710306447;13133.47466101356;13682.657933551654;16977.2360243789;17133.400330988417;16186.676266474693;16662.916685416556;16932.4073657422;16949.152542372893;17633.382831600895;4657.720536767788;4806.966502202026;4807.711537307755;4811.061842940628;4813.518648135194;3572;5877.9118313225335;6069.939300607004;6159.260888693363;6254.906176407358;5566.526845218162;5932.3474417814;6228.408301211378;6242.063690446424;6243.313235544087;5566.666000039991;5576.888462230732;5583.720813959286;5586.24585680932;5588.580856435743;5265.710385928773;5305.549028332596;5361.4102448731;5458.836234912959;5460.6177567570285;12321.075919305998;12351.38243087842;12391.318477483735;12406.503739850396;16421.671566568722;1928.7492625958675;2005.6189324028535;2140.9830330593;2264.445210923318;2444.401121725171;8765.693200737944;9417;9627.02645957577;14436.711265774731;14612.488562900247;1779.065990355061;1822.1916195152196;1884.9255924123256;1900.0974537094908;1905.2188602672977;67485;68030;72740.63629681818;80012;91145.54427227822;16348.182590870409;16748.330066797193;16982.830171698373;17078.48764537066;17671.911640441715;29966.70033299639;30126;30127.288544030704;30167;30194.698053019627;9263.536823158815;9459.716208513755;9574.04259574037;9756.07317304855;9757.512124393894;7103.431725461951;7200.711971521186;7212.819679508054;7389.224131466226;7480.513766605153;8366.456180348257;8445.831083378298;8746.737597872074;8924.419912705653;8934.776630584158;2608.4391855751105;3171.0011346425804;3405.9318813624086;3569.1255642367787;3636.29092326999;734.328089797829;791.3392317415023;803.445622520459;856.7986523167051;873.3929918706438;16304.347826086823;16763.832361676228;17091.572710682332;17306;17453.040082795567;766.1801871997021;915.1306259053925;920.300571565615;932.4451951089092;940.4451373689556;9468.005859384803;9517.762055948657;9751.463669498104;9755.219582433388;9962.107199632046;399700;402854;404067;404456;405171;75528.24471755212;77915.61042194752;78678.6066069666;81575;88689.55655221683;927.893292271384;953.2802733935972;1001.7595777013464;1001.9749506262402;1008.4755926917961;15909.920450397674;16699.248533815764;16752.078635675156;16797.41209057677;17067.914660427115;37760.6783762567;38899.41650875183;38918.4162237561;39504;39867;49331.506684934124;50682;51125.488745112074;55123.44876551183;55962.48168832331;7482.1021477423055;7505.362044226302;7634.389248860189;7640.426967977312;7780.260875216845;10942.977856350917;11661.59184428575;11663.358515281741;11819.113566482376;11985.101117416054;9469.810603788024;9764.267679923893;9808.65669701557;10117.696469105938;10276.383416995004;9913.950430248091;10082.395056296646;10306;10393.740156496147;10473.633422830164;9651.131398174199;9682.951585242028;9752.9024709752;9842.360246583949;9985.550650220612;7393.1497877742595;7600.771976840703;7664.80837979055;7669.23307669251;7729.111152217567;7926.643301051351;9091.59087841062;9178.862317065115;9232.769180770534;9331.206847418045 04:20:04 INFO - PROCESS | 2678 | |3;mootools.html;1394.2331717555346;1430.8569143085692;1487.2712370938243;1656.8343165683425;1694.381550733981;7476.813079673011;7979.680812767489;7986.241307075833;7998.080220774613;8001.519908805476;6811.318868113186;7532.661030253638;7685.423593230516;7731.845363092735;7839.725609603665;2126.691629713687;2157.46063484129;2159.589677961182;2160.027987405666;2163.978360216401;2137.711408959794;2139.935801925945;2204.4048107011176;2205.63607004844;2209.690643309938;2171.6416791229426;2183.0721943174167;2188;2194.703714998471;2196.417949243452;2114.45024293684;2121.968170477444;2122.0768965500056;2122.989385053065;2125.0118694806833;2086.613976414368;2137.722096127509;2142.9785702142935;2143.249862548111;2144.6782982552586;8003.759887203393;8052.396070294752;8070.596470176526;8101.716439924633;8140.145284745135;4579.335996280521;4710.152172608929;4711.458182309044;4712.6229901607785;4713.952860471386;2788.442311537701;2822.435512897408;2903.2886942698965;2913.6940621234676;2916.1336744483337;874.8381549413378;919.0533750237266;939.915407613318;941.223490620241;944.5182956692067;10859.13126949842;10870.565177392895;10891.237613367068;10918.836217456665;10925.945370273177;3940.5074365704286;4090.1615168890307;4103.671706263492;4244.2996905510245;4259.5101563319995;9314.44113353201;9321.673741419017;9325.487098209664;9325.673601423918;9327.720168394959;4536.206163921301;4545.113702827983;4548.95451045492;4551.635869130461;4553.954460455353;3704.3332200204222;4072.714909956334;4130.731502452331;4168.6665066794585;4222.239996800606;3560.1989552351033;3590.6588874056924;3670.3209906166912;3686.6866316363407;3704.3517384457614;1164.371239530644;1179.5222934711455;1182.3910685996718;1187.5309252845077;1194.50428072349;784.1844481738927;833.2417500074903;845.0830848529414;848.2832006954237;873.6156091319799;4947.777350019258;5168.405633352138;5175.713421976996;5198.818041368534;5227.980543793924;945.0879900895669;949.4066208619613;958.434523631061;984.2667212926416;986.7681094942683;3587.7488575799443;3612.7832330060282;3613.747037707335;3657.579378371468;3768.4535742317757;9696.75758106053;9895.158911492459;10004.299699021;10009.949950250348;10032.749181270381;9917.768502086972;9923.454210018519;9928.503574821374;9928.702138935843;9939.353941993748;6762.459003279824;6997.895031574632;7009.264027277164;7475.588842613708;7655.311022007934;5426.565874730091;5490.505854472957;5501.257330260363;5506.229127922014;5554.4445555445745;8955.373123881527;9144.679936202201;9274.072592740671;9295.67465138717;9320;9096.135867092615;9265.67570135069;9373.765655858659;9403.388779729295;9414.340996130206;5824.94175058244;6459.580127291711;6591.736330546916;6759.763408280686;6786.69459874297;7479.027726395534;7723.613819309125;7724.884126737991;7730.072391313079;7746.419018573517;6427.646479443675;6472.773452929125;6518.348165183634;6708.899366509604;6709.530332876652;6632.436242919406;6650.301718319701;6673.099131617169;6673.432758215412;6693.26374098839;6054.273487181566;6140.232470941132;6141.723622436912;6164.660943648142;6171.352008039271;4427.8228870844805;4556.476005259437;4587.63298936091;4601.746903920316;4608.953910460987;4372.125574885099;4444.444444444444;4459.130469558405;4483.103379324213;4502.5497450256025 04:20:04 INFO - PROCESS | 2678 | |4;prototype.html;292.48522600223606;295.70872690399966;296.8515742128936;299.0534956861533;303.5840897969781;4826.855194344175;4832.540908613681;4835.854924352267;4846.369971903657;4850.830220942264;4770.236762118062;4777.641676874239;4780.466352832996;4782.210935195689;4791.425028996517;3567.482715006316;3674.3386190485703;3711.257748450307;3780.111673756665;3783.375743002401;3780.281746468176;3782.5460944686674;3810.218905124457;3811.4473401356786;3822.2355528894195;3748.4377343398437;3758.774473531583;3764.849406023756;3771.962280377202;3780.2250538639514;3506.140995456104;3634.5638523377365;3758.8872333830027;3776.603456637041;3794.260119276742;3333.3499967506345;3342.782719123249;3360.1095709636966;3370.9494357584654;3379.746519011084;4714.057188562301;4715.646326525524;4728.83449079284;4732.935089604845;4736.2895565665085;4213.810378532943;4309.5690430956965;4310.4396428464415;4326.420856387427;4326.978365108186;4926.655134140611;4959.7768100435205;4967.205247160437;4994.551580041783;5008.273800298973;3623.2210074833774;3625.329314076904;3629.1471504196493;3648.7628304160144;3682.852685892561;5028.547430731252;5238.816641417532;5255.921161182585;5259.710715910624;5281.9207711884355;3745.6254374562327;3752.574021871726;3754.849806007729;3756.962430375716;3768.491253680718;3812.8856134316015;3838.7696738195796;3841.1357444574746;3841.4621952926614;3843.423486477051;4323.567643235651;4331.45856767904;4332.935005974913;4343.566623014398;4345.500267469219;4790.5448982346625;5025.849224523197;5041.21861111521;5053.722045287471;5075.543201111917;1272.287518989369;1283.5058502476613;1287.7682017236987;1288.549007847246;1297.9935100324437;773.6943907156642;801.7714951238869;823.283743143469;835.4444294544181;842.7176895739996;4347.760873151944;4438.933415998763;4451.06527629195;4460.50934397216;4467.776611169494;5136;5160.535551800356;5172.818951336685;5189.740512974412;5198.324217851655;4426.490953540319;4451.931536431298;4454.198244316054;4465;4481.215787237285;2588.935276618062;2723.8365698057783;2751.6010178524;2751.830472049371;2762.875670594828;4863.489333619919;4864.70811751289;4872.14737420957;4876.756162191876;4879.39007624047;5044.949550504448;5293.153095504701;5308.654937429054;5311.28297679816;5323.1749078892835;4841.273808928689;4869.9269510957365;4897.4857639948;4899.877503062452;4921.557059864629;5198.376194856642;5216.895662086661;5218.269442278008;5221.955608878164;5222.97388513055;4950.950490495049;5114.411842638145;5131.692098474104;5157.149070403491;5166.354205724285;4896.74366546257;4910.950890491049;4914.2628605708;4917.237828136645;4920.384951881015;3776.792276424823;3801.6388443097862;3814.790186539767;3824.694024478091;3845.3462911305705;5080;5132.538071573576;5235.267062611161;5240.292560504436;5255.89488210226;4952.780692549872;4976.328195693535;4977.502249774993;4978.825741099189;4996.2505624156665;4961.42943561495;4991.051700176955;4992.700437973734;5046.066477701637;5048.091343558048;4743.573078422958;4885.2427873679635;4887.509309660588;4892.65751397413;4896.902061958812;4842;4916.459189489225;4997.375328083989;5013.122703526941;5025.195968644999;4924.8276310328965;4944.332515110414;4947.703137811743;4962.627802914868;4969.552740253394 04:20:04 INFO - PROCESS | 2678 | |5;yui.html;580.4340767751443;596.3499785234095;662.2582508415159;676.3067855448165;718.9137303523578;11666.358350290731;12412.441440135193;12549.121561490689;12560.246385216893;12580.937095314523;12561.309127997956;12561.434735436904;12714.682132946658;12752.681182970407;12762.80855787164;744.4453881858024;775.1744392222265;784;784.9725259615915;785.5286827903269;626.4675026227693;741.5291290030842;774.7869335932608;778.1790211327047;782.5109306683323;825.7316372179035;827.4042689263721;832.4381042796118;832.8084540555676;833.7582101190648;819.1521774962914;821.195228675895;821.4619424277108;821.5399376349263;821.9589020548949;481.33816002995883;580.0979476913398;592.925884264467;593.6734795862259;601.8284788835161;12947.352632368438;12957.22256664603;12960.611181664472;12967.416466259041;12990.28553429561;12464.501419943192;12828.871711282862;12889.806652900214;12974;13527.32363381827;11425.771484570261;11506.309621422657;11551.364674942875;11605.477753501029;11692.94153529227;4035.1324465239823;4097.7541347519245;4099.9180016399805;4107.445494858186;4123.175364926997;60026.69986650082;60564.69717651427;61281.38718612846;61698.38301617016;61828.69085654588;9279.350445468752;9350.251979841596;9728.367656102331;9828.508574571386;10081.445520496296;17206;17356.39252626152;17399.565010874576;17450.56373590645;17550.29798808059;6461.095446637474;6488.67556622167;6688.86622267552;6702.229243637044;6713.26154123046;3368.1579605098727;3522.5244591979754;3535.8762443314877;3561.252137051248;3586.946195807065;2820.323122450638;2889.7832662550395;2907.098799372159;2921.2258751430886;2925.3417980954114;488.1896052552816;492.1289317907284;495.8066354121895;503.8135889720827;511.493621314903;377.03291058435025;435.3643680226904;454.65219107382876;464.1737706881756;469.25154378765814;6674.499412544079;6714.429273511806;6880.862382752417;7041.119860017498;7145.21402645719;400.50737592761067;528.9206619007102;530.5967464726758;540.5675459632279;546.5026825588695;3205.054508919874;3250.800990587451;3273.1980664737293;3315.966840331614;3559.305935342635;93207;93617.53191234;96165.51917240368;96259.51870240604;97319;79891;80553.59723201346;81247.65651949983;82555;85037;45982.310265346714;62788.68605656942;72719;74024;74791.25208747952;8089.9010373196425;8456.83086338282;8485.618147183515;8626.611802468778;8641.135886411561;42004.78997604993;42119.57880421157;42691.3596296062;42967.785161073996;44009.55990440054;59202.40797591969;61624.69187654033;63560.68219658872;64002.35997639964;66863.66568167128;10664.253502254767;10830.945845271039;11145.609903653332;11216.607418740305;11349.035331996542;11612.709682258012;11904;11999.940000299943;12122.75754484923;12552.623421297376;10631.787364252827;10733.785324293314;10988.615398461016;11045.944770276097;11573.13201509907;11457.942710286396;11590.478428470908;11654.941725291319;11916.702082447662;12789.74420511566;11325.546978120783;11445.141614378788;11661.53353865836;11947.402629868646;11977.161598688008;9612.423254604746;10124.797504050026;10135.645252416425;10459.581616735246;10592.099671527698;9290.62837486493;9612.231021518122;9712.951435242778;9720.319577629498;9720.902790971999 04:20:04 INFO - PROCESS | 2678 | __end_tp_report 04:20:04 INFO - PROCESS | 2678 | __start_cc_report 04:20:04 INFO - PROCESS | 2678 | _x_x_mozilla_cycle_collect,923 04:20:04 INFO - PROCESS | 2678 | __end_cc_report 04:20:04 INFO - PROCESS | 2678 | __startTimestamp1469445604933__endTimestamp 04:20:04 INFO - PROCESS | 2678 | 04:20:04 INFO - PROCESS | 2678 | ------- Summary: start ------- 04:20:04 INFO - PROCESS | 2678 | Number of tests: 6 04:20:04 INFO - PROCESS | 2678 | 04:20:04 INFO - PROCESS | 2678 | [#0] dojo.html Cycles:165 Average:4670.67 Median:4791.43 stddev:485.15 (10.1%) stddev-sans-first:458.56 04:20:04 INFO - PROCESS | 2678 | Values: 2596.9 2610.2 2637.9 2645.1 2650.7 4609.3 4681.0 4705.6 4753.0 4768.8 4666.5 4684.4 4732.0 4741.0 4750.0 4258.8 4301.7 4325.9 4397.7 4526.9 4434.0 4473.1 4479.7 4489.9 4505.2 4269.8 4314.1 4327.1 4364.7 4398.6 4324.2 4335.6 4343.7 4357.1 4381.7 4319.1 4370.5 4381.2 4383.6 4421.2 4604.1 4710.0 4763.6 4822.9 4826.0 5021.9 5090.7 5110.6 5183.9 5184.0 4992.4 5019.8 5041.0 5050.5 5071.2 4605.4 4652.9 4729.9 4734.5 4740.8 5065.3 5073.3 5119.3 5129.9 5143.9 4883.1 4886.6 4889.2 4923.7 4941.1 4914.7 4965.2 4992.1 5028.9 5032.0 4982.3 5038.6 5075.1 5077.4 5082.2 4988.9 4993.3 5000.9 5003.0 5051.7 4270.6 4271.2 4274.2 4275.9 4316.1 3671.4 3699.0 3700.6 3749.7 3784.1 4348.2 4373.2 4375.2 4389.1 4416.9 5074.2 5077.0 5082.0 5082.8 5121.7 4262.5 4318.1 4348.4 4365.7 4369.3 4557.7 4572.1 4572.9 4610.5 4619.1 4760.5 4771.8 4772.0 4773.7 4818.7 4954.9 5015.8 5033.8 5076.3 5078.1 4765.8 4788.3 4791.4 4821.6 4835.3 4951.4 4985.7 4988.7 4995.3 5037.9 4916.4 5004.6 5040.4 5045.3 5062.3 4921.5 4923.0 4940.9 4946.3 4955.6 4956.6 4959.9 4960.7 4964.0 4994.2 4700.4 4770.0 4782.1 4826.3 4868.5 4951.9 4955.4 4967.8 4976.3 4983.3 4862.2 4866.1 4870.3 4871.6 4894.7 04:20:04 INFO - PROCESS | 2678 | 04:20:04 INFO - PROCESS | 2678 | [#1] ext.html Cycles:180 Average:49135.78 Median:36819.45 stddev:87165.90 (236.7%) stddev-sans-first:87334.09 04:20:04 INFO - PROCESS | 2678 | Values: 552.6 581.8 582.3 589.5 592.7 39485.8 40236.4 40307.0 40355.6 44236.3 40222.4 40636.0 40668.4 40713.2 40753.0 7469.0 7639.5 7823.3 7823.7 9808.8 9631.7 9929.6 9950.3 9970.1 9983.1 10044.1 10084.2 10095.0 10098.5 10108.0 10021.5 10057.0 10066.8 10073.3 10075.7 9864.2 9885.4 9902.9 9919.4 9923.7 38794.2 39131.0 39131.8 39140.4 39210.4 2978.7 3005.5 3043.2 3079.8 3319.6 29575.4 29921.4 30153.5 30179.2 31031.4 3435.7 3441.5 3446.9 3490.8 3539.6 246837.0 247189.0 247552.0 247586.0 248003.0 54187.0 54475.7 54538.2 54573.0 54701.5 33108.8 33930.3 57383.7 57415.1 57644.7 15115.2 15528.8 15559.5 15570.6 15572.5 11001.0 11229.7 11242.0 11463.6 11809.8 18320.3 18917.8 18982.9 19350.8 19374.7 6825.4 6888.4 6983.4 6996.7 7028.6 814.0 818.3 930.6 934.9 977.7 27370.9 28900.4 29007.9 29071.7 29255.3 909.0 943.9 952.6 999.7 1059.3 14886.5 15252.2 15272.2 15431.5 15498.7 375543.0 378336.0 378875.0 378936.0 379422.0 48719.5 49262.3 49303.0 49311.0 49340.0 1066.1 1121.5 1177.8 1195.8 1199.5 25862.5 27182.9 30158.4 30388.8 30652.2 47656.5 49398.8 49478.5 53948.5 54009.5 350027.0 356005.0 356260.0 356623.0 356655.0 38344.8 39345.8 40120.0 41971.6 42141.8 39254.4 41558.0 42806.8 42813.0 42931.6 36024.0 36219.3 36804.3 37122.6 38125.2 36493.6 36834.6 36940.3 37412.0 37504.0 35701.6 35807.0 36397.6 36626.1 37131.6 31484.4 33203.7 33509.2 33633.8 33743.3 24988.5 25413.9 25494.0 25584.2 25854.0 04:20:04 INFO - PROCESS | 2678 | 04:20:04 INFO - PROCESS | 2678 | [#2] jquery.html Cycles:180 Average:25734.02 Median:5285.63 stddev:66659.80 (1261.2%) stddev-sans-first:66819.54 04:20:04 INFO - PROCESS | 2678 | Values: 344.7 365.5 367.0 368.8 381.1 12257.9 13133.5 13682.7 16977.2 17133.4 16186.7 16662.9 16932.4 16949.2 17633.4 4657.7 4807.0 4807.7 4811.1 4813.5 3572.0 5877.9 6069.9 6159.3 6254.9 5566.5 5932.3 6228.4 6242.1 6243.3 5566.7 5576.9 5583.7 5586.2 5588.6 5265.7 5305.5 5361.4 5458.8 5460.6 12321.1 12351.4 12391.3 12406.5 16421.7 1928.7 2005.6 2141.0 2264.4 2444.4 8765.7 9417.0 9627.0 14436.7 14612.5 1779.1 1822.2 1884.9 1900.1 1905.2 67485.0 68030.0 72740.6 80012.0 91145.5 16348.2 16748.3 16982.8 17078.5 17671.9 29966.7 30126.0 30127.3 30167.0 30194.7 9263.5 9459.7 9574.0 9756.1 9757.5 7103.4 7200.7 7212.8 7389.2 7480.5 8366.5 8445.8 8746.7 8924.4 8934.8 2608.4 3171.0 3405.9 3569.1 3636.3 734.3 791.3 803.4 856.8 873.4 16304.3 16763.8 17091.6 17306.0 17453.0 766.2 915.1 920.3 932.4 940.4 9468.0 9517.8 9751.5 9755.2 9962.1 399700.0 402854.0 404067.0 404456.0 405171.0 75528.2 77915.6 78678.6 81575.0 88689.6 927.9 953.3 1001.8 1002.0 1008.5 15909.9 16699.2 16752.1 16797.4 17067.9 37760.7 38899.4 38918.4 39504.0 39867.0 49331.5 50682.0 51125.5 55123.4 55962.5 7482.1 7505.4 7634.4 7640.4 7780.3 10943.0 11661.6 11663.4 11819.1 11985.1 9469.8 9764.3 9808.7 10117.7 10276.4 9914.0 10082.4 10306.0 10393.7 10473.6 9651.1 9683.0 9752.9 9842.4 9985.6 7393.1 7600.8 7664.8 7669.2 7729.1 7926.6 9091.6 9178.9 9232.8 9331.2 04:20:04 INFO - PROCESS | 2678 | 04:20:04 INFO - PROCESS | 2678 | [#3] mootools.html Cycles:180 Average:5172.61 Median:5187.27 stddev:2946.19 (56.8%) stddev-sans-first:2940.77 04:20:04 INFO - PROCESS | 2678 | Values: 1394.2 1430.9 1487.3 1656.8 1694.4 7476.8 7979.7 7986.2 7998.1 8001.5 6811.3 7532.7 7685.4 7731.8 7839.7 2126.7 2157.5 2159.6 2160.0 2164.0 2137.7 2139.9 2204.4 2205.6 2209.7 2171.6 2183.1 2188.0 2194.7 2196.4 2114.5 2122.0 2122.1 2123.0 2125.0 2086.6 2137.7 2143.0 2143.2 2144.7 8003.8 8052.4 8070.6 8101.7 8140.1 4579.3 4710.2 4711.5 4712.6 4714.0 2788.4 2822.4 2903.3 2913.7 2916.1 874.8 919.1 939.9 941.2 944.5 10859.1 10870.6 10891.2 10918.8 10925.9 3940.5 4090.2 4103.7 4244.3 4259.5 9314.4 9321.7 9325.5 9325.7 9327.7 4536.2 4545.1 4549.0 4551.6 4554.0 3704.3 4072.7 4130.7 4168.7 4222.2 3560.2 3590.7 3670.3 3686.7 3704.4 1164.4 1179.5 1182.4 1187.5 1194.5 784.2 833.2 845.1 848.3 873.6 4947.8 5168.4 5175.7 5198.8 5228.0 945.1 949.4 958.4 984.3 986.8 3587.7 3612.8 3613.7 3657.6 3768.5 9696.8 9895.2 10004.3 10009.9 10032.7 9917.8 9923.5 9928.5 9928.7 9939.4 6762.5 6997.9 7009.3 7475.6 7655.3 5426.6 5490.5 5501.3 5506.2 5554.4 8955.4 9144.7 9274.1 9295.7 9320.0 9096.1 9265.7 9373.8 9403.4 9414.3 5824.9 6459.6 6591.7 6759.8 6786.7 7479.0 7723.6 7724.9 7730.1 7746.4 6427.6 6472.8 6518.3 6708.9 6709.5 6632.4 6650.3 6673.1 6673.4 6693.3 6054.3 6140.2 6141.7 6164.7 6171.4 4427.8 4556.5 4587.6 4601.7 4609.0 4372.1 4444.4 4459.1 4483.1 4502.5 04:20:04 INFO - PROCESS | 2678 | 04:20:04 INFO - PROCESS | 2678 | [#4] prototype.html Cycles:180 Average:4191.36 Median:4790.98 stddev:1209.63 (25.2%) stddev-sans-first:1177.09 04:20:04 INFO - PROCESS | 2678 | Values: 292.5 295.7 296.9 299.1 303.6 4826.9 4832.5 4835.9 4846.4 4850.8 4770.2 4777.6 4780.5 4782.2 4791.4 3567.5 3674.3 3711.3 3780.1 3783.4 3780.3 3782.5 3810.2 3811.4 3822.2 3748.4 3758.8 3764.8 3772.0 3780.2 3506.1 3634.6 3758.9 3776.6 3794.3 3333.3 3342.8 3360.1 3370.9 3379.7 4714.1 4715.6 4728.8 4732.9 4736.3 4213.8 4309.6 4310.4 4326.4 4327.0 4926.7 4959.8 4967.2 4994.6 5008.3 3623.2 3625.3 3629.1 3648.8 3682.9 5028.5 5238.8 5255.9 5259.7 5281.9 3745.6 3752.6 3754.8 3757.0 3768.5 3812.9 3838.8 3841.1 3841.5 3843.4 4323.6 4331.5 4332.9 4343.6 4345.5 4790.5 5025.8 5041.2 5053.7 5075.5 1272.3 1283.5 1287.8 1288.5 1298.0 773.7 801.8 823.3 835.4 842.7 4347.8 4438.9 4451.1 4460.5 4467.8 5136.0 5160.5 5172.8 5189.7 5198.3 4426.5 4451.9 4454.2 4465.0 4481.2 2588.9 2723.8 2751.6 2751.8 2762.9 4863.5 4864.7 4872.1 4876.8 4879.4 5044.9 5293.2 5308.7 5311.3 5323.2 4841.3 4869.9 4897.5 4899.9 4921.6 5198.4 5216.9 5218.3 5222.0 5223.0 4951.0 5114.4 5131.7 5157.1 5166.4 4896.7 4911.0 4914.3 4917.2 4920.4 3776.8 3801.6 3814.8 3824.7 3845.3 5080.0 5132.5 5235.3 5240.3 5255.9 4952.8 4976.3 4977.5 4978.8 4996.3 4961.4 4991.1 4992.7 5046.1 5048.1 4743.6 4885.2 4887.5 4892.7 4896.9 4842.0 4916.5 4997.4 5013.1 5025.2 4924.8 4944.3 4947.7 4962.6 4969.6 04:20:04 INFO - PROCESS | 2678 | 04:20:04 INFO - PROCESS | 2678 | [#5] yui.html Cycles:180 Average:17185.79 Median:466.71 stddev:24471.83 (5243.4%) stddev-sans-first:24508.72 04:20:04 INFO - PROCESS | 2678 | Values: 580.4 596.3 662.3 676.3 718.9 11666.4 12412.4 12549.1 12560.2 12580.9 12561.3 12561.4 12714.7 12752.7 12762.8 744.4 775.2 784.0 785.0 785.5 626.5 741.5 774.8 778.2 782.5 825.7 827.4 832.4 832.8 833.8 819.2 821.2 821.5 821.5 822.0 481.3 580.1 592.9 593.7 601.8 12947.4 12957.2 12960.6 12967.4 12990.3 12464.5 12828.9 12889.8 12974.0 13527.3 11425.8 11506.3 11551.4 11605.5 11692.9 4035.1 4097.8 4099.9 4107.4 4123.2 60026.7 60564.7 61281.4 61698.4 61828.7 9279.4 9350.3 9728.4 9828.5 10081.4 17206.0 17356.4 17399.6 17450.6 17550.3 6461.1 6488.7 6688.9 6702.2 6713.3 3368.2 3522.5 3535.9 3561.3 3586.9 2820.3 2889.8 2907.1 2921.2 2925.3 488.2 492.1 495.8 503.8 511.5 377.0 435.4 454.7 464.2 469.3 6674.5 6714.4 6880.9 7041.1 7145.2 400.5 528.9 530.6 540.6 546.5 3205.1 3250.8 3273.2 3316.0 3559.3 93207.0 93617.5 96165.5 96259.5 97319.0 79891.0 80553.6 81247.7 82555.0 85037.0 45982.3 62788.7 72719.0 74024.0 74791.3 8089.9 8456.8 8485.6 8626.6 8641.1 42004.8 42119.6 42691.4 42967.8 44009.6 59202.4 61624.7 63560.7 64002.4 66863.7 10664.3 10830.9 11145.6 11216.6 11349.0 11612.7 11904.0 11999.9 12122.8 12552.6 10631.8 10733.8 10988.6 11045.9 11573.1 11457.9 11590.5 11654.9 11916.7 12789.7 11325.5 11445.1 11661.5 11947.4 11977.2 9612.4 10124.8 10135.6 10459.6 10592.1 9290.6 9612.2 9713.0 9720.3 9720.9 04:20:04 INFO - PROCESS | 2678 | -------- Summary: end -------- 04:20:04 INFO - PROCESS | 2678 | 04:20:05 INFO - TEST-INFO | 2678: exit 0 04:20:05 INFO - TEST-OK | dromaeo_css | took 1119434ms 04:20:05 INFO - TEST-START | kraken 04:20:05 INFO - operating with platform_type : linux_ 04:20:05 INFO - Initialising browser for kraken test... 04:20:05 INFO - TEST-INFO | started process 2978 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplkv74G/profile http://localhost:54768/getInfo.html) 04:20:09 INFO - PROCESS | 2978 | JavaScript error: , line 0: uncaught exception: undefined 04:20:10 INFO - PROCESS | 2978 | __metrics Screen width/height:1600/1200 04:20:10 INFO - PROCESS | 2978 | colorDepth:24 04:20:10 INFO - PROCESS | 2978 | Browser inner width/height: 1024/697 04:20:10 INFO - PROCESS | 2978 | __metrics 04:20:11 INFO - PROCESS | 2978 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:20:11 INFO - PROCESS | 2978 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:20:12 INFO - TEST-INFO | 2978: exit 0 04:20:12 INFO - Browser initialized. 04:20:12 INFO - Running cycle 1/1 for kraken test... 04:20:12 INFO - TEST-INFO | started process 3062 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplkv74G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:20:24 INFO - PROCESS | 3062 | RSS: Main: 159100928 04:20:24 INFO - PROCESS | 3062 | 04:20:26 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54768/tests/kraken/driver.html?testName=audio-beat-detection) 04:20:27 INFO - PROCESS | 3062 | RSS: Main: 215130112 04:20:27 INFO - PROCESS | 3062 | 04:20:30 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54768/tests/kraken/driver.html?testName=audio-dft) 04:20:31 INFO - PROCESS | 3062 | RSS: Main: 192602112 04:20:31 INFO - PROCESS | 3062 | 04:20:34 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54768/tests/kraken/driver.html?testName=audio-fft) 04:20:35 INFO - PROCESS | 3062 | RSS: Main: 272453632 04:20:35 INFO - PROCESS | 3062 | 04:20:37 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54768/tests/kraken/driver.html?testName=audio-oscillator) 04:20:37 INFO - PROCESS | 3062 | RSS: Main: 182050816 04:20:37 INFO - PROCESS | 3062 | 04:20:40 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54768/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:20:40 INFO - PROCESS | 3062 | RSS: Main: 186413056 04:20:40 INFO - PROCESS | 3062 | 04:20:43 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54768/tests/kraken/driver.html?testName=imaging-darkroom) 04:20:44 INFO - PROCESS | 3062 | RSS: Main: 222810112 04:20:44 INFO - PROCESS | 3062 | 04:20:47 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54768/tests/kraken/driver.html?testName=imaging-desaturate) 04:20:48 INFO - PROCESS | 3062 | RSS: Main: 216948736 04:20:48 INFO - PROCESS | 3062 | 04:20:51 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54768/tests/kraken/driver.html?testName=json-parse-financial) 04:20:52 INFO - PROCESS | 3062 | RSS: Main: 220282880 04:20:52 INFO - PROCESS | 3062 | 04:20:53 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54768/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:20:54 INFO - PROCESS | 3062 | RSS: Main: 186187776 04:20:54 INFO - PROCESS | 3062 | 04:20:56 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54768/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:20:56 INFO - PROCESS | 3062 | RSS: Main: 192589824 04:20:56 INFO - PROCESS | 3062 | 04:20:59 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54768/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:20:59 INFO - PROCESS | 3062 | RSS: Main: 195584000 04:20:59 INFO - PROCESS | 3062 | 04:21:02 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:21:03 INFO - PROCESS | 3062 | RSS: Main: 191074304 04:21:03 INFO - PROCESS | 3062 | 04:21:05 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:21:06 INFO - PROCESS | 3062 | RSS: Main: 194146304 04:21:06 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | Cycle 1(1): loaded http://localhost:54768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54768/tests/kraken/driver.html?testName=ai-astar) 04:21:08 INFO - PROCESS | 3062 | RSS: Main: 245055488 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | __start_tp_report 04:21:08 INFO - PROCESS | 3062 | _x_x_mozilla_page_load 04:21:08 INFO - PROCESS | 3062 | _x_x_mozilla_page_load_details 04:21:08 INFO - PROCESS | 3062 | |i|pagename|runs| 04:21:08 INFO - PROCESS | 3062 | |0;ai-astar;104;115;115;113;113;115;113;113;113;115 04:21:08 INFO - PROCESS | 3062 | |1;audio-beat-detection;147;121;124;178;123;127;148;123;162;122 04:21:08 INFO - PROCESS | 3062 | |2;audio-dft;162;161;160;161;161;160;161;160;161;163 04:21:08 INFO - PROCESS | 3062 | |3;audio-fft;75;76;76;125;77;76;84;75;104;75 04:21:08 INFO - PROCESS | 3062 | |4;audio-oscillator;87;86;126;86;86;85;121;86;85;132 04:21:08 INFO - PROCESS | 3062 | |5;imaging-gaussian-blur;117;117;117;117;118;117;117;116;116;117 04:21:08 INFO - PROCESS | 3062 | |6;imaging-darkroom;146;147;147;146;146;146;146;147;146;145 04:21:08 INFO - PROCESS | 3062 | |7;imaging-desaturate;96;96;95;95;96;95;96;95;95;96 04:21:08 INFO - PROCESS | 3062 | |8;json-parse-financial;63;63;63;63;61;63;64;64;62;61 04:21:08 INFO - PROCESS | 3062 | |9;json-stringify-tinderbox;66;66;66;66;65;65;66;66;66;66 04:21:08 INFO - PROCESS | 3062 | |10;stanford-crypto-aes;83;79;82;80;80;79;82;78;78;83 04:21:08 INFO - PROCESS | 3062 | |11;stanford-crypto-ccm;133;126;132;126;138;126;135;137;177;132 04:21:08 INFO - PROCESS | 3062 | |12;stanford-crypto-pbkdf2;162;166;161;162;163;163;161;167;161;162 04:21:08 INFO - PROCESS | 3062 | |13;stanford-crypto-sha256-iterative;67;67;69;67;67;66;66;67;66;64 04:21:08 INFO - PROCESS | 3062 | __end_tp_report 04:21:08 INFO - PROCESS | 3062 | __start_cc_report 04:21:08 INFO - PROCESS | 3062 | _x_x_mozilla_cycle_collect,1961 04:21:08 INFO - PROCESS | 3062 | __end_cc_report 04:21:08 INFO - PROCESS | 3062 | __startTimestamp1469445668568__endTimestamp 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | ------- Summary: start ------- 04:21:08 INFO - PROCESS | 3062 | Number of tests: 14 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.00 stddev:3.28 (2.9%) stddev-sans-first:1.05 04:21:08 INFO - PROCESS | 3062 | Values: 104.0 115.0 115.0 113.0 113.0 115.0 113.0 113.0 113.0 115.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:137.00 stddev:20.18 (14.7%) stddev-sans-first:21.11 04:21:08 INFO - PROCESS | 3062 | Values: 147.0 121.0 124.0 178.0 123.0 127.0 148.0 123.0 162.0 122.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:0.93 04:21:08 INFO - PROCESS | 3062 | Values: 162.0 161.0 160.0 161.0 161.0 160.0 161.0 160.0 161.0 163.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#3] audio-fft Cycles:10 Average:84.30 Median:76.00 stddev:16.88 (22.2%) stddev-sans-first:17.56 04:21:08 INFO - PROCESS | 3062 | Values: 75.0 76.0 76.0 125.0 77.0 76.0 84.0 75.0 104.0 75.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:86.00 stddev:19.73 (22.9%) stddev-sans-first:20.52 04:21:08 INFO - PROCESS | 3062 | Values: 87.0 86.0 126.0 86.0 86.0 85.0 121.0 86.0 85.0 132.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 04:21:08 INFO - PROCESS | 3062 | Values: 117.0 117.0 117.0 117.0 118.0 117.0 117.0 116.0 116.0 117.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 04:21:08 INFO - PROCESS | 3062 | Values: 146.0 147.0 147.0 146.0 146.0 146.0 146.0 147.0 146.0 145.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 04:21:08 INFO - PROCESS | 3062 | Values: 96.0 96.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:63.00 stddev:1.06 (1.7%) stddev-sans-first:1.12 04:21:08 INFO - PROCESS | 3062 | Values: 63.0 63.0 63.0 63.0 61.0 63.0 64.0 64.0 62.0 61.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 04:21:08 INFO - PROCESS | 3062 | Values: 66.0 66.0 66.0 66.0 65.0 65.0 66.0 66.0 66.0 66.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:81.00 stddev:1.96 (2.4%) stddev-sans-first:1.83 04:21:08 INFO - PROCESS | 3062 | Values: 83.0 79.0 82.0 80.0 80.0 79.0 82.0 78.0 78.0 83.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#11] stanford-crypto-ccm Cycles:10 Average:136.20 Median:134.00 stddev:15.01 (11.2%) stddev-sans-first:15.88 04:21:08 INFO - PROCESS | 3062 | Values: 133.0 126.0 132.0 126.0 138.0 126.0 135.0 137.0 177.0 132.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.80 Median:162.50 stddev:2.10 (1.3%) stddev-sans-first:2.20 04:21:08 INFO - PROCESS | 3062 | Values: 162.0 166.0 161.0 162.0 163.0 163.0 161.0 167.0 161.0 162.0 04:21:08 INFO - PROCESS | 3062 | 04:21:08 INFO - PROCESS | 3062 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.26 (1.9%) stddev-sans-first:1.33 04:21:08 INFO - PROCESS | 3062 | Values: 67.0 67.0 69.0 67.0 67.0 66.0 66.0 67.0 66.0 64.0 04:21:08 INFO - PROCESS | 3062 | -------- Summary: end -------- 04:21:08 INFO - PROCESS | 3062 | 04:21:09 INFO - TEST-INFO | 3062: exit 0 04:21:09 INFO - TEST-OK | kraken | took 63862ms 04:21:09 INFO - SUITE-END | took 1183s 04:21:09 INFO - Completed test suite (00:19:43) 04:21:09 INFO - javascript benchmark 04:21:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2596.8961241550337, 2610.151700697274, 2637.8812953417096, 2645.0874448315326, 2650.7084220735715, 4609.262517997121, 4681.0, 4705.623550115991, 4753.025629745903, 4768.761561921903, 4666.53334666534, 4684.391029166203, 4731.952680473194, 4741.0, 4750.049990001996, 4258.7870606469705, 4301.741895486265, 4325.870223893288, 4397.73613583186, 4526.864194074183, 4434.024514606797, 4473.127740090684, 4479.664025198123, 4489.865304040868, 4505.166544189319, 4269.765162916038, 4314.1371725655, 4327.06968001878, 4364.672649551265, 4398.582134697199, 4324.178406102862, 4335.588119128678, 4343.717658352229, 4357.063231405232, 4381.671374646914, 4319.0713996490595, 4370.519242883281, 4381.211381951248, 4383.605475507188, 4421.204183247047, 4604.056168485486, 4709.952900470986, 4763.618910487153, 4822.927656085161, 4826.0347930414055, 5021.899562008776, 5090.694558326475, 5110.6422550421485, 5183.922241166386, 5184.0, 4992.40091189056, 5019.774110165052, 5041.017001684673, 5050.520200580939, 5071.2139618359215, 4605.424321959755, 4652.883677908079, 4729.858104256878, 4734.502877197879, 4740.834070807505, 5065.341505604321, 5073.340465739504, 5119.257707632447, 5129.948700512947, 5143.871403214949, 4883.145449546315, 4886.560209581155, 4889.168841296988, 4923.704577725348, 4941.110600092018, 4914.70511769295, 4965.155923493015, 4992.051510213048, 5028.899422011613, 5031.974840125776, 4982.327385802942, 5038.64729468941, 5075.061113693904, 5077.416097148801, 5082.161443361804, 4988.925166122511, 4993.300937868701, 5000.924986125211, 5003.0, 5051.6716413433005, 4270.594293542109, 4271.188474189894, 4274.230638485041, 4275.87172384829, 4316.050468896838, 3671.394219953731, 3698.9630103699155, 3700.555933287969, 3749.6625303722794, 3784.129650180474, 4348.217320882209, 4373.2128216921255, 4375.190589740908, 4389.078293558388, 4416.911661766747, 5074.188129899198, 5077.035363280965, 5081.9745901271, 5082.847514574569, 5121.667091639031, 4262.50981137167, 4318.050028993679, 4348.412964249849, 4365.695135070828, 4369.3227549729845, 4557.726536407826, 4572.0855828834365, 4572.908541829145, 4610.4697959734385, 4619.0530941156985, 4760.547747963938, 4771.761411929389, 4772.0455908817685, 4773.689710168828, 4818.71087734737, 4954.900901981868, 5015.799368025384, 5033.84898453047, 5076.340075790124, 5078.136716758234, 4765.785539650793, 4788.329633851194, 4791.425028996543, 4821.566059054561, 4835.274708793709, 4951.3810773653295, 4985.651004429655, 4988.650794444499, 4995.300657907823, 5037.899242015213, 4916.360873086476, 5004.6246531511015, 5040.395152581567, 5045.31888195089, 5062.265971434048, 4921.458639549575, 4922.950770492249, 4940.901181976412, 4946.258061290691, 4955.628327875496, 4956.603471722326, 4959.851204463872, 4960.70235785854, 4964.0, 4994.225894986283, 4700.412448443944, 4770.045990801784, 4782.067496838084, 4826.251930950708, 4868.464468908488, 4951.876203094807, 4955.380577427822, 4967.776450059829, 4976.328195693535, 4983.327250821093, 4862.246351815475, 4866.099771542276, 4870.269459581091, 4871.585915197248, 4894.730789806595], "value": 4639.628802461673, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.6125411308954, 581.8167277307648, 582.2954225387278, 589.5283772981613, 592.6858764854628, 39485.80257098714, 40236.39645405321, 40307.0, 40355.59644403555, 44236.33645495312, 40222.39666404999, 40636.0, 40668.389974150414, 40713.18573628525, 40753.0, 7468.962655186717, 7639.541627502367, 7823.256790604912, 7823.687052517893, 9808.803823923517, 9631.662891798753, 9929.553170107327, 9950.25373097017, 9970.052844979751, 9983.051610097029, 10044.096031357212, 10084.193264538819, 10095.0, 10098.495075246208, 10108.039736225046, 10021.549030293654, 10057.044580764816, 10066.848997265048, 10073.345232559934, 10075.697729068139, 9864.161546268575, 9885.406875587416, 9902.851457228147, 9919.355241909252, 9923.652672156511, 38794.22411551781, 39131.0, 39131.8043409784, 39140.41289380633, 39210.41184382237, 2978.7021297870256, 3005.549167624852, 3043.193553708269, 3079.7844150909445, 3319.5684561007156, 29575.408491830258, 29921.40157196844, 30153.547696784564, 30179.245518861986, 31031.379372412426, 3435.6736110069755, 3441.5181874537584, 3446.8965931022108, 3490.7730997485082, 3539.592946811124, 246837.0, 247189.0, 247552.0, 247585.9999999982, 248003.0, 54187.0, 54475.72762136164, 54538.18192727112, 54573.0, 54701.45298546964, 33108.834455827564, 33930.32139357199, 57383.713081434325, 57415.13877291844, 57644.71177644169, 15115.244237788067, 15528.844711552965, 15559.533214003599, 15570.610734731723, 15572.454964076203, 11001.0, 11229.71925701864, 11242.0, 11463.598774042868, 11809.822852657217, 18320.267189312544, 18917.810821891606, 18982.905085474762, 19350.806491935182, 19374.70937935932, 6825.35159159879, 6888.41448476885, 6983.371496565232, 6996.720131194696, 7028.613426261605, 813.9674413023531, 818.2553875972956, 930.6463543853292, 934.8550974598948, 977.7360112769512, 27370.863145684143, 28900.42199156005, 29007.85496072506, 29071.709282907323, 29255.268618284714, 908.9681861134828, 943.8725772020689, 952.5618215620892, 999.7150812018685, 1059.2638116508951, 14886.531328520745, 15252.237388130548, 15272.160031198391, 15431.537053888402, 15498.690026199638, 375543.0, 378336.0, 378875.0, 378936.0, 379422.0, 48719.5128048722, 49262.26106608404, 49303.0, 49311.0, 49340.0, 1066.0778426660881, 1121.5121422181378, 1177.8056620657496, 1195.7668254690252, 1199.4962115911337, 25862.482750345265, 27182.864085679445, 30158.396832063674, 30388.84805575958, 30652.233694157825, 47656.5234347666, 49398.753006234736, 49478.50521494739, 53948.46051539591, 54009.45990540044, 350027.0, 356005.0, 356260.0, 356623.0, 356655.0, 38344.80827595844, 39345.80327098461, 40120.0, 41971.58028419677, 42141.78929105335, 39254.41118383284, 41558.0, 42806.78596606997, 42813.0, 42931.570684292754, 36024.0, 36219.27561448809, 36804.26391472209, 37122.628773713, 38125.237495249385, 36493.635063649024, 36834.63165368312, 36940.26119477649, 37412.0, 37504.0, 35701.64298356983, 35807.0, 36397.636023639425, 36626.08434789152, 37131.628683712814, 31484.370312594077, 33203.66796332102, 33509.16227094245, 33633.83183084069, 33743.3251334967, 24988.50022999566, 25413.87293063523, 25493.98024079016, 25584.184540348004, 25854.0], "value": 18535.42213790421, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.6742828027515, 365.5394203303836, 366.98348574314156, 368.78426120719377, 381.1044046490747, 12257.938710306447, 13133.47466101356, 13682.657933551654, 16977.2360243789, 17133.400330988417, 16186.676266474693, 16662.916685416556, 16932.4073657422, 16949.152542372893, 17633.382831600895, 4657.720536767788, 4806.966502202026, 4807.711537307755, 4811.061842940628, 4813.518648135194, 3572.0, 5877.9118313225335, 6069.939300607004, 6159.260888693363, 6254.906176407358, 5566.526845218162, 5932.3474417814, 6228.408301211378, 6242.063690446424, 6243.313235544087, 5566.666000039991, 5576.888462230732, 5583.720813959286, 5586.24585680932, 5588.580856435743, 5265.710385928773, 5305.549028332596, 5361.4102448731, 5458.836234912959, 5460.6177567570285, 12321.075919305998, 12351.38243087842, 12391.318477483735, 12406.503739850396, 16421.671566568722, 1928.7492625958675, 2005.6189324028535, 2140.9830330593, 2264.445210923318, 2444.401121725171, 8765.693200737944, 9417.0, 9627.02645957577, 14436.711265774731, 14612.488562900247, 1779.065990355061, 1822.1916195152196, 1884.9255924123256, 1900.0974537094908, 1905.2188602672977, 67485.0, 68030.0, 72740.63629681818, 80012.0, 91145.54427227822, 16348.182590870409, 16748.330066797193, 16982.830171698373, 17078.48764537066, 17671.911640441715, 29966.70033299639, 30126.0, 30127.288544030704, 30167.0, 30194.698053019627, 9263.536823158815, 9459.716208513755, 9574.04259574037, 9756.07317304855, 9757.512124393894, 7103.431725461951, 7200.711971521186, 7212.819679508054, 7389.224131466226, 7480.513766605153, 8366.456180348257, 8445.831083378298, 8746.737597872074, 8924.419912705653, 8934.776630584158, 2608.4391855751105, 3171.0011346425804, 3405.9318813624086, 3569.1255642367787, 3636.29092326999, 734.328089797829, 791.3392317415023, 803.445622520459, 856.7986523167051, 873.3929918706438, 16304.347826086823, 16763.832361676228, 17091.572710682332, 17306.0, 17453.040082795567, 766.1801871997021, 915.1306259053925, 920.300571565615, 932.4451951089092, 940.4451373689556, 9468.005859384803, 9517.762055948657, 9751.463669498104, 9755.219582433388, 9962.107199632046, 399700.0, 402854.0, 404067.0, 404456.0, 405171.0, 75528.24471755212, 77915.61042194752, 78678.6066069666, 81575.0, 88689.55655221683, 927.893292271384, 953.2802733935972, 1001.7595777013464, 1001.9749506262402, 1008.4755926917961, 15909.920450397674, 16699.248533815764, 16752.078635675156, 16797.41209057677, 17067.914660427115, 37760.6783762567, 38899.41650875183, 38918.4162237561, 39504.0, 39867.0, 49331.506684934124, 50682.0, 51125.488745112074, 55123.44876551183, 55962.48168832331, 7482.1021477423055, 7505.362044226302, 7634.389248860189, 7640.426967977312, 7780.260875216845, 10942.977856350917, 11661.59184428575, 11663.358515281741, 11819.113566482376, 11985.101117416054, 9469.810603788024, 9764.267679923893, 9808.65669701557, 10117.696469105938, 10276.383416995004, 9913.950430248091, 10082.395056296646, 10306.0, 10393.740156496147, 10473.633422830164, 9651.131398174199, 9682.951585242028, 9752.9024709752, 9842.360246583949, 9985.550650220612, 7393.1497877742595, 7600.771976840703, 7664.80837979055, 7669.23307669251, 7729.111152217567, 7926.643301051351, 9091.59087841062, 9178.862317065115, 9232.769180770534, 9331.206847418045], "value": 8908.684522845897, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1394.2331717555346, 1430.8569143085692, 1487.2712370938243, 1656.8343165683425, 1694.381550733981, 7476.813079673011, 7979.680812767489, 7986.241307075833, 7998.080220774613, 8001.519908805476, 6811.318868113186, 7532.661030253638, 7685.423593230516, 7731.845363092735, 7839.725609603665, 2126.691629713687, 2157.46063484129, 2159.589677961182, 2160.027987405666, 2163.978360216401, 2137.711408959794, 2139.935801925945, 2204.4048107011176, 2205.63607004844, 2209.690643309938, 2171.6416791229426, 2183.0721943174167, 2188.0, 2194.703714998471, 2196.417949243452, 2114.45024293684, 2121.968170477444, 2122.0768965500056, 2122.989385053065, 2125.0118694806833, 2086.613976414368, 2137.722096127509, 2142.9785702142935, 2143.249862548111, 2144.6782982552586, 8003.759887203393, 8052.396070294752, 8070.596470176526, 8101.716439924633, 8140.145284745135, 4579.335996280521, 4710.152172608929, 4711.458182309044, 4712.6229901607785, 4713.952860471386, 2788.442311537701, 2822.435512897408, 2903.2886942698965, 2913.6940621234676, 2916.1336744483337, 874.8381549413378, 919.0533750237266, 939.915407613318, 941.223490620241, 944.5182956692067, 10859.13126949842, 10870.565177392895, 10891.237613367068, 10918.836217456665, 10925.945370273177, 3940.5074365704286, 4090.1615168890307, 4103.671706263492, 4244.2996905510245, 4259.5101563319995, 9314.44113353201, 9321.673741419017, 9325.487098209664, 9325.673601423918, 9327.720168394959, 4536.206163921301, 4545.113702827983, 4548.95451045492, 4551.635869130461, 4553.954460455353, 3704.3332200204222, 4072.714909956334, 4130.731502452331, 4168.6665066794585, 4222.239996800606, 3560.1989552351033, 3590.6588874056924, 3670.3209906166912, 3686.6866316363407, 3704.3517384457614, 1164.371239530644, 1179.5222934711455, 1182.3910685996718, 1187.5309252845077, 1194.50428072349, 784.1844481738927, 833.2417500074903, 845.0830848529414, 848.2832006954237, 873.6156091319799, 4947.777350019258, 5168.405633352138, 5175.713421976996, 5198.818041368534, 5227.980543793924, 945.0879900895669, 949.4066208619613, 958.434523631061, 984.2667212926416, 986.7681094942683, 3587.7488575799443, 3612.7832330060282, 3613.747037707335, 3657.579378371468, 3768.4535742317757, 9696.75758106053, 9895.158911492459, 10004.299699021, 10009.949950250348, 10032.749181270381, 9917.768502086972, 9923.454210018519, 9928.503574821374, 9928.702138935843, 9939.353941993748, 6762.459003279824, 6997.895031574632, 7009.264027277164, 7475.588842613708, 7655.311022007934, 5426.565874730091, 5490.505854472957, 5501.257330260363, 5506.229127922014, 5554.4445555445745, 8955.373123881527, 9144.679936202201, 9274.072592740671, 9295.67465138717, 9320.0, 9096.135867092615, 9265.67570135069, 9373.765655858659, 9403.388779729295, 9414.340996130206, 5824.94175058244, 6459.580127291711, 6591.736330546916, 6759.763408280686, 6786.69459874297, 7479.027726395534, 7723.613819309125, 7724.884126737991, 7730.072391313079, 7746.419018573517, 6427.646479443675, 6472.773452929125, 6518.348165183634, 6708.899366509604, 6709.530332876652, 6632.436242919406, 6650.301718319701, 6673.099131617169, 6673.432758215412, 6693.26374098839, 6054.273487181566, 6140.232470941132, 6141.723622436912, 6164.660943648142, 6171.352008039271, 4427.8228870844805, 4556.476005259437, 4587.63298936091, 4601.746903920316, 4608.953910460987, 4372.125574885099, 4444.444444444444, 4459.130469558405, 4483.103379324213, 4502.5497450256025], "value": 4159.797240829257, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.48522600223606, 295.70872690399966, 296.8515742128936, 299.0534956861533, 303.5840897969781, 4826.855194344175, 4832.540908613681, 4835.854924352267, 4846.369971903657, 4850.830220942264, 4770.236762118062, 4777.641676874239, 4780.466352832996, 4782.210935195689, 4791.425028996517, 3567.482715006316, 3674.3386190485703, 3711.257748450307, 3780.111673756665, 3783.375743002401, 3780.281746468176, 3782.5460944686674, 3810.218905124457, 3811.4473401356786, 3822.2355528894195, 3748.4377343398437, 3758.774473531583, 3764.849406023756, 3771.962280377202, 3780.2250538639514, 3506.140995456104, 3634.5638523377365, 3758.8872333830027, 3776.603456637041, 3794.260119276742, 3333.3499967506345, 3342.782719123249, 3360.1095709636966, 3370.9494357584654, 3379.746519011084, 4714.057188562301, 4715.646326525524, 4728.83449079284, 4732.935089604845, 4736.2895565665085, 4213.810378532943, 4309.5690430956965, 4310.4396428464415, 4326.420856387427, 4326.978365108186, 4926.655134140611, 4959.7768100435205, 4967.205247160437, 4994.551580041783, 5008.273800298973, 3623.2210074833774, 3625.329314076904, 3629.1471504196493, 3648.7628304160144, 3682.852685892561, 5028.547430731252, 5238.816641417532, 5255.921161182585, 5259.710715910624, 5281.9207711884355, 3745.6254374562327, 3752.574021871726, 3754.849806007729, 3756.962430375716, 3768.491253680718, 3812.8856134316015, 3838.7696738195796, 3841.1357444574746, 3841.4621952926614, 3843.423486477051, 4323.567643235651, 4331.45856767904, 4332.935005974913, 4343.566623014398, 4345.500267469219, 4790.5448982346625, 5025.849224523197, 5041.21861111521, 5053.722045287471, 5075.543201111917, 1272.287518989369, 1283.5058502476613, 1287.7682017236987, 1288.549007847246, 1297.9935100324437, 773.6943907156642, 801.7714951238869, 823.283743143469, 835.4444294544181, 842.7176895739996, 4347.760873151944, 4438.933415998763, 4451.06527629195, 4460.50934397216, 4467.776611169494, 5136.0, 5160.535551800356, 5172.818951336685, 5189.740512974412, 5198.324217851655, 4426.490953540319, 4451.931536431298, 4454.198244316054, 4465.0, 4481.215787237285, 2588.935276618062, 2723.8365698057783, 2751.6010178524, 2751.830472049371, 2762.875670594828, 4863.489333619919, 4864.70811751289, 4872.14737420957, 4876.756162191876, 4879.39007624047, 5044.949550504448, 5293.153095504701, 5308.654937429054, 5311.28297679816, 5323.1749078892835, 4841.273808928689, 4869.9269510957365, 4897.4857639948, 4899.877503062452, 4921.557059864629, 5198.376194856642, 5216.895662086661, 5218.269442278008, 5221.955608878164, 5222.97388513055, 4950.950490495049, 5114.411842638145, 5131.692098474104, 5157.149070403491, 5166.354205724285, 4896.74366546257, 4910.950890491049, 4914.2628605708, 4917.237828136645, 4920.384951881015, 3776.792276424823, 3801.6388443097862, 3814.790186539767, 3824.694024478091, 3845.3462911305705, 5080.0, 5132.538071573576, 5235.267062611161, 5240.292560504436, 5255.89488210226, 4952.780692549872, 4976.328195693535, 4977.502249774993, 4978.825741099189, 4996.2505624156665, 4961.42943561495, 4991.051700176955, 4992.700437973734, 5046.066477701637, 5048.091343558048, 4743.573078422958, 4885.2427873679635, 4887.509309660588, 4892.65751397413, 4896.902061958812, 4842.0, 4916.459189489225, 4997.375328083989, 5013.122703526941, 5025.195968644999, 4924.8276310328965, 4944.332515110414, 4947.703137811743, 4962.627802914868, 4969.552740253394], "value": 3802.1131052767237, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.4340767751443, 596.3499785234095, 662.2582508415159, 676.3067855448165, 718.9137303523578, 11666.358350290731, 12412.441440135193, 12549.121561490689, 12560.246385216893, 12580.937095314523, 12561.309127997956, 12561.434735436904, 12714.682132946658, 12752.681182970407, 12762.80855787164, 744.4453881858024, 775.1744392222265, 784.0, 784.9725259615915, 785.5286827903269, 626.4675026227693, 741.5291290030842, 774.7869335932608, 778.1790211327047, 782.5109306683323, 825.7316372179035, 827.4042689263721, 832.4381042796118, 832.8084540555676, 833.7582101190648, 819.1521774962914, 821.195228675895, 821.4619424277108, 821.5399376349263, 821.9589020548949, 481.33816002995883, 580.0979476913398, 592.925884264467, 593.6734795862259, 601.8284788835161, 12947.352632368438, 12957.22256664603, 12960.611181664472, 12967.416466259041, 12990.28553429561, 12464.501419943192, 12828.871711282862, 12889.806652900214, 12974.0, 13527.32363381827, 11425.771484570261, 11506.309621422657, 11551.364674942875, 11605.477753501029, 11692.94153529227, 4035.1324465239823, 4097.7541347519245, 4099.9180016399805, 4107.445494858186, 4123.175364926997, 60026.69986650082, 60564.69717651427, 61281.38718612846, 61698.38301617016, 61828.69085654588, 9279.350445468752, 9350.251979841596, 9728.367656102331, 9828.508574571386, 10081.445520496296, 17206.0, 17356.39252626152, 17399.565010874576, 17450.56373590645, 17550.29798808059, 6461.095446637474, 6488.67556622167, 6688.86622267552, 6702.229243637044, 6713.26154123046, 3368.1579605098727, 3522.5244591979754, 3535.8762443314877, 3561.252137051248, 3586.946195807065, 2820.323122450638, 2889.7832662550395, 2907.098799372159, 2921.2258751430886, 2925.3417980954114, 488.1896052552816, 492.1289317907284, 495.8066354121895, 503.8135889720827, 511.493621314903, 377.03291058435025, 435.3643680226904, 454.65219107382876, 464.1737706881756, 469.25154378765814, 6674.499412544079, 6714.429273511806, 6880.862382752417, 7041.119860017498, 7145.21402645719, 400.50737592761067, 528.9206619007102, 530.5967464726758, 540.5675459632279, 546.5026825588695, 3205.054508919874, 3250.800990587451, 3273.1980664737293, 3315.966840331614, 3559.305935342635, 93207.0, 93617.53191234, 96165.51917240368, 96259.51870240604, 97319.0, 79891.0, 80553.59723201346, 81247.65651949983, 82555.0, 85037.0, 45982.310265346714, 62788.68605656942, 72719.0, 74024.0, 74791.25208747952, 8089.9010373196425, 8456.83086338282, 8485.618147183515, 8626.611802468778, 8641.135886411561, 42004.78997604993, 42119.57880421157, 42691.3596296062, 42967.785161073996, 44009.55990440054, 59202.40797591969, 61624.69187654033, 63560.68219658872, 64002.35997639964, 66863.66568167128, 10664.253502254767, 10830.945845271039, 11145.609903653332, 11216.607418740305, 11349.035331996542, 11612.709682258012, 11904.0, 11999.940000299943, 12122.75754484923, 12552.623421297376, 10631.787364252827, 10733.785324293314, 10988.615398461016, 11045.944770276097, 11573.13201509907, 11457.942710286396, 11590.478428470908, 11654.941725291319, 11916.702082447662, 12789.74420511566, 11325.546978120783, 11445.141614378788, 11661.53353865836, 11947.402629868646, 11977.161598688008, 9612.423254604746, 10124.797504050026, 10135.645252416425, 10459.581616735246, 10592.099671527698, 9290.62837486493, 9612.231021518122, 9712.951435242778, 9720.319577629498, 9720.902790971999], "value": 6338.76612924314, "unit": "score"}], "name": "dromaeo_css", "value": 6519.922170697021, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 115.0, 113.0, 113.0, 115.0, 113.0, 113.0, 113.0, 115.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 121.0, 124.0, 178.0, 123.0, 127.0, 148.0, 123.0, 162.0, 122.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 161.0, 161.0, 160.0, 161.0, 160.0, 161.0, 163.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 125.0, 77.0, 76.0, 84.0, 75.0, 104.0, 75.0], "value": 84.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 126.0, 86.0, 86.0, 85.0, 121.0, 86.0, 85.0, 132.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 61.0, 63.0, 64.0, 64.0, 62.0, 61.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 79.0, 82.0, 80.0, 80.0, 79.0, 82.0, 78.0, 78.0, 83.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 126.0, 132.0, 126.0, 138.0, 126.0, 135.0, 137.0, 177.0, 132.0], "value": 136.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 166.0, 161.0, 162.0, 163.0, 163.0, 161.0, 167.0, 161.0, 162.0], "value": 162.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 67.0, 67.0, 66.0, 66.0, 67.0, 66.0, 64.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1526.8, "alertThreshold": 2.0}]} 04:21:09 INFO - Return code: 0 04:21:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:21:09 INFO - # TBPL SUCCESS # 04:21:09 INFO - Running post-action listener: _resource_record_post_action 04:21:09 INFO - [mozharness: 2016-07-25 11:21:09.899508Z] Finished run-tests step (success) 04:21:09 INFO - Running post-run listener: _resource_record_post_run 04:21:09 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 547643392; Read time: 20; Write time: 404864 04:21:09 INFO - TinderboxPrint: CPU usage
13.0% 04:21:09 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 04:21:09 INFO - TinderboxPrint: I/O write bytes / time
547,643,392 / 404,864 04:21:09 INFO - TinderboxPrint: CPU idle
8,334.7 (87.0%) 04:21:09 INFO - TinderboxPrint: CPU user
1,198.5 (12.5%) 04:21:09 INFO - TinderboxPrint: Swap in / out
0 / 0 04:21:09 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 246595584; Read time: 0; Write time: 234576 04:21:09 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288497664; Read time: 20; Write time: 169012 04:21:10 INFO - Running post-run listener: _upload_blobber_files 04:21:10 INFO - Blob upload gear active. 04:21:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:21:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:21:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:21:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:21:10 INFO - (blobuploader) - INFO - Open directory for files ... 04:21:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:21:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:21:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:11 INFO - (blobuploader) - INFO - Done attempting. 04:21:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:21:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:21:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:16 INFO - (blobuploader) - INFO - Done attempting. 04:21:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:21:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:21:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:21:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:21:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:21:17 INFO - (blobuploader) - INFO - Done attempting. 04:21:17 INFO - (blobuploader) - INFO - Iteration through files over. 04:21:17 INFO - Return code: 0 04:21:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:21:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:21:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fe18d8061c10e6f407f2e654b702b1de1587ac16276f71a430dff9c6c922a793398e6b9c97c573d32d2d38003deaf6cd8f4a67d0e19d09fce70b7218ec68088a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/603e2e7fd5fc4fe5ed17395a737173b76c80f25ead3104c5f34212cce918aaa70f908ad4778035a824061fc10216ddf80ef79527029ef99afe80252a6503f12a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d094b9bd9a26e6679f2a4bbe8df45c0447ebbb9f94eff2d839e258208beb63ed3b5e49f9308756e178075a550e30d1f2da73cdcc487310773cc7105e496d1679"} 04:21:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:21:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:21:17 INFO - Contents: 04:21:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fe18d8061c10e6f407f2e654b702b1de1587ac16276f71a430dff9c6c922a793398e6b9c97c573d32d2d38003deaf6cd8f4a67d0e19d09fce70b7218ec68088a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/603e2e7fd5fc4fe5ed17395a737173b76c80f25ead3104c5f34212cce918aaa70f908ad4778035a824061fc10216ddf80ef79527029ef99afe80252a6503f12a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d094b9bd9a26e6679f2a4bbe8df45c0447ebbb9f94eff2d839e258208beb63ed3b5e49f9308756e178075a550e30d1f2da73cdcc487310773cc7105e496d1679"} 04:21:17 INFO - Running post-run listener: copy_logs_to_upload_dir 04:21:17 INFO - Copying logs to upload dir... 04:21:17 INFO - mkdir: /builds/slave/test/build/upload/logs 04:21:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.056368 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-25 04:21:16.753028) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 04:21:16.756836) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fe18d8061c10e6f407f2e654b702b1de1587ac16276f71a430dff9c6c922a793398e6b9c97c573d32d2d38003deaf6cd8f4a67d0e19d09fce70b7218ec68088a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/603e2e7fd5fc4fe5ed17395a737173b76c80f25ead3104c5f34212cce918aaa70f908ad4778035a824061fc10216ddf80ef79527029ef99afe80252a6503f12a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d094b9bd9a26e6679f2a4bbe8df45c0447ebbb9f94eff2d839e258208beb63ed3b5e49f9308756e178075a550e30d1f2da73cdcc487310773cc7105e496d1679"} build_url:https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011420 build_url: 'https://queue.taskcluster.net/v1/task/JCw-Ot3JQSCz56QI7zYo6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/fe18d8061c10e6f407f2e654b702b1de1587ac16276f71a430dff9c6c922a793398e6b9c97c573d32d2d38003deaf6cd8f4a67d0e19d09fce70b7218ec68088a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/603e2e7fd5fc4fe5ed17395a737173b76c80f25ead3104c5f34212cce918aaa70f908ad4778035a824061fc10216ddf80ef79527029ef99afe80252a6503f12a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/d094b9bd9a26e6679f2a4bbe8df45c0447ebbb9f94eff2d839e258208beb63ed3b5e49f9308756e178075a550e30d1f2da73cdcc487310773cc7105e496d1679"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 04:21:16.782058) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:21:16.782412) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c43b49b387003dc8f5b4e5c000001c4-1469443770.431702-1578475148 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004539 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 04:21:16.867752) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 04:21:16.868131) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 04:21:16.868451) ========= ========= Total master_lag: 0.22 =========