builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-042 starttime: 1468233667.77 results: success (0) buildid: 20160711021108 builduid: 2d61cd1c1a984fb5939e87c71e00c98f revision: 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:07.774741) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:07.775191) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:07.775463) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:07.806665) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:07.806927) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-11 03:41:09-- 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% 40.5M=0s 2016-07-11 03:41:09 (40.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.310635 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:08.133533) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:08.133911) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017839 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:08.183863) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:08.184271) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 --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 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 --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=74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-11 03:41:09,810 truncating revision to first 12 chars 2016-07-11 03:41:09,810 Setting DEBUG logging. 2016-07-11 03:41:09,810 attempt 1/10 2016-07-11 03:41:09,810 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1a3c9b20d193?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 03:41:10,145 unpacking tar archive at: fx-team-1a3c9b20d193/testing/mozharness/ program finished with exit code 0 elapsedTime=0.644623 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:08.842868) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:08.843190) ========= script_repo_revision: 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:08.843660) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:08.843976) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 03:41:08.854923) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-11 03:41:08.855385) ========= /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=74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:41:10 INFO - MultiFileLogger online at 20160711 03:41:10 in /builds/slave/test 03:41:10 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 03:41:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:41:10 INFO - {'append_to_log': False, 03:41:10 INFO - 'base_work_dir': '/builds/slave/test', 03:41:10 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 03:41:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:41:10 INFO - 'branch': 'Fx-Team-Non-PGO', 03:41:10 INFO - 'buildbot_json_path': 'buildprops.json', 03:41:10 INFO - 'config_files': ('talos/linux_config.py',), 03:41:10 INFO - 'default_actions': ('clobber', 03:41:10 INFO - 'read-buildbot-config', 03:41:10 INFO - 'download-and-extract', 03:41:10 INFO - 'populate-webroot', 03:41:10 INFO - 'create-virtualenv', 03:41:10 INFO - 'install', 03:41:10 INFO - 'run-tests'), 03:41:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:41:10 INFO - 'download_minidump_stackwalk': True, 03:41:10 INFO - 'download_symbols': 'ondemand', 03:41:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:41:10 INFO - 'tooltool.py': '/tools/tooltool.py', 03:41:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:41:10 INFO - '/tools/misc-python/virtualenv.py')}, 03:41:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:41:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:41:10 INFO - 'installer_path': 'installer.exe', 03:41:10 INFO - 'log_level': 'info', 03:41:10 INFO - 'log_name': 'talos', 03:41:10 INFO - 'log_to_console': True, 03:41:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:41:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:41:10 INFO - 'opt_config_files': (), 03:41:10 INFO - 'pip_index': False, 03:41:10 INFO - 'sps_profile': False, 03:41:10 INFO - 'sps_profile_interval': 0, 03:41:10 INFO - 'suite': 'dromaeojs', 03:41:10 INFO - 'system_bits': '32', 03:41:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:41:10 INFO - 'title': 'talos-linux64-ix-042', 03:41:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:41:10 INFO - 'use_talos_json': True, 03:41:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:41:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:41:10 INFO - 'work_dir': 'build'} 03:41:10 INFO - ##### 03:41:10 INFO - ##### Running clobber step. 03:41:10 INFO - ##### 03:41:10 INFO - Running pre-action listener: _resource_record_pre_action 03:41:10 INFO - Running main action method: clobber 03:41:10 INFO - rmtree: /builds/slave/test/build 03:41:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:41:11 INFO - Running post-action listener: _resource_record_post_action 03:41:11 INFO - ##### 03:41:11 INFO - ##### Finished clobber step (success) 03:41:11 INFO - ##### 03:41:11 INFO - ##### 03:41:11 INFO - ##### Running read-buildbot-config step. 03:41:11 INFO - ##### 03:41:11 INFO - Running pre-action listener: _resource_record_pre_action 03:41:11 INFO - Running main action method: read_buildbot_config 03:41:11 INFO - Using buildbot properties: 03:41:11 INFO - { 03:41:11 INFO - "project": "", 03:41:11 INFO - "product": "firefox", 03:41:11 INFO - "script_repo_revision": "production", 03:41:11 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 03:41:11 INFO - "repository": "", 03:41:11 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 03:41:11 INFO - "buildid": "20160711021108", 03:41:11 INFO - "pgo_build": "False", 03:41:11 INFO - "basedir": "/builds/slave/test", 03:41:11 INFO - "buildnumber": 1963, 03:41:11 INFO - "slavename": "talos-linux64-ix-042", 03:41:11 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:41:11 INFO - "platform": "ubuntu64_hw", 03:41:11 INFO - "scheduler": "tests-fx-team-linux64-talos", 03:41:11 INFO - "branch": "fx-team", 03:41:11 INFO - "repo_path": "integration/fx-team", 03:41:11 INFO - "revision": "1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9", 03:41:11 INFO - "stage_platform": "linux64", 03:41:11 INFO - "builduid": "2d61cd1c1a984fb5939e87c71e00c98f", 03:41:11 INFO - "slavebuilddir": "test" 03:41:11 INFO - } 03:41:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 03:41:11 INFO - Running post-action listener: _resource_record_post_action 03:41:11 INFO - ##### 03:41:11 INFO - ##### Finished read-buildbot-config step (success) 03:41:11 INFO - ##### 03:41:11 INFO - ##### 03:41:11 INFO - ##### Running download-and-extract step. 03:41:11 INFO - ##### 03:41:11 INFO - Running pre-action listener: _resource_record_pre_action 03:41:11 INFO - Running main action method: download_and_extract 03:41:11 INFO - mkdir: /builds/slave/test/build/tests 03:41:11 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')]} 03:41:11 INFO - https://queue.taskcluster.net/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:41:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:41:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:41:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/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 03:41:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/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 03:41:12 INFO - Downloaded 1361 bytes. 03:41:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 03:41:12 INFO - Using the following test package requirements: 03:41:12 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 03:41:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:41:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:41:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:41:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 03:41:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:41:12 INFO - u'jsshell-linux-x86_64.zip'], 03:41:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:41:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:41:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 03:41:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:41:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:41:12 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:41:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:41:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:41:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:41:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 03:41:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:41:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:41: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')]} 03:41:12 INFO - https://queue.taskcluster.net/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:41:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:41:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:41:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/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 03:41:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/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 03:41:13 INFO - Downloaded 22735150 bytes. 03:41:13 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'] 03:41:13 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 03:41:14 INFO - Return code: 0 03:41:14 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 03:41:14 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')]} 03:41:14 INFO - https://queue.taskcluster.net/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:41:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:41:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 03:41:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/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 03:41:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/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 03:41:15 INFO - Downloaded 22735150 bytes. 03:41:15 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'] 03:41:15 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 03:41:17 INFO - Return code: 0 03:41:17 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')]} 03:41:17 INFO - https://queue.taskcluster.net/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:41:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 03:41:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 03:41:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/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 03:41:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/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 03:41:18 INFO - Downloaded 14403333 bytes. 03:41:18 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'] 03:41:18 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 03:41:18 INFO - Return code: 0 03:41:18 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')]} 03:41:18 INFO - https://queue.taskcluster.net/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:41:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:41:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:41:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/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 03:41:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjHQrGRtTg66c0jqMiFPNg/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 03:41:21 INFO - Downloaded 56652836 bytes. 03:41:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:41:21 INFO - mkdir: /builds/slave/test/properties 03:41:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:41:21 INFO - Writing to file /builds/slave/test/properties/build_url 03:41:21 INFO - Contents: 03:41:21 INFO - build_url:https://queue.taskcluster.net/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 03:41:22 INFO - Running post-action listener: _resource_record_post_action 03:41:22 INFO - Running post-action listener: set_extra_try_arguments 03:41:22 INFO - ##### 03:41:22 INFO - ##### Finished download-and-extract step (success) 03:41:22 INFO - ##### 03:41:22 INFO - ##### 03:41:22 INFO - ##### Running populate-webroot step. 03:41:22 INFO - ##### 03:41:22 INFO - Running pre-action listener: _resource_record_pre_action 03:41:22 INFO - Running main action method: populate_webroot 03:41:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:41:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:41:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:41:22 INFO - '--tppagecycles', 03:41:22 INFO - '7'], 03:41:22 INFO - 'tests': ['tsvgm']}}, 03:41:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:41:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:41:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:41:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:41:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:41:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:41:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:22 INFO - 'talos_options': ['--disable-e10s'], 03:41:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:41:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:41:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:22 INFO - 'talos_options': ['--disable-e10s'], 03:41:22 INFO - 'tests': ['damp', 'tps']}, 03:41:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:22 INFO - 'tests': ['damp', 'tps']}, 03:41:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:41:22 INFO - 'tests': ['dromaeo_dom']}, 03:41:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:41:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:41:22 INFO - 'tests': ['basic_compositor_video']}, 03:41:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:41:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:41:22 INFO - 'tests': ['a11yr', 03:41:22 INFO - 'ts_paint', 03:41:22 INFO - 'tpaint', 03:41:22 INFO - 'sessionrestore', 03:41:22 INFO - 'sessionrestore_no_auto_restore', 03:41:22 INFO - 'tabpaint']}, 03:41:22 INFO - 'other-e10s': {'tests': ['a11yr', 03:41:22 INFO - 'ts_paint', 03:41:22 INFO - 'tpaint', 03:41:22 INFO - 'sessionrestore', 03:41:22 INFO - 'sessionrestore_no_auto_restore', 03:41:22 INFO - 'tabpaint']}, 03:41:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:41:22 INFO - 'ts_paint', 03:41:22 INFO - 'tpaint', 03:41:22 INFO - 'sessionrestore', 03:41:22 INFO - 'sessionrestore_no_auto_restore', 03:41:22 INFO - 'tabpaint']}, 03:41:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:41:22 INFO - 'ts_paint', 03:41:22 INFO - 'tpaint', 03:41:22 INFO - 'sessionrestore', 03:41:22 INFO - 'sessionrestore_no_auto_restore', 03:41:22 INFO - 'tabpaint']}, 03:41:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:41:22 INFO - 'tests': ['a11yr', 03:41:22 INFO - 'ts_paint', 03:41:22 INFO - 'tpaint', 03:41:22 INFO - 'sessionrestore', 03:41:22 INFO - 'sessionrestore_no_auto_restore', 03:41:22 INFO - 'tabpaint']}, 03:41:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:41:22 INFO - 'tests': ['a11yr', 03:41:22 INFO - 'ts_paint', 03:41:22 INFO - 'tpaint', 03:41:22 INFO - 'sessionrestore', 03:41:22 INFO - 'sessionrestore_no_auto_restore', 03:41:22 INFO - 'tabpaint']}, 03:41:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:41:22 INFO - 'tests': ['tsvgx', 03:41:22 INFO - 'tsvgr_opacity', 03:41:22 INFO - 'tart', 03:41:22 INFO - 'tscrollx', 03:41:22 INFO - 'cart']}, 03:41:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:41:22 INFO - 'tsvgr_opacity', 03:41:22 INFO - 'tart', 03:41:22 INFO - 'tscrollx', 03:41:22 INFO - 'cart']}, 03:41:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:22 INFO - 'talos_options': ['--disable-e10s'], 03:41:22 INFO - 'tests': ['tp5o']}, 03:41:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:22 INFO - 'tests': ['tp5o']}, 03:41:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:22 INFO - 'talos_options': ['--disable-e10s', 03:41:22 INFO - '--xperf_path', 03:41:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:41:22 INFO - 'tests': ['tp5n']}, 03:41:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:22 INFO - 'talos_options': ['--xperf_path', 03:41:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:41:22 INFO - 'tests': ['tp5n']}}, 03:41:22 INFO - 'talos.zip': {'path': '', 03:41:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:41:22 INFO - Running post-action listener: _resource_record_post_action 03:41:22 INFO - ##### 03:41:22 INFO - ##### Finished populate-webroot step (success) 03:41:22 INFO - ##### 03:41:22 INFO - ##### 03:41:22 INFO - ##### Running create-virtualenv step. 03:41:22 INFO - ##### 03:41:22 INFO - Running pre-action listener: _resource_record_pre_action 03:41:22 INFO - Running main action method: create_virtualenv 03:41:22 INFO - Creating virtualenv /builds/slave/test/build/venv 03:41:22 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 03:41:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:41:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:41:22 INFO - Using real prefix '/usr' 03:41:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:41:22 INFO - Installing distribute.............................................................................................................................................................................................done. 03:41:24 INFO - Installing pip.................done. 03:41:24 INFO - Return code: 0 03:41:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:24 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 0x1d36030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff53b0>, '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 0x1ff62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff6740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', '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 03:41:24 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 03:41:24 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 03:41:24 INFO - Using env: {'DISPLAY': ':0', 03:41:24 INFO - 'HOME': '/home/cltbld', 03:41:24 INFO - 'LANG': 'en_US.UTF-8', 03:41:24 INFO - 'LANGUAGE': 'en_US:en', 03:41:24 INFO - 'LOGNAME': 'cltbld', 03:41:24 INFO - 'MAIL': '/var/mail/cltbld', 03:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:24 INFO - 'MOZ_NO_REMOTE': '1', 03:41:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:24 INFO - 'NO_EM_RESTART': '1', 03:41:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:24 INFO - 'PWD': '/builds/slave/test', 03:41:24 INFO - 'SHELL': '/bin/bash', 03:41:24 INFO - 'SHLVL': '1', 03:41:24 INFO - 'TERM': 'linux', 03:41:24 INFO - 'TMOUT': '86400', 03:41:24 INFO - 'USER': 'cltbld', 03:41:24 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', 03:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:24 INFO - '_': '/tools/buildbot/bin/python'} 03:41:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:24 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 03:41:24 INFO - Cleaning up... 03:41:24 INFO - Return code: 0 03:41:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:24 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 0x1d36030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff53b0>, '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 0x1ff62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff6740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', '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 03:41:24 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 03:41:24 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 03:41:24 INFO - Using env: {'DISPLAY': ':0', 03:41:24 INFO - 'HOME': '/home/cltbld', 03:41:24 INFO - 'LANG': 'en_US.UTF-8', 03:41:24 INFO - 'LANGUAGE': 'en_US:en', 03:41:24 INFO - 'LOGNAME': 'cltbld', 03:41:24 INFO - 'MAIL': '/var/mail/cltbld', 03:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:24 INFO - 'MOZ_NO_REMOTE': '1', 03:41:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:24 INFO - 'NO_EM_RESTART': '1', 03:41:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:24 INFO - 'PWD': '/builds/slave/test', 03:41:24 INFO - 'SHELL': '/bin/bash', 03:41:24 INFO - 'SHLVL': '1', 03:41:24 INFO - 'TERM': 'linux', 03:41:24 INFO - 'TMOUT': '86400', 03:41:24 INFO - 'USER': 'cltbld', 03:41:24 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', 03:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:24 INFO - '_': '/tools/buildbot/bin/python'} 03:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:25 INFO - Downloading/unpacking psutil>=3.1.1 03:41:25 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 03:41:25 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 03:41:25 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 03:41:25 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 03:41:25 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 03:41:25 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 03:41:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:41:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:41:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:41:27 INFO - Installing collected packages: psutil 03:41:27 INFO - Running setup.py install for psutil 03:41:27 INFO - building 'psutil._psutil_linux' extension 03:41:27 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 03:41:27 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 03:41:27 INFO - building 'psutil._psutil_posix' extension 03:41:27 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 03:41:27 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 03:41:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:41:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:41:27 INFO - Successfully installed psutil 03:41:27 INFO - Cleaning up... 03:41:27 INFO - Return code: 0 03:41:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:27 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 0x1d36030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff53b0>, '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 0x1ff62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff6740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', '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 03:41:27 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 03:41:27 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 03:41:27 INFO - Using env: {'DISPLAY': ':0', 03:41:27 INFO - 'HOME': '/home/cltbld', 03:41:27 INFO - 'LANG': 'en_US.UTF-8', 03:41:27 INFO - 'LANGUAGE': 'en_US:en', 03:41:27 INFO - 'LOGNAME': 'cltbld', 03:41:27 INFO - 'MAIL': '/var/mail/cltbld', 03:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:27 INFO - 'MOZ_NO_REMOTE': '1', 03:41:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:27 INFO - 'NO_EM_RESTART': '1', 03:41:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:27 INFO - 'PWD': '/builds/slave/test', 03:41:27 INFO - 'SHELL': '/bin/bash', 03:41:27 INFO - 'SHLVL': '1', 03:41:27 INFO - 'TERM': 'linux', 03:41:27 INFO - 'TMOUT': '86400', 03:41:27 INFO - 'USER': 'cltbld', 03:41:27 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', 03:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:27 INFO - '_': '/tools/buildbot/bin/python'} 03:41:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:41:27 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 03:41:27 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 03:41:27 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 03:41:27 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 03:41:27 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 03:41:27 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 03:41:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:41:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:41:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:41:29 INFO - Installing collected packages: mozsystemmonitor 03:41:29 INFO - Running setup.py install for mozsystemmonitor 03:41:29 INFO - Successfully installed mozsystemmonitor 03:41:29 INFO - Cleaning up... 03:41:29 INFO - Return code: 0 03:41:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:29 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 0x1d36030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff53b0>, '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 0x1ff62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff6740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', '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 03:41:29 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 03:41:29 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 03:41:29 INFO - Using env: {'DISPLAY': ':0', 03:41:29 INFO - 'HOME': '/home/cltbld', 03:41:29 INFO - 'LANG': 'en_US.UTF-8', 03:41:29 INFO - 'LANGUAGE': 'en_US:en', 03:41:29 INFO - 'LOGNAME': 'cltbld', 03:41:29 INFO - 'MAIL': '/var/mail/cltbld', 03:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:29 INFO - 'MOZ_NO_REMOTE': '1', 03:41:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:29 INFO - 'NO_EM_RESTART': '1', 03:41:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:29 INFO - 'PWD': '/builds/slave/test', 03:41:29 INFO - 'SHELL': '/bin/bash', 03:41:29 INFO - 'SHLVL': '1', 03:41:29 INFO - 'TERM': 'linux', 03:41:29 INFO - 'TMOUT': '86400', 03:41:29 INFO - 'USER': 'cltbld', 03:41:29 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', 03:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:29 INFO - '_': '/tools/buildbot/bin/python'} 03:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:30 INFO - Downloading/unpacking jsonschema==2.5.1 03:41:30 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 03:41:30 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 03:41:30 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 03:41:30 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 03:41:30 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 03:41:30 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 03:41:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:41:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 INFO - Downloading functools32-3.2.3-2.tar.gz 03:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:41:32 INFO - warning: no files found matching '*.txt' 03:41:32 INFO - no previously-included directories found matching 'build' 03:41:32 INFO - no previously-included directories found matching 'dist' 03:41:32 INFO - no previously-included directories found matching '.git*' 03:41:32 INFO - Installing collected packages: jsonschema, functools32 03:41:32 INFO - Running setup.py install for functools32 03:41:32 INFO - warning: no files found matching '*.txt' 03:41:32 INFO - no previously-included directories found matching 'build' 03:41:32 INFO - no previously-included directories found matching 'dist' 03:41:32 INFO - no previously-included directories found matching '.git*' 03:41:32 INFO - Successfully installed jsonschema functools32 03:41:32 INFO - Cleaning up... 03:41:32 INFO - Return code: 0 03:41:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:41:32 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')]} 03:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:32 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')]} 03:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:32 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 0x1d36030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff53b0>, '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 0x1ff62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff6740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', '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 03:41:32 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 03:41:32 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 03:41:32 INFO - Using env: {'DISPLAY': ':0', 03:41:32 INFO - 'HOME': '/home/cltbld', 03:41:32 INFO - 'LANG': 'en_US.UTF-8', 03:41:32 INFO - 'LANGUAGE': 'en_US:en', 03:41:32 INFO - 'LOGNAME': 'cltbld', 03:41:32 INFO - 'MAIL': '/var/mail/cltbld', 03:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:32 INFO - 'MOZ_NO_REMOTE': '1', 03:41:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:32 INFO - 'NO_EM_RESTART': '1', 03:41:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:32 INFO - 'PWD': '/builds/slave/test', 03:41:32 INFO - 'SHELL': '/bin/bash', 03:41:32 INFO - 'SHLVL': '1', 03:41:32 INFO - 'TERM': 'linux', 03:41:32 INFO - 'TMOUT': '86400', 03:41:32 INFO - 'USER': 'cltbld', 03:41:32 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', 03:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:32 INFO - '_': '/tools/buildbot/bin/python'} 03:41:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:32 INFO - Downloading/unpacking blobuploader==1.2.4 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:34 INFO - Downloading blobuploader-1.2.4.tar.gz 03:41:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:41:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:41:34 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 03:41:34 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 03:41:34 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 03:41:34 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 03:41:34 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 03:41:34 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 03:41:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:41:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:41: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 03:41: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 03:41: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 03:41: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 03:41: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 03:41: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 03:41:35 INFO - Downloading docopt-0.6.1.tar.gz 03:41:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:41:35 INFO - Installing collected packages: blobuploader, requests, docopt 03:41:35 INFO - Running setup.py install for blobuploader 03:41:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:41:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:41:35 INFO - Running setup.py install for requests 03:41:35 INFO - Running setup.py install for docopt 03:41:35 INFO - Successfully installed blobuploader requests docopt 03:41:35 INFO - Cleaning up... 03:41:36 INFO - Return code: 0 03:41:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:36 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 0x1d36030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff53b0>, '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 0x1ff62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff6740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', '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 03:41:36 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 03:41:36 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 03:41:36 INFO - Using env: {'DISPLAY': ':0', 03:41:36 INFO - 'HOME': '/home/cltbld', 03:41:36 INFO - 'LANG': 'en_US.UTF-8', 03:41:36 INFO - 'LANGUAGE': 'en_US:en', 03:41:36 INFO - 'LOGNAME': 'cltbld', 03:41:36 INFO - 'MAIL': '/var/mail/cltbld', 03:41:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:36 INFO - 'MOZ_NO_REMOTE': '1', 03:41:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:36 INFO - 'NO_EM_RESTART': '1', 03:41:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:36 INFO - 'PWD': '/builds/slave/test', 03:41:36 INFO - 'SHELL': '/bin/bash', 03:41:36 INFO - 'SHLVL': '1', 03:41:36 INFO - 'TERM': 'linux', 03:41:36 INFO - 'TMOUT': '86400', 03:41:36 INFO - 'USER': 'cltbld', 03:41:36 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', 03:41:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:36 INFO - '_': '/tools/buildbot/bin/python'} 03:41:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:41:36 INFO - Running setup.py (path:/tmp/pip-ucwyeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:41:36 INFO - Running setup.py (path:/tmp/pip-RY3ji0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:41:36 INFO - Running setup.py (path:/tmp/pip-3RNOQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:41:36 INFO - Running setup.py (path:/tmp/pip-C5ed7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:41:36 INFO - Running setup.py (path:/tmp/pip-v0gbsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:41:36 INFO - Running setup.py (path:/tmp/pip-cBsf_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:41:36 INFO - Running setup.py (path:/tmp/pip-OoFHh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:41:36 INFO - Running setup.py (path:/tmp/pip-E3NlWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:41:36 INFO - Running setup.py (path:/tmp/pip-GN3pMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:41:36 INFO - Running setup.py (path:/tmp/pip-m7wb6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:41:36 INFO - Running setup.py (path:/tmp/pip-Qq6JJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:41:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:41:36 INFO - Running setup.py (path:/tmp/pip-E6MQjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:41:37 INFO - Running setup.py (path:/tmp/pip-rBe428-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:41:37 INFO - Running setup.py (path:/tmp/pip-cVjnav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:37 INFO - Running setup.py (path:/tmp/pip-Zz7ea2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:41:37 INFO - Running setup.py (path:/tmp/pip-0wqDpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:41:37 INFO - Running setup.py (path:/tmp/pip-HIM6sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:41:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:41:37 INFO - Running setup.py install for manifestparser 03:41:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:41:37 INFO - Running setup.py install for mozcrash 03:41:37 INFO - Running setup.py install for mozdebug 03:41:37 INFO - Running setup.py install for mozdevice 03:41:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:41:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:41:37 INFO - Running setup.py install for mozfile 03:41:37 INFO - Running setup.py install for mozhttpd 03:41:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Running setup.py install for mozinfo 03:41:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Running setup.py install for mozInstall 03:41:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Running setup.py install for mozleak 03:41:38 INFO - Running setup.py install for mozlog 03:41:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Running setup.py install for moznetwork 03:41:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Running setup.py install for mozprocess 03:41:38 INFO - Running setup.py install for mozprofile 03:41:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Running setup.py install for mozrunner 03:41:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:41:38 INFO - Running setup.py install for mozscreenshot 03:41:38 INFO - Running setup.py install for moztest 03:41:39 INFO - Running setup.py install for mozversion 03:41:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:41:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:41:39 INFO - Cleaning up... 03:41:39 INFO - Return code: 0 03:41:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:39 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')]} 03:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:39 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')]} 03:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:39 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 0x1d36030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff53b0>, '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 0x1ff62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff6740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', '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 03:41:39 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 03:41:39 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 03:41:39 INFO - Using env: {'DISPLAY': ':0', 03:41:39 INFO - 'HOME': '/home/cltbld', 03:41:39 INFO - 'LANG': 'en_US.UTF-8', 03:41:39 INFO - 'LANGUAGE': 'en_US:en', 03:41:39 INFO - 'LOGNAME': 'cltbld', 03:41:39 INFO - 'MAIL': '/var/mail/cltbld', 03:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:39 INFO - 'MOZ_NO_REMOTE': '1', 03:41:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:39 INFO - 'NO_EM_RESTART': '1', 03:41:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:39 INFO - 'PWD': '/builds/slave/test', 03:41:39 INFO - 'SHELL': '/bin/bash', 03:41:39 INFO - 'SHLVL': '1', 03:41:39 INFO - 'TERM': 'linux', 03:41:39 INFO - 'TMOUT': '86400', 03:41:39 INFO - 'USER': 'cltbld', 03:41:39 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', 03:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:39 INFO - '_': '/tools/buildbot/bin/python'} 03:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:41:39 INFO - Running setup.py (path:/tmp/pip-iTOL8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:41:39 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)) 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:41:39 INFO - Running setup.py (path:/tmp/pip-vdGaqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:41:39 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)) 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:41:39 INFO - Running setup.py (path:/tmp/pip-aroEX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:41:39 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)) 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:41:39 INFO - Running setup.py (path:/tmp/pip-PylMpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:41:39 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)) 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:41:39 INFO - Running setup.py (path:/tmp/pip-0p1He6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:41:39 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)) 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:41:39 INFO - Running setup.py (path:/tmp/pip-vd1FDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:41:39 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)) 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:41:39 INFO - Running setup.py (path:/tmp/pip-WkLtmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:41:39 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)) 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:41:39 INFO - Running setup.py (path:/tmp/pip-miknMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:41:39 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)) 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:41:39 INFO - Running setup.py (path:/tmp/pip-4gO6Rj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:41:40 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)) 03:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:41:40 INFO - Running setup.py (path:/tmp/pip-adeh9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:41:40 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)) 03:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:41:40 INFO - Running setup.py (path:/tmp/pip-5sdiYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:41:40 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)) 03:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:41:40 INFO - Running setup.py (path:/tmp/pip-CjAVq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:41:40 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)) 03:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:41:40 INFO - Running setup.py (path:/tmp/pip-0G88x9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:41:40 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)) 03:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:41:40 INFO - Running setup.py (path:/tmp/pip-zJjyg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:41:40 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)) 03:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:40 INFO - Running setup.py (path:/tmp/pip-7q3Bjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:40 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)) 03:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:41:40 INFO - Running setup.py (path:/tmp/pip-eaSRh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:41:40 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)) 03:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:41:40 INFO - Running setup.py (path:/tmp/pip-LAGbUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:41:40 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)) 03:41:40 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)) 03:41:40 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)) 03:41:40 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)) 03:41:40 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)) 03:41:40 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)) 03:41:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:41:40 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 03:41:40 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 03:41:40 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 03:41:40 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 03:41:40 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 03:41:40 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 03:41:42 INFO - Downloading blessings-1.6.tar.gz 03:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:41:42 INFO - Installing collected packages: blessings 03:41:42 INFO - Running setup.py install for blessings 03:41:42 INFO - Successfully installed blessings 03:41:42 INFO - Cleaning up... 03:41:42 INFO - Return code: 0 03:41:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:41:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:41:42 INFO - Reading from file tmpfile_stdout 03:41:42 INFO - Current package versions: 03:41:42 INFO - argparse == 1.2.1 03:41:42 INFO - blessings == 1.6 03:41:42 INFO - blobuploader == 1.2.4 03:41:42 INFO - docopt == 0.6.1 03:41:42 INFO - functools32 == 3.2.3-2 03:41:42 INFO - jsonschema == 2.5.1 03:41:42 INFO - manifestparser == 1.1 03:41:42 INFO - mozInstall == 1.12 03:41:42 INFO - mozcrash == 0.17 03:41:42 INFO - mozdebug == 0.1 03:41:42 INFO - mozdevice == 0.48 03:41:42 INFO - mozfile == 1.2 03:41:42 INFO - mozhttpd == 0.7 03:41:42 INFO - mozinfo == 0.9 03:41:42 INFO - mozleak == 0.1 03:41:42 INFO - mozlog == 3.2 03:41:42 INFO - moznetwork == 0.27 03:41:42 INFO - mozprocess == 0.23 03:41:42 INFO - mozprofile == 0.28 03:41:42 INFO - mozrunner == 6.12 03:41:42 INFO - mozscreenshot == 0.1 03:41:42 INFO - mozsystemmonitor == 0.3 03:41:42 INFO - moztest == 0.7 03:41:42 INFO - mozversion == 1.4 03:41:42 INFO - psutil == 3.1.1 03:41:42 INFO - requests == 1.2.3 03:41:42 INFO - wsgiref == 0.1.2 03:41:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:42 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')]} 03:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:42 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')]} 03:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:42 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 0x1d36030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff53b0>, '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 0x1ff62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff6740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', '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 03:41:42 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 03:41:42 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 03:41:42 INFO - Using env: {'DISPLAY': ':0', 03:41:42 INFO - 'HOME': '/home/cltbld', 03:41:42 INFO - 'LANG': 'en_US.UTF-8', 03:41:42 INFO - 'LANGUAGE': 'en_US:en', 03:41:42 INFO - 'LOGNAME': 'cltbld', 03:41:42 INFO - 'MAIL': '/var/mail/cltbld', 03:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:42 INFO - 'MOZ_NO_REMOTE': '1', 03:41:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:42 INFO - 'NO_EM_RESTART': '1', 03:41:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:42 INFO - 'PWD': '/builds/slave/test', 03:41:42 INFO - 'SHELL': '/bin/bash', 03:41:42 INFO - 'SHLVL': '1', 03:41:42 INFO - 'TERM': 'linux', 03:41:42 INFO - 'TMOUT': '86400', 03:41:42 INFO - 'USER': 'cltbld', 03:41:42 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', 03:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:42 INFO - '_': '/tools/buildbot/bin/python'} 03:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:43 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)) 03:41:43 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)) 03:41:43 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)) 03:41:43 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)) 03:41:43 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)) 03:41:43 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)) 03:41:43 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)) 03:41:43 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)) 03:41:43 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)) 03:41:43 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)) 03:41:43 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)) 03:41:43 INFO - Cleaning up... 03:41:43 INFO - Return code: 0 03:41:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d36030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff53b0>, '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 0x1ff62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff6740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', '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 03:41: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', 'jsonschema'] in /builds/slave/test/build 03:41: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 jsonschema 03:41:43 INFO - Using env: {'DISPLAY': ':0', 03:41:43 INFO - 'HOME': '/home/cltbld', 03:41:43 INFO - 'LANG': 'en_US.UTF-8', 03:41:43 INFO - 'LANGUAGE': 'en_US:en', 03:41:43 INFO - 'LOGNAME': 'cltbld', 03:41:43 INFO - 'MAIL': '/var/mail/cltbld', 03:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:43 INFO - 'MOZ_NO_REMOTE': '1', 03:41:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:41:43 INFO - 'NO_EM_RESTART': '1', 03:41:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:43 INFO - 'PWD': '/builds/slave/test', 03:41:43 INFO - 'SHELL': '/bin/bash', 03:41:43 INFO - 'SHLVL': '1', 03:41:43 INFO - 'TERM': 'linux', 03:41:43 INFO - 'TMOUT': '86400', 03:41:43 INFO - 'USER': 'cltbld', 03:41:43 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', 03:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:43 INFO - '_': '/tools/buildbot/bin/python'} 03:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:41:43 INFO - Cleaning up... 03:41:43 INFO - Return code: 0 03:41:43 INFO - Running post-action listener: _resource_record_post_action 03:41:43 INFO - Running post-action listener: _start_resource_monitoring 03:41:43 INFO - Starting resource monitoring. 03:41:43 INFO - ##### 03:41:43 INFO - ##### Finished create-virtualenv step (success) 03:41:43 INFO - ##### 03:41:43 INFO - ##### 03:41:43 INFO - ##### Running install step. 03:41:43 INFO - ##### 03:41:43 INFO - Running pre-action listener: _resource_record_pre_action 03:41:43 INFO - Running main action method: install 03:41:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:41:43 INFO - Reading from file tmpfile_stdout 03:41:43 INFO - Detecting whether we're running mozinstall >=1.0... 03:41:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:41:43 INFO - Reading from file tmpfile_stdout 03:41:43 INFO - Output received: 03:41:43 INFO - Usage: mozinstall [options] installer 03:41:43 INFO - Options: 03:41:43 INFO - -h, --help show this help message and exit 03:41:43 INFO - -d DEST, --destination=DEST 03:41:43 INFO - Directory to install application into. [default: 03:41:43 INFO - "/builds/slave/test"] 03:41:43 INFO - --app=APP Application being installed. [default: firefox] 03:41:43 INFO - mkdir: /builds/slave/test/build/application 03:41:43 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'] 03:41:43 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 03:42:00 INFO - Reading from file tmpfile_stdout 03:42:00 INFO - Output received: 03:42:00 INFO - /builds/slave/test/build/application/firefox/firefox 03:42:00 INFO - Running post-action listener: _resource_record_post_action 03:42:00 INFO - ##### 03:42:00 INFO - ##### Finished install step (success) 03:42:00 INFO - ##### 03:42:00 INFO - ##### 03:42:00 INFO - ##### Running run-tests step. 03:42:00 INFO - ##### 03:42:00 INFO - Running pre-action listener: _resource_record_pre_action 03:42:00 INFO - Running main action method: run_tests 03:42:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:42:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:42:00 INFO - Python 2.7.3 03:42:00 INFO - Return code: 0 03:42:00 INFO - grabbing minidump binary from tooltool 03:42:00 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')]} 03:42:00 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 0x1ff53b0>, '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 0x1ff62b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff6740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:42:00 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 03:42:00 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 03:42:00 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 03:42:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:42:00 INFO - Return code: 0 03:42:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:42:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:42:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:42:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:42:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:42:00 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/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 03:42:00 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/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --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 03:42:00 INFO - Using env: {'DISPLAY': ':0', 03:42:00 INFO - 'HOME': '/home/cltbld', 03:42:00 INFO - 'LANG': 'en_US.UTF-8', 03:42:00 INFO - 'LANGUAGE': 'en_US:en', 03:42:00 INFO - 'LOGNAME': 'cltbld', 03:42:00 INFO - 'MAIL': '/var/mail/cltbld', 03:42:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:42:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:42:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:00 INFO - 'MOZ_NO_REMOTE': '1', 03:42:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:42:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:42:00 INFO - 'NO_EM_RESTART': '1', 03:42:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:42:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:00 INFO - 'PWD': '/builds/slave/test', 03:42:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:42:00 INFO - 'SHELL': '/bin/bash', 03:42:00 INFO - 'SHLVL': '1', 03:42:00 INFO - 'TERM': 'linux', 03:42:00 INFO - 'TMOUT': '86400', 03:42:00 INFO - 'USER': 'cltbld', 03:42:00 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821', 03:42:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:00 INFO - '_': '/tools/buildbot/bin/python'} 03:42:00 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/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 03:42:00 INFO - mozversion application_buildid: 20160711021108 03:42:00 INFO - mozversion application_changeset: 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 03:42:00 INFO - mozversion application_display_name: Nightly 03:42:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:42:00 INFO - mozversion application_name: Firefox 03:42:00 INFO - mozversion application_remotingname: firefox 03:42:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:42:00 INFO - mozversion application_vendor: Mozilla 03:42:00 INFO - mozversion application_version: 50.0a1 03:42:00 INFO - mozversion platform_buildid: 20160711021108 03:42:00 INFO - mozversion platform_changeset: 1a3c9b20d193fb01dc8f3b536ff8e3586f9421b9 03:42:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:42:00 INFO - mozversion platform_version: 50.0a1 03:42:00 INFO - using testdate: 1468233720 03:42:00 INFO - actual date: 1468233720 03:42:00 INFO - starting webserver on 'localhost:38428' 03:42:00 INFO - SUITE-START | Running 2 tests 03:42:00 INFO - TEST-START | dromaeo_css 03:42:00 INFO - operating with platform_type : linux_ 03:42:00 INFO - Initialising browser for dromaeo_css test... 03:42:00 INFO - TEST-INFO | started process 1717 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzXbGzp/profile http://localhost:38428/getInfo.html) 03:42:04 INFO - PROCESS | 1717 | __metrics Screen width/height:1600/1200 03:42:04 INFO - PROCESS | 1717 | colorDepth:24 03:42:04 INFO - PROCESS | 1717 | Browser inner width/height: 1024/697 03:42:04 INFO - PROCESS | 1717 | __metrics 03:42:05 INFO - PROCESS | 1717 | 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" 03:42:05 INFO - PROCESS | 1717 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:42:06 INFO - TEST-INFO | 1717: exit 0 03:42:06 INFO - Browser initialized. 03:42:06 INFO - Running cycle 1/1 for dromaeo_css test... 03:42:06 INFO - TEST-INFO | started process 1796 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzXbGzp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:42:18 INFO - PROCESS | 1796 | RSS: Main: 159232000 03:42:18 INFO - PROCESS | 1796 | 03:43:10 INFO - PROCESS | 1796 | 1468233790132 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] 03:45:21 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:38428/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38428/tests/dromaeo/cssquery-ext.html) 03:45:22 INFO - PROCESS | 1796 | RSS: Main: 242413568 03:45:22 INFO - PROCESS | 1796 | 03:48:25 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:38428/tests/dromaeo/cssquery-ext.html (next: http://localhost:38428/tests/dromaeo/cssquery-jquery.html) 03:48:26 INFO - PROCESS | 1796 | RSS: Main: 241414144 03:48:26 INFO - PROCESS | 1796 | 03:51:28 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:38428/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38428/tests/dromaeo/cssquery-mootools.html) 03:51:28 INFO - PROCESS | 1796 | RSS: Main: 240545792 03:51:28 INFO - PROCESS | 1796 | 03:54:31 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:38428/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38428/tests/dromaeo/cssquery-prototype.html) 03:54:31 INFO - PROCESS | 1796 | RSS: Main: 236838912 03:54:31 INFO - PROCESS | 1796 | 03:57:34 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:38428/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38428/tests/dromaeo/cssquery-yui.html) 03:57:35 INFO - PROCESS | 1796 | RSS: Main: 219885568 03:57:35 INFO - PROCESS | 1796 | 04:00:38 INFO - PROCESS | 1796 | Cycle 1(1): loaded http://localhost:38428/tests/dromaeo/cssquery-yui.html (next: http://localhost:38428/tests/dromaeo/cssquery-dojo.html) 04:00:38 INFO - PROCESS | 1796 | RSS: Main: 278790144 04:00:38 INFO - PROCESS | 1796 | 04:00:38 INFO - PROCESS | 1796 | __start_tp_report 04:00:38 INFO - PROCESS | 1796 | _x_x_mozilla_page_load 04:00:38 INFO - PROCESS | 1796 | _x_x_mozilla_page_load_details 04:00:38 INFO - PROCESS | 1796 | |i|pagename|runs| 04:00:38 INFO - PROCESS | 1796 | |0;dojo.html;2525.621156826475;2537.251510804313;2571.9999999999995;2583.005542865996;2585.3536615846037;4412.933805992912;4490.573395527428;4538.341940418642;4546.227141385968;4564.543545645433;4519.8418055368065;4529.909401811962;4533.4786499552565;4570.428696412949;4585.151746926821;4117.444145040411;4175.331946888498;4175.519815221258;4194.66442684586;4217.514985776629;4206.305959516676;4235.152969406131;4272.03879127197;4283.271843786548;4289.678274129437;4074.327735923569;4120.052387950788;4121.072758629314;4153.210889930903;4196.601322874322;4104.897377565555;4161.3549899765585;4178.582141785797;4182.017225951899;4198.6221240088535;4149.481314835645;4162.105147393295;4162.271602469556;4183.686223533247;4195.056112374691;4420.447444069491;4468.240399132155;4533.841315553973;4589.082183563267;4592.357070010201;4836.129496690594;4842.055799119174;4899.069176856421;4901.313816065754;4903.754812259373;4872.9756351218375;4886.364772579577;4920.606351491872;4930;4931.605471562302;4448.332750087513;4467.6202522785925;4543.840965566189;4610.124076425468;4615.676902616819;4889.071076495455;4891.73095479752;4897.510248975074;4925.753712314442;4938.358013458227;4700.764961751899;4707.576318131385;4742.217534106902;4744.786484608201;4752.95247047532;4731.148393289241;4754.191787396151;4791.592714619227;4811.350467686818;4823.686460380134;4743.83396581118;4754.072955773591;4768.642351823558;4780.784864681098;4824.34871292378;4748.95251047492;4751.619870410359;4756.381670382828;4760.880977975578;4804.279358096243;4030.395440683862;4066.0851308455954;4070.61329173728;4099;4103.712740108223;3632.4551317302617;3634.9091272717865;3635.600083990759;3646.908827279339;3648.21563363878;4181.351890456984;4230.280852255124;4232.217039847638;4236.08924081324;4240.830366785356;4783.928241076386;4809.422869255712;4822.879428014257;4823.541763532458;4888.633352498577;4196.559361267084;4234.13200293939;4247.193033323659;4247.6254040673375;4291.67812414064;4336.696431249782;4386.649068074546;4398.912021759611;4444.044530425974;4457.554244575516;4614.30785382182;4618.307253911873;4622.2835460503675;4623.56076172763;4667.043256132485;4858.514148585114;4870.829520966749;4878.536539028786;4883.389576302962;4926.630502712239;4344.10945756113;4501.369808226917;4531.546845315442;4570.97153140554;4581.5647513486165;4728.054389122258;4757.952420475751;4761.047790441856;4761.166795810788;4805.423349198119;4703.153432382204;4811.663183577116;4818.951810481991;4821.4696383397095;4866.416030076413;4644.512326205835;4689.226277664284;4699.882502937454;4706.058788242297;4709.740964246999;4675.4155730533685;4700.76496175183;4726.621870250303;4735.266033764772;4752.928706069342;4554.952360956932;4600.056988317454;4651.232546629768;4654.045920586339;4689.507601701772;4743;4757.238841785298;4772.904541909212;4787.832425865078;4813.542713442217;4677.555632215071;4681.204195286741;4689.202835518038;4717.080169366941;4733.360996265598 04:00:38 INFO - PROCESS | 1796 | |1;ext.html;532.6191772882388;543.817821029955;565.8670212500064;577.2985822225994;581.3227589857815;29354.119376418686;31279.530807037856;31396;31400.842995784988;39813.203735925264;34870.476942845875;37890.8105459473;37910.24179516408;37942.0514487138;37964;7666.463347565658;7855.13593504714;7859.096203936563;7864.606769661523;7870.960645196766;7738.806529836743;7821.296083352498;7824.1002284737415;7827.960860195691;7836.255555722226;7778.338841198505;7810.101838288584;7812.10160831506;7812.10160831506;7815.2966233039015;7780.766577002699;7807.6876924922935;7830.608469576556;7834.412419068564;7840.490368126054;7677.846443071107;7719.884201736979;7744.3417309528195;7747.14781374054;7751.992241008689;35647.82176089129;35972.64027359719;36043.4593481098;36085.63914360849;36130.09674758126;2745.6842463116795;2901.680815110336;2949.4838403279555;2972.301509145349;3131.2641529240836;27851.303717407023;27899.302517437023;28678.713212867813;28829.56755648667;31795.205119871956;3299.950500742491;3305.272839975202;3312.7681062325646;3320.286138480238;3327.15157634804;258942;262005;263294;265151;266274;31816.204594885312;42072.579274206866;42470.57529424728;42507.7874610625;42521.78739106285;32734.67265327364;34555.481667775006;34565.30869382598;34653.48019779706;34658;13634.1137826041;13872.791908121386;13908.374123164482;13923.094998825043;13923.234222117679;11291;11507.597234096766;11524.769504610029;11530.654080377602;11535.942320288344;7944.4111177763525;7993.760967050117;7995.800104997305;8006.799830004296;8044.2357975993355;3148.354587309596;3156.1478400832107;3166.4142133705336;3170.476871316207;3182.427163110662;872.9694460693845;898.1871406377239;957.6025949230991;958.8322043642474;1022.381459217163;28134.577981330298;28700;28845.85577072143;29013.56479652807;29295.414091718045;973.8198433289864;995.66147509847;1007.6271779441654;1034.6590937299845;1071.6731396923858;14977.850221497863;15080.773788393182;15175.544733658007;15194.620134496727;15296.847031529542;375983;380195;381206;384338;384995;48110.51889481131;48727;48735;48778;48888.511114888395;1052.6894566103017;1125.1111621818673;1155.9190856640123;1192.2310109979014;1205.6624145239348;28646.713532864404;29083.56374654424;29152.854235728686;29362.853185733933;29835.70164298329;41298;41675.16649667051;41813.372799408644;41814.79092604518;41851.372229415974;290513;291879;292462;292742;293209;22385.44036399103;22443.102275909434;22856.542869142857;23022.194223202107;25115.87442062778;39169.41245881257;39298;39820.2035959285;39928.2014359717;40838.9790255246;34526;34976;35890.820545897106;35981;36532.086697831706;35084.29831403409;35664.643353566134;36152;36380.45429318615;36507;32400.513992290606;33912.15219619529;34150.82924585361;34854.30291394208;35234.47148292726;31388.686113138578;32120.363890833123;32356.191095221868;32528.02415927526;33530.161745956546;24314.149004784747;24396.878015609807;24501.50996980086;24897.128600498894;24964.37589060288 04:00:38 INFO - PROCESS | 1796 | |2;jquery.html;360.4791076893888;365.12916693685554;375.55496736367405;378.3133612493326;381.3421847313383;16393.75409368859;17532.211050502727;18050.18774155163;18061.909690451546;18118.27526898926;16027.919860400682;16386.508404747878;17835.286588536444;17838.10809459525;17869;4555.066211426658;4747.667663263573;4769.546893045155;4771.570558649721;4774.092922344761;3435.811030393327;3607.981960090196;4688.484266730674;5830.921279563273;6154.50763938886;6070.514358851281;6085.117657939596;6097.664628445434;6109.3890610939;6109.450149486545;5466.1527463243265;5470.507654311111;5470.507654311131;5470.91793623096;5480.068388373986;3349.6650334966307;5274.129768588178;6323.841903952438;6788.2193547742145;6808.046873437723;22253.777462225495;23045.76954230453;23398.53202935949;24405.26784196477;24450.266492005267;2092.989535052315;2105.999650166174;2135.5515341778246;2217.634090375086;2222.3777342344006;13747;14755.483558075415;15156.393744250217;15176.392944282215;15374;1892.8769629974008;2021.4036859126593;2109.630814607453;2118.766935637079;2149.4948687058527;81621.59189204076;83757.16242837555;84148.73776893412;85091.5745421269;85753.57123214407;16214.837851621569;16419.343226270816;16629.999999999756;17006;17168.313267469162;29078;29234.707652923626;29273;29457.852710736308;29483.410331793242;8246;8635.222829945335;8669.783255418664;8714.215720585178;8726.607302671395;7073.858522829514;7074.151101867808;7092.822679432952;7215;7247.637618119073;8224;8376.41365104449;8401.74794756146;8422.747317580483;8580.184882436159;2678.410749635077;3116.750659947199;3310.9668903310658;3342.38165939302;3444.4533216154937;754.4190972950797;792.7066985215507;830.6884918155691;843.2789964580196;911.444019148319;16374.754378684329;16502.2573984171;16634.667306654042;16981.235844387033;17302.394416195373;846.6655671009917;851.9020312664;864.429476545477;866.2939704141231;888.2627419242014;6552.770653027216;9257.907420925705;9458.385204961656;9545.52272386378;9806.607735690492;352391;361981;362733;363396;363940;46031.53968460273;48930;62989.68505157445;68433.65783171052;74087;888.5512816027993;888.706726780161;897.9146981036791;901.1079031758512;908.2688435809237;14736.631584210481;15075.849241507884;15135.545933622008;15163.545093647208;15232.162231076954;35736.463953041246;35915.28169436545;35931;36341.636583634885;36682.44976325304;48612.75693621509;48984;49280;49285.753571231915;49685.25472117993;7436.144843343085;7466.813329666802;7478.4765066444825;7499;7706.614669266402;7685.846283074419;10884.401357925073;11065.170112241774;11319.377434241196;11547.653570392902;10382.3251488653;10393.37639741618;10470.162387009173;10474.162067034771;10592.258541901992;9736.22110231194;9945.900540994788;10157.441340726331;10244.436555989492;10308.536115874953;10187.286889917634;10287.948560257151;10341;10519.105876000625;10600.787984240425;6626.602403855784;6802.693878775368;6898.758543450955;7072.71709131629;7649.579273140031;7604.8479030420185;8934.28525717954;10142.137918277029;10224.285920908771;10279.434631095362 04:00:38 INFO - PROCESS | 1796 | |3;mootools.html;1331.7935719963402;1379.4068550523275;1429.2639290765258;1687.6793409252234;1704.2756828347956;7340.412766978642;7714.112877019144;7760.456768026241;7806.687732490701;7817.882731759021;6412.487001039917;7318.96340518298;7502.5873576953245;7557.735479258227;7627.580483073442;2157.590057889004;2167.8482506224573;2190.1896298369625;2190.496185877249;2190.8356873234493;1870.6819840627127;2153.2356013615176;2157.4822042709716;2229.0972156276653;2230.877301748403;2069.5757369739244;2143.3141394753684;2148.430665873537;2160.2763074370105;2160.470684682255;2046.6418376784004;2064.2981386328647;2073.740782402192;2123.5965166618294;2130.840186985982;2137.219914731112;2154.5906277807167;2154.666026765838;2159.676048592708;2160.9567808643737;7734.1879102693965;7778.844423111563;7788.571628560427;7797.025371828521;7814.72648457307;4425.712328698656;4475.239209334421;4516.841910533149;4532.637389008872;4533.093381323749;2661.0819267352927;2731.508328500869;2760.3513174403997;2775.4449110177843;2796.622455968459;887.104024934814;892.4199270474244;916.4180745226829;927.2906226736552;930.7766136127349;10370.533326000274;10401.167906567456;10401.219908506817;10434.582616695323;10454.63408780689;4005.539362973237;4078.1232035112594;4209.663226941837;4256.7445953242905;4262.126264115911;8922.330825188137;8966.462012279284;8980.595873185723;8983.505907175168;8991.505467199237;4526.683132180716;4540.886477838015;4549.272116461417;4551.886202844956;4552.499225085238;4117.711760176759;4150.730202536825;4151.750894946253;4159.355299928456;4162.438070860395;3569.37535931211;3589.856405743741;3598.1724203433355;3627.6372362763514;3697.3899306614644;1067.114295135036;1141.6403832792644;1174.831043112109;1175.9000484958874;1195.0678470792795;729.3873146556812;729.5367441674468;743.7173873927873;751.8571471420413;768.7539987204041;4901.5588597026435;4978.427480839678;5001.374828146481;5031.471695471996;5095.847124586268;759.400073941591;785.1990969211487;812.2405550810012;824.7979245084992;829.4194064154995;3288.490284005983;3354.7483938704695;3385.9492107618407;3391.457366821346;3457.3431048101284;9061.275097992146;9255.583498742439;9426.434413935049;9430.528473576294;9435.481048542397;8057.919420805834;9291.953540232256;9329.860052099224;9385.296102792317;9386.90613093874;6765.323467653294;6891.035255064295;6894.758683446155;7053.4004609608755;7117.1459424869345;5192.42883282831;5317.494837990384;5343.0916744154365;5406.918896216638;5479.917801233064;8689.044205137303;8702.999155097245;8705.56472176376;8772.649094036167;8787.956060219914;8468.745937621881;8828.161324674145;8842.602082906156;9054.32092593045;9095.727128186165;6294.905576416449;6437.034444833313;6440.806775796734;6442.774502892376;6447.548671593131;7090.716371345088;7100;7109.502334836719;7157.677904494206;7157.928420715726;5618.803341883015;6073.696315184311;6469.967650161907;6558.409743123142;6568.540202185801;5991.161237426677;6144.78493252734;6264.498840092872;6298.811035668938;6537.542372033912;5885.882282354414;5904.704764761831;5952.345242023461;5957.404259574181;5957.672328022;4151.232022075926;4163.687723420695;4180.561041090642;4205.3481710334945;4233.5131459882505;4366.12677464502;4368.511680219759;4385.736855788663;4434.600885920283;4451.510333863337 04:00:38 INFO - PROCESS | 1796 | |4;prototype.html;293.1089487956612;301.63351527893604;302.64590429197835;305.4044613004639;305.6469777407094;4696.459907110683;4705.2942058691215;4705.64707646927;4741.549552792483;4744.739039352834;4677.88305292367;4679.953200467994;4684.180268453024;4685.7188568685815;4715.764211789414;3519.2609551994046;3615.8372873220637;3639.2357604903004;3717.368047431943;3745.8501659933704;3688.483612294281;3709.108354739089;3733.8319775610025;3739.5325584301963;3747.250549890019;3678;3685.262947410515;3687.15195505034;3694.131879008431;3694.353488139578;3531.5938667053233;3551.8224088795455;3647.9420967919273;3657.3051120287328;3671.210689701727;3259.445894197992;3264.0860559043504;3268.509723541488;3271.4765637498126;3278.7704860659765;4565.155446242423;4582.266837306015;4588.678792484528;4592.288195329729;4615.169269531483;3950.861719839792;4153.771542565158;4162.542120366757;4170.895727606804;4185.790710464465;4903.7057776533165;4920.876978075541;4922.163232250526;4929.309896614477;4930.728809915453;3488.145404375944;3514.420120680085;3520.3487354839435;3520.77114987525;3525.9118522036897;5023.773930173151;5122.129238029544;5155.922661160085;5166.405863325728;5174.068667639824;3589.425691889285;3607.1162565171703;3612.2956023575152;3612.548431446069;3617.4212126059706;3612.078919875415;3619.3123306571665;3622.710183185339;3648.890533284006;3658.9817050914576;4133;4155.625993660585;4155.792210389468;4160.438340824011;4161.271777438936;4770.160021182912;4908.435529914105;4920.138975679313;4928.827491037797;4929.531694489017;1231.8952889004356;1239.913206075566;1247.52594014274;1251.8873301402919;1257.207958985833;798.217746608327;803.0804728585737;813.2111851504031;838.6310023589601;852.3181454836106;4365.781710914442;4379.890502737393;4382.627476664456;4401.339799030171;4414.4702635683925;4994.600431965434;5000.349954505891;5001.374828146481;5023.296738456619;5032.32063671407;4180.853670121531;4380.620104667019;4401.273789824643;4401.845935392246;4412.63923800765;2676.531606968773;2689.6906855711454;2701.7703495203127;2712.7151649076563;2713.4437440324687;4808.110499557593;4819.409594833766;4859.805607775649;4872.952315252238;4877.975610121926;4963.503649635008;5207.4898279499275;5238.97380513095;5244.659097158673;5255.343082114736;4822.831200907952;4830.468648448667;4832.44426890912;4856.635752318591;4861.957977649396;5071.7464126792775;5071.858831762824;5073.883745575968;5074.264231686311;5086.821961231339;4726.952730472652;4943.530364615356;4981.875453113701;4990.80036798524;5007.899842003067;4854.126257273725;4867.367242258484;4868.829590964299;4874.390701162355;4884.145274577006;3610.1696609780424;3612.927741445104;3620.7465477416326;3626.4016437288606;3630.3828349180176;4966.255061740623;5053.2420136979745;5108.846734597968;5110.897782044412;5111.565516931102;4943.802247910044;4946.604271658187;4949.257611358325;4955.950440495549;4961.975190124026;4906;4914.189158788759;4915.86935004958;4926.630502712383;4934.851954441372;4707.269194459546;4799.232122860326;4832.420109586872;4861.756912154449;4873.95126048735;4962.702237865739;4977.502249774993;4987.700737955734;4993.875153121201;5019.548240658212;4842.661013729005;4849.248366503198;4853.102176097434;4855.49017353187;4875.561199492063 04:00:38 INFO - PROCESS | 1796 | |5;yui.html;534.8448949804556;593.2643522032679;618.7957973868624;625.4652272307177;670.3397153803505;12269.325187114717;12769.489220431184;12775.425105870236;13275.800862987038;13320.800187997163;11921.22512036717;11979.460924258408;11989.580364687237;12010.519579216843;12015.759684806299;614.057421857449;743.4944237918213;745.6569977810213;770.6532060572737;771.0477560213133;741.7960060983219;745.2845268542179;745.7874505765858;745.8508298340327;746.70131947221;801.6713147609481;809.8825670277806;810.0401024785618;810.2990912860363;810.9797255068612;646.5377255262509;781.9648115834801;812.4394168024074;812.5287333346645;831.2601784411818;603.3483837455537;605.3764622438895;607.1954660075373;607.5139888089511;608.2913405882142;12957.09300348976;13006.349682515836;13020.609381718563;13054.151480153854;13078;12284.938575307156;12963.805542916863;12995.935020324932;13024.609261722164;13164.934175329157;9835.409875407428;10124.088832005154;10189.59241630334;10309.896901030968;10489.475526223734;3231.418344697953;3263.7715359924814;3268.512826663862;3338.2488939988552;3356.3790698720813;59215.70392148055;60809.39190608082;61783.691081544755;62239.37760622381;62805.371946280866;10355.534000969974;10513.211509136845;10547.472626368804;10606.734831629117;10639.361638301727;19027.524311892037;19049.7142542862;19059.809401905804;19063.428097157106;19079.71380429267;6439.8068057958335;6517.902231466532;6536.640484773383;6589.53873228879;6679.86640267192;3714.665680088805;3725.3294407006456;3727.552693676722;3761.285355782393;3792.791396473165;2772.7781777457753;2869.153599688097;2870.8708108135183;2875.324298789783;2875.4680384129;486.4017258771731;488.504168269207;499.83755279534296;503.7632312813045;521.7287010754386;461.01343125711;495.43689659125516;517.6764522173642;554.7420449491007;564.0467609739527;7574.166841647414;7700.576468294185;7920;7925.247101525346;8018.799530011796;485.91253574356955;537.0252990821724;560.736453866682;569.6582050769505;582.2459914410848;3592.586852511942;3750.7562008469363;3813.7330386872654;3879.3405121129476;3919.7844118573753;80236;91458;94871.52564237134;95120;95416;82233;83118;83326.58336708277;83409.58295208485;84120.57939710385;73057.2694273061;73413.632931835;73639.63180184172;74073.6296318515;74719.50560988858;6914.377706006484;7104.11198600175;7244.673989670372;7347.559146451016;7430.219826918095;30666.540001899542;41650.375244371964;42595.36106958336;42966.57033429626;43252.56747432611;60474.69762651159;65723;65869;66205;66401;8705.434146780439;8784.91215087841;8889.822203555763;9168.54157292146;9552.761180970254;10559.788804224027;10568.577256909637;10597.4701264938;11711.004564612103;11716.531338746354;9802.332231035027;10823.945880270549;10834.891651083704;11059.281146725438;11110.833337500106;9731.805363892541;10042.748931276776;10062.446565438679;10228.335158214692;10269.178465722574;9478.95260523693;9531.952340238255;9644.9035509644;9645.469499177612;9936.354136981074;7999.560024198725;8117.512949223065;8131.390145739211;8160.5511696854865;8269.875951860848;8256;8557.786239757383;8568.614412351584;8595.82808343842;8790.164934331227 04:00:38 INFO - PROCESS | 1796 | __end_tp_report 04:00:38 INFO - PROCESS | 1796 | __start_cc_report 04:00:38 INFO - PROCESS | 1796 | _x_x_mozilla_cycle_collect,892 04:00:38 INFO - PROCESS | 1796 | __end_cc_report 04:00:38 INFO - PROCESS | 1796 | __startTimestamp1468234838355__endTimestamp 04:00:38 INFO - PROCESS | 1796 | 04:00:38 INFO - PROCESS | 1796 | ------- Summary: start ------- 04:00:38 INFO - PROCESS | 1796 | Number of tests: 6 04:00:38 INFO - PROCESS | 1796 | 04:00:38 INFO - PROCESS | 1796 | [#0] dojo.html Cycles:165 Average:4479.78 Median:4615.68 stddev:454.96 (9.9%) stddev-sans-first:429.76 04:00:38 INFO - PROCESS | 1796 | Values: 2525.6 2537.3 2572.0 2583.0 2585.4 4412.9 4490.6 4538.3 4546.2 4564.5 4519.8 4529.9 4533.5 4570.4 4585.2 4117.4 4175.3 4175.5 4194.7 4217.5 4206.3 4235.2 4272.0 4283.3 4289.7 4074.3 4120.1 4121.1 4153.2 4196.6 4104.9 4161.4 4178.6 4182.0 4198.6 4149.5 4162.1 4162.3 4183.7 4195.1 4420.4 4468.2 4533.8 4589.1 4592.4 4836.1 4842.1 4899.1 4901.3 4903.8 4873.0 4886.4 4920.6 4930.0 4931.6 4448.3 4467.6 4543.8 4610.1 4615.7 4889.1 4891.7 4897.5 4925.8 4938.4 4700.8 4707.6 4742.2 4744.8 4753.0 4731.1 4754.2 4791.6 4811.4 4823.7 4743.8 4754.1 4768.6 4780.8 4824.3 4749.0 4751.6 4756.4 4760.9 4804.3 4030.4 4066.1 4070.6 4099.0 4103.7 3632.5 3634.9 3635.6 3646.9 3648.2 4181.4 4230.3 4232.2 4236.1 4240.8 4783.9 4809.4 4822.9 4823.5 4888.6 4196.6 4234.1 4247.2 4247.6 4291.7 4336.7 4386.6 4398.9 4444.0 4457.6 4614.3 4618.3 4622.3 4623.6 4667.0 4858.5 4870.8 4878.5 4883.4 4926.6 4344.1 4501.4 4531.5 4571.0 4581.6 4728.1 4758.0 4761.0 4761.2 4805.4 4703.2 4811.7 4819.0 4821.5 4866.4 4644.5 4689.2 4699.9 4706.1 4709.7 4675.4 4700.8 4726.6 4735.3 4752.9 4555.0 4600.1 4651.2 4654.0 4689.5 4743.0 4757.2 4772.9 4787.8 4813.5 4677.6 4681.2 4689.2 4717.1 4733.4 04:00:38 INFO - PROCESS | 1796 | 04:00:38 INFO - PROCESS | 1796 | [#1] ext.html Cycles:180 Average:45017.16 Median:34609.39 stddev:83356.74 (240.9%) stddev-sans-first:83523.67 04:00:38 INFO - PROCESS | 1796 | Values: 532.6 543.8 565.9 577.3 581.3 29354.1 31279.5 31396.0 31400.8 39813.2 34870.5 37890.8 37910.2 37942.1 37964.0 7666.5 7855.1 7859.1 7864.6 7871.0 7738.8 7821.3 7824.1 7828.0 7836.3 7778.3 7810.1 7812.1 7812.1 7815.3 7780.8 7807.7 7830.6 7834.4 7840.5 7677.8 7719.9 7744.3 7747.1 7752.0 35647.8 35972.6 36043.5 36085.6 36130.1 2745.7 2901.7 2949.5 2972.3 3131.3 27851.3 27899.3 28678.7 28829.6 31795.2 3300.0 3305.3 3312.8 3320.3 3327.2 258942.0 262005.0 263294.0 265151.0 266274.0 31816.2 42072.6 42470.6 42507.8 42521.8 32734.7 34555.5 34565.3 34653.5 34658.0 13634.1 13872.8 13908.4 13923.1 13923.2 11291.0 11507.6 11524.8 11530.7 11535.9 7944.4 7993.8 7995.8 8006.8 8044.2 3148.4 3156.1 3166.4 3170.5 3182.4 873.0 898.2 957.6 958.8 1022.4 28134.6 28700.0 28845.9 29013.6 29295.4 973.8 995.7 1007.6 1034.7 1071.7 14977.9 15080.8 15175.5 15194.6 15296.8 375983.0 380195.0 381206.0 384338.0 384995.0 48110.5 48727.0 48735.0 48778.0 48888.5 1052.7 1125.1 1155.9 1192.2 1205.7 28646.7 29083.6 29152.9 29362.9 29835.7 41298.0 41675.2 41813.4 41814.8 41851.4 290513.0 291879.0 292462.0 292742.0 293209.0 22385.4 22443.1 22856.5 23022.2 25115.9 39169.4 39298.0 39820.2 39928.2 40839.0 34526.0 34976.0 35890.8 35981.0 36532.1 35084.3 35664.6 36152.0 36380.5 36507.0 32400.5 33912.2 34150.8 34854.3 35234.5 31388.7 32120.4 32356.2 32528.0 33530.2 24314.1 24396.9 24501.5 24897.1 24964.4 04:00:38 INFO - PROCESS | 1796 | 04:00:38 INFO - PROCESS | 1796 | [#2] jquery.html Cycles:180 Average:24358.18 Median:376.93 stddev:59683.23 (15833.9%) stddev-sans-first:59823.46 04:00:38 INFO - PROCESS | 1796 | Values: 360.5 365.1 375.6 378.3 381.3 16393.8 17532.2 18050.2 18061.9 18118.3 16027.9 16386.5 17835.3 17838.1 17869.0 4555.1 4747.7 4769.5 4771.6 4774.1 3435.8 3608.0 4688.5 5830.9 6154.5 6070.5 6085.1 6097.7 6109.4 6109.5 5466.2 5470.5 5470.5 5470.9 5480.1 3349.7 5274.1 6323.8 6788.2 6808.0 22253.8 23045.8 23398.5 24405.3 24450.3 2093.0 2106.0 2135.6 2217.6 2222.4 13747.0 14755.5 15156.4 15176.4 15374.0 1892.9 2021.4 2109.6 2118.8 2149.5 81621.6 83757.2 84148.7 85091.6 85753.6 16214.8 16419.3 16630.0 17006.0 17168.3 29078.0 29234.7 29273.0 29457.9 29483.4 8246.0 8635.2 8669.8 8714.2 8726.6 7073.9 7074.2 7092.8 7215.0 7247.6 8224.0 8376.4 8401.7 8422.7 8580.2 2678.4 3116.8 3311.0 3342.4 3444.5 754.4 792.7 830.7 843.3 911.4 16374.8 16502.3 16634.7 16981.2 17302.4 846.7 851.9 864.4 866.3 888.3 6552.8 9257.9 9458.4 9545.5 9806.6 352391.0 361981.0 362733.0 363396.0 363940.0 46031.5 48930.0 62989.7 68433.7 74087.0 888.6 888.7 897.9 901.1 908.3 14736.6 15075.8 15135.5 15163.5 15232.2 35736.5 35915.3 35931.0 36341.6 36682.4 48612.8 48984.0 49280.0 49285.8 49685.3 7436.1 7466.8 7478.5 7499.0 7706.6 7685.8 10884.4 11065.2 11319.4 11547.7 10382.3 10393.4 10470.2 10474.2 10592.3 9736.2 9945.9 10157.4 10244.4 10308.5 10187.3 10287.9 10341.0 10519.1 10600.8 6626.6 6802.7 6898.8 7072.7 7649.6 7604.8 8934.3 10142.1 10224.3 10279.4 04:00:38 INFO - PROCESS | 1796 | 04:00:38 INFO - PROCESS | 1796 | [#3] mootools.html Cycles:180 Average:4968.93 Median:5254.96 stddev:2785.01 (53.0%) stddev-sans-first:2779.41 04:00:38 INFO - PROCESS | 1796 | Values: 1331.8 1379.4 1429.3 1687.7 1704.3 7340.4 7714.1 7760.5 7806.7 7817.9 6412.5 7319.0 7502.6 7557.7 7627.6 2157.6 2167.8 2190.2 2190.5 2190.8 1870.7 2153.2 2157.5 2229.1 2230.9 2069.6 2143.3 2148.4 2160.3 2160.5 2046.6 2064.3 2073.7 2123.6 2130.8 2137.2 2154.6 2154.7 2159.7 2161.0 7734.2 7778.8 7788.6 7797.0 7814.7 4425.7 4475.2 4516.8 4532.6 4533.1 2661.1 2731.5 2760.4 2775.4 2796.6 887.1 892.4 916.4 927.3 930.8 10370.5 10401.2 10401.2 10434.6 10454.6 4005.5 4078.1 4209.7 4256.7 4262.1 8922.3 8966.5 8980.6 8983.5 8991.5 4526.7 4540.9 4549.3 4551.9 4552.5 4117.7 4150.7 4151.8 4159.4 4162.4 3569.4 3589.9 3598.2 3627.6 3697.4 1067.1 1141.6 1174.8 1175.9 1195.1 729.4 729.5 743.7 751.9 768.8 4901.6 4978.4 5001.4 5031.5 5095.8 759.4 785.2 812.2 824.8 829.4 3288.5 3354.7 3385.9 3391.5 3457.3 9061.3 9255.6 9426.4 9430.5 9435.5 8057.9 9292.0 9329.9 9385.3 9386.9 6765.3 6891.0 6894.8 7053.4 7117.1 5192.4 5317.5 5343.1 5406.9 5479.9 8689.0 8703.0 8705.6 8772.6 8788.0 8468.7 8828.2 8842.6 9054.3 9095.7 6294.9 6437.0 6440.8 6442.8 6447.5 7090.7 7100.0 7109.5 7157.7 7157.9 5618.8 6073.7 6470.0 6558.4 6568.5 5991.2 6144.8 6264.5 6298.8 6537.5 5885.9 5904.7 5952.3 5957.4 5957.7 4151.2 4163.7 4180.6 4205.3 4233.5 4366.1 4368.5 4385.7 4434.6 4451.5 04:00:38 INFO - PROCESS | 1796 | 04:00:38 INFO - PROCESS | 1796 | [#4] prototype.html Cycles:180 Average:4102.69 Median:4705.47 stddev:1191.98 (25.3%) stddev-sans-first:1160.52 04:00:38 INFO - PROCESS | 1796 | Values: 293.1 301.6 302.6 305.4 305.6 4696.5 4705.3 4705.6 4741.5 4744.7 4677.9 4680.0 4684.2 4685.7 4715.8 3519.3 3615.8 3639.2 3717.4 3745.9 3688.5 3709.1 3733.8 3739.5 3747.3 3678.0 3685.3 3687.2 3694.1 3694.4 3531.6 3551.8 3647.9 3657.3 3671.2 3259.4 3264.1 3268.5 3271.5 3278.8 4565.2 4582.3 4588.7 4592.3 4615.2 3950.9 4153.8 4162.5 4170.9 4185.8 4903.7 4920.9 4922.2 4929.3 4930.7 3488.1 3514.4 3520.3 3520.8 3525.9 5023.8 5122.1 5155.9 5166.4 5174.1 3589.4 3607.1 3612.3 3612.5 3617.4 3612.1 3619.3 3622.7 3648.9 3659.0 4133.0 4155.6 4155.8 4160.4 4161.3 4770.2 4908.4 4920.1 4928.8 4929.5 1231.9 1239.9 1247.5 1251.9 1257.2 798.2 803.1 813.2 838.6 852.3 4365.8 4379.9 4382.6 4401.3 4414.5 4994.6 5000.3 5001.4 5023.3 5032.3 4180.9 4380.6 4401.3 4401.8 4412.6 2676.5 2689.7 2701.8 2712.7 2713.4 4808.1 4819.4 4859.8 4873.0 4878.0 4963.5 5207.5 5239.0 5244.7 5255.3 4822.8 4830.5 4832.4 4856.6 4862.0 5071.7 5071.9 5073.9 5074.3 5086.8 4727.0 4943.5 4981.9 4990.8 5007.9 4854.1 4867.4 4868.8 4874.4 4884.1 3610.2 3612.9 3620.7 3626.4 3630.4 4966.3 5053.2 5108.8 5110.9 5111.6 4943.8 4946.6 4949.3 4956.0 4962.0 4906.0 4914.2 4915.9 4926.6 4934.9 4707.3 4799.2 4832.4 4861.8 4874.0 4962.7 4977.5 4987.7 4993.9 5019.5 4842.7 4849.2 4853.1 4855.5 4875.6 04:00:38 INFO - PROCESS | 1796 | 04:00:38 INFO - PROCESS | 1796 | [#5] yui.html Cycles:180 Average:17050.50 Median:30539.02 stddev:24796.87 (81.2%) stddev-sans-first:24835.43 04:00:38 INFO - PROCESS | 1796 | Values: 534.8 593.3 618.8 625.5 670.3 12269.3 12769.5 12775.4 13275.8 13320.8 11921.2 11979.5 11989.6 12010.5 12015.8 614.1 743.5 745.7 770.7 771.0 741.8 745.3 745.8 745.9 746.7 801.7 809.9 810.0 810.3 811.0 646.5 782.0 812.4 812.5 831.3 603.3 605.4 607.2 607.5 608.3 12957.1 13006.3 13020.6 13054.2 13078.0 12284.9 12963.8 12995.9 13024.6 13164.9 9835.4 10124.1 10189.6 10309.9 10489.5 3231.4 3263.8 3268.5 3338.2 3356.4 59215.7 60809.4 61783.7 62239.4 62805.4 10355.5 10513.2 10547.5 10606.7 10639.4 19027.5 19049.7 19059.8 19063.4 19079.7 6439.8 6517.9 6536.6 6589.5 6679.9 3714.7 3725.3 3727.6 3761.3 3792.8 2772.8 2869.2 2870.9 2875.3 2875.5 486.4 488.5 499.8 503.8 521.7 461.0 495.4 517.7 554.7 564.0 7574.2 7700.6 7920.0 7925.2 8018.8 485.9 537.0 560.7 569.7 582.2 3592.6 3750.8 3813.7 3879.3 3919.8 80236.0 91458.0 94871.5 95120.0 95416.0 82233.0 83118.0 83326.6 83409.6 84120.6 73057.3 73413.6 73639.6 74073.6 74719.5 6914.4 7104.1 7244.7 7347.6 7430.2 30666.5 41650.4 42595.4 42966.6 43252.6 60474.7 65723.0 65869.0 66205.0 66401.0 8705.4 8784.9 8889.8 9168.5 9552.8 10559.8 10568.6 10597.5 11711.0 11716.5 9802.3 10823.9 10834.9 11059.3 11110.8 9731.8 10042.7 10062.4 10228.3 10269.2 9479.0 9532.0 9644.9 9645.5 9936.4 7999.6 8117.5 8131.4 8160.6 8269.9 8256.0 8557.8 8568.6 8595.8 8790.2 04:00:38 INFO - PROCESS | 1796 | -------- Summary: end -------- 04:00:38 INFO - PROCESS | 1796 | 04:00:39 INFO - TEST-INFO | 1796: exit 0 04:00:39 INFO - TEST-OK | dromaeo_css | took 1118873ms 04:00:39 INFO - TEST-START | kraken 04:00:39 INFO - operating with platform_type : linux_ 04:00:39 INFO - Initialising browser for kraken test... 04:00:39 INFO - TEST-INFO | started process 1979 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7_o8yd/profile http://localhost:38428/getInfo.html) 04:00:43 INFO - PROCESS | 1979 | __metrics Screen width/height:1600/1200 04:00:43 INFO - PROCESS | 1979 | colorDepth:24 04:00:43 INFO - PROCESS | 1979 | Browser inner width/height: 1024/697 04:00:43 INFO - PROCESS | 1979 | __metrics 04:00:44 INFO - PROCESS | 1979 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:00:44 INFO - PROCESS | 1979 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:00:45 INFO - TEST-INFO | 1979: exit 0 04:00:45 INFO - Browser initialized. 04:00:45 INFO - Running cycle 1/1 for kraken test... 04:00:45 INFO - TEST-INFO | started process 2062 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7_o8yd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:00:56 INFO - PROCESS | 2062 | RSS: Main: 158400512 04:00:56 INFO - PROCESS | 2062 | 04:00:59 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38428/tests/kraken/driver.html?testName=audio-beat-detection) 04:01:00 INFO - PROCESS | 2062 | RSS: Main: 216555520 04:01:00 INFO - PROCESS | 2062 | 04:01:03 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38428/tests/kraken/driver.html?testName=audio-dft) 04:01:03 INFO - PROCESS | 2062 | RSS: Main: 204242944 04:01:03 INFO - PROCESS | 2062 | 04:01:07 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38428/tests/kraken/driver.html?testName=audio-fft) 04:01:07 INFO - PROCESS | 2062 | RSS: Main: 270680064 04:01:07 INFO - PROCESS | 2062 | 04:01:10 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38428/tests/kraken/driver.html?testName=audio-oscillator) 04:01:10 INFO - PROCESS | 2062 | RSS: Main: 181837824 04:01:10 INFO - PROCESS | 2062 | 04:01:12 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38428/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:01:13 INFO - PROCESS | 2062 | RSS: Main: 186232832 04:01:13 INFO - PROCESS | 2062 | 04:01:16 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38428/tests/kraken/driver.html?testName=imaging-darkroom) 04:01:17 INFO - PROCESS | 2062 | RSS: Main: 222052352 04:01:17 INFO - PROCESS | 2062 | 04:01:20 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38428/tests/kraken/driver.html?testName=imaging-desaturate) 04:01:21 INFO - PROCESS | 2062 | RSS: Main: 217690112 04:01:21 INFO - PROCESS | 2062 | 04:01:24 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38428/tests/kraken/driver.html?testName=json-parse-financial) 04:01:25 INFO - PROCESS | 2062 | RSS: Main: 221011968 04:01:25 INFO - PROCESS | 2062 | 04:01:26 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38428/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:01:27 INFO - PROCESS | 2062 | RSS: Main: 209571840 04:01:27 INFO - PROCESS | 2062 | 04:01:29 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38428/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:01:29 INFO - PROCESS | 2062 | RSS: Main: 210915328 04:01:29 INFO - PROCESS | 2062 | 04:01:31 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38428/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:01:32 INFO - PROCESS | 2062 | RSS: Main: 212959232 04:01:32 INFO - PROCESS | 2062 | 04:01:35 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38428/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:01:36 INFO - PROCESS | 2062 | RSS: Main: 225075200 04:01:36 INFO - PROCESS | 2062 | 04:01:38 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38428/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:01:39 INFO - PROCESS | 2062 | RSS: Main: 224935936 04:01:39 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | Cycle 1(1): loaded http://localhost:38428/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38428/tests/kraken/driver.html?testName=ai-astar) 04:01:41 INFO - PROCESS | 2062 | RSS: Main: 267653120 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | __start_tp_report 04:01:41 INFO - PROCESS | 2062 | _x_x_mozilla_page_load 04:01:41 INFO - PROCESS | 2062 | _x_x_mozilla_page_load_details 04:01:41 INFO - PROCESS | 2062 | |i|pagename|runs| 04:01:41 INFO - PROCESS | 2062 | |0;ai-astar;110;115;115;115;116;115;114;121;114;114 04:01:41 INFO - PROCESS | 2062 | |1;audio-beat-detection;157;127;128;175;124;130;146;124;181;121 04:01:41 INFO - PROCESS | 2062 | |2;audio-dft;161;160;162;162;164;163;163;161;161;160 04:01:41 INFO - PROCESS | 2062 | |3;audio-fft;76;76;78;108;76;77;76;75;100;76 04:01:41 INFO - PROCESS | 2062 | |4;audio-oscillator;85;84;116;85;86;85;115;84;84;124 04:01:41 INFO - PROCESS | 2062 | |5;imaging-gaussian-blur;117;117;117;116;117;116;116;117;118;118 04:01:41 INFO - PROCESS | 2062 | |6;imaging-darkroom;147;146;147;146;146;146;147;147;146;146 04:01:41 INFO - PROCESS | 2062 | |7;imaging-desaturate;95;95;94;95;94;95;95;94;94;95 04:01:41 INFO - PROCESS | 2062 | |8;json-parse-financial;64;64;65;64;66;63;63;63;63;63 04:01:41 INFO - PROCESS | 2062 | |9;json-stringify-tinderbox;65;65;64;64;64;65;66;69;65;68 04:01:41 INFO - PROCESS | 2062 | |10;stanford-crypto-aes;78;80;77;87;78;77;78;79;79;85 04:01:41 INFO - PROCESS | 2062 | |11;stanford-crypto-ccm;133;131;130;133;140;130;129;130;159;138 04:01:41 INFO - PROCESS | 2062 | |12;stanford-crypto-pbkdf2;167;169;169;163;166;170;166;166;165;165 04:01:41 INFO - PROCESS | 2062 | |13;stanford-crypto-sha256-iterative;69;67;66;66;67;65;67;66;68;66 04:01:41 INFO - PROCESS | 2062 | __end_tp_report 04:01:41 INFO - PROCESS | 2062 | __start_cc_report 04:01:41 INFO - PROCESS | 2062 | _x_x_mozilla_cycle_collect,1952 04:01:41 INFO - PROCESS | 2062 | __end_cc_report 04:01:41 INFO - PROCESS | 2062 | __startTimestamp1468234901577__endTimestamp 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | ------- Summary: start ------- 04:01:41 INFO - PROCESS | 2062 | Number of tests: 14 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#0] ai-astar Cycles:10 Average:114.90 Median:115.00 stddev:2.69 (2.3%) stddev-sans-first:2.19 04:01:41 INFO - PROCESS | 2062 | Values: 110.0 115.0 115.0 115.0 116.0 115.0 114.0 121.0 114.0 114.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:138.00 stddev:22.36 (16.2%) stddev-sans-first:22.98 04:01:41 INFO - PROCESS | 2062 | Values: 157.0 127.0 128.0 175.0 124.0 130.0 146.0 124.0 181.0 121.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.34 (0.8%) stddev-sans-first:1.39 04:01:41 INFO - PROCESS | 2062 | Values: 161.0 160.0 162.0 162.0 164.0 163.0 163.0 161.0 161.0 160.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#3] audio-fft Cycles:10 Average:81.80 Median:76.00 stddev:11.88 (15.6%) stddev-sans-first:12.41 04:01:41 INFO - PROCESS | 2062 | Values: 76.0 76.0 78.0 108.0 76.0 77.0 76.0 75.0 100.0 76.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:84.50 stddev:16.42 (19.4%) stddev-sans-first:17.03 04:01:41 INFO - PROCESS | 2062 | Values: 85.0 84.0 116.0 85.0 86.0 85.0 115.0 84.0 84.0 124.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 04:01:41 INFO - PROCESS | 2062 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 116.0 117.0 118.0 118.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 04:01:41 INFO - PROCESS | 2062 | Values: 147.0 146.0 147.0 146.0 146.0 146.0 147.0 147.0 146.0 146.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 04:01:41 INFO - PROCESS | 2062 | Values: 95.0 95.0 94.0 95.0 94.0 95.0 95.0 94.0 94.0 95.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:64.00 stddev:1.03 (1.6%) stddev-sans-first:1.09 04:01:41 INFO - PROCESS | 2062 | Values: 64.0 64.0 65.0 64.0 66.0 63.0 63.0 63.0 63.0 63.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:65.00 stddev:1.72 (2.6%) stddev-sans-first:1.81 04:01:41 INFO - PROCESS | 2062 | Values: 65.0 65.0 64.0 64.0 64.0 65.0 66.0 69.0 65.0 68.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.00 stddev:3.43 (4.3%) stddev-sans-first:3.57 04:01:41 INFO - PROCESS | 2062 | Values: 78.0 80.0 77.0 87.0 78.0 77.0 78.0 79.0 79.0 85.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#11] stanford-crypto-ccm Cycles:10 Average:135.30 Median:133.00 stddev:9.09 (6.8%) stddev-sans-first:9.61 04:01:41 INFO - PROCESS | 2062 | Values: 133.0 131.0 130.0 133.0 140.0 130.0 129.0 130.0 159.0 138.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:166.50 stddev:2.17 (1.3%) stddev-sans-first:2.30 04:01:41 INFO - PROCESS | 2062 | Values: 167.0 169.0 169.0 163.0 166.0 170.0 166.0 166.0 165.0 165.0 04:01:41 INFO - PROCESS | 2062 | 04:01:41 INFO - PROCESS | 2062 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.16 (1.7%) stddev-sans-first:0.88 04:01:41 INFO - PROCESS | 2062 | Values: 69.0 67.0 66.0 66.0 67.0 65.0 67.0 66.0 68.0 66.0 04:01:41 INFO - PROCESS | 2062 | -------- Summary: end -------- 04:01:41 INFO - PROCESS | 2062 | 04:01:42 INFO - TEST-INFO | 2062: exit 0 04:01:42 INFO - TEST-OK | kraken | took 63475ms 04:01:42 INFO - SUITE-END | took 1182s 04:01:42 INFO - Completed test suite (00:19:42) 04:01:42 INFO - javascript benchmark 04:01:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2525.621156826475, 2537.251510804313, 2571.9999999999995, 2583.005542865996, 2585.3536615846037, 4412.933805992912, 4490.573395527428, 4538.341940418642, 4546.227141385968, 4564.543545645433, 4519.8418055368065, 4529.909401811962, 4533.4786499552565, 4570.428696412949, 4585.151746926821, 4117.444145040411, 4175.331946888498, 4175.519815221258, 4194.66442684586, 4217.514985776629, 4206.305959516676, 4235.152969406131, 4272.03879127197, 4283.271843786548, 4289.678274129437, 4074.327735923569, 4120.052387950788, 4121.072758629314, 4153.210889930903, 4196.601322874322, 4104.897377565555, 4161.3549899765585, 4178.582141785797, 4182.017225951899, 4198.6221240088535, 4149.481314835645, 4162.105147393295, 4162.271602469556, 4183.686223533247, 4195.056112374691, 4420.447444069491, 4468.240399132155, 4533.841315553973, 4589.082183563267, 4592.357070010201, 4836.129496690594, 4842.055799119174, 4899.069176856421, 4901.313816065754, 4903.754812259373, 4872.9756351218375, 4886.364772579577, 4920.606351491872, 4930.0, 4931.605471562302, 4448.332750087513, 4467.6202522785925, 4543.840965566189, 4610.124076425468, 4615.676902616819, 4889.071076495455, 4891.73095479752, 4897.510248975074, 4925.753712314442, 4938.358013458227, 4700.764961751899, 4707.576318131385, 4742.217534106902, 4744.786484608201, 4752.95247047532, 4731.148393289241, 4754.191787396151, 4791.592714619227, 4811.350467686818, 4823.686460380134, 4743.83396581118, 4754.072955773591, 4768.642351823558, 4780.784864681098, 4824.34871292378, 4748.95251047492, 4751.619870410359, 4756.381670382828, 4760.880977975578, 4804.279358096243, 4030.395440683862, 4066.0851308455954, 4070.61329173728, 4099.0, 4103.712740108223, 3632.4551317302617, 3634.9091272717865, 3635.600083990759, 3646.908827279339, 3648.21563363878, 4181.351890456984, 4230.280852255124, 4232.217039847638, 4236.08924081324, 4240.830366785356, 4783.928241076386, 4809.422869255712, 4822.879428014257, 4823.541763532458, 4888.633352498577, 4196.559361267084, 4234.13200293939, 4247.193033323659, 4247.6254040673375, 4291.67812414064, 4336.696431249782, 4386.649068074546, 4398.912021759611, 4444.044530425974, 4457.554244575516, 4614.30785382182, 4618.307253911873, 4622.2835460503675, 4623.56076172763, 4667.043256132485, 4858.514148585114, 4870.829520966749, 4878.536539028786, 4883.389576302962, 4926.630502712239, 4344.10945756113, 4501.369808226917, 4531.546845315442, 4570.97153140554, 4581.5647513486165, 4728.054389122258, 4757.952420475751, 4761.047790441856, 4761.166795810788, 4805.423349198119, 4703.153432382204, 4811.663183577116, 4818.951810481991, 4821.4696383397095, 4866.416030076413, 4644.512326205835, 4689.226277664284, 4699.882502937454, 4706.058788242297, 4709.740964246999, 4675.4155730533685, 4700.76496175183, 4726.621870250303, 4735.266033764772, 4752.928706069342, 4554.952360956932, 4600.056988317454, 4651.232546629768, 4654.045920586339, 4689.507601701772, 4743.0, 4757.238841785298, 4772.904541909212, 4787.832425865078, 4813.542713442217, 4677.555632215071, 4681.204195286741, 4689.202835518038, 4717.080169366941, 4733.360996265598], "value": 4451.538766747457, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.6191772882388, 543.817821029955, 565.8670212500064, 577.2985822225994, 581.3227589857815, 29354.119376418686, 31279.530807037856, 31396.0, 31400.842995784988, 39813.203735925264, 34870.476942845875, 37890.8105459473, 37910.24179516408, 37942.0514487138, 37964.0, 7666.463347565658, 7855.13593504714, 7859.096203936563, 7864.606769661523, 7870.960645196766, 7738.806529836743, 7821.296083352498, 7824.1002284737415, 7827.960860195691, 7836.255555722226, 7778.338841198505, 7810.101838288584, 7812.10160831506, 7812.10160831506, 7815.2966233039015, 7780.766577002699, 7807.6876924922935, 7830.608469576556, 7834.412419068564, 7840.490368126054, 7677.846443071107, 7719.884201736979, 7744.3417309528195, 7747.14781374054, 7751.992241008689, 35647.82176089129, 35972.64027359719, 36043.4593481098, 36085.63914360849, 36130.09674758126, 2745.6842463116795, 2901.680815110336, 2949.4838403279555, 2972.301509145349, 3131.2641529240836, 27851.303717407023, 27899.302517437023, 28678.713212867813, 28829.56755648667, 31795.205119871956, 3299.950500742491, 3305.272839975202, 3312.7681062325646, 3320.286138480238, 3327.15157634804, 258942.0, 262005.0, 263294.0, 265151.0, 266274.0, 31816.204594885312, 42072.579274206866, 42470.57529424728, 42507.7874610625, 42521.78739106285, 32734.67265327364, 34555.481667775006, 34565.30869382598, 34653.48019779706, 34658.0, 13634.1137826041, 13872.791908121386, 13908.374123164482, 13923.094998825043, 13923.234222117679, 11291.0, 11507.597234096766, 11524.769504610029, 11530.654080377602, 11535.942320288344, 7944.4111177763525, 7993.760967050117, 7995.800104997305, 8006.799830004296, 8044.2357975993355, 3148.354587309596, 3156.1478400832107, 3166.4142133705336, 3170.476871316207, 3182.427163110662, 872.9694460693845, 898.1871406377239, 957.6025949230991, 958.8322043642474, 1022.381459217163, 28134.577981330298, 28700.0, 28845.85577072143, 29013.56479652807, 29295.414091718045, 973.8198433289864, 995.66147509847, 1007.6271779441654, 1034.6590937299845, 1071.6731396923858, 14977.850221497863, 15080.773788393182, 15175.544733658007, 15194.620134496727, 15296.847031529542, 375983.0, 380195.0, 381206.0, 384338.0, 384995.0, 48110.51889481131, 48727.0, 48735.0, 48778.0, 48888.511114888395, 1052.6894566103017, 1125.1111621818673, 1155.9190856640123, 1192.2310109979014, 1205.6624145239348, 28646.713532864404, 29083.56374654424, 29152.854235728686, 29362.853185733933, 29835.70164298329, 41298.0, 41675.16649667051, 41813.372799408644, 41814.79092604518, 41851.372229415974, 290513.0, 291879.0, 292462.0, 292742.0, 293209.0, 22385.44036399103, 22443.102275909434, 22856.542869142857, 23022.194223202107, 25115.87442062778, 39169.41245881257, 39298.0, 39820.2035959285, 39928.2014359717, 40838.9790255246, 34526.0, 34976.0, 35890.820545897106, 35981.0, 36532.086697831706, 35084.29831403409, 35664.643353566134, 36152.0, 36380.45429318615, 36507.0, 32400.513992290606, 33912.15219619529, 34150.82924585361, 34854.30291394208, 35234.47148292726, 31388.686113138578, 32120.363890833123, 32356.191095221868, 32528.02415927526, 33530.161745956546, 24314.149004784747, 24396.878015609807, 24501.50996980086, 24897.128600498894, 24964.37589060288], "value": 16128.617387873566, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.4791076893888, 365.12916693685554, 375.55496736367405, 378.3133612493326, 381.3421847313383, 16393.75409368859, 17532.211050502727, 18050.18774155163, 18061.909690451546, 18118.27526898926, 16027.919860400682, 16386.508404747878, 17835.286588536444, 17838.10809459525, 17869.0, 4555.066211426658, 4747.667663263573, 4769.546893045155, 4771.570558649721, 4774.092922344761, 3435.811030393327, 3607.981960090196, 4688.484266730674, 5830.921279563273, 6154.50763938886, 6070.514358851281, 6085.117657939596, 6097.664628445434, 6109.3890610939, 6109.450149486545, 5466.1527463243265, 5470.507654311111, 5470.507654311131, 5470.91793623096, 5480.068388373986, 3349.6650334966307, 5274.129768588178, 6323.841903952438, 6788.2193547742145, 6808.046873437723, 22253.777462225495, 23045.76954230453, 23398.53202935949, 24405.26784196477, 24450.266492005267, 2092.989535052315, 2105.999650166174, 2135.5515341778246, 2217.634090375086, 2222.3777342344006, 13747.0, 14755.483558075415, 15156.393744250217, 15176.392944282215, 15374.0, 1892.8769629974008, 2021.4036859126593, 2109.630814607453, 2118.766935637079, 2149.4948687058527, 81621.59189204076, 83757.16242837555, 84148.73776893412, 85091.5745421269, 85753.57123214407, 16214.837851621569, 16419.343226270816, 16629.999999999756, 17006.0, 17168.313267469162, 29078.0, 29234.707652923626, 29273.0, 29457.852710736308, 29483.410331793242, 8246.0, 8635.222829945335, 8669.783255418664, 8714.215720585178, 8726.607302671395, 7073.858522829514, 7074.151101867808, 7092.822679432952, 7215.0, 7247.637618119073, 8224.0, 8376.41365104449, 8401.74794756146, 8422.747317580483, 8580.184882436159, 2678.410749635077, 3116.750659947199, 3310.9668903310658, 3342.38165939302, 3444.4533216154937, 754.4190972950797, 792.7066985215507, 830.6884918155691, 843.2789964580196, 911.444019148319, 16374.754378684329, 16502.2573984171, 16634.667306654042, 16981.235844387033, 17302.394416195373, 846.6655671009917, 851.9020312664, 864.429476545477, 866.2939704141231, 888.2627419242014, 6552.770653027216, 9257.907420925705, 9458.385204961656, 9545.52272386378, 9806.607735690492, 352391.0, 361981.0, 362733.0, 363396.0, 363940.0, 46031.53968460273, 48930.0, 62989.68505157445, 68433.65783171052, 74087.0, 888.5512816027993, 888.706726780161, 897.9146981036791, 901.1079031758512, 908.2688435809237, 14736.631584210481, 15075.849241507884, 15135.545933622008, 15163.545093647208, 15232.162231076954, 35736.463953041246, 35915.28169436545, 35931.0, 36341.636583634885, 36682.44976325304, 48612.75693621509, 48984.0, 49280.0, 49285.753571231915, 49685.25472117993, 7436.144843343085, 7466.813329666802, 7478.4765066444825, 7499.0, 7706.614669266402, 7685.846283074419, 10884.401357925073, 11065.170112241774, 11319.377434241196, 11547.653570392902, 10382.3251488653, 10393.37639741618, 10470.162387009173, 10474.162067034771, 10592.258541901992, 9736.22110231194, 9945.900540994788, 10157.441340726331, 10244.436555989492, 10308.536115874953, 10187.286889917634, 10287.948560257151, 10341.0, 10519.105876000625, 10600.787984240425, 6626.602403855784, 6802.693878775368, 6898.758543450955, 7072.71709131629, 7649.579273140031, 7604.8479030420185, 8934.28525717954, 10142.137918277029, 10224.285920908771, 10279.434631095362], "value": 8915.699549997587, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.7935719963402, 1379.4068550523275, 1429.2639290765258, 1687.6793409252234, 1704.2756828347956, 7340.412766978642, 7714.112877019144, 7760.456768026241, 7806.687732490701, 7817.882731759021, 6412.487001039917, 7318.96340518298, 7502.5873576953245, 7557.735479258227, 7627.580483073442, 2157.590057889004, 2167.8482506224573, 2190.1896298369625, 2190.496185877249, 2190.8356873234493, 1870.6819840627127, 2153.2356013615176, 2157.4822042709716, 2229.0972156276653, 2230.877301748403, 2069.5757369739244, 2143.3141394753684, 2148.430665873537, 2160.2763074370105, 2160.470684682255, 2046.6418376784004, 2064.2981386328647, 2073.740782402192, 2123.5965166618294, 2130.840186985982, 2137.219914731112, 2154.5906277807167, 2154.666026765838, 2159.676048592708, 2160.9567808643737, 7734.1879102693965, 7778.844423111563, 7788.571628560427, 7797.025371828521, 7814.72648457307, 4425.712328698656, 4475.239209334421, 4516.841910533149, 4532.637389008872, 4533.093381323749, 2661.0819267352927, 2731.508328500869, 2760.3513174403997, 2775.4449110177843, 2796.622455968459, 887.104024934814, 892.4199270474244, 916.4180745226829, 927.2906226736552, 930.7766136127349, 10370.533326000274, 10401.167906567456, 10401.219908506817, 10434.582616695323, 10454.63408780689, 4005.539362973237, 4078.1232035112594, 4209.663226941837, 4256.7445953242905, 4262.126264115911, 8922.330825188137, 8966.462012279284, 8980.595873185723, 8983.505907175168, 8991.505467199237, 4526.683132180716, 4540.886477838015, 4549.272116461417, 4551.886202844956, 4552.499225085238, 4117.711760176759, 4150.730202536825, 4151.750894946253, 4159.355299928456, 4162.438070860395, 3569.37535931211, 3589.856405743741, 3598.1724203433355, 3627.6372362763514, 3697.3899306614644, 1067.114295135036, 1141.6403832792644, 1174.831043112109, 1175.9000484958874, 1195.0678470792795, 729.3873146556812, 729.5367441674468, 743.7173873927873, 751.8571471420413, 768.7539987204041, 4901.5588597026435, 4978.427480839678, 5001.374828146481, 5031.471695471996, 5095.847124586268, 759.400073941591, 785.1990969211487, 812.2405550810012, 824.7979245084992, 829.4194064154995, 3288.490284005983, 3354.7483938704695, 3385.9492107618407, 3391.457366821346, 3457.3431048101284, 9061.275097992146, 9255.583498742439, 9426.434413935049, 9430.528473576294, 9435.481048542397, 8057.919420805834, 9291.953540232256, 9329.860052099224, 9385.296102792317, 9386.90613093874, 6765.323467653294, 6891.035255064295, 6894.758683446155, 7053.4004609608755, 7117.1459424869345, 5192.42883282831, 5317.494837990384, 5343.0916744154365, 5406.918896216638, 5479.917801233064, 8689.044205137303, 8702.999155097245, 8705.56472176376, 8772.649094036167, 8787.956060219914, 8468.745937621881, 8828.161324674145, 8842.602082906156, 9054.32092593045, 9095.727128186165, 6294.905576416449, 6437.034444833313, 6440.806775796734, 6442.774502892376, 6447.548671593131, 7090.716371345088, 7100.0, 7109.502334836719, 7157.677904494206, 7157.928420715726, 5618.803341883015, 6073.696315184311, 6469.967650161907, 6558.409743123142, 6568.540202185801, 5991.161237426677, 6144.78493252734, 6264.498840092872, 6298.811035668938, 6537.542372033912, 5885.882282354414, 5904.704764761831, 5952.345242023461, 5957.404259574181, 5957.672328022, 4151.232022075926, 4163.687723420695, 4180.561041090642, 4205.3481710334945, 4233.5131459882505, 4366.12677464502, 4368.511680219759, 4385.736855788663, 4434.600885920283, 4451.510333863337], "value": 4002.322820603127, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.1089487956612, 301.63351527893604, 302.64590429197835, 305.4044613004639, 305.6469777407094, 4696.459907110683, 4705.2942058691215, 4705.64707646927, 4741.549552792483, 4744.739039352834, 4677.88305292367, 4679.953200467994, 4684.180268453024, 4685.7188568685815, 4715.764211789414, 3519.2609551994046, 3615.8372873220637, 3639.2357604903004, 3717.368047431943, 3745.8501659933704, 3688.483612294281, 3709.108354739089, 3733.8319775610025, 3739.5325584301963, 3747.250549890019, 3678.0, 3685.262947410515, 3687.15195505034, 3694.131879008431, 3694.353488139578, 3531.5938667053233, 3551.8224088795455, 3647.9420967919273, 3657.3051120287328, 3671.210689701727, 3259.445894197992, 3264.0860559043504, 3268.509723541488, 3271.4765637498126, 3278.7704860659765, 4565.155446242423, 4582.266837306015, 4588.678792484528, 4592.288195329729, 4615.169269531483, 3950.861719839792, 4153.771542565158, 4162.542120366757, 4170.895727606804, 4185.790710464465, 4903.7057776533165, 4920.876978075541, 4922.163232250526, 4929.309896614477, 4930.728809915453, 3488.145404375944, 3514.420120680085, 3520.3487354839435, 3520.77114987525, 3525.9118522036897, 5023.773930173151, 5122.129238029544, 5155.922661160085, 5166.405863325728, 5174.068667639824, 3589.425691889285, 3607.1162565171703, 3612.2956023575152, 3612.548431446069, 3617.4212126059706, 3612.078919875415, 3619.3123306571665, 3622.710183185339, 3648.890533284006, 3658.9817050914576, 4133.0, 4155.625993660585, 4155.792210389468, 4160.438340824011, 4161.271777438936, 4770.160021182912, 4908.435529914105, 4920.138975679313, 4928.827491037797, 4929.531694489017, 1231.8952889004356, 1239.913206075566, 1247.52594014274, 1251.8873301402919, 1257.207958985833, 798.217746608327, 803.0804728585737, 813.2111851504031, 838.6310023589601, 852.3181454836106, 4365.781710914442, 4379.890502737393, 4382.627476664456, 4401.339799030171, 4414.4702635683925, 4994.600431965434, 5000.349954505891, 5001.374828146481, 5023.296738456619, 5032.32063671407, 4180.853670121531, 4380.620104667019, 4401.273789824643, 4401.845935392246, 4412.63923800765, 2676.531606968773, 2689.6906855711454, 2701.7703495203127, 2712.7151649076563, 2713.4437440324687, 4808.110499557593, 4819.409594833766, 4859.805607775649, 4872.952315252238, 4877.975610121926, 4963.503649635008, 5207.4898279499275, 5238.97380513095, 5244.659097158673, 5255.343082114736, 4822.831200907952, 4830.468648448667, 4832.44426890912, 4856.635752318591, 4861.957977649396, 5071.7464126792775, 5071.858831762824, 5073.883745575968, 5074.264231686311, 5086.821961231339, 4726.952730472652, 4943.530364615356, 4981.875453113701, 4990.80036798524, 5007.899842003067, 4854.126257273725, 4867.367242258484, 4868.829590964299, 4874.390701162355, 4884.145274577006, 3610.1696609780424, 3612.927741445104, 3620.7465477416326, 3626.4016437288606, 3630.3828349180176, 4966.255061740623, 5053.2420136979745, 5108.846734597968, 5110.897782044412, 5111.565516931102, 4943.802247910044, 4946.604271658187, 4949.257611358325, 4955.950440495549, 4961.975190124026, 4906.0, 4914.189158788759, 4915.86935004958, 4926.630502712383, 4934.851954441372, 4707.269194459546, 4799.232122860326, 4832.420109586872, 4861.756912154449, 4873.95126048735, 4962.702237865739, 4977.502249774993, 4987.700737955734, 4993.875153121201, 5019.548240658212, 4842.661013729005, 4849.248366503198, 4853.102176097434, 4855.49017353187, 4875.561199492063], "value": 3724.422194759318, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [534.8448949804556, 593.2643522032679, 618.7957973868624, 625.4652272307177, 670.3397153803505, 12269.325187114717, 12769.489220431184, 12775.425105870236, 13275.800862987038, 13320.800187997163, 11921.22512036717, 11979.460924258408, 11989.580364687237, 12010.519579216843, 12015.759684806299, 614.057421857449, 743.4944237918213, 745.6569977810213, 770.6532060572737, 771.0477560213133, 741.7960060983219, 745.2845268542179, 745.7874505765858, 745.8508298340327, 746.70131947221, 801.6713147609481, 809.8825670277806, 810.0401024785618, 810.2990912860363, 810.9797255068612, 646.5377255262509, 781.9648115834801, 812.4394168024074, 812.5287333346645, 831.2601784411818, 603.3483837455537, 605.3764622438895, 607.1954660075373, 607.5139888089511, 608.2913405882142, 12957.09300348976, 13006.349682515836, 13020.609381718563, 13054.151480153854, 13078.0, 12284.938575307156, 12963.805542916863, 12995.935020324932, 13024.609261722164, 13164.934175329157, 9835.409875407428, 10124.088832005154, 10189.59241630334, 10309.896901030968, 10489.475526223734, 3231.418344697953, 3263.7715359924814, 3268.512826663862, 3338.2488939988552, 3356.3790698720813, 59215.70392148055, 60809.39190608082, 61783.691081544755, 62239.37760622381, 62805.371946280866, 10355.534000969974, 10513.211509136845, 10547.472626368804, 10606.734831629117, 10639.361638301727, 19027.524311892037, 19049.7142542862, 19059.809401905804, 19063.428097157106, 19079.71380429267, 6439.8068057958335, 6517.902231466532, 6536.640484773383, 6589.53873228879, 6679.86640267192, 3714.665680088805, 3725.3294407006456, 3727.552693676722, 3761.285355782393, 3792.791396473165, 2772.7781777457753, 2869.153599688097, 2870.8708108135183, 2875.324298789783, 2875.4680384129, 486.4017258771731, 488.504168269207, 499.83755279534296, 503.7632312813045, 521.7287010754386, 461.01343125711, 495.43689659125516, 517.6764522173642, 554.7420449491007, 564.0467609739527, 7574.166841647414, 7700.576468294185, 7920.0, 7925.247101525346, 8018.799530011796, 485.91253574356955, 537.0252990821724, 560.736453866682, 569.6582050769505, 582.2459914410848, 3592.586852511942, 3750.7562008469363, 3813.7330386872654, 3879.3405121129476, 3919.7844118573753, 80236.0, 91458.0, 94871.52564237134, 95120.0, 95416.0, 82233.0, 83118.0, 83326.58336708277, 83409.58295208485, 84120.57939710385, 73057.2694273061, 73413.632931835, 73639.63180184172, 74073.6296318515, 74719.50560988858, 6914.377706006484, 7104.11198600175, 7244.673989670372, 7347.559146451016, 7430.219826918095, 30666.540001899542, 41650.375244371964, 42595.36106958336, 42966.57033429626, 43252.56747432611, 60474.69762651159, 65723.0, 65869.0, 66205.0, 66401.0, 8705.434146780439, 8784.91215087841, 8889.822203555763, 9168.54157292146, 9552.761180970254, 10559.788804224027, 10568.577256909637, 10597.4701264938, 11711.004564612103, 11716.531338746354, 9802.332231035027, 10823.945880270549, 10834.891651083704, 11059.281146725438, 11110.833337500106, 9731.805363892541, 10042.748931276776, 10062.446565438679, 10228.335158214692, 10269.178465722574, 9478.95260523693, 9531.952340238255, 9644.9035509644, 9645.469499177612, 9936.354136981074, 7999.560024198725, 8117.512949223065, 8131.390145739211, 8160.5511696854865, 8269.875951860848, 8256.0, 8557.786239757383, 8568.614412351584, 8595.82808343842, 8790.164934331227], "value": 6184.198649974768, "unit": "score"}], "name": "dromaeo_css", "value": 6239.666075986477, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 115.0, 115.0, 115.0, 116.0, 115.0, 114.0, 121.0, 114.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 127.0, 128.0, 175.0, 124.0, 130.0, 146.0, 124.0, 181.0, 121.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 162.0, 164.0, 163.0, 163.0, 161.0, 161.0, 160.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 78.0, 108.0, 76.0, 77.0, 76.0, 75.0, 100.0, 76.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 116.0, 85.0, 86.0, 85.0, 115.0, 84.0, 84.0, 124.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 118.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 66.0, 63.0, 63.0, 63.0, 63.0, 63.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 64.0, 64.0, 65.0, 66.0, 69.0, 65.0, 68.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 77.0, 87.0, 78.0, 77.0, 78.0, 79.0, 79.0, 85.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 131.0, 130.0, 133.0, 140.0, 130.0, 129.0, 130.0, 159.0, 138.0], "value": 135.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 169.0, 163.0, 166.0, 170.0, 166.0, 166.0, 165.0, 165.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 66.0, 66.0, 67.0, 65.0, 67.0, 66.0, 68.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1530.0999999999997, "alertThreshold": 2.0}]} 04:01:42 INFO - Return code: 0 04:01:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:01:42 INFO - # TBPL SUCCESS # 04:01:42 INFO - Running post-action listener: _resource_record_post_action 04:01:42 INFO - ##### 04:01:42 INFO - ##### Finished run-tests step (success) 04:01:42 INFO - ##### 04:01:42 INFO - Running post-run listener: _resource_record_post_run 04:01:42 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 535375872; Read time: 16; Write time: 430940 04:01:42 INFO - TinderboxPrint: CPU usage
12.9% 04:01:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 04:01:42 INFO - TinderboxPrint: I/O write bytes / time
535,375,872 / 430,940 04:01:42 INFO - TinderboxPrint: CPU idle
8,330.2 (87.0%) 04:01:42 INFO - TinderboxPrint: CPU user
1,196.7 (12.5%) 04:01:42 INFO - TinderboxPrint: Swap in / out
0 / 0 04:01:42 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246599680; Read time: 0; Write time: 223524 04:01:42 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 288776192; Read time: 0; Write time: 207416 04:01:43 INFO - Running post-run listener: _upload_blobber_files 04:01:43 INFO - Blob upload gear active. 04:01:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:01:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:01:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:01:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:01:43 INFO - (blobuploader) - INFO - Open directory for files ... 04:01:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:01:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:01:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:01:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:01:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:01:45 INFO - (blobuploader) - INFO - Done attempting. 04:01:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:01:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:01:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:01:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:01:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:01:46 INFO - (blobuploader) - INFO - Done attempting. 04:01:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:01:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:01:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:01:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:01:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:01:50 INFO - (blobuploader) - INFO - Done attempting. 04:01:50 INFO - (blobuploader) - INFO - Iteration through files over. 04:01:50 INFO - Return code: 0 04:01:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:01:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:01:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4ad5c2d0065f566ef3ff7e9b52a4b5aebd0c18b21a7bca38babe967086255c43f70af089eeabe8dc6688dac5b5e5dd9a82ffbe40f179ad76b5a15e7b2b5d13e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/552e5b6c57b510f91a4b47da51b7723a5c868de157eb952f7dda58c8d36601bbbbd1a3be334c3de7744f90f2c3fe01fb838ee29f954818742b78d846fb8a751c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2a05245bb547e6f257ef82487b1f4385f5a371760f9b4bf606c55be249ea386713ff40a4169820124ddb1fd9b1801062c2e441538585cc296a3b2b932b90ea09"} 04:01:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:01:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:01:50 INFO - Contents: 04:01:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4ad5c2d0065f566ef3ff7e9b52a4b5aebd0c18b21a7bca38babe967086255c43f70af089eeabe8dc6688dac5b5e5dd9a82ffbe40f179ad76b5a15e7b2b5d13e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/552e5b6c57b510f91a4b47da51b7723a5c868de157eb952f7dda58c8d36601bbbbd1a3be334c3de7744f90f2c3fe01fb838ee29f954818742b78d846fb8a751c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2a05245bb547e6f257ef82487b1f4385f5a371760f9b4bf606c55be249ea386713ff40a4169820124ddb1fd9b1801062c2e441538585cc296a3b2b932b90ea09"} 04:01:50 INFO - Running post-run listener: copy_logs_to_upload_dir 04:01:50 INFO - Copying logs to upload dir... 04:01:50 INFO - mkdir: /builds/slave/test/build/upload/logs 04:01:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.287307 ========= master_lag: 10.64 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-11 04:01:59.784576) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 04:01:59.787956) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821 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/4ad5c2d0065f566ef3ff7e9b52a4b5aebd0c18b21a7bca38babe967086255c43f70af089eeabe8dc6688dac5b5e5dd9a82ffbe40f179ad76b5a15e7b2b5d13e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/552e5b6c57b510f91a4b47da51b7723a5c868de157eb952f7dda58c8d36601bbbbd1a3be334c3de7744f90f2c3fe01fb838ee29f954818742b78d846fb8a751c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2a05245bb547e6f257ef82487b1f4385f5a371760f9b4bf606c55be249ea386713ff40a4169820124ddb1fd9b1801062c2e441538585cc296a3b2b932b90ea09"} build_url:https://queue.taskcluster.net/v1/task/CjHQrGRtTg66c0jqMiFPNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010352 build_url: 'https://queue.taskcluster.net/v1/task/CjHQrGRtTg66c0jqMiFPNg/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/4ad5c2d0065f566ef3ff7e9b52a4b5aebd0c18b21a7bca38babe967086255c43f70af089eeabe8dc6688dac5b5e5dd9a82ffbe40f179ad76b5a15e7b2b5d13e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/552e5b6c57b510f91a4b47da51b7723a5c868de157eb952f7dda58c8d36601bbbbd1a3be334c3de7744f90f2c3fe01fb838ee29f954818742b78d846fb8a751c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2a05245bb547e6f257ef82487b1f4385f5a371760f9b4bf606c55be249ea386713ff40a4169820124ddb1fd9b1801062c2e441538585cc296a3b2b932b90ea09"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 04:01:59.850074) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 04:01:59.850391) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1468233665.536166-1980444821 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004445 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 04:01:59.899435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 04:01:59.899759) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 04:01:59.900080) ========= ========= Total master_lag: 10.80 =========