builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-027 starttime: 1468500262.21 results: success (0) buildid: 20160714051342 builduid: 9e0d509dc7424364bdf88537309e5318 revision: 22010b91698c2ad3fe74b165883041eee22f67d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:22.214091) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:22.214497) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:22.214887) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:22.230925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:22.231240) ========= 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=1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-14 05:44:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 367K=0.03s 2016-07-14 05:44:22 (367 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136918 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:22.402840) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:22.403269) ========= 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=1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023345 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:22.453728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:22.454208) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 22010b91698c2ad3fe74b165883041eee22f67d0 --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 22010b91698c2ad3fe74b165883041eee22f67d0 --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=1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-14 05:44:22,884 truncating revision to first 12 chars 2016-07-14 05:44:22,884 Setting DEBUG logging. 2016-07-14 05:44:22,884 attempt 1/10 2016-07-14 05:44:22,884 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/22010b91698c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-14 05:44:23,206 unpacking tar archive at: fx-team-22010b91698c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662401 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:23.133586) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:23.133898) ========= script_repo_revision: 22010b91698c2ad3fe74b165883041eee22f67d0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:23.134421) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:23.134689) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 05:44:23.146252) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-14 05:44:23.146522) ========= /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=1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:44:23 INFO - MultiFileLogger online at 20160714 05:44:23 in /builds/slave/test 05:44:23 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 05:44:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:44:23 INFO - {'append_to_log': False, 05:44:23 INFO - 'base_work_dir': '/builds/slave/test', 05:44:23 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:44:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:44:23 INFO - 'branch': 'Fx-Team-Non-PGO', 05:44:23 INFO - 'buildbot_json_path': 'buildprops.json', 05:44:23 INFO - 'config_files': ('talos/linux_config.py',), 05:44:23 INFO - 'default_actions': ('clobber', 05:44:23 INFO - 'read-buildbot-config', 05:44:23 INFO - 'download-and-extract', 05:44:23 INFO - 'populate-webroot', 05:44:23 INFO - 'create-virtualenv', 05:44:23 INFO - 'install', 05:44:23 INFO - 'run-tests'), 05:44:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:44:23 INFO - 'download_minidump_stackwalk': True, 05:44:23 INFO - 'download_symbols': 'ondemand', 05:44:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:44:23 INFO - 'tooltool.py': '/tools/tooltool.py', 05:44:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:44:23 INFO - '/tools/misc-python/virtualenv.py')}, 05:44:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:44:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:44:23 INFO - 'installer_path': 'installer.exe', 05:44:23 INFO - 'log_level': 'info', 05:44:23 INFO - 'log_name': 'talos', 05:44:23 INFO - 'log_to_console': True, 05:44:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:44:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:44:23 INFO - 'opt_config_files': (), 05:44:23 INFO - 'pip_index': False, 05:44:23 INFO - 'sps_profile': False, 05:44:23 INFO - 'sps_profile_interval': 0, 05:44:23 INFO - 'suite': 'dromaeojs', 05:44:23 INFO - 'system_bits': '32', 05:44:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:44:23 INFO - 'title': 'talos-linux64-ix-027', 05:44:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:44:23 INFO - 'use_talos_json': True, 05:44:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:44:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:44:23 INFO - 'work_dir': 'build'} 05:44:23 INFO - ##### 05:44:23 INFO - ##### Running clobber step. 05:44:23 INFO - ##### 05:44:23 INFO - Running pre-action listener: _resource_record_pre_action 05:44:23 INFO - Running main action method: clobber 05:44:23 INFO - rmtree: /builds/slave/test/build 05:44:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:44:24 INFO - Running post-action listener: _resource_record_post_action 05:44:24 INFO - ##### 05:44:24 INFO - ##### Finished clobber step (success) 05:44:24 INFO - ##### 05:44:24 INFO - ##### 05:44:24 INFO - ##### Running read-buildbot-config step. 05:44:24 INFO - ##### 05:44:24 INFO - Running pre-action listener: _resource_record_pre_action 05:44:24 INFO - Running main action method: read_buildbot_config 05:44:24 INFO - Using buildbot properties: 05:44:24 INFO - { 05:44:24 INFO - "project": "", 05:44:24 INFO - "product": "firefox", 05:44:24 INFO - "script_repo_revision": "production", 05:44:24 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:44:24 INFO - "repository": "", 05:44:24 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 05:44:24 INFO - "buildid": "20160714051342", 05:44:24 INFO - "pgo_build": "False", 05:44:24 INFO - "basedir": "/builds/slave/test", 05:44:24 INFO - "buildnumber": 3157, 05:44:24 INFO - "slavename": "talos-linux64-ix-027", 05:44:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:44:24 INFO - "platform": "ubuntu64_hw", 05:44:24 INFO - "scheduler": "tests-fx-team-linux64-talos", 05:44:24 INFO - "branch": "fx-team", 05:44:24 INFO - "repo_path": "integration/fx-team", 05:44:24 INFO - "revision": "22010b91698c2ad3fe74b165883041eee22f67d0", 05:44:24 INFO - "stage_platform": "linux64", 05:44:24 INFO - "builduid": "9e0d509dc7424364bdf88537309e5318", 05:44:24 INFO - "slavebuilddir": "test" 05:44:24 INFO - } 05:44:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:44:24 INFO - Running post-action listener: _resource_record_post_action 05:44:24 INFO - ##### 05:44:24 INFO - ##### Finished read-buildbot-config step (success) 05:44:24 INFO - ##### 05:44:24 INFO - ##### 05:44:24 INFO - ##### Running download-and-extract step. 05:44:24 INFO - ##### 05:44:24 INFO - Running pre-action listener: _resource_record_pre_action 05:44:24 INFO - Running main action method: download_and_extract 05:44:24 INFO - mkdir: /builds/slave/test/build/tests 05:44:24 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')]} 05:44:24 INFO - https://queue.taskcluster.net/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:44:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:44:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:44:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/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 05:44:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/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 05:44:24 INFO - Downloaded 1361 bytes. 05:44:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:44:24 INFO - Using the following test package requirements: 05:44:24 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:44:24 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:44:24 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:44:24 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:24 INFO - u'jsshell-linux-x86_64.zip'], 05:44:24 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:44:24 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:44:24 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:44:24 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:44:24 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:44:24 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:44:24 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:44:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:44:24 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')]} 05:44:24 INFO - https://queue.taskcluster.net/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:44:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:44:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:44:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/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 05:44:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/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 05:44:25 INFO - Downloaded 22756103 bytes. 05:44:25 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'] 05:44:25 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 05:44:27 INFO - Return code: 0 05:44:27 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 05:44:27 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')]} 05:44:27 INFO - https://queue.taskcluster.net/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:44:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:44:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:44:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/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 05:44:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/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 05:44:28 INFO - Downloaded 22756103 bytes. 05:44:28 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'] 05:44:28 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 05:44:29 INFO - Return code: 0 05:44:29 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')]} 05:44:29 INFO - https://queue.taskcluster.net/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:44:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:44:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:44:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/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 05:44:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/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 05:44:30 INFO - Downloaded 14403317 bytes. 05:44:30 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'] 05:44:30 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 05:44:30 INFO - Return code: 0 05:44:30 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')]} 05:44:30 INFO - https://queue.taskcluster.net/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:44:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:44:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:44:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/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 05:44:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/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 05:44:32 INFO - Downloaded 57046184 bytes. 05:44:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:44:32 INFO - mkdir: /builds/slave/test/properties 05:44:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:44:32 INFO - Writing to file /builds/slave/test/properties/build_url 05:44:32 INFO - Contents: 05:44:32 INFO - build_url:https://queue.taskcluster.net/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:44:32 INFO - Running post-action listener: _resource_record_post_action 05:44:32 INFO - Running post-action listener: set_extra_try_arguments 05:44:32 INFO - ##### 05:44:32 INFO - ##### Finished download-and-extract step (success) 05:44:32 INFO - ##### 05:44:32 INFO - ##### 05:44:32 INFO - ##### Running populate-webroot step. 05:44:32 INFO - ##### 05:44:32 INFO - Running pre-action listener: _resource_record_pre_action 05:44:32 INFO - Running main action method: populate_webroot 05:44:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:44:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:44:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:44:32 INFO - '--tppagecycles', 05:44:32 INFO - '7'], 05:44:32 INFO - 'tests': ['tsvgm']}}, 05:44:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:44:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:44:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:44:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:44:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:44:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:44:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:32 INFO - 'talos_options': ['--disable-e10s'], 05:44:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:44:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:44:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:32 INFO - 'talos_options': ['--disable-e10s'], 05:44:32 INFO - 'tests': ['damp', 'tps']}, 05:44:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:32 INFO - 'tests': ['damp', 'tps']}, 05:44:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:44:32 INFO - 'tests': ['dromaeo_dom']}, 05:44:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:44:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:44:32 INFO - 'tests': ['basic_compositor_video']}, 05:44:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:44:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:44:32 INFO - 'tests': ['a11yr', 05:44:32 INFO - 'ts_paint', 05:44:32 INFO - 'tpaint', 05:44:32 INFO - 'sessionrestore', 05:44:32 INFO - 'sessionrestore_no_auto_restore', 05:44:32 INFO - 'tabpaint']}, 05:44:32 INFO - 'other-e10s': {'tests': ['a11yr', 05:44:32 INFO - 'ts_paint', 05:44:32 INFO - 'tpaint', 05:44:32 INFO - 'sessionrestore', 05:44:32 INFO - 'sessionrestore_no_auto_restore', 05:44:32 INFO - 'tabpaint']}, 05:44:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:44:32 INFO - 'ts_paint', 05:44:32 INFO - 'tpaint', 05:44:32 INFO - 'sessionrestore', 05:44:32 INFO - 'sessionrestore_no_auto_restore', 05:44:32 INFO - 'tabpaint']}, 05:44:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:44:32 INFO - 'ts_paint', 05:44:32 INFO - 'tpaint', 05:44:32 INFO - 'sessionrestore', 05:44:32 INFO - 'sessionrestore_no_auto_restore', 05:44:32 INFO - 'tabpaint']}, 05:44:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:44:32 INFO - 'tests': ['a11yr', 05:44:32 INFO - 'ts_paint', 05:44:32 INFO - 'tpaint', 05:44:32 INFO - 'sessionrestore', 05:44:32 INFO - 'sessionrestore_no_auto_restore', 05:44:32 INFO - 'tabpaint']}, 05:44:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:44:32 INFO - 'tests': ['a11yr', 05:44:32 INFO - 'ts_paint', 05:44:32 INFO - 'tpaint', 05:44:32 INFO - 'sessionrestore', 05:44:32 INFO - 'sessionrestore_no_auto_restore', 05:44:32 INFO - 'tabpaint']}, 05:44:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:44:32 INFO - 'tests': ['tsvgx', 05:44:32 INFO - 'tsvgr_opacity', 05:44:32 INFO - 'tart', 05:44:32 INFO - 'tscrollx', 05:44:32 INFO - 'cart']}, 05:44:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:44:32 INFO - 'tsvgr_opacity', 05:44:32 INFO - 'tart', 05:44:32 INFO - 'tscrollx', 05:44:32 INFO - 'cart']}, 05:44:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:32 INFO - 'talos_options': ['--disable-e10s'], 05:44:32 INFO - 'tests': ['tp5o']}, 05:44:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:32 INFO - 'tests': ['tp5o']}, 05:44:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:32 INFO - 'talos_options': ['--disable-e10s', 05:44:32 INFO - '--xperf_path', 05:44:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:44:32 INFO - 'tests': ['tp5n']}, 05:44:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:44:32 INFO - 'talos_options': ['--xperf_path', 05:44:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:44:32 INFO - 'tests': ['tp5n']}}, 05:44:32 INFO - 'talos.zip': {'path': '', 05:44:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:44:32 INFO - Running post-action listener: _resource_record_post_action 05:44:32 INFO - ##### 05:44:32 INFO - ##### Finished populate-webroot step (success) 05:44:32 INFO - ##### 05:44:32 INFO - ##### 05:44:32 INFO - ##### Running create-virtualenv step. 05:44:32 INFO - ##### 05:44:32 INFO - Running pre-action listener: _resource_record_pre_action 05:44:32 INFO - Running main action method: create_virtualenv 05:44:32 INFO - Creating virtualenv /builds/slave/test/build/venv 05:44:32 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 05:44:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:44:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:44:33 INFO - Using real prefix '/usr' 05:44:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:44:33 INFO - Installing distribute.............................................................................................................................................................................................done. 05:44:35 INFO - Installing pip.................done. 05:44:35 INFO - Return code: 0 05:44:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:44:35 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')]} 05:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:35 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')]} 05:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:35 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 0x1cfc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb3b0>, '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 0x1fbc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbc740>, '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': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', '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 05:44:35 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 05:44:35 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 05:44:35 INFO - Using env: {'DISPLAY': ':0', 05:44:35 INFO - 'HOME': '/home/cltbld', 05:44:35 INFO - 'LANG': 'en_US.UTF-8', 05:44:35 INFO - 'LANGUAGE': 'en_US:en', 05:44:35 INFO - 'LOGNAME': 'cltbld', 05:44:35 INFO - 'MAIL': '/var/mail/cltbld', 05:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:35 INFO - 'MOZ_NO_REMOTE': '1', 05:44:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:35 INFO - 'NO_EM_RESTART': '1', 05:44:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:35 INFO - 'PWD': '/builds/slave/test', 05:44:35 INFO - 'SHELL': '/bin/bash', 05:44:35 INFO - 'SHLVL': '1', 05:44:35 INFO - 'TERM': 'linux', 05:44:35 INFO - 'TMOUT': '86400', 05:44:35 INFO - 'USER': 'cltbld', 05:44:35 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', 05:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:35 INFO - '_': '/tools/buildbot/bin/python'} 05:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:35 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 05:44:35 INFO - Cleaning up... 05:44:35 INFO - Return code: 0 05:44:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:44:35 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')]} 05:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:35 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')]} 05:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:35 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 0x1cfc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb3b0>, '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 0x1fbc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbc740>, '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': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', '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 05:44:35 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 05:44:35 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 05:44:35 INFO - Using env: {'DISPLAY': ':0', 05:44:35 INFO - 'HOME': '/home/cltbld', 05:44:35 INFO - 'LANG': 'en_US.UTF-8', 05:44:35 INFO - 'LANGUAGE': 'en_US:en', 05:44:35 INFO - 'LOGNAME': 'cltbld', 05:44:35 INFO - 'MAIL': '/var/mail/cltbld', 05:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:35 INFO - 'MOZ_NO_REMOTE': '1', 05:44:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:35 INFO - 'NO_EM_RESTART': '1', 05:44:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:35 INFO - 'PWD': '/builds/slave/test', 05:44:35 INFO - 'SHELL': '/bin/bash', 05:44:35 INFO - 'SHLVL': '1', 05:44:35 INFO - 'TERM': 'linux', 05:44:35 INFO - 'TMOUT': '86400', 05:44:35 INFO - 'USER': 'cltbld', 05:44:35 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', 05:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:35 INFO - '_': '/tools/buildbot/bin/python'} 05:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:35 INFO - Downloading/unpacking psutil>=3.1.1 05:44:35 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 05:44:35 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 05:44:35 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 05:44:35 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 05:44:35 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 05:44:35 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 05:44:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:44:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:44:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:44:37 INFO - Installing collected packages: psutil 05:44:37 INFO - Running setup.py install for psutil 05:44:38 INFO - building 'psutil._psutil_linux' extension 05:44:38 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 05:44:38 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 05:44:38 INFO - building 'psutil._psutil_posix' extension 05:44:38 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 05:44:38 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 05:44:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:44:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:44:38 INFO - Successfully installed psutil 05:44:38 INFO - Cleaning up... 05:44:38 INFO - Return code: 0 05:44:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:44:38 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')]} 05:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:38 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')]} 05:44:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:38 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 0x1cfc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb3b0>, '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 0x1fbc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbc740>, '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': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', '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 05:44:38 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 05:44:38 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 05:44:38 INFO - Using env: {'DISPLAY': ':0', 05:44:38 INFO - 'HOME': '/home/cltbld', 05:44:38 INFO - 'LANG': 'en_US.UTF-8', 05:44:38 INFO - 'LANGUAGE': 'en_US:en', 05:44:38 INFO - 'LOGNAME': 'cltbld', 05:44:38 INFO - 'MAIL': '/var/mail/cltbld', 05:44:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:38 INFO - 'MOZ_NO_REMOTE': '1', 05:44:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:38 INFO - 'NO_EM_RESTART': '1', 05:44:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:38 INFO - 'PWD': '/builds/slave/test', 05:44:38 INFO - 'SHELL': '/bin/bash', 05:44:38 INFO - 'SHLVL': '1', 05:44:38 INFO - 'TERM': 'linux', 05:44:38 INFO - 'TMOUT': '86400', 05:44:38 INFO - 'USER': 'cltbld', 05:44:38 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', 05:44:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:38 INFO - '_': '/tools/buildbot/bin/python'} 05:44:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:44:38 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 05:44:38 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 05:44:38 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 05:44:38 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 05:44:38 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 05:44:38 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 05:44:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:44:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:44:40 INFO - Installing collected packages: mozsystemmonitor 05:44:40 INFO - Running setup.py install for mozsystemmonitor 05:44:40 INFO - Successfully installed mozsystemmonitor 05:44:40 INFO - Cleaning up... 05:44:40 INFO - Return code: 0 05:44:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:44:40 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')]} 05:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:40 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')]} 05:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:40 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 0x1cfc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb3b0>, '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 0x1fbc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbc740>, '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': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', '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 05:44:40 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 05:44:40 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 05:44:40 INFO - Using env: {'DISPLAY': ':0', 05:44:40 INFO - 'HOME': '/home/cltbld', 05:44:40 INFO - 'LANG': 'en_US.UTF-8', 05:44:40 INFO - 'LANGUAGE': 'en_US:en', 05:44:40 INFO - 'LOGNAME': 'cltbld', 05:44:40 INFO - 'MAIL': '/var/mail/cltbld', 05:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:40 INFO - 'MOZ_NO_REMOTE': '1', 05:44:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:40 INFO - 'NO_EM_RESTART': '1', 05:44:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:40 INFO - 'PWD': '/builds/slave/test', 05:44:40 INFO - 'SHELL': '/bin/bash', 05:44:40 INFO - 'SHLVL': '1', 05:44:40 INFO - 'TERM': 'linux', 05:44:40 INFO - 'TMOUT': '86400', 05:44:40 INFO - 'USER': 'cltbld', 05:44:40 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', 05:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:40 INFO - '_': '/tools/buildbot/bin/python'} 05:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:41 INFO - Downloading/unpacking jsonschema==2.5.1 05:44:41 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 05:44:41 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 05:44:41 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 05:44:41 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 05:44:41 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 05:44:41 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 05:44:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:44:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:44:43 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 05:44:43 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 05:44:43 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 05:44:43 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 05:44:43 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 05:44:43 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 05:44:43 INFO - Downloading functools32-3.2.3-2.tar.gz 05:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:44:43 INFO - warning: no files found matching '*.txt' 05:44:43 INFO - no previously-included directories found matching 'build' 05:44:43 INFO - no previously-included directories found matching 'dist' 05:44:43 INFO - no previously-included directories found matching '.git*' 05:44:43 INFO - Installing collected packages: jsonschema, functools32 05:44:43 INFO - Running setup.py install for functools32 05:44:43 INFO - warning: no files found matching '*.txt' 05:44:43 INFO - no previously-included directories found matching 'build' 05:44:43 INFO - no previously-included directories found matching 'dist' 05:44:43 INFO - no previously-included directories found matching '.git*' 05:44:43 INFO - Successfully installed jsonschema functools32 05:44:43 INFO - Cleaning up... 05:44:43 INFO - Return code: 0 05:44:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:44:43 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')]} 05:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:43 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')]} 05:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:43 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 0x1cfc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb3b0>, '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 0x1fbc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbc740>, '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': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', '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 05:44:43 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 05:44:43 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 05:44:43 INFO - Using env: {'DISPLAY': ':0', 05:44:43 INFO - 'HOME': '/home/cltbld', 05:44:43 INFO - 'LANG': 'en_US.UTF-8', 05:44:43 INFO - 'LANGUAGE': 'en_US:en', 05:44:43 INFO - 'LOGNAME': 'cltbld', 05:44:43 INFO - 'MAIL': '/var/mail/cltbld', 05:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:43 INFO - 'MOZ_NO_REMOTE': '1', 05:44:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:43 INFO - 'NO_EM_RESTART': '1', 05:44:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:43 INFO - 'PWD': '/builds/slave/test', 05:44:43 INFO - 'SHELL': '/bin/bash', 05:44:43 INFO - 'SHLVL': '1', 05:44:43 INFO - 'TERM': 'linux', 05:44:43 INFO - 'TMOUT': '86400', 05:44:43 INFO - 'USER': 'cltbld', 05:44:43 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', 05:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:43 INFO - '_': '/tools/buildbot/bin/python'} 05:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:43 INFO - Downloading/unpacking blobuploader==1.2.4 05:44:43 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 05:44:43 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 05:44:43 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 05:44:43 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 05:44:43 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 05:44:43 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 05:44:45 INFO - Downloading blobuploader-1.2.4.tar.gz 05:44:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:44:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:44:45 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 05:44:45 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 05:44:45 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 05:44:45 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 05:44:45 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 05:44:45 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 05:44:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:44:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:44:46 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 05:44:46 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 05:44:46 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 05:44:46 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 05:44:46 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 05:44:46 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 05:44:46 INFO - Downloading docopt-0.6.1.tar.gz 05:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:44:46 INFO - Installing collected packages: blobuploader, requests, docopt 05:44:46 INFO - Running setup.py install for blobuploader 05:44:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:44:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:44:46 INFO - Running setup.py install for requests 05:44:46 INFO - Running setup.py install for docopt 05:44:46 INFO - Successfully installed blobuploader requests docopt 05:44:46 INFO - Cleaning up... 05:44:47 INFO - Return code: 0 05:44:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:44:47 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')]} 05:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:47 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')]} 05:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:47 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 0x1cfc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb3b0>, '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 0x1fbc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbc740>, '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': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', '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 05:44:47 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 05:44:47 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 05:44:47 INFO - Using env: {'DISPLAY': ':0', 05:44:47 INFO - 'HOME': '/home/cltbld', 05:44:47 INFO - 'LANG': 'en_US.UTF-8', 05:44:47 INFO - 'LANGUAGE': 'en_US:en', 05:44:47 INFO - 'LOGNAME': 'cltbld', 05:44:47 INFO - 'MAIL': '/var/mail/cltbld', 05:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:47 INFO - 'MOZ_NO_REMOTE': '1', 05:44:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:47 INFO - 'NO_EM_RESTART': '1', 05:44:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:47 INFO - 'PWD': '/builds/slave/test', 05:44:47 INFO - 'SHELL': '/bin/bash', 05:44:47 INFO - 'SHLVL': '1', 05:44:47 INFO - 'TERM': 'linux', 05:44:47 INFO - 'TMOUT': '86400', 05:44:47 INFO - 'USER': 'cltbld', 05:44:47 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', 05:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:47 INFO - '_': '/tools/buildbot/bin/python'} 05:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:44:47 INFO - Running setup.py (path:/tmp/pip-ifH4lM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:44:47 INFO - Running setup.py (path:/tmp/pip-DeETwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:44:47 INFO - Running setup.py (path:/tmp/pip-oxy2SC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:44:47 INFO - Running setup.py (path:/tmp/pip-2oXz73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:44:47 INFO - Running setup.py (path:/tmp/pip-m9tZEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:44:47 INFO - Running setup.py (path:/tmp/pip-O8JwsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:44:47 INFO - Running setup.py (path:/tmp/pip-hwY9Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:44:47 INFO - Running setup.py (path:/tmp/pip-epoXYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:44:47 INFO - Running setup.py (path:/tmp/pip-VoVThJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:44:47 INFO - Running setup.py (path:/tmp/pip-m3CRHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:44:47 INFO - Running setup.py (path:/tmp/pip-1I58Zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:44:48 INFO - Running setup.py (path:/tmp/pip-t0ULc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:44:48 INFO - Running setup.py (path:/tmp/pip-O3uOJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:44:48 INFO - Running setup.py (path:/tmp/pip-CclvJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:44:48 INFO - Running setup.py (path:/tmp/pip-0kDoj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:44:48 INFO - Running setup.py (path:/tmp/pip-bttT6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:44:48 INFO - Running setup.py (path:/tmp/pip-NyZ2Pb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:44:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:44:48 INFO - Running setup.py install for manifestparser 05:44:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:44:48 INFO - Running setup.py install for mozcrash 05:44:48 INFO - Running setup.py install for mozdebug 05:44:48 INFO - Running setup.py install for mozdevice 05:44:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:44:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:44:48 INFO - Running setup.py install for mozfile 05:44:48 INFO - Running setup.py install for mozhttpd 05:44:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Running setup.py install for mozinfo 05:44:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Running setup.py install for mozInstall 05:44:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Running setup.py install for mozleak 05:44:49 INFO - Running setup.py install for mozlog 05:44:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Running setup.py install for moznetwork 05:44:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Running setup.py install for mozprocess 05:44:49 INFO - Running setup.py install for mozprofile 05:44:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Running setup.py install for mozrunner 05:44:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:44:49 INFO - Running setup.py install for mozscreenshot 05:44:50 INFO - Running setup.py install for moztest 05:44:50 INFO - Running setup.py install for mozversion 05:44:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:44:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:44:50 INFO - Cleaning up... 05:44:50 INFO - Return code: 0 05:44:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:44: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')]} 05:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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')]} 05:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:50 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 0x1cfc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb3b0>, '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 0x1fbc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbc740>, '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': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', '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 05:44:50 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 05:44:50 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 05:44:50 INFO - Using env: {'DISPLAY': ':0', 05:44:50 INFO - 'HOME': '/home/cltbld', 05:44:50 INFO - 'LANG': 'en_US.UTF-8', 05:44:50 INFO - 'LANGUAGE': 'en_US:en', 05:44:50 INFO - 'LOGNAME': 'cltbld', 05:44:50 INFO - 'MAIL': '/var/mail/cltbld', 05:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:50 INFO - 'MOZ_NO_REMOTE': '1', 05:44:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:50 INFO - 'NO_EM_RESTART': '1', 05:44:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:50 INFO - 'PWD': '/builds/slave/test', 05:44:50 INFO - 'SHELL': '/bin/bash', 05:44:50 INFO - 'SHLVL': '1', 05:44:50 INFO - 'TERM': 'linux', 05:44:50 INFO - 'TMOUT': '86400', 05:44:50 INFO - 'USER': 'cltbld', 05:44:50 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', 05:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:50 INFO - '_': '/tools/buildbot/bin/python'} 05:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:44:50 INFO - Running setup.py (path:/tmp/pip-t_6eX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:44:50 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)) 05:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:44:50 INFO - Running setup.py (path:/tmp/pip-ve7n5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:44:50 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)) 05:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:44:50 INFO - Running setup.py (path:/tmp/pip-qAGIVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:44:50 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)) 05:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:44:50 INFO - Running setup.py (path:/tmp/pip-YGpows-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:44:50 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)) 05:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:44:50 INFO - Running setup.py (path:/tmp/pip-kdDeF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:44:50 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)) 05:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:44:50 INFO - Running setup.py (path:/tmp/pip-BPuCUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:44:50 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)) 05:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:44:50 INFO - Running setup.py (path:/tmp/pip-Hxm5BD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:44:50 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)) 05:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:44:50 INFO - Running setup.py (path:/tmp/pip-bqOHul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:44:51 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)) 05:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:44:51 INFO - Running setup.py (path:/tmp/pip-BUpgzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:44:51 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)) 05:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:44:51 INFO - Running setup.py (path:/tmp/pip-TcV4OP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 05:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:44:51 INFO - Running setup.py (path:/tmp/pip-1RHCbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:44:51 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)) 05:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:44:51 INFO - Running setup.py (path:/tmp/pip-eTuBMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:44:51 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)) 05:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:44:51 INFO - Running setup.py (path:/tmp/pip-MLwn87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:44:51 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)) 05:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:44:51 INFO - Running setup.py (path:/tmp/pip-uemyKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:44:51 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)) 05:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:44:51 INFO - Running setup.py (path:/tmp/pip-h6GHE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:44:51 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)) 05:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:44:51 INFO - Running setup.py (path:/tmp/pip-LWU9Cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:44:51 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)) 05:44:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:44:51 INFO - Running setup.py (path:/tmp/pip-4l2dVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:44:51 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)) 05:44:51 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)) 05:44:51 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)) 05:44:51 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)) 05:44:51 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)) 05:44:51 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)) 05:44:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:44:51 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 05:44:51 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 05:44:51 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 05:44:51 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 05:44:51 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 05:44:51 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 05:44:54 INFO - Downloading blessings-1.6.tar.gz 05:44:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:44:54 INFO - Installing collected packages: blessings 05:44:54 INFO - Running setup.py install for blessings 05:44:54 INFO - Successfully installed blessings 05:44:54 INFO - Cleaning up... 05:44:54 INFO - Return code: 0 05:44:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:44:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:44:55 INFO - Reading from file tmpfile_stdout 05:44:55 INFO - Current package versions: 05:44:55 INFO - argparse == 1.2.1 05:44:55 INFO - blessings == 1.6 05:44:55 INFO - blobuploader == 1.2.4 05:44:55 INFO - docopt == 0.6.1 05:44:55 INFO - functools32 == 3.2.3-2 05:44:55 INFO - jsonschema == 2.5.1 05:44:55 INFO - manifestparser == 1.1 05:44:55 INFO - mozInstall == 1.12 05:44:55 INFO - mozcrash == 0.17 05:44:55 INFO - mozdebug == 0.1 05:44:55 INFO - mozdevice == 0.48 05:44:55 INFO - mozfile == 1.2 05:44:55 INFO - mozhttpd == 0.7 05:44:55 INFO - mozinfo == 0.9 05:44:55 INFO - mozleak == 0.1 05:44:55 INFO - mozlog == 3.2 05:44:55 INFO - moznetwork == 0.27 05:44:55 INFO - mozprocess == 0.23 05:44:55 INFO - mozprofile == 0.28 05:44:55 INFO - mozrunner == 6.12 05:44:55 INFO - mozscreenshot == 0.1 05:44:55 INFO - mozsystemmonitor == 0.3 05:44:55 INFO - moztest == 0.7 05:44:55 INFO - mozversion == 1.4 05:44:55 INFO - psutil == 3.1.1 05:44:55 INFO - requests == 1.2.3 05:44:55 INFO - wsgiref == 0.1.2 05:44:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:44: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')]} 05:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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')]} 05:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44:55 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 0x1cfc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb3b0>, '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 0x1fbc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbc740>, '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': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', '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 05:44:55 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 05:44:55 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 05:44:55 INFO - Using env: {'DISPLAY': ':0', 05:44:55 INFO - 'HOME': '/home/cltbld', 05:44:55 INFO - 'LANG': 'en_US.UTF-8', 05:44:55 INFO - 'LANGUAGE': 'en_US:en', 05:44:55 INFO - 'LOGNAME': 'cltbld', 05:44:55 INFO - 'MAIL': '/var/mail/cltbld', 05:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:55 INFO - 'MOZ_NO_REMOTE': '1', 05:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:55 INFO - 'NO_EM_RESTART': '1', 05:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:55 INFO - 'PWD': '/builds/slave/test', 05:44:55 INFO - 'SHELL': '/bin/bash', 05:44:55 INFO - 'SHLVL': '1', 05:44:55 INFO - 'TERM': 'linux', 05:44:55 INFO - 'TMOUT': '86400', 05:44:55 INFO - 'USER': 'cltbld', 05:44:55 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', 05:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:55 INFO - '_': '/tools/buildbot/bin/python'} 05:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:55 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)) 05:44:55 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)) 05:44:55 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)) 05:44:55 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)) 05:44:55 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)) 05:44:55 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)) 05:44:55 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)) 05:44:55 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)) 05:44:55 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)) 05:44:55 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)) 05:44:55 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)) 05:44:55 INFO - Cleaning up... 05:44:55 INFO - Return code: 0 05:44:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:44: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')]} 05:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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')]} 05:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:44: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cfc030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fbb3b0>, '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 0x1fbc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbc740>, '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': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', '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 05:44: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'] in /builds/slave/test/build 05:44: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 05:44:55 INFO - Using env: {'DISPLAY': ':0', 05:44:55 INFO - 'HOME': '/home/cltbld', 05:44:55 INFO - 'LANG': 'en_US.UTF-8', 05:44:55 INFO - 'LANGUAGE': 'en_US:en', 05:44:55 INFO - 'LOGNAME': 'cltbld', 05:44:55 INFO - 'MAIL': '/var/mail/cltbld', 05:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:44:55 INFO - 'MOZ_NO_REMOTE': '1', 05:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:44:55 INFO - 'NO_EM_RESTART': '1', 05:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:44:55 INFO - 'PWD': '/builds/slave/test', 05:44:55 INFO - 'SHELL': '/bin/bash', 05:44:55 INFO - 'SHLVL': '1', 05:44:55 INFO - 'TERM': 'linux', 05:44:55 INFO - 'TMOUT': '86400', 05:44:55 INFO - 'USER': 'cltbld', 05:44:55 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', 05:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:44:55 INFO - '_': '/tools/buildbot/bin/python'} 05:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:44:55 INFO - Cleaning up... 05:44:55 INFO - Return code: 0 05:44:55 INFO - Running post-action listener: _resource_record_post_action 05:44:55 INFO - Running post-action listener: _start_resource_monitoring 05:44:55 INFO - Starting resource monitoring. 05:44:55 INFO - ##### 05:44:55 INFO - ##### Finished create-virtualenv step (success) 05:44:55 INFO - ##### 05:44:55 INFO - ##### 05:44:55 INFO - ##### Running install step. 05:44:55 INFO - ##### 05:44:55 INFO - Running pre-action listener: _resource_record_pre_action 05:44:55 INFO - Running main action method: install 05:44:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:44:55 INFO - Reading from file tmpfile_stdout 05:44:55 INFO - Detecting whether we're running mozinstall >=1.0... 05:44:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:44:55 INFO - Reading from file tmpfile_stdout 05:44:55 INFO - Output received: 05:44:55 INFO - Usage: mozinstall [options] installer 05:44:55 INFO - Options: 05:44:55 INFO - -h, --help show this help message and exit 05:44:55 INFO - -d DEST, --destination=DEST 05:44:55 INFO - Directory to install application into. [default: 05:44:55 INFO - "/builds/slave/test"] 05:44:55 INFO - --app=APP Application being installed. [default: firefox] 05:44:55 INFO - mkdir: /builds/slave/test/build/application 05:44:55 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'] 05:44:55 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 05:45:12 INFO - Reading from file tmpfile_stdout 05:45:12 INFO - Output received: 05:45:12 INFO - /builds/slave/test/build/application/firefox/firefox 05:45:12 INFO - Running post-action listener: _resource_record_post_action 05:45:12 INFO - ##### 05:45:12 INFO - ##### Finished install step (success) 05:45:12 INFO - ##### 05:45:12 INFO - ##### 05:45:12 INFO - ##### Running run-tests step. 05:45:12 INFO - ##### 05:45:12 INFO - Running pre-action listener: _resource_record_pre_action 05:45:12 INFO - Running main action method: run_tests 05:45:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:45:12 INFO - Python 2.7.3 05:45:12 INFO - Return code: 0 05:45:12 INFO - grabbing minidump binary from tooltool 05:45:12 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')]} 05:45:12 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 0x1fbb3b0>, '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 0x1fbc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fbc740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:45:12 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 05:45:12 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 05:45:12 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 05:45:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:45:12 INFO - Return code: 0 05:45:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:45:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:45:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:45:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:45:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:45:12 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/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 05:45:12 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/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 05:45:12 INFO - Using env: {'DISPLAY': ':0', 05:45:12 INFO - 'HOME': '/home/cltbld', 05:45:12 INFO - 'LANG': 'en_US.UTF-8', 05:45:12 INFO - 'LANGUAGE': 'en_US:en', 05:45:12 INFO - 'LOGNAME': 'cltbld', 05:45:12 INFO - 'MAIL': '/var/mail/cltbld', 05:45:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:45:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:45:12 INFO - 'MOZ_NO_REMOTE': '1', 05:45:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:45:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:45:12 INFO - 'NO_EM_RESTART': '1', 05:45:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:45:12 INFO - 'PWD': '/builds/slave/test', 05:45:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:45:12 INFO - 'SHELL': '/bin/bash', 05:45:12 INFO - 'SHLVL': '1', 05:45:12 INFO - 'TERM': 'linux', 05:45:12 INFO - 'TMOUT': '86400', 05:45:12 INFO - 'USER': 'cltbld', 05:45:12 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527', 05:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:45:12 INFO - '_': '/tools/buildbot/bin/python'} 05:45:12 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/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 05:45:13 INFO - mozversion application_buildid: 20160714051342 05:45:13 INFO - mozversion application_changeset: 22010b91698c2ad3fe74b165883041eee22f67d0 05:45:13 INFO - mozversion application_display_name: Nightly 05:45:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:45:13 INFO - mozversion application_name: Firefox 05:45:13 INFO - mozversion application_remotingname: firefox 05:45:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:45:13 INFO - mozversion application_vendor: Mozilla 05:45:13 INFO - mozversion application_version: 50.0a1 05:45:13 INFO - mozversion platform_buildid: 20160714051342 05:45:13 INFO - mozversion platform_changeset: 22010b91698c2ad3fe74b165883041eee22f67d0 05:45:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:45:13 INFO - mozversion platform_version: 50.0a1 05:45:13 INFO - using testdate: 1468500313 05:45:13 INFO - actual date: 1468500313 05:45:13 INFO - starting webserver on 'localhost:56247' 05:45:13 INFO - SUITE-START | Running 2 tests 05:45:13 INFO - TEST-START | dromaeo_css 05:45:13 INFO - operating with platform_type : linux_ 05:45:13 INFO - Initialising browser for dromaeo_css test... 05:45:13 INFO - TEST-INFO | started process 14775 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgorSt2/profile http://localhost:56247/getInfo.html) 05:45:17 INFO - PROCESS | 14775 | __metrics Screen width/height:1600/1200 05:45:17 INFO - PROCESS | 14775 | colorDepth:24 05:45:17 INFO - PROCESS | 14775 | Browser inner width/height: 1024/697 05:45:17 INFO - PROCESS | 14775 | __metrics 05:45:17 INFO - PROCESS | 14775 | 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" 05:45:17 INFO - PROCESS | 14775 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:45:18 INFO - TEST-INFO | 14775: exit 0 05:45:18 INFO - Browser initialized. 05:45:18 INFO - Running cycle 1/1 for dromaeo_css test... 05:45:18 INFO - TEST-INFO | started process 14858 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgorSt2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:45:30 INFO - PROCESS | 14858 | RSS: Main: 158310400 05:45:30 INFO - PROCESS | 14858 | 05:46:22 INFO - PROCESS | 14858 | 1468500382591 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] 05:48:34 INFO - PROCESS | 14858 | Cycle 1(1): loaded http://localhost:56247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56247/tests/dromaeo/cssquery-ext.html) 05:48:35 INFO - PROCESS | 14858 | RSS: Main: 260382720 05:48:35 INFO - PROCESS | 14858 | 05:51:38 INFO - PROCESS | 14858 | Cycle 1(1): loaded http://localhost:56247/tests/dromaeo/cssquery-ext.html (next: http://localhost:56247/tests/dromaeo/cssquery-jquery.html) 05:51:38 INFO - PROCESS | 14858 | RSS: Main: 246075392 05:51:38 INFO - PROCESS | 14858 | 05:54:41 INFO - PROCESS | 14858 | Cycle 1(1): loaded http://localhost:56247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56247/tests/dromaeo/cssquery-mootools.html) 05:54:41 INFO - PROCESS | 14858 | RSS: Main: 248315904 05:54:41 INFO - PROCESS | 14858 | 05:57:43 INFO - PROCESS | 14858 | Cycle 1(1): loaded http://localhost:56247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56247/tests/dromaeo/cssquery-prototype.html) 05:57:44 INFO - PROCESS | 14858 | RSS: Main: 244461568 05:57:44 INFO - PROCESS | 14858 | 06:00:47 INFO - PROCESS | 14858 | Cycle 1(1): loaded http://localhost:56247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56247/tests/dromaeo/cssquery-yui.html) 06:00:48 INFO - PROCESS | 14858 | RSS: Main: 216817664 06:00:48 INFO - PROCESS | 14858 | 06:03:51 INFO - PROCESS | 14858 | Cycle 1(1): loaded http://localhost:56247/tests/dromaeo/cssquery-yui.html (next: http://localhost:56247/tests/dromaeo/cssquery-dojo.html) 06:03:51 INFO - PROCESS | 14858 | RSS: Main: 295026688 06:03:51 INFO - PROCESS | 14858 | 06:03:51 INFO - PROCESS | 14858 | __start_tp_report 06:03:51 INFO - PROCESS | 14858 | _x_x_mozilla_page_load 06:03:51 INFO - PROCESS | 14858 | _x_x_mozilla_page_load_details 06:03:51 INFO - PROCESS | 14858 | |i|pagename|runs| 06:03:51 INFO - PROCESS | 14858 | |0;dojo.html;2657.9999999999995;2672.5991101334807;2692.932676683083;2693.771029462496;2697.150397624748;4572.771361431932;4630;4684.250519916814;4739.502352253011;4793.472718001021;4646.210144275473;4646.976765116178;4647.953520464803;4677.228257337544;4707.62339012879;4233.068724880529;4239.724417912841;4300.935485967713;4331.740095594275;4453.732776033448;4385.386045953554;4408.889777755566;4415.757133357664;4442.444694413199;4467.776611169445;4166.958330416687;4188.308929026707;4196.202721482909;4257.446531950826;4278.122984788127;4176.874693759192;4180.519240287375;4208.389783481378;4294.248506511348;4367.650587952956;4205.285101532747;4206.558311377292;4219.7046206765535;4240.766757828318;4259.616634502879;4538.636909047302;4590.173768721629;4622.653301002405;4639.837605683785;4684.578387945101;5039.168537191395;5122.615803814691;5135.743212839343;5137.845864624067;5139.434662187156;5061.215511595708;5114.514121158521;5118.820841270538;5129.974350128263;5161.509656582619;4540.409746732969;4562.156001139733;4649.372334734835;4754.714717117019;4806.134895718735;5084.77118529667;5135.512126347991;5201.3238279024245;5204.115300398941;5207.5833933286;4935.432425271068;4979.90040199194;4989.650724449254;4990.6506544541535;4995.70025798446;4851.102546028996;4962.925556116661;5010.49895010496;5032.974835125801;5076.517730815566;4997.925031124536;5008.6744361616375;5031.622628302892;5033.647644664911;5065.341505604321;4925.335079764258;4982.626303027288;4988.800447982113;4989.3264409305;5022;4163.479565054368;4189.141226048653;4261.87214383569;4266.466691663542;4324.3945847580735;3728.832202550892;3748.8500459981296;3757.0982964088967;3787.4886890269463;3796.145867179918;4376.474823021194;4378;4391.692581519327;4394.450693663292;4440.289553671397;4863.537963893425;4916.115099282092;5050.141475949097;5062.291279220912;5104.03023425548;4227.239096962577;4277.978610106992;4333.804978775963;4341.544137865541;4351.9564804352185;4553.043860789271;4554.726716396961;4565.954340456553;4571.748553829571;4598.747068911242;4629.189891768994;4747.335373047707;4811.158047341702;4812.8796780080775;4819.2530157825595;5012.072766538202;5059.316992206005;5095.13382724945;5105.948940510547;5110.872228194176;4790.305405716222;4798.4001999750035;4798.448178459522;4799.400074990626;4810.831620893252;4794.688345257547;4946.975265123651;4980.042193674888;4983.501649834988;5026.84919452417;4939.629527785359;4974.253861920741;4989.825356112518;4991.675541089818;4996.475370086088;4875.0493653737185;4885.65800393969;4889.291052797395;4897.804087836446;4944.851654450372;4806.615470762401;4876.463589005136;4882.975585122052;4897.216445368724;4942.085714142895;4842.273658951045;4843.370361852936;4915.5330243626795;4919.262110683426;4939.777710002986;4968.726720030433;4982.302477653059;4987.501249875128;4990.126727822689;5027.522385373383;4605.40129783126;4658.347831303552;4697.248440249544;4873.074115917964;4881.219004959189 06:03:51 INFO - PROCESS | 14858 | |1;ext.html;579.5537436174146;587.3715124816448;587.888301222768;589.7935722497124;592.0497601349834;27090.458190836198;39251.41122883159;40812.79593602035;40869.591304086985;44275.77862110685;42077.158456830846;42465.36301955473;42542.57457425432;42558.787206064;42567.57432425675;7622.542647441143;7883.526988380701;8037.517748935083;8042.115367309591;8045.959770201141;7603.973463582401;7837.373010159202;7840.843183136334;10066.748331291703;10304.484775761219;10354.430506322149;10450.425226612533;10453.21600879937;10467.633632822854;10474.214433917448;10311;10362.37825730451;10369.585216591327;10374.585016599327;10378.221633377452;7674.769756907302;10016.449095299755;10353.741156471073;10367.948160259226;10747.78504429907;39687.40468892969;39975.600243997476;39989.60010399888;40009.599904000876;40057.599424005966;2961.9851900740573;2999.07028821064;3603.6396360364015;3719.051641831316;3836.9808150959066;29875.402491950255;29964.70035299641;30129.246768830737;30256.8487157565;31580.36839263202;3101.6122984626923;3109.207152176203;3117.7973431726864;3142.1673256587023;3210.4221240176757;238798;239482;239736;240132;240771;31760.36479270448;33167;56015.719921400945;56549;56612.15081773777;33575.66424335774;33590.66409335924;33688.32623347519;33691.49462758061;33701;13690.315484225748;13809.930950345384;13815.585532434043;13828.37772300249;13841.723165536632;9698.36960597559;11564.306141631529;11609.709757255967;11648.592299269485;11651.300921944541;7833.2950034496025;8090.2718755311125;8096.311813495919;8180.9181908181345;8247.381446391544;3124.140861263171;3166.3350696353564;3166.5725127108008;3211;3219.742420606346;829.2619568583965;846.1961136919828;957.167264479893;957.4733896356976;1003.4230317567428;27601.04334865234;27881.58177627337;28716;28909.85545072261;29029;956.3114557518576;1001.2540657210279;1017.699778565325;1037.7457522906936;1076.1982323169289;14418.783718244234;14924.40302387914;15127.54617361481;15262.550490478947;15416.152111633968;372419;373518;375134;375177;376622;48886.75556622265;49194.26208606874;49337.75331123322;49360;49415.25877111846;1100.1638754546443;1191.3269003013443;1213.1386715432143;1222.291071178732;1247.9188852724544;28349.433011339246;29440;29568.930174888414;29728;29995.25011874633;42663.7866810664;42714.78642606892;43087.35368969405;43088.13823723445;43138;291651;292581;292767;293701;294178;22720.886395568577;23320.883395582914;25002.3749406259;25583.104591339215;25615.487690246464;41647.167056658094;42045;42316.36525452182;43284.567154329314;43458.782706087535;36120.45819312765;36865;37139.81430092832;37508.81245593754;37732;36637.633623664486;36881.44677829781;36906.077348066516;37437.4384384229;37850.24299514049;35201.29597408089;35586.46620300749;35801;35957.46063808993;36518.26963460769;32317.51523727193;33402.665973340925;33507.66492335143;34044.65955340415;34410.65589344175;25722;25944;26138;26268.737312627392;26336.604950925368 06:03:51 INFO - PROCESS | 14858 | |2;jquery.html;352.98411571479284;354.8757934722847;358.0761634981746;365.52846827592407;390.9218156368727;13138.080334376586;16357.672846543077;17143.742843857337;18657.90671046643;18688.15903284352;17715.645687086282;17820.732689009674;17821.287148514042;18267.90866045668;18270.269189232447;4805.663603547744;5227.79088836446;5242.030224408478;5242.475752424746;5245.291885595453;3702.7037836973113;5122.462141475147;5788.27646544182;6044.667543285118;6077.301110372297;6103.938960610403;6115.113308570255;6134.478569321591;6140.907886381708;6143.47780438661;5325.600579956499;5627.71861406928;5637.915431268514;5705.6006079574445;5710.343310519302;5313.628046036779;5501.367342755554;5511.255980442669;5521.641093328961;5523.861903452405;11380.374079425628;11386.886131138748;11429.428528573539;11533.942330288295;11571.884281157249;1820.945371638853;1831.7435559021746;1843.9631207375912;1878.7181922711566;1917.7890432052463;8713.215810577016;8996.325275604291;9181.173694367471;9569.33014688972;13361.799573006412;1744.72956691713;1766.5848525596475;1799.2173404568923;1816.7365731968923;1871.129924585061;66933.66533167304;73221;73367;73863.26136738618;75005;9057.411268267542;9659.71020869375;10050;10225.897741022643;10270.33242839213;11673.883261167279;12387.442565084593;12423.813642795365;12689.49242030327;12703.872961270454;7131.750388736369;7241.6050141817495;7417.962910185414;7430.807911666071;7477.962610187023;7273.745418910313;7283.417326613858;7305.283258434264;7308.96345518269;7413.073365829271;3906.0625449891822;3965.92068158641;3981.0843505993785;4033.9596604034173;4048.838046478108;1843.889366638006;1871.3520368678169;1897.9715304270446;1901.7908030116675;1925.711143328484;752.3417010116149;757.5681861338984;870.1559487297311;880.4585180114311;899.3120262998812;16284.592885177964;17033.31866725317;17646.2059207336;17731.822681773017;18098.185581648857;833.9166083391733;835.4444294544181;852.5268475995758;857.185673610073;895.8969718482327;9393.248540116774;9446.385984911096;9951.950240248898;10039.247056470795;10076.49617519121;373305;375595;376295;376537;377477;73393;78646.6067669658;79867.60066199632;80959.59520202361;82344;901.2339511415375;908.1735620585235;927.2303987690201;927.9350445468888;928.2388441477923;15532;15661.921690391475;15687;15837;16102.516924491816;27706.584401234402;27707.584386234626;38236.617633823305;38347.041323967125;40547.59452405556;52714.20928686149;54411.45588544222;54734;55317.44682553284;55325.44674553203;7551;7941.523508589503;8104.189581041848;8127.2279133482225;8358.289545388467;6751.358620931003;8804.735857924272;8909.376343655882;11586;11676.182667213554;5222.817201397933;5355.892882142258;10549.208809339178;10701.143908487458;10879.564817407216;11105.944470277596;11352.602658906899;11375.653442881832;11384.203105782515;11637.650870473899;10687.343251965933;10970.780584388109;11219;11402.771944561227;11429.657110286706;7641.961790191236;7986.201379861967;8127.593620319079;8248.876266856121;8321.750347489586;9845;10596.8940310599;11023.228374014063;11166.776664466828;11301.943490282496 06:03:51 INFO - PROCESS | 14858 | |3;mootools.html;1268.3721557828876;1388.9583312500624;1445.4868521674803;1521.878249740021;1764;6692.63190524521;7669.118051424088;7874;7907.881381779278;8116.269535741782;7822.178671239523;7978.162292959243;7984.081830589484;7985.560794156319;7994.760157195294;1867.738516607676;2133.328001679466;2199.527101673144;2206.79035491628;2225.9666105008437;2226.654868495386;2244.02384962541;2244.7306323241232;2247.685324054634;2247.8876056197205;2196.0556960506974;2215.2468160825315;2222.066731972567;2226.6103431899433;2226.810721088709;1817.0914542728635;2047.8668886522328;2082.6771850363216;2087.6430320291934;2158.902849371774;2125.2136709417614;2131.6695912133646;2142.1110239250847;2144.2066435418838;2144.9678254826035;7505.962470187614;7545.849083018364;7547.018887544639;7548;7557.319841214317;4560.293154561048;4621.630269578425;4628.305754136873;4635.2120139576355;4639.048994956026;2665.733426657319;2864.1550742530794;2866.7993240473174;2869.397426540429;2872.339361946743;917.6467060181811;950.6910254167424;963.5037955452947;1608.509404631587;1680;10812.189085818516;10881.99999999992;10914.617988370448;10975.396353200571;10998.34009959405;3934.4885164928946;4014.698897582693;4029.4761680981283;4194.748315101104;4206.348016057516;8540.701075462453;8556.914430855737;8566.700165494178;8568.828623427496;8602.440841345293;4560.749158796298;4562.68061235717;4564.132814765184;4565.908681826345;4567.81728730847;3692.889213323551;4152.584741525823;4260.616544511009;4267.210566045292;4343.131373725268;3469.757117001836;3520.718342532591;3566.3045706087582;3609.7473176877893;3636.2727454509204;959.0697023886819;1032.5766435761302;1049.4490392543823;1051.8211903976342;1059.9576016959236;810.8256724804196;837.5393533556642;838.4005436113208;864.5936409887343;868.4702331577732;5060.873478163002;5237.371515418175;5331.360236771535;5346.572274218053;5434.510894019557;814.3810703865105;851.5614458553849;861.8491763941286;886.4858382138344;890.7773056735816;3731.6081811409413;3811.6188381162215;3832.3485007548784;3890.105275786528;3900.414937759359;9601.90398096024;10027.348222365523;10048.598056077675;10058.647947321808;10076.798464030679;9701.563429645683;9875.703728888146;9955.25335599833;9958.502074896227;9965.55155018026;7333.779986600411;7451.552906825499;7512.098548174254;7515.962420187973;7519.172890981988;5150.716710580954;5222.686638801684;5244.5279924806955;5363.490468405495;5379.515843574097;8655.826883462421;8839.734807955772;8845.336599755174;8912.73261802147;8931.062238465127;8777.91222087771;9110.407823491452;9115.407498512575;9116.726498205064;9126.269898408244;6575.671216439255;6673.9666301668185;6690.933090669031;6697.162854643169;6698.765543205965;7498.925010749823;7550.5469671819865;7557.168711441847;7565.583892885724;7570.886436703564;6734.629595372302;6849.726010959505;6901.689423975833;6964.860702785814;7012.298770122947;6556.311587283458;6663.400293973566;6706.597604143767;6805.897911531232;6835.93164068353;6267.62394256346;6273.62358258506;6467;6467.935320646733;6615.17310336208;4633.953660463352;4672.462666793226;4695.788689509049;4699.318598803086;4771.997880445146;4652.488226295172;4667.673262871566;4727.692699974628;4791.233402655558;4830.806767729251 06:03:51 INFO - PROCESS | 14858 | |4;prototype.html;300.8781443515376;301.48897618536574;307.9060886429639;310.3730464461786;310.51094526121364;4830.492798256177;4838.274258861173;4847.019083263794;4854.5873600743935;4865.5864251538615;4779.35478710374;4791.904161916759;4800.783964721587;4808.182608956477;4808.230683090706;3616.566012078548;3726.37902512408;3730.5336832895887;3802.6577608015273;3812.1041555234497;3736.215394767102;3779.7165212609025;3789.3179227738997;3812.7140464465137;3827.617238276178;3769.792661403622;3772.660950210873;3788.3559794834805;3792.1657235408165;3803.3914573668217;3577.7853328800225;3650.7261955353324;3744.794036328001;3777.5655799583124;3816.313063648542;3342.7492938029745;3347.6652334766572;3347.782394144397;3350.3634309481363;3356.882509112194;4696.459907110692;4698.671093023489;4702.153612349775;4712;4750.334953106568;4223.901785535752;4348.565143485626;4361.520232774392;4363.214621368153;4396.78016099197;5009.373828271466;5029.220470827027;5045.823396181153;5050.070958843862;5051.520105589963;3688.483612294281;3714;3715.8699445519546;3720.962790372089;3725;5125.4618265082;5309.203619457074;5339.225812257201;5350.876315973611;5358.33020872391;3787.867424640124;3795.772253664789;3799.259144466858;3800.5819359870393;3807;3856.5757766645647;3861.691064714816;3862.9034274143373;3863.1114843586133;3864.6908247340143;4330.740155590612;4337.631301339421;4352.869413917588;4355.390245365651;4359.193549193409;4738.952610473852;4999.650024498323;5062.898742025139;5063.797448102035;5096.51583099605;1285.6978610026636;1288.652063942749;1295.1322613848745;1297.941592628334;1299.6231092983119;810.3436216664403;822.3010441124995;833.1168960901342;849.3290300662532;850.2050582705191;4384.6273066789045;4505.7296562206375;4507.549245075466;4514.367988481616;4516.23224051912;5155.50490357792;5175.767090480937;5180.222966554971;5200.2199670049795;5230.137027390514;4491.214037543416;4500.324951257272;4509.413776209072;4511.729296242236;4539.500654927955;2597.116980226732;2765.958510622342;2775.33391985926;2780.179846945156;2781.4979910847915;4958;4979.6265280104135;4984.7258400788305;4985.152524070917;5005.374328208974;5153.407358153785;5274.156135018456;5319.468053194727;5365.946340536622;5371.543418809367;4925.482824303468;4930.359053323045;4948.406191257072;4951.533926251638;4951.628627852998;5193.506616871391;5195.856911479468;5203.895922081613;5255.710935898409;5265.709416403876;4853.4418541868135;5101.489851014869;5121.1550094234035;5122.334096567572;5142.460041695717;4973.701577905337;4974.129527332775;4980.402351717817;5009.924851127309;5015.322931404359;3682.116292089933;3712.8514859405323;3746.6815320698047;3749.2314075614977;3761.0221342450614;5124.7950081996305;5303.946960530346;5310.336207974004;5332.706701131475;5335.279737235423;5079.958608485178;5084.3898732152375;5110.543495103878;5111.591072714248;5114.872128196676;5101.540861322499;5104.948950510596;5113.41195762492;5155.845324640267;5156.819511317236;4895.926561101515;4939.293873519781;5013.824516141917;5053.59571234293;5059.266406370982;4843.757812109451;4940.8764780880765;5108.79943213352;5130.461301563431;5134.871628209325;5003.599712022957;5011.649184557046;5014.674046186986;5042.823501177441;5065.822696205615 06:03:51 INFO - PROCESS | 14858 | |5;yui.html;686.9347411995861;695.2144077192771;725.1008749151055;736.6464147050174;746.9514481558701;12418.875811241896;14549.70900581989;15163.39346426143;15249.39002439901;15264.542063738105;15472.922635386807;15522.844771552282;15572.532824015268;15601.843981560181;15646.921765391156;624.5752888036134;777.2111307023375;786.5359437931619;807.6809660184224;808.7331180710352;788.45202584204;793.4683761879554;794.6622685358728;800.3037357498984;806.544302469106;841.4404421059987;848.2069265237024;848.2789628815489;848.7750746052301;849.2908421468081;651.840299126717;809.4778867630346;836.1262480707675;840.3949156607264;841.6759547574167;610.6092101055328;610.9480694140959;611.016263815257;611.3580740222749;613.1752792494088;13609.659758506017;13646.86353136466;13652.180869147784;13659.726805463935;13659.795103073462;12047;12356.320402377865;12494.187877787916;12692.682682932907;13164.341782910817;11968.760624787456;11973.640790776291;12151.149419540636;12183.51265949361;12644;4238.618524332794;4278.336857787017;4436.689431739779;4439.75581343026;4445.644348452116;60879.69560152215;62017.37982620161;62564.37435625631;62842.37157628411;63581.68209158971;10429.374237545771;10471.947640261902;10638.095761860173;10940.179486538542;11004.119670426348;19711.408657740292;19741.703874441893;19779.20883164686;19823.900880495505;20209.898950505154;6636.734530618721;6687.431568316651;6699.732010719516;6860.211075726255;7054.294570542905;3718.1448266898765;3737.364648009845;3742.644448777362;3787.3182827091387;3795.5065841440437;2715.416185520123;2784.540550809094;2799.636047313877;2799.650043744532;2801.6918139004692;528.4715284715285;551.6193826259868;556.9888602227933;560.3919747074469;566.3373852592476;413.56575595625077;511.2484647568069;513.7354262554787;519.1745125250869;520.4067363205949;7748.690052397841;7779.138038725796;7779.299863012356;8048.678052877936;8244.474772167168;518.8416188655176;528.9656172348786;532.9973350133224;534.593708781329;539.5926075812815;3633.473146393811;3652.652997965189;3706.3884459064493;3721.4417837324077;3843.9807800961376;99935.50032249792;101832;102237.48881255546;105206;107203.46398267959;87069.56465217633;88668.55665721759;89139.55430222808;89467.10532894588;89671.55164224138;79446.20553794388;79460.60269698614;79623;80093.19906800857;81133.59433202796;7067.187273463617;7184.389326907271;7236.20401755796;7278.199398066315;7278.96360518194;45165;45875.311870321304;47530;47627.04745905132;47848.760756197385;55584.72207638936;57374.42625573691;59536;64306.356936430035;64489.35510644833;7498.512596681417;7688.154303026775;7724.266194711493;11356.94321528387;11487.770244594894;10364.896351036394;10485.16118710486;10683.786324273626;11776.234544754563;12184.57353992606;10105.44420056904;10880.891191087987;11064.723381915517;11665.941670291595;11730.70673233176;11574.71063223426;11687.590934317259;11891.702707432383;12311.56909508163;12362.44369003415;11498.94250528742;11994.700132496757;12353.135280530276;12507;12724;10101.69694909154;10422.895771042497;10478.214133939833;10557.788844223227;10679.51942162589;9847.359921605073;10264.178865690577;10491.895081049397;10607.522661480407;10616.203784716332 06:03:51 INFO - PROCESS | 14858 | __end_tp_report 06:03:51 INFO - PROCESS | 14858 | __start_cc_report 06:03:51 INFO - PROCESS | 14858 | _x_x_mozilla_cycle_collect,943 06:03:51 INFO - PROCESS | 14858 | __end_cc_report 06:03:51 INFO - PROCESS | 14858 | __startTimestamp1468501431543__endTimestamp 06:03:51 INFO - PROCESS | 14858 | 06:03:51 INFO - PROCESS | 14858 | ------- Summary: start ------- 06:03:51 INFO - PROCESS | 14858 | Number of tests: 6 06:03:51 INFO - PROCESS | 14858 | 06:03:51 INFO - PROCESS | 14858 | [#0] dojo.html Cycles:165 Average:4647.10 Median:4798.45 stddev:488.05 (10.2%) stddev-sans-first:463.93 06:03:51 INFO - PROCESS | 14858 | Values: 2658.0 2672.6 2692.9 2693.8 2697.2 4572.8 4630.0 4684.3 4739.5 4793.5 4646.2 4647.0 4648.0 4677.2 4707.6 4233.1 4239.7 4300.9 4331.7 4453.7 4385.4 4408.9 4415.8 4442.4 4467.8 4167.0 4188.3 4196.2 4257.4 4278.1 4176.9 4180.5 4208.4 4294.2 4367.7 4205.3 4206.6 4219.7 4240.8 4259.6 4538.6 4590.2 4622.7 4639.8 4684.6 5039.2 5122.6 5135.7 5137.8 5139.4 5061.2 5114.5 5118.8 5130.0 5161.5 4540.4 4562.2 4649.4 4754.7 4806.1 5084.8 5135.5 5201.3 5204.1 5207.6 4935.4 4979.9 4989.7 4990.7 4995.7 4851.1 4962.9 5010.5 5033.0 5076.5 4997.9 5008.7 5031.6 5033.6 5065.3 4925.3 4982.6 4988.8 4989.3 5022.0 4163.5 4189.1 4261.9 4266.5 4324.4 3728.8 3748.9 3757.1 3787.5 3796.1 4376.5 4378.0 4391.7 4394.5 4440.3 4863.5 4916.1 5050.1 5062.3 5104.0 4227.2 4278.0 4333.8 4341.5 4352.0 4553.0 4554.7 4566.0 4571.7 4598.7 4629.2 4747.3 4811.2 4812.9 4819.3 5012.1 5059.3 5095.1 5105.9 5110.9 4790.3 4798.4 4798.4 4799.4 4810.8 4794.7 4947.0 4980.0 4983.5 5026.8 4939.6 4974.3 4989.8 4991.7 4996.5 4875.0 4885.7 4889.3 4897.8 4944.9 4806.6 4876.5 4883.0 4897.2 4942.1 4842.3 4843.4 4915.5 4919.3 4939.8 4968.7 4982.3 4987.5 4990.1 5027.5 4605.4 4658.3 4697.2 4873.1 4881.2 06:03:51 INFO - PROCESS | 14858 | 06:03:51 INFO - PROCESS | 14858 | [#1] ext.html Cycles:180 Average:45404.85 Median:33284.83 stddev:80849.53 (242.9%) stddev-sans-first:81006.28 06:03:51 INFO - PROCESS | 14858 | Values: 579.6 587.4 587.9 589.8 592.0 27090.5 39251.4 40812.8 40869.6 44275.8 42077.2 42465.4 42542.6 42558.8 42567.6 7622.5 7883.5 8037.5 8042.1 8046.0 7604.0 7837.4 7840.8 10066.7 10304.5 10354.4 10450.4 10453.2 10467.6 10474.2 10311.0 10362.4 10369.6 10374.6 10378.2 7674.8 10016.4 10353.7 10367.9 10747.8 39687.4 39975.6 39989.6 40009.6 40057.6 2962.0 2999.1 3603.6 3719.1 3837.0 29875.4 29964.7 30129.2 30256.8 31580.4 3101.6 3109.2 3117.8 3142.2 3210.4 238798.0 239482.0 239736.0 240132.0 240771.0 31760.4 33167.0 56015.7 56549.0 56612.2 33575.7 33590.7 33688.3 33691.5 33701.0 13690.3 13809.9 13815.6 13828.4 13841.7 9698.4 11564.3 11609.7 11648.6 11651.3 7833.3 8090.3 8096.3 8180.9 8247.4 3124.1 3166.3 3166.6 3211.0 3219.7 829.3 846.2 957.2 957.5 1003.4 27601.0 27881.6 28716.0 28909.9 29029.0 956.3 1001.3 1017.7 1037.7 1076.2 14418.8 14924.4 15127.5 15262.6 15416.2 372419.0 373518.0 375134.0 375177.0 376622.0 48886.8 49194.3 49337.8 49360.0 49415.3 1100.2 1191.3 1213.1 1222.3 1247.9 28349.4 29440.0 29568.9 29728.0 29995.3 42663.8 42714.8 43087.4 43088.1 43138.0 291651.0 292581.0 292767.0 293701.0 294178.0 22720.9 23320.9 25002.4 25583.1 25615.5 41647.2 42045.0 42316.4 43284.6 43458.8 36120.5 36865.0 37139.8 37508.8 37732.0 36637.6 36881.4 36906.1 37437.4 37850.2 35201.3 35586.5 35801.0 35957.5 36518.3 32317.5 33402.7 33507.7 34044.7 34410.7 25722.0 25944.0 26138.0 26268.7 26336.6 06:03:51 INFO - PROCESS | 14858 | 06:03:51 INFO - PROCESS | 14858 | [#2] jquery.html Cycles:180 Average:23889.94 Median:22676.63 stddev:62340.22 (274.9%) stddev-sans-first:62490.05 06:03:51 INFO - PROCESS | 14858 | Values: 353.0 354.9 358.1 365.5 390.9 13138.1 16357.7 17143.7 18657.9 18688.2 17715.6 17820.7 17821.3 18267.9 18270.3 4805.7 5227.8 5242.0 5242.5 5245.3 3702.7 5122.5 5788.3 6044.7 6077.3 6103.9 6115.1 6134.5 6140.9 6143.5 5325.6 5627.7 5637.9 5705.6 5710.3 5313.6 5501.4 5511.3 5521.6 5523.9 11380.4 11386.9 11429.4 11533.9 11571.9 1820.9 1831.7 1844.0 1878.7 1917.8 8713.2 8996.3 9181.2 9569.3 13361.8 1744.7 1766.6 1799.2 1816.7 1871.1 66933.7 73221.0 73367.0 73863.3 75005.0 9057.4 9659.7 10050.0 10225.9 10270.3 11673.9 12387.4 12423.8 12689.5 12703.9 7131.8 7241.6 7418.0 7430.8 7478.0 7273.7 7283.4 7305.3 7309.0 7413.1 3906.1 3965.9 3981.1 4034.0 4048.8 1843.9 1871.4 1898.0 1901.8 1925.7 752.3 757.6 870.2 880.5 899.3 16284.6 17033.3 17646.2 17731.8 18098.2 833.9 835.4 852.5 857.2 895.9 9393.2 9446.4 9952.0 10039.2 10076.5 373305.0 375595.0 376295.0 376537.0 377477.0 73393.0 78646.6 79867.6 80959.6 82344.0 901.2 908.2 927.2 927.9 928.2 15532.0 15661.9 15687.0 15837.0 16102.5 27706.6 27707.6 38236.6 38347.0 40547.6 52714.2 54411.5 54734.0 55317.4 55325.4 7551.0 7941.5 8104.2 8127.2 8358.3 6751.4 8804.7 8909.4 11586.0 11676.2 5222.8 5355.9 10549.2 10701.1 10879.6 11105.9 11352.6 11375.7 11384.2 11637.7 10687.3 10970.8 11219.0 11402.8 11429.7 7642.0 7986.2 8127.6 8248.9 8321.8 9845.0 10596.9 11023.2 11166.8 11301.9 06:03:51 INFO - PROCESS | 14858 | 06:03:51 INFO - PROCESS | 14858 | [#3] mootools.html Cycles:180 Average:5163.38 Median:5105.80 stddev:2899.57 (56.8%) stddev-sans-first:2892.93 06:03:51 INFO - PROCESS | 14858 | Values: 1268.4 1389.0 1445.5 1521.9 1764.0 6692.6 7669.1 7874.0 7907.9 8116.3 7822.2 7978.2 7984.1 7985.6 7994.8 1867.7 2133.3 2199.5 2206.8 2226.0 2226.7 2244.0 2244.7 2247.7 2247.9 2196.1 2215.2 2222.1 2226.6 2226.8 1817.1 2047.9 2082.7 2087.6 2158.9 2125.2 2131.7 2142.1 2144.2 2145.0 7506.0 7545.8 7547.0 7548.0 7557.3 4560.3 4621.6 4628.3 4635.2 4639.0 2665.7 2864.2 2866.8 2869.4 2872.3 917.6 950.7 963.5 1608.5 1680.0 10812.2 10882.0 10914.6 10975.4 10998.3 3934.5 4014.7 4029.5 4194.7 4206.3 8540.7 8556.9 8566.7 8568.8 8602.4 4560.7 4562.7 4564.1 4565.9 4567.8 3692.9 4152.6 4260.6 4267.2 4343.1 3469.8 3520.7 3566.3 3609.7 3636.3 959.1 1032.6 1049.4 1051.8 1060.0 810.8 837.5 838.4 864.6 868.5 5060.9 5237.4 5331.4 5346.6 5434.5 814.4 851.6 861.8 886.5 890.8 3731.6 3811.6 3832.3 3890.1 3900.4 9601.9 10027.3 10048.6 10058.6 10076.8 9701.6 9875.7 9955.3 9958.5 9965.6 7333.8 7451.6 7512.1 7516.0 7519.2 5150.7 5222.7 5244.5 5363.5 5379.5 8655.8 8839.7 8845.3 8912.7 8931.1 8777.9 9110.4 9115.4 9116.7 9126.3 6575.7 6674.0 6690.9 6697.2 6698.8 7498.9 7550.5 7557.2 7565.6 7570.9 6734.6 6849.7 6901.7 6964.9 7012.3 6556.3 6663.4 6706.6 6805.9 6835.9 6267.6 6273.6 6467.0 6467.9 6615.2 4634.0 4672.5 4695.8 4699.3 4772.0 4652.5 4667.7 4727.7 4791.2 4830.8 06:03:51 INFO - PROCESS | 14858 | 06:03:51 INFO - PROCESS | 14858 | [#4] prototype.html Cycles:180 Average:4229.63 Median:4819.36 stddev:1225.80 (25.4%) stddev-sans-first:1193.24 06:03:51 INFO - PROCESS | 14858 | Values: 300.9 301.5 307.9 310.4 310.5 4830.5 4838.3 4847.0 4854.6 4865.6 4779.4 4791.9 4800.8 4808.2 4808.2 3616.6 3726.4 3730.5 3802.7 3812.1 3736.2 3779.7 3789.3 3812.7 3827.6 3769.8 3772.7 3788.4 3792.2 3803.4 3577.8 3650.7 3744.8 3777.6 3816.3 3342.7 3347.7 3347.8 3350.4 3356.9 4696.5 4698.7 4702.2 4712.0 4750.3 4223.9 4348.6 4361.5 4363.2 4396.8 5009.4 5029.2 5045.8 5050.1 5051.5 3688.5 3714.0 3715.9 3721.0 3725.0 5125.5 5309.2 5339.2 5350.9 5358.3 3787.9 3795.8 3799.3 3800.6 3807.0 3856.6 3861.7 3862.9 3863.1 3864.7 4330.7 4337.6 4352.9 4355.4 4359.2 4739.0 4999.7 5062.9 5063.8 5096.5 1285.7 1288.7 1295.1 1297.9 1299.6 810.3 822.3 833.1 849.3 850.2 4384.6 4505.7 4507.5 4514.4 4516.2 5155.5 5175.8 5180.2 5200.2 5230.1 4491.2 4500.3 4509.4 4511.7 4539.5 2597.1 2766.0 2775.3 2780.2 2781.5 4958.0 4979.6 4984.7 4985.2 5005.4 5153.4 5274.2 5319.5 5365.9 5371.5 4925.5 4930.4 4948.4 4951.5 4951.6 5193.5 5195.9 5203.9 5255.7 5265.7 4853.4 5101.5 5121.2 5122.3 5142.5 4973.7 4974.1 4980.4 5009.9 5015.3 3682.1 3712.9 3746.7 3749.2 3761.0 5124.8 5303.9 5310.3 5332.7 5335.3 5080.0 5084.4 5110.5 5111.6 5114.9 5101.5 5104.9 5113.4 5155.8 5156.8 4895.9 4939.3 5013.8 5053.6 5059.3 4843.8 4940.9 5108.8 5130.5 5134.9 5003.6 5011.6 5014.7 5042.8 5065.8 06:03:51 INFO - PROCESS | 14858 | 06:03:51 INFO - PROCESS | 14858 | [#5] yui.html Cycles:180 Average:18318.94 Median:24805.32 stddev:26488.97 (106.8%) stddev-sans-first:26530.20 06:03:51 INFO - PROCESS | 14858 | Values: 686.9 695.2 725.1 736.6 747.0 12418.9 14549.7 15163.4 15249.4 15264.5 15472.9 15522.8 15572.5 15601.8 15646.9 624.6 777.2 786.5 807.7 808.7 788.5 793.5 794.7 800.3 806.5 841.4 848.2 848.3 848.8 849.3 651.8 809.5 836.1 840.4 841.7 610.6 610.9 611.0 611.4 613.2 13609.7 13646.9 13652.2 13659.7 13659.8 12047.0 12356.3 12494.2 12692.7 13164.3 11968.8 11973.6 12151.1 12183.5 12644.0 4238.6 4278.3 4436.7 4439.8 4445.6 60879.7 62017.4 62564.4 62842.4 63581.7 10429.4 10471.9 10638.1 10940.2 11004.1 19711.4 19741.7 19779.2 19823.9 20209.9 6636.7 6687.4 6699.7 6860.2 7054.3 3718.1 3737.4 3742.6 3787.3 3795.5 2715.4 2784.5 2799.6 2799.7 2801.7 528.5 551.6 557.0 560.4 566.3 413.6 511.2 513.7 519.2 520.4 7748.7 7779.1 7779.3 8048.7 8244.5 518.8 529.0 533.0 534.6 539.6 3633.5 3652.7 3706.4 3721.4 3844.0 99935.5 101832.0 102237.5 105206.0 107203.5 87069.6 88668.6 89139.6 89467.1 89671.6 79446.2 79460.6 79623.0 80093.2 81133.6 7067.2 7184.4 7236.2 7278.2 7279.0 45165.0 45875.3 47530.0 47627.0 47848.8 55584.7 57374.4 59536.0 64306.4 64489.4 7498.5 7688.2 7724.3 11356.9 11487.8 10364.9 10485.2 10683.8 11776.2 12184.6 10105.4 10880.9 11064.7 11665.9 11730.7 11574.7 11687.6 11891.7 12311.6 12362.4 11498.9 11994.7 12353.1 12507.0 12724.0 10101.7 10422.9 10478.2 10557.8 10679.5 9847.4 10264.2 10491.9 10607.5 10616.2 06:03:51 INFO - PROCESS | 14858 | -------- Summary: end -------- 06:03:51 INFO - PROCESS | 14858 | 06:03:52 INFO - TEST-INFO | 14858: exit 0 06:03:52 INFO - TEST-OK | dromaeo_css | took 1119410ms 06:03:52 INFO - TEST-START | kraken 06:03:52 INFO - operating with platform_type : linux_ 06:03:52 INFO - Initialising browser for kraken test... 06:03:52 INFO - TEST-INFO | started process 15045 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGhotI0/profile http://localhost:56247/getInfo.html) 06:03:56 INFO - PROCESS | 15045 | __metrics Screen width/height:1600/1200 06:03:56 INFO - PROCESS | 15045 | colorDepth:24 06:03:56 INFO - PROCESS | 15045 | Browser inner width/height: 1024/697 06:03:56 INFO - PROCESS | 15045 | __metrics 06:03:57 INFO - PROCESS | 15045 | 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" 06:03:57 INFO - PROCESS | 15045 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:03:58 INFO - TEST-INFO | 15045: exit 0 06:03:58 INFO - Browser initialized. 06:03:58 INFO - Running cycle 1/1 for kraken test... 06:03:58 INFO - TEST-INFO | started process 15127 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGhotI0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:04:10 INFO - PROCESS | 15127 | RSS: Main: 158736384 06:04:10 INFO - PROCESS | 15127 | 06:04:12 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56247/tests/kraken/driver.html?testName=audio-beat-detection) 06:04:13 INFO - PROCESS | 15127 | RSS: Main: 217161728 06:04:13 INFO - PROCESS | 15127 | 06:04:16 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56247/tests/kraken/driver.html?testName=audio-dft) 06:04:17 INFO - PROCESS | 15127 | RSS: Main: 205295616 06:04:17 INFO - PROCESS | 15127 | 06:04:20 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56247/tests/kraken/driver.html?testName=audio-fft) 06:04:20 INFO - PROCESS | 15127 | RSS: Main: 270159872 06:04:20 INFO - PROCESS | 15127 | 06:04:23 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56247/tests/kraken/driver.html?testName=audio-oscillator) 06:04:23 INFO - PROCESS | 15127 | RSS: Main: 181952512 06:04:23 INFO - PROCESS | 15127 | 06:04:25 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:04:26 INFO - PROCESS | 15127 | RSS: Main: 185856000 06:04:26 INFO - PROCESS | 15127 | 06:04:29 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56247/tests/kraken/driver.html?testName=imaging-darkroom) 06:04:30 INFO - PROCESS | 15127 | RSS: Main: 221974528 06:04:30 INFO - PROCESS | 15127 | 06:04:33 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56247/tests/kraken/driver.html?testName=imaging-desaturate) 06:04:34 INFO - PROCESS | 15127 | RSS: Main: 217600000 06:04:34 INFO - PROCESS | 15127 | 06:04:37 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56247/tests/kraken/driver.html?testName=json-parse-financial) 06:04:38 INFO - PROCESS | 15127 | RSS: Main: 220835840 06:04:38 INFO - PROCESS | 15127 | 06:04:39 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:04:40 INFO - PROCESS | 15127 | RSS: Main: 207740928 06:04:40 INFO - PROCESS | 15127 | 06:04:42 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56247/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:04:42 INFO - PROCESS | 15127 | RSS: Main: 211906560 06:04:42 INFO - PROCESS | 15127 | 06:04:45 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:04:45 INFO - PROCESS | 15127 | RSS: Main: 211390464 06:04:45 INFO - PROCESS | 15127 | 06:04:48 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:04:49 INFO - PROCESS | 15127 | RSS: Main: 229912576 06:04:49 INFO - PROCESS | 15127 | 06:04:51 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:04:52 INFO - PROCESS | 15127 | RSS: Main: 224014336 06:04:52 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | Cycle 1(1): loaded http://localhost:56247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56247/tests/kraken/driver.html?testName=ai-astar) 06:04:54 INFO - PROCESS | 15127 | RSS: Main: 265859072 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | __start_tp_report 06:04:54 INFO - PROCESS | 15127 | _x_x_mozilla_page_load 06:04:54 INFO - PROCESS | 15127 | _x_x_mozilla_page_load_details 06:04:54 INFO - PROCESS | 15127 | |i|pagename|runs| 06:04:54 INFO - PROCESS | 15127 | |0;ai-astar;102;114;111;113;113;113;113;112;111;113 06:04:54 INFO - PROCESS | 15127 | |1;audio-beat-detection;151;128;125;167;123;123;147;123;158;124 06:04:54 INFO - PROCESS | 15127 | |2;audio-dft;162;160;161;161;162;163;161;162;163;160 06:04:54 INFO - PROCESS | 15127 | |3;audio-fft;76;76;76;114;75;77;75;75;101;76 06:04:54 INFO - PROCESS | 15127 | |4;audio-oscillator;85;86;112;86;86;85;111;86;85;132 06:04:54 INFO - PROCESS | 15127 | |5;imaging-gaussian-blur;117;116;116;117;117;119;117;117;117;117 06:04:54 INFO - PROCESS | 15127 | |6;imaging-darkroom;146;146;146;145;146;146;146;145;146;146 06:04:54 INFO - PROCESS | 15127 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;94 06:04:54 INFO - PROCESS | 15127 | |8;json-parse-financial;66;65;65;65;66;66;67;65;66;64 06:04:54 INFO - PROCESS | 15127 | |9;json-stringify-tinderbox;66;67;65;66;66;66;65;65;65;65 06:04:54 INFO - PROCESS | 15127 | |10;stanford-crypto-aes;78;79;86;84;78;78;78;79;84;82 06:04:54 INFO - PROCESS | 15127 | |11;stanford-crypto-ccm;130;126;129;126;126;131;131;129;180;128 06:04:54 INFO - PROCESS | 15127 | |12;stanford-crypto-pbkdf2;162;171;164;173;167;162;163;162;162;163 06:04:54 INFO - PROCESS | 15127 | |13;stanford-crypto-sha256-iterative;66;70;66;66;66;67;65;65;65;64 06:04:54 INFO - PROCESS | 15127 | __end_tp_report 06:04:54 INFO - PROCESS | 15127 | __start_cc_report 06:04:54 INFO - PROCESS | 15127 | _x_x_mozilla_cycle_collect,1894 06:04:54 INFO - PROCESS | 15127 | __end_cc_report 06:04:54 INFO - PROCESS | 15127 | __startTimestamp1468501494480__endTimestamp 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | ------- Summary: start ------- 06:04:54 INFO - PROCESS | 15127 | Number of tests: 14 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:3.47 (3.1%) stddev-sans-first:1.01 06:04:54 INFO - PROCESS | 15127 | Values: 102.0 114.0 111.0 113.0 113.0 113.0 113.0 112.0 111.0 113.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#1] audio-beat-detection Cycles:10 Average:136.90 Median:137.50 stddev:17.06 (12.4%) stddev-sans-first:17.31 06:04:54 INFO - PROCESS | 15127 | Values: 151.0 128.0 125.0 167.0 123.0 123.0 147.0 123.0 158.0 124.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 06:04:54 INFO - PROCESS | 15127 | Values: 162.0 160.0 161.0 161.0 162.0 163.0 161.0 162.0 163.0 160.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#3] audio-fft Cycles:10 Average:82.10 Median:76.00 stddev:13.75 (18.1%) stddev-sans-first:14.40 06:04:54 INFO - PROCESS | 15127 | Values: 76.0 76.0 76.0 114.0 75.0 77.0 75.0 75.0 101.0 76.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:85.50 stddev:16.79 (19.6%) stddev-sans-first:17.38 06:04:54 INFO - PROCESS | 15127 | Values: 85.0 86.0 112.0 86.0 86.0 85.0 111.0 86.0 85.0 132.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 06:04:54 INFO - PROCESS | 15127 | Values: 117.0 116.0 116.0 117.0 117.0 119.0 117.0 117.0 117.0 117.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 06:04:54 INFO - PROCESS | 15127 | Values: 146.0 146.0 146.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 06:04:54 INFO - PROCESS | 15127 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:66.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 06:04:54 INFO - PROCESS | 15127 | Values: 66.0 65.0 65.0 65.0 66.0 66.0 67.0 65.0 66.0 64.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 06:04:54 INFO - PROCESS | 15127 | Values: 66.0 67.0 65.0 66.0 66.0 66.0 65.0 65.0 65.0 65.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:80.50 stddev:3.10 (3.8%) stddev-sans-first:3.14 06:04:54 INFO - PROCESS | 15127 | Values: 78.0 79.0 86.0 84.0 78.0 78.0 78.0 79.0 84.0 82.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#11] stanford-crypto-ccm Cycles:10 Average:133.60 Median:129.50 stddev:16.42 (12.7%) stddev-sans-first:17.36 06:04:54 INFO - PROCESS | 15127 | Values: 130.0 126.0 129.0 126.0 126.0 131.0 131.0 129.0 180.0 128.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:163.50 stddev:4.07 (2.5%) stddev-sans-first:4.18 06:04:54 INFO - PROCESS | 15127 | Values: 162.0 171.0 164.0 173.0 167.0 162.0 163.0 162.0 162.0 163.0 06:04:54 INFO - PROCESS | 15127 | 06:04:54 INFO - PROCESS | 15127 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:1.63 (2.5%) stddev-sans-first:1.73 06:04:54 INFO - PROCESS | 15127 | Values: 66.0 70.0 66.0 66.0 66.0 67.0 65.0 65.0 65.0 64.0 06:04:54 INFO - PROCESS | 15127 | -------- Summary: end -------- 06:04:54 INFO - PROCESS | 15127 | 06:04:55 INFO - TEST-INFO | 15127: exit 0 06:04:55 INFO - TEST-OK | kraken | took 63122ms 06:04:55 INFO - SUITE-END | took 1182s 06:04:55 INFO - Completed test suite (00:19:42) 06:04:55 INFO - javascript benchmark 06:04:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2657.9999999999995, 2672.5991101334807, 2692.932676683083, 2693.771029462496, 2697.150397624748, 4572.771361431932, 4630.0, 4684.250519916814, 4739.502352253011, 4793.472718001021, 4646.210144275473, 4646.976765116178, 4647.953520464803, 4677.228257337544, 4707.62339012879, 4233.068724880529, 4239.724417912841, 4300.935485967713, 4331.740095594275, 4453.732776033448, 4385.386045953554, 4408.889777755566, 4415.757133357664, 4442.444694413199, 4467.776611169445, 4166.958330416687, 4188.308929026707, 4196.202721482909, 4257.446531950826, 4278.122984788127, 4176.874693759192, 4180.519240287375, 4208.389783481378, 4294.248506511348, 4367.650587952956, 4205.285101532747, 4206.558311377292, 4219.7046206765535, 4240.766757828318, 4259.616634502879, 4538.636909047302, 4590.173768721629, 4622.653301002405, 4639.837605683785, 4684.578387945101, 5039.168537191395, 5122.615803814691, 5135.743212839343, 5137.845864624067, 5139.434662187156, 5061.215511595708, 5114.514121158521, 5118.820841270538, 5129.974350128263, 5161.509656582619, 4540.409746732969, 4562.156001139733, 4649.372334734835, 4754.714717117019, 4806.134895718735, 5084.77118529667, 5135.512126347991, 5201.3238279024245, 5204.115300398941, 5207.5833933286, 4935.432425271068, 4979.90040199194, 4989.650724449254, 4990.6506544541535, 4995.70025798446, 4851.102546028996, 4962.925556116661, 5010.49895010496, 5032.974835125801, 5076.517730815566, 4997.925031124536, 5008.6744361616375, 5031.622628302892, 5033.647644664911, 5065.341505604321, 4925.335079764258, 4982.626303027288, 4988.800447982113, 4989.3264409305, 5022.0, 4163.479565054368, 4189.141226048653, 4261.87214383569, 4266.466691663542, 4324.3945847580735, 3728.832202550892, 3748.8500459981296, 3757.0982964088967, 3787.4886890269463, 3796.145867179918, 4376.474823021194, 4378.0, 4391.692581519327, 4394.450693663292, 4440.289553671397, 4863.537963893425, 4916.115099282092, 5050.141475949097, 5062.291279220912, 5104.03023425548, 4227.239096962577, 4277.978610106992, 4333.804978775963, 4341.544137865541, 4351.9564804352185, 4553.043860789271, 4554.726716396961, 4565.954340456553, 4571.748553829571, 4598.747068911242, 4629.189891768994, 4747.335373047707, 4811.158047341702, 4812.8796780080775, 4819.2530157825595, 5012.072766538202, 5059.316992206005, 5095.13382724945, 5105.948940510547, 5110.872228194176, 4790.305405716222, 4798.4001999750035, 4798.448178459522, 4799.400074990626, 4810.831620893252, 4794.688345257547, 4946.975265123651, 4980.042193674888, 4983.501649834988, 5026.84919452417, 4939.629527785359, 4974.253861920741, 4989.825356112518, 4991.675541089818, 4996.475370086088, 4875.0493653737185, 4885.65800393969, 4889.291052797395, 4897.804087836446, 4944.851654450372, 4806.615470762401, 4876.463589005136, 4882.975585122052, 4897.216445368724, 4942.085714142895, 4842.273658951045, 4843.370361852936, 4915.5330243626795, 4919.262110683426, 4939.777710002986, 4968.726720030433, 4982.302477653059, 4987.501249875128, 4990.126727822689, 5027.522385373383, 4605.40129783126, 4658.347831303552, 4697.248440249544, 4873.074115917964, 4881.219004959189], "value": 4616.561975032335, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.5537436174146, 587.3715124816448, 587.888301222768, 589.7935722497124, 592.0497601349834, 27090.458190836198, 39251.41122883159, 40812.79593602035, 40869.591304086985, 44275.77862110685, 42077.158456830846, 42465.36301955473, 42542.57457425432, 42558.787206064, 42567.57432425675, 7622.542647441143, 7883.526988380701, 8037.517748935083, 8042.115367309591, 8045.959770201141, 7603.973463582401, 7837.373010159202, 7840.843183136334, 10066.748331291703, 10304.484775761219, 10354.430506322149, 10450.425226612533, 10453.21600879937, 10467.633632822854, 10474.214433917448, 10311.0, 10362.37825730451, 10369.585216591327, 10374.585016599327, 10378.221633377452, 7674.769756907302, 10016.449095299755, 10353.741156471073, 10367.948160259226, 10747.78504429907, 39687.40468892969, 39975.600243997476, 39989.60010399888, 40009.599904000876, 40057.599424005966, 2961.9851900740573, 2999.07028821064, 3603.6396360364015, 3719.051641831316, 3836.9808150959066, 29875.402491950255, 29964.70035299641, 30129.246768830737, 30256.8487157565, 31580.36839263202, 3101.6122984626923, 3109.207152176203, 3117.7973431726864, 3142.1673256587023, 3210.4221240176757, 238798.0, 239482.0, 239736.0, 240132.0, 240771.0, 31760.36479270448, 33167.0, 56015.719921400945, 56549.0, 56612.15081773777, 33575.66424335774, 33590.66409335924, 33688.32623347519, 33691.49462758061, 33701.0, 13690.315484225748, 13809.930950345384, 13815.585532434043, 13828.37772300249, 13841.723165536632, 9698.36960597559, 11564.306141631529, 11609.709757255967, 11648.592299269485, 11651.300921944541, 7833.2950034496025, 8090.2718755311125, 8096.311813495919, 8180.9181908181345, 8247.381446391544, 3124.140861263171, 3166.3350696353564, 3166.5725127108008, 3211.0, 3219.742420606346, 829.2619568583965, 846.1961136919828, 957.167264479893, 957.4733896356976, 1003.4230317567428, 27601.04334865234, 27881.58177627337, 28716.0, 28909.85545072261, 29029.0, 956.3114557518576, 1001.2540657210279, 1017.699778565325, 1037.7457522906936, 1076.1982323169289, 14418.783718244234, 14924.40302387914, 15127.54617361481, 15262.550490478947, 15416.152111633968, 372419.0, 373518.0, 375134.0, 375177.0, 376622.0, 48886.75556622265, 49194.26208606874, 49337.75331123322, 49360.0, 49415.25877111846, 1100.1638754546443, 1191.3269003013443, 1213.1386715432143, 1222.291071178732, 1247.9188852724544, 28349.433011339246, 29440.0, 29568.930174888414, 29728.0, 29995.25011874633, 42663.7866810664, 42714.78642606892, 43087.35368969405, 43088.13823723445, 43138.0, 291651.0, 292581.0, 292767.0, 293701.0, 294178.0, 22720.886395568577, 23320.883395582914, 25002.3749406259, 25583.104591339215, 25615.487690246464, 41647.167056658094, 42045.0, 42316.36525452182, 43284.567154329314, 43458.782706087535, 36120.45819312765, 36865.0, 37139.81430092832, 37508.81245593754, 37732.0, 36637.633623664486, 36881.44677829781, 36906.077348066516, 37437.4384384229, 37850.24299514049, 35201.29597408089, 35586.46620300749, 35801.0, 35957.46063808993, 36518.26963460769, 32317.51523727193, 33402.665973340925, 33507.66492335143, 34044.65955340415, 34410.65589344175, 25722.0, 25944.0, 26138.0, 26268.737312627392, 26336.604950925368], "value": 17012.776984178898, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.98411571479284, 354.8757934722847, 358.0761634981746, 365.52846827592407, 390.9218156368727, 13138.080334376586, 16357.672846543077, 17143.742843857337, 18657.90671046643, 18688.15903284352, 17715.645687086282, 17820.732689009674, 17821.287148514042, 18267.90866045668, 18270.269189232447, 4805.663603547744, 5227.79088836446, 5242.030224408478, 5242.475752424746, 5245.291885595453, 3702.7037836973113, 5122.462141475147, 5788.27646544182, 6044.667543285118, 6077.301110372297, 6103.938960610403, 6115.113308570255, 6134.478569321591, 6140.907886381708, 6143.47780438661, 5325.600579956499, 5627.71861406928, 5637.915431268514, 5705.6006079574445, 5710.343310519302, 5313.628046036779, 5501.367342755554, 5511.255980442669, 5521.641093328961, 5523.861903452405, 11380.374079425628, 11386.886131138748, 11429.428528573539, 11533.942330288295, 11571.884281157249, 1820.945371638853, 1831.7435559021746, 1843.9631207375912, 1878.7181922711566, 1917.7890432052463, 8713.215810577016, 8996.325275604291, 9181.173694367471, 9569.33014688972, 13361.799573006412, 1744.72956691713, 1766.5848525596475, 1799.2173404568923, 1816.7365731968923, 1871.129924585061, 66933.66533167304, 73221.0, 73367.0, 73863.26136738618, 75005.0, 9057.411268267542, 9659.71020869375, 10050.0, 10225.897741022643, 10270.33242839213, 11673.883261167279, 12387.442565084593, 12423.813642795365, 12689.49242030327, 12703.872961270454, 7131.750388736369, 7241.6050141817495, 7417.962910185414, 7430.807911666071, 7477.962610187023, 7273.745418910313, 7283.417326613858, 7305.283258434264, 7308.96345518269, 7413.073365829271, 3906.0625449891822, 3965.92068158641, 3981.0843505993785, 4033.9596604034173, 4048.838046478108, 1843.889366638006, 1871.3520368678169, 1897.9715304270446, 1901.7908030116675, 1925.711143328484, 752.3417010116149, 757.5681861338984, 870.1559487297311, 880.4585180114311, 899.3120262998812, 16284.592885177964, 17033.31866725317, 17646.2059207336, 17731.822681773017, 18098.185581648857, 833.9166083391733, 835.4444294544181, 852.5268475995758, 857.185673610073, 895.8969718482327, 9393.248540116774, 9446.385984911096, 9951.950240248898, 10039.247056470795, 10076.49617519121, 373305.0, 375595.0, 376295.0, 376537.0, 377477.0, 73393.0, 78646.6067669658, 79867.60066199632, 80959.59520202361, 82344.0, 901.2339511415375, 908.1735620585235, 927.2303987690201, 927.9350445468888, 928.2388441477923, 15532.0, 15661.921690391475, 15687.0, 15837.0, 16102.516924491816, 27706.584401234402, 27707.584386234626, 38236.617633823305, 38347.041323967125, 40547.59452405556, 52714.20928686149, 54411.45588544222, 54734.0, 55317.44682553284, 55325.44674553203, 7551.0, 7941.523508589503, 8104.189581041848, 8127.2279133482225, 8358.289545388467, 6751.358620931003, 8804.735857924272, 8909.376343655882, 11586.0, 11676.182667213554, 5222.817201397933, 5355.892882142258, 10549.208809339178, 10701.143908487458, 10879.564817407216, 11105.944470277596, 11352.602658906899, 11375.653442881832, 11384.203105782515, 11637.650870473899, 10687.343251965933, 10970.780584388109, 11219.0, 11402.771944561227, 11429.657110286706, 7641.961790191236, 7986.201379861967, 8127.593620319079, 8248.876266856121, 8321.750347489586, 9845.0, 10596.8940310599, 11023.228374014063, 11166.776664466828, 11301.943490282496], "value": 8118.519992977058, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1268.3721557828876, 1388.9583312500624, 1445.4868521674803, 1521.878249740021, 1764.0, 6692.63190524521, 7669.118051424088, 7874.0, 7907.881381779278, 8116.269535741782, 7822.178671239523, 7978.162292959243, 7984.081830589484, 7985.560794156319, 7994.760157195294, 1867.738516607676, 2133.328001679466, 2199.527101673144, 2206.79035491628, 2225.9666105008437, 2226.654868495386, 2244.02384962541, 2244.7306323241232, 2247.685324054634, 2247.8876056197205, 2196.0556960506974, 2215.2468160825315, 2222.066731972567, 2226.6103431899433, 2226.810721088709, 1817.0914542728635, 2047.8668886522328, 2082.6771850363216, 2087.6430320291934, 2158.902849371774, 2125.2136709417614, 2131.6695912133646, 2142.1110239250847, 2144.2066435418838, 2144.9678254826035, 7505.962470187614, 7545.849083018364, 7547.018887544639, 7548.0, 7557.319841214317, 4560.293154561048, 4621.630269578425, 4628.305754136873, 4635.2120139576355, 4639.048994956026, 2665.733426657319, 2864.1550742530794, 2866.7993240473174, 2869.397426540429, 2872.339361946743, 917.6467060181811, 950.6910254167424, 963.5037955452947, 1608.509404631587, 1680.0, 10812.189085818516, 10881.99999999992, 10914.617988370448, 10975.396353200571, 10998.34009959405, 3934.4885164928946, 4014.698897582693, 4029.4761680981283, 4194.748315101104, 4206.348016057516, 8540.701075462453, 8556.914430855737, 8566.700165494178, 8568.828623427496, 8602.440841345293, 4560.749158796298, 4562.68061235717, 4564.132814765184, 4565.908681826345, 4567.81728730847, 3692.889213323551, 4152.584741525823, 4260.616544511009, 4267.210566045292, 4343.131373725268, 3469.757117001836, 3520.718342532591, 3566.3045706087582, 3609.7473176877893, 3636.2727454509204, 959.0697023886819, 1032.5766435761302, 1049.4490392543823, 1051.8211903976342, 1059.9576016959236, 810.8256724804196, 837.5393533556642, 838.4005436113208, 864.5936409887343, 868.4702331577732, 5060.873478163002, 5237.371515418175, 5331.360236771535, 5346.572274218053, 5434.510894019557, 814.3810703865105, 851.5614458553849, 861.8491763941286, 886.4858382138344, 890.7773056735816, 3731.6081811409413, 3811.6188381162215, 3832.3485007548784, 3890.105275786528, 3900.414937759359, 9601.90398096024, 10027.348222365523, 10048.598056077675, 10058.647947321808, 10076.798464030679, 9701.563429645683, 9875.703728888146, 9955.25335599833, 9958.502074896227, 9965.55155018026, 7333.779986600411, 7451.552906825499, 7512.098548174254, 7515.962420187973, 7519.172890981988, 5150.716710580954, 5222.686638801684, 5244.5279924806955, 5363.490468405495, 5379.515843574097, 8655.826883462421, 8839.734807955772, 8845.336599755174, 8912.73261802147, 8931.062238465127, 8777.91222087771, 9110.407823491452, 9115.407498512575, 9116.726498205064, 9126.269898408244, 6575.671216439255, 6673.9666301668185, 6690.933090669031, 6697.162854643169, 6698.765543205965, 7498.925010749823, 7550.5469671819865, 7557.168711441847, 7565.583892885724, 7570.886436703564, 6734.629595372302, 6849.726010959505, 6901.689423975833, 6964.860702785814, 7012.298770122947, 6556.311587283458, 6663.400293973566, 6706.597604143767, 6805.897911531232, 6835.93164068353, 6267.62394256346, 6273.62358258506, 6467.0, 6467.935320646733, 6615.17310336208, 4633.953660463352, 4672.462666793226, 4695.788689509049, 4699.318598803086, 4771.997880445146, 4652.488226295172, 4667.673262871566, 4727.692699974628, 4791.233402655558, 4830.806767729251], "value": 4164.155939749685, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.8781443515376, 301.48897618536574, 307.9060886429639, 310.3730464461786, 310.51094526121364, 4830.492798256177, 4838.274258861173, 4847.019083263794, 4854.5873600743935, 4865.5864251538615, 4779.35478710374, 4791.904161916759, 4800.783964721587, 4808.182608956477, 4808.230683090706, 3616.566012078548, 3726.37902512408, 3730.5336832895887, 3802.6577608015273, 3812.1041555234497, 3736.215394767102, 3779.7165212609025, 3789.3179227738997, 3812.7140464465137, 3827.617238276178, 3769.792661403622, 3772.660950210873, 3788.3559794834805, 3792.1657235408165, 3803.3914573668217, 3577.7853328800225, 3650.7261955353324, 3744.794036328001, 3777.5655799583124, 3816.313063648542, 3342.7492938029745, 3347.6652334766572, 3347.782394144397, 3350.3634309481363, 3356.882509112194, 4696.459907110692, 4698.671093023489, 4702.153612349775, 4712.0, 4750.334953106568, 4223.901785535752, 4348.565143485626, 4361.520232774392, 4363.214621368153, 4396.78016099197, 5009.373828271466, 5029.220470827027, 5045.823396181153, 5050.070958843862, 5051.520105589963, 3688.483612294281, 3714.0, 3715.8699445519546, 3720.962790372089, 3725.0, 5125.4618265082, 5309.203619457074, 5339.225812257201, 5350.876315973611, 5358.33020872391, 3787.867424640124, 3795.772253664789, 3799.259144466858, 3800.5819359870393, 3807.0, 3856.5757766645647, 3861.691064714816, 3862.9034274143373, 3863.1114843586133, 3864.6908247340143, 4330.740155590612, 4337.631301339421, 4352.869413917588, 4355.390245365651, 4359.193549193409, 4738.952610473852, 4999.650024498323, 5062.898742025139, 5063.797448102035, 5096.51583099605, 1285.6978610026636, 1288.652063942749, 1295.1322613848745, 1297.941592628334, 1299.6231092983119, 810.3436216664403, 822.3010441124995, 833.1168960901342, 849.3290300662532, 850.2050582705191, 4384.6273066789045, 4505.7296562206375, 4507.549245075466, 4514.367988481616, 4516.23224051912, 5155.50490357792, 5175.767090480937, 5180.222966554971, 5200.2199670049795, 5230.137027390514, 4491.214037543416, 4500.324951257272, 4509.413776209072, 4511.729296242236, 4539.500654927955, 2597.116980226732, 2765.958510622342, 2775.33391985926, 2780.179846945156, 2781.4979910847915, 4958.0, 4979.6265280104135, 4984.7258400788305, 4985.152524070917, 5005.374328208974, 5153.407358153785, 5274.156135018456, 5319.468053194727, 5365.946340536622, 5371.543418809367, 4925.482824303468, 4930.359053323045, 4948.406191257072, 4951.533926251638, 4951.628627852998, 5193.506616871391, 5195.856911479468, 5203.895922081613, 5255.710935898409, 5265.709416403876, 4853.4418541868135, 5101.489851014869, 5121.1550094234035, 5122.334096567572, 5142.460041695717, 4973.701577905337, 4974.129527332775, 4980.402351717817, 5009.924851127309, 5015.322931404359, 3682.116292089933, 3712.8514859405323, 3746.6815320698047, 3749.2314075614977, 3761.0221342450614, 5124.7950081996305, 5303.946960530346, 5310.336207974004, 5332.706701131475, 5335.279737235423, 5079.958608485178, 5084.3898732152375, 5110.543495103878, 5111.591072714248, 5114.872128196676, 5101.540861322499, 5104.948950510596, 5113.41195762492, 5155.845324640267, 5156.819511317236, 4895.926561101515, 4939.293873519781, 5013.824516141917, 5053.59571234293, 5059.266406370982, 4843.757812109451, 4940.8764780880765, 5108.79943213352, 5130.461301563431, 5134.871628209325, 5003.599712022957, 5011.649184557046, 5014.674046186986, 5042.823501177441, 5065.822696205615], "value": 3838.079230455174, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.9347411995861, 695.2144077192771, 725.1008749151055, 736.6464147050174, 746.9514481558701, 12418.875811241896, 14549.70900581989, 15163.39346426143, 15249.39002439901, 15264.542063738105, 15472.922635386807, 15522.844771552282, 15572.532824015268, 15601.843981560181, 15646.921765391156, 624.5752888036134, 777.2111307023375, 786.5359437931619, 807.6809660184224, 808.7331180710352, 788.45202584204, 793.4683761879554, 794.6622685358728, 800.3037357498984, 806.544302469106, 841.4404421059987, 848.2069265237024, 848.2789628815489, 848.7750746052301, 849.2908421468081, 651.840299126717, 809.4778867630346, 836.1262480707675, 840.3949156607264, 841.6759547574167, 610.6092101055328, 610.9480694140959, 611.016263815257, 611.3580740222749, 613.1752792494088, 13609.659758506017, 13646.86353136466, 13652.180869147784, 13659.726805463935, 13659.795103073462, 12047.0, 12356.320402377865, 12494.187877787916, 12692.682682932907, 13164.341782910817, 11968.760624787456, 11973.640790776291, 12151.149419540636, 12183.51265949361, 12644.0, 4238.618524332794, 4278.336857787017, 4436.689431739779, 4439.75581343026, 4445.644348452116, 60879.69560152215, 62017.37982620161, 62564.37435625631, 62842.37157628411, 63581.68209158971, 10429.374237545771, 10471.947640261902, 10638.095761860173, 10940.179486538542, 11004.119670426348, 19711.408657740292, 19741.703874441893, 19779.20883164686, 19823.900880495505, 20209.898950505154, 6636.734530618721, 6687.431568316651, 6699.732010719516, 6860.211075726255, 7054.294570542905, 3718.1448266898765, 3737.364648009845, 3742.644448777362, 3787.3182827091387, 3795.5065841440437, 2715.416185520123, 2784.540550809094, 2799.636047313877, 2799.650043744532, 2801.6918139004692, 528.4715284715285, 551.6193826259868, 556.9888602227933, 560.3919747074469, 566.3373852592476, 413.56575595625077, 511.2484647568069, 513.7354262554787, 519.1745125250869, 520.4067363205949, 7748.690052397841, 7779.138038725796, 7779.299863012356, 8048.678052877936, 8244.474772167168, 518.8416188655176, 528.9656172348786, 532.9973350133224, 534.593708781329, 539.5926075812815, 3633.473146393811, 3652.652997965189, 3706.3884459064493, 3721.4417837324077, 3843.9807800961376, 99935.50032249792, 101832.0, 102237.48881255546, 105206.0, 107203.46398267959, 87069.56465217633, 88668.55665721759, 89139.55430222808, 89467.10532894588, 89671.55164224138, 79446.20553794388, 79460.60269698614, 79623.0, 80093.19906800857, 81133.59433202796, 7067.187273463617, 7184.389326907271, 7236.20401755796, 7278.199398066315, 7278.96360518194, 45165.0, 45875.311870321304, 47530.0, 47627.04745905132, 47848.760756197385, 55584.72207638936, 57374.42625573691, 59536.0, 64306.356936430035, 64489.35510644833, 7498.512596681417, 7688.154303026775, 7724.266194711493, 11356.94321528387, 11487.770244594894, 10364.896351036394, 10485.16118710486, 10683.786324273626, 11776.234544754563, 12184.57353992606, 10105.44420056904, 10880.891191087987, 11064.723381915517, 11665.941670291595, 11730.70673233176, 11574.71063223426, 11687.590934317259, 11891.702707432383, 12311.56909508163, 12362.44369003415, 11498.94250528742, 11994.700132496757, 12353.135280530276, 12507.0, 12724.0, 10101.69694909154, 10422.895771042497, 10478.214133939833, 10557.788844223227, 10679.51942162589, 9847.359921605073, 10264.178865690577, 10491.895081049397, 10607.522661480407, 10616.203784716332], "value": 6641.387779315705, "unit": "score"}], "name": "dromaeo_css", "value": 6383.9043922809105, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 111.0, 113.0, 113.0, 113.0, 113.0, 112.0, 111.0, 113.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 128.0, 125.0, 167.0, 123.0, 123.0, 147.0, 123.0, 158.0, 124.0], "value": 136.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 161.0, 162.0, 163.0, 161.0, 162.0, 163.0, 160.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 114.0, 75.0, 77.0, 75.0, 75.0, 101.0, 76.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 112.0, 86.0, 86.0, 85.0, 111.0, 86.0, 85.0, 132.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 119.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 65.0, 66.0, 66.0, 67.0, 65.0, 66.0, 64.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 66.0, 66.0, 66.0, 65.0, 65.0, 65.0, 65.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 86.0, 84.0, 78.0, 78.0, 78.0, 79.0, 84.0, 82.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 126.0, 129.0, 126.0, 126.0, 131.0, 131.0, 129.0, 180.0, 128.0], "value": 133.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 171.0, 164.0, 173.0, 167.0, 162.0, 163.0, 162.0, 162.0, 163.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 66.0, 66.0, 66.0, 67.0, 65.0, 65.0, 65.0, 64.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1521.1999999999998, "alertThreshold": 2.0}]} 06:04:55 INFO - Return code: 0 06:04:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:04:55 INFO - # TBPL SUCCESS # 06:04:55 INFO - Running post-action listener: _resource_record_post_action 06:04:55 INFO - ##### 06:04:55 INFO - ##### Finished run-tests step (success) 06:04:55 INFO - ##### 06:04:55 INFO - Running post-run listener: _resource_record_post_run 06:04:55 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 8192; Write bytes: 547893248; Read time: 188; Write time: 454100 06:04:55 INFO - TinderboxPrint: CPU usage
13.0% 06:04:55 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 188 06:04:55 INFO - TinderboxPrint: I/O write bytes / time
547,893,248 / 454,100 06:04:55 INFO - TinderboxPrint: CPU idle
8,335.8 (87.0%) 06:04:55 INFO - TinderboxPrint: CPU user
1,202.3 (12.6%) 06:04:55 INFO - TinderboxPrint: Swap in / out
0 / 0 06:04:55 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 259383296; Read time: 0; Write time: 229084 06:04:55 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288329728; Read time: 176; Write time: 224988 06:04:56 INFO - Running post-run listener: _upload_blobber_files 06:04:56 INFO - Blob upload gear active. 06:04:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:04:56 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 06:04:56 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'] 06:04:56 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 06:04:56 INFO - (blobuploader) - INFO - Open directory for files ... 06:04:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:04:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:04:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:00 INFO - (blobuploader) - INFO - Done attempting. 06:05:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:05:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:05:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:00 INFO - (blobuploader) - INFO - Done attempting. 06:05:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:05:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:05:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:05:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:05:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:05:01 INFO - (blobuploader) - INFO - Done attempting. 06:05:01 INFO - (blobuploader) - INFO - Iteration through files over. 06:05:01 INFO - Return code: 0 06:05:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:05:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:05:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f47634010cc054fa29eb4ad84f664d93bd3cad07ec95a6c82e6b4c1671fea56afc1a479e4092b2a74883ab984ee8ce30593a24d54085b9fb6a214cc51c0ccde7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b73c5d28ddb36f25732bb5ca06da23ba36b32744fe697f3a0a6235fe17378574ea3a6d0e75fb0fffdf3801c8b82a72f8b1b3b9ebb5a055b7d963e79e744b0439", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e52fbc3796f9f69b13666fa20b14dd8324d57cabff4663f68b0d4a72ca1fa1f8c6403a60632f52777ed266b1d121c94cfbc5aa91e8478cea8a39b1f0630ecd97"} 06:05:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:05:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:05:01 INFO - Contents: 06:05:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f47634010cc054fa29eb4ad84f664d93bd3cad07ec95a6c82e6b4c1671fea56afc1a479e4092b2a74883ab984ee8ce30593a24d54085b9fb6a214cc51c0ccde7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b73c5d28ddb36f25732bb5ca06da23ba36b32744fe697f3a0a6235fe17378574ea3a6d0e75fb0fffdf3801c8b82a72f8b1b3b9ebb5a055b7d963e79e744b0439", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e52fbc3796f9f69b13666fa20b14dd8324d57cabff4663f68b0d4a72ca1fa1f8c6403a60632f52777ed266b1d121c94cfbc5aa91e8478cea8a39b1f0630ecd97"} 06:05:01 INFO - Running post-run listener: copy_logs_to_upload_dir 06:05:01 INFO - Copying logs to upload dir... 06:05:01 INFO - mkdir: /builds/slave/test/build/upload/logs 06:05:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.149677 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-14 06:05:01.311843) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 06:05:01.315514) ========= 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=1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527 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/f47634010cc054fa29eb4ad84f664d93bd3cad07ec95a6c82e6b4c1671fea56afc1a479e4092b2a74883ab984ee8ce30593a24d54085b9fb6a214cc51c0ccde7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b73c5d28ddb36f25732bb5ca06da23ba36b32744fe697f3a0a6235fe17378574ea3a6d0e75fb0fffdf3801c8b82a72f8b1b3b9ebb5a055b7d963e79e744b0439", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e52fbc3796f9f69b13666fa20b14dd8324d57cabff4663f68b0d4a72ca1fa1f8c6403a60632f52777ed266b1d121c94cfbc5aa91e8478cea8a39b1f0630ecd97"} build_url:https://queue.taskcluster.net/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011362 build_url: 'https://queue.taskcluster.net/v1/task/UzY3rY2pRz6DVdJa1jJcPQ/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/f47634010cc054fa29eb4ad84f664d93bd3cad07ec95a6c82e6b4c1671fea56afc1a479e4092b2a74883ab984ee8ce30593a24d54085b9fb6a214cc51c0ccde7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/b73c5d28ddb36f25732bb5ca06da23ba36b32744fe697f3a0a6235fe17378574ea3a6d0e75fb0fffdf3801c8b82a72f8b1b3b9ebb5a055b7d963e79e744b0439", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e52fbc3796f9f69b13666fa20b14dd8324d57cabff4663f68b0d4a72ca1fa1f8c6403a60632f52777ed266b1d121c94cfbc5aa91e8478cea8a39b1f0630ecd97"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 06:05:01.341235) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 06:05:01.341556) ========= 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=1f5564757af27664ca02d71f000001b0-1468498943.930804-4895527 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 06:05:01.427784) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 06:05:01.428161) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 06:05:01.428606) ========= ========= Total master_lag: 0.19 =========