builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-063 starttime: 1468758194.37 results: success (0) buildid: 20160717023020 builduid: 801c31bf1a9244e7a44a6698d076107e revision: e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.375320) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.375814) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.376286) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.438711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.439021) ========= 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=33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-17 05:23:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 28.6M=0s 2016-07-17 05:23:14 (28.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.238865 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.719134) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.719446) ========= 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=33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.167410 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.896959) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 05:23:14.897303) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 --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 e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 --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=33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-17 05:23:14,951 truncating revision to first 12 chars 2016-07-17 05:23:14,951 Setting DEBUG logging. 2016-07-17 05:23:14,951 attempt 1/10 2016-07-17 05:23:14,951 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e7a27a7538b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-17 05:23:15,876 unpacking tar archive at: fx-team-e7a27a7538b2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.360083 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-17 05:23:16.271231) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:16.271565) ========= script_repo_revision: e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:16.272008) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:16.272319) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:16.281764) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2016-07-17 05:23:16.282098) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:23:16 INFO - MultiFileLogger online at 20160717 05:23:16 in /builds/slave/test 05:23:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:23:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:23:16 INFO - {'append_to_log': False, 05:23:16 INFO - 'base_work_dir': '/builds/slave/test', 05:23:16 INFO - 'blob_upload_branch': 'Fx-Team', 05:23:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:23:16 INFO - 'branch': 'Fx-Team', 05:23:16 INFO - 'buildbot_json_path': 'buildprops.json', 05:23:16 INFO - 'config_files': ('talos/linux_config.py',), 05:23:16 INFO - 'default_actions': ('clobber', 05:23:16 INFO - 'read-buildbot-config', 05:23:16 INFO - 'download-and-extract', 05:23:16 INFO - 'populate-webroot', 05:23:16 INFO - 'create-virtualenv', 05:23:16 INFO - 'install', 05:23:16 INFO - 'run-tests'), 05:23:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:23:16 INFO - 'download_minidump_stackwalk': True, 05:23:16 INFO - 'download_symbols': 'ondemand', 05:23:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:23:16 INFO - 'tooltool.py': '/tools/tooltool.py', 05:23:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:23:16 INFO - '/tools/misc-python/virtualenv.py')}, 05:23:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:23:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:23:16 INFO - 'installer_path': 'installer.exe', 05:23:16 INFO - 'log_level': 'info', 05:23:16 INFO - 'log_name': 'talos', 05:23:16 INFO - 'log_to_console': True, 05:23:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:23:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:23:16 INFO - 'opt_config_files': (), 05:23:16 INFO - 'pip_index': False, 05:23:16 INFO - 'sps_profile': False, 05:23:16 INFO - 'sps_profile_interval': 0, 05:23:16 INFO - 'suite': 'dromaeojs-e10s', 05:23:16 INFO - 'system_bits': '32', 05:23:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:23:16 INFO - 'title': 'talos-linux64-ix-063', 05:23:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:23:16 INFO - 'use_talos_json': True, 05:23:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:23:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:23:16 INFO - 'work_dir': 'build'} 05:23:16 INFO - ##### 05:23:16 INFO - ##### Running clobber step. 05:23:16 INFO - ##### 05:23:16 INFO - Running pre-action listener: _resource_record_pre_action 05:23:16 INFO - Running main action method: clobber 05:23:16 INFO - rmtree: /builds/slave/test/build 05:23:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:23:20 INFO - Running post-action listener: _resource_record_post_action 05:23:20 INFO - ##### 05:23:20 INFO - ##### Finished clobber step (success) 05:23:20 INFO - ##### 05:23:20 INFO - ##### 05:23:20 INFO - ##### Running read-buildbot-config step. 05:23:20 INFO - ##### 05:23:20 INFO - Running pre-action listener: _resource_record_pre_action 05:23:20 INFO - Running main action method: read_buildbot_config 05:23:20 INFO - Using buildbot properties: 05:23:20 INFO - { 05:23:20 INFO - "project": "", 05:23:20 INFO - "product": "firefox", 05:23:20 INFO - "script_repo_revision": "production", 05:23:20 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 05:23:20 INFO - "repository": "", 05:23:20 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 05:23:20 INFO - "buildid": "20160717023020", 05:23:20 INFO - "pgo_build": "True", 05:23:20 INFO - "basedir": "/builds/slave/test", 05:23:20 INFO - "buildnumber": 626, 05:23:20 INFO - "slavename": "talos-linux64-ix-063", 05:23:20 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:23:20 INFO - "platform": "ubuntu64_hw", 05:23:20 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:23:20 INFO - "branch": "fx-team", 05:23:20 INFO - "repo_path": "integration/fx-team", 05:23:20 INFO - "revision": "e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8", 05:23:20 INFO - "stage_platform": "linux64-pgo", 05:23:20 INFO - "builduid": "801c31bf1a9244e7a44a6698d076107e", 05:23:20 INFO - "slavebuilddir": "test" 05:23:20 INFO - } 05:23:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:23:20 INFO - Running post-action listener: _resource_record_post_action 05:23:20 INFO - ##### 05:23:20 INFO - ##### Finished read-buildbot-config step (success) 05:23:20 INFO - ##### 05:23:20 INFO - ##### 05:23:20 INFO - ##### Running download-and-extract step. 05:23:20 INFO - ##### 05:23:20 INFO - Running pre-action listener: _resource_record_pre_action 05:23:20 INFO - Running main action method: download_and_extract 05:23:20 INFO - mkdir: /builds/slave/test/build/tests 05:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:20 INFO - https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:23:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:23:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:23:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:23:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:23:21 INFO - Downloaded 1361 bytes. 05:23:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:23:21 INFO - Using the following test package requirements: 05:23:21 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:23:21 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:23:21 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:23:21 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:21 INFO - u'jsshell-linux-x86_64.zip'], 05:23:21 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:23:21 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:23:21 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:23:21 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:23:21 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:23:21 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:23:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:21 INFO - https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:23:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:23:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:23:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:23:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:23:22 INFO - Downloaded 24333242 bytes. 05:23:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:23:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:23:23 INFO - Return code: 0 05:23:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:23 INFO - https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:23:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:23:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:23:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:23:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:23:27 INFO - Downloaded 24333242 bytes. 05:23:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:23:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:23:41 INFO - Return code: 0 05:23:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:41 INFO - https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:23:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:23:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:23:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:23:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:23:42 INFO - Downloaded 14403317 bytes. 05:23:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:23:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:23:42 INFO - Return code: 0 05:23: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')]} 05:23:42 INFO - https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:23:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:23:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:23:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:23:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:23:43 INFO - Downloaded 60814853 bytes. 05:23:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:23:43 INFO - mkdir: /builds/slave/test/properties 05:23:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:23:43 INFO - Writing to file /builds/slave/test/properties/build_url 05:23:43 INFO - Contents: 05:23:43 INFO - build_url:https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:23:44 INFO - Running post-action listener: _resource_record_post_action 05:23:44 INFO - Running post-action listener: set_extra_try_arguments 05:23:44 INFO - ##### 05:23:44 INFO - ##### Finished download-and-extract step (success) 05:23:44 INFO - ##### 05:23:44 INFO - ##### 05:23:44 INFO - ##### Running populate-webroot step. 05:23:44 INFO - ##### 05:23:44 INFO - Running pre-action listener: _resource_record_pre_action 05:23:44 INFO - Running main action method: populate_webroot 05:23:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:23:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:23:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:23:44 INFO - '--tppagecycles', 05:23:44 INFO - '7'], 05:23:44 INFO - 'tests': ['tsvgm']}}, 05:23:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:23:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:23:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:23:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:23:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:23:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:23:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:44 INFO - 'talos_options': ['--disable-e10s'], 05:23:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:44 INFO - 'talos_options': ['--disable-e10s'], 05:23:44 INFO - 'tests': ['damp', 'tps']}, 05:23:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:44 INFO - 'tests': ['damp', 'tps']}, 05:23:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:23:44 INFO - 'tests': ['dromaeo_dom']}, 05:23:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:23:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:23:44 INFO - 'tests': ['basic_compositor_video']}, 05:23:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:23:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:23:44 INFO - 'tests': ['a11yr', 05:23:44 INFO - 'ts_paint', 05:23:44 INFO - 'tpaint', 05:23:44 INFO - 'sessionrestore', 05:23:44 INFO - 'sessionrestore_no_auto_restore', 05:23:44 INFO - 'tabpaint']}, 05:23:44 INFO - 'other-e10s': {'tests': ['a11yr', 05:23:44 INFO - 'ts_paint', 05:23:44 INFO - 'tpaint', 05:23:44 INFO - 'sessionrestore', 05:23:44 INFO - 'sessionrestore_no_auto_restore', 05:23:44 INFO - 'tabpaint']}, 05:23:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:23:44 INFO - 'ts_paint', 05:23:44 INFO - 'tpaint', 05:23:44 INFO - 'sessionrestore', 05:23:44 INFO - 'sessionrestore_no_auto_restore', 05:23:44 INFO - 'tabpaint']}, 05:23:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:23:44 INFO - 'ts_paint', 05:23:44 INFO - 'tpaint', 05:23:44 INFO - 'sessionrestore', 05:23:44 INFO - 'sessionrestore_no_auto_restore', 05:23:44 INFO - 'tabpaint']}, 05:23:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:23:44 INFO - 'tests': ['a11yr', 05:23:44 INFO - 'ts_paint', 05:23:44 INFO - 'tpaint', 05:23:44 INFO - 'sessionrestore', 05:23:44 INFO - 'sessionrestore_no_auto_restore', 05:23:44 INFO - 'tabpaint']}, 05:23:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:23:44 INFO - 'tests': ['a11yr', 05:23:44 INFO - 'ts_paint', 05:23:44 INFO - 'tpaint', 05:23:44 INFO - 'sessionrestore', 05:23:44 INFO - 'sessionrestore_no_auto_restore', 05:23:44 INFO - 'tabpaint']}, 05:23:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:23:44 INFO - 'tests': ['tsvgx', 05:23:44 INFO - 'tsvgr_opacity', 05:23:44 INFO - 'tart', 05:23:44 INFO - 'tscrollx', 05:23:44 INFO - 'cart']}, 05:23:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:23:44 INFO - 'tsvgr_opacity', 05:23:44 INFO - 'tart', 05:23:44 INFO - 'tscrollx', 05:23:44 INFO - 'cart']}, 05:23:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:44 INFO - 'talos_options': ['--disable-e10s'], 05:23:44 INFO - 'tests': ['tp5o']}, 05:23:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:44 INFO - 'tests': ['tp5o']}, 05:23:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:44 INFO - 'talos_options': ['--disable-e10s', 05:23:44 INFO - '--xperf_path', 05:23:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:44 INFO - 'tests': ['tp5n']}, 05:23:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:44 INFO - 'talos_options': ['--xperf_path', 05:23:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:44 INFO - 'tests': ['tp5n']}}, 05:23:44 INFO - 'talos.zip': {'path': '', 05:23:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:23:44 INFO - Running post-action listener: _resource_record_post_action 05:23:44 INFO - ##### 05:23:44 INFO - ##### Finished populate-webroot step (success) 05:23:44 INFO - ##### 05:23:44 INFO - ##### 05:23:44 INFO - ##### Running create-virtualenv step. 05:23:44 INFO - ##### 05:23:44 INFO - Running pre-action listener: _resource_record_pre_action 05:23:44 INFO - Running main action method: create_virtualenv 05:23:44 INFO - Creating virtualenv /builds/slave/test/build/venv 05:23:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:23:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:23:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:23:58 INFO - Using real prefix '/usr' 05:23:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:24:06 INFO - Installing distribute.............................................................................................................................................................................................done. 05:24:08 INFO - Installing pip.................done. 05:24:08 INFO - Return code: 0 05:24:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:08 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 0x227a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25393b0>, '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 0x253a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253a740>, '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': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:24:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:24:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:24:08 INFO - Using env: {'DISPLAY': ':0', 05:24:08 INFO - 'HOME': '/home/cltbld', 05:24:08 INFO - 'LANG': 'en_US.UTF-8', 05:24:08 INFO - 'LANGUAGE': 'en_US:en', 05:24:08 INFO - 'LOGNAME': 'cltbld', 05:24:08 INFO - 'MAIL': '/var/mail/cltbld', 05:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:08 INFO - 'MOZ_NO_REMOTE': '1', 05:24:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:08 INFO - 'NO_EM_RESTART': '1', 05:24:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:08 INFO - 'PWD': '/builds/slave/test', 05:24:08 INFO - 'SHELL': '/bin/bash', 05:24:08 INFO - 'SHLVL': '1', 05:24:08 INFO - 'TERM': 'linux', 05:24:08 INFO - 'TMOUT': '86400', 05:24:08 INFO - 'USER': 'cltbld', 05:24:08 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 05:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:08 INFO - '_': '/tools/buildbot/bin/python'} 05:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:24:09 INFO - Cleaning up... 05:24:09 INFO - Return code: 0 05:24:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x227a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25393b0>, '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 0x253a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253a740>, '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': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:24:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:24:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:24:09 INFO - Using env: {'DISPLAY': ':0', 05:24:09 INFO - 'HOME': '/home/cltbld', 05:24:09 INFO - 'LANG': 'en_US.UTF-8', 05:24:09 INFO - 'LANGUAGE': 'en_US:en', 05:24:09 INFO - 'LOGNAME': 'cltbld', 05:24:09 INFO - 'MAIL': '/var/mail/cltbld', 05:24:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:09 INFO - 'MOZ_NO_REMOTE': '1', 05:24:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:09 INFO - 'NO_EM_RESTART': '1', 05:24:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:09 INFO - 'PWD': '/builds/slave/test', 05:24:09 INFO - 'SHELL': '/bin/bash', 05:24:09 INFO - 'SHLVL': '1', 05:24:09 INFO - 'TERM': 'linux', 05:24:09 INFO - 'TMOUT': '86400', 05:24:09 INFO - 'USER': 'cltbld', 05:24:09 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 05:24:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:09 INFO - '_': '/tools/buildbot/bin/python'} 05:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:09 INFO - Downloading/unpacking psutil>=3.1.1 05:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:24:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:11 INFO - Installing collected packages: psutil 05:24:11 INFO - Running setup.py install for psutil 05:24:11 INFO - building 'psutil._psutil_linux' extension 05:24:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:24:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:24:13 INFO - building 'psutil._psutil_posix' extension 05:24:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:24:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:24:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:13 INFO - Successfully installed psutil 05:24:13 INFO - Cleaning up... 05:24:13 INFO - Return code: 0 05:24:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:13 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 0x227a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25393b0>, '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 0x253a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253a740>, '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': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:24:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:24:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:24:13 INFO - Using env: {'DISPLAY': ':0', 05:24:13 INFO - 'HOME': '/home/cltbld', 05:24:13 INFO - 'LANG': 'en_US.UTF-8', 05:24:13 INFO - 'LANGUAGE': 'en_US:en', 05:24:13 INFO - 'LOGNAME': 'cltbld', 05:24:13 INFO - 'MAIL': '/var/mail/cltbld', 05:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:13 INFO - 'MOZ_NO_REMOTE': '1', 05:24:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:13 INFO - 'NO_EM_RESTART': '1', 05:24:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:13 INFO - 'PWD': '/builds/slave/test', 05:24:13 INFO - 'SHELL': '/bin/bash', 05:24:13 INFO - 'SHLVL': '1', 05:24:13 INFO - 'TERM': 'linux', 05:24:13 INFO - 'TMOUT': '86400', 05:24:13 INFO - 'USER': 'cltbld', 05:24:13 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 05:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:13 INFO - '_': '/tools/buildbot/bin/python'} 05:24:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:24:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:24:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:24:15 INFO - Installing collected packages: mozsystemmonitor 05:24:15 INFO - Running setup.py install for mozsystemmonitor 05:24:16 INFO - Successfully installed mozsystemmonitor 05:24:16 INFO - Cleaning up... 05:24:16 INFO - Return code: 0 05:24:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:24:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:16 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 0x227a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25393b0>, '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 0x253a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253a740>, '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': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:24:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:24:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:24:16 INFO - Using env: {'DISPLAY': ':0', 05:24:16 INFO - 'HOME': '/home/cltbld', 05:24:16 INFO - 'LANG': 'en_US.UTF-8', 05:24:16 INFO - 'LANGUAGE': 'en_US:en', 05:24:16 INFO - 'LOGNAME': 'cltbld', 05:24:16 INFO - 'MAIL': '/var/mail/cltbld', 05:24:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:16 INFO - 'MOZ_NO_REMOTE': '1', 05:24:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:16 INFO - 'NO_EM_RESTART': '1', 05:24:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:16 INFO - 'PWD': '/builds/slave/test', 05:24:16 INFO - 'SHELL': '/bin/bash', 05:24:16 INFO - 'SHLVL': '1', 05:24:16 INFO - 'TERM': 'linux', 05:24:16 INFO - 'TMOUT': '86400', 05:24:16 INFO - 'USER': 'cltbld', 05:24:16 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 05:24:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:16 INFO - '_': '/tools/buildbot/bin/python'} 05:24:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:16 INFO - Downloading/unpacking jsonschema==2.5.1 05:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:24:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:18 INFO - Downloading functools32-3.2.3-2.tar.gz 05:24:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:24:18 INFO - warning: no files found matching '*.txt' 05:24:18 INFO - no previously-included directories found matching 'build' 05:24:18 INFO - no previously-included directories found matching 'dist' 05:24:18 INFO - no previously-included directories found matching '.git*' 05:24:18 INFO - Installing collected packages: jsonschema, functools32 05:24:18 INFO - Running setup.py install for functools32 05:24:18 INFO - warning: no files found matching '*.txt' 05:24:18 INFO - no previously-included directories found matching 'build' 05:24:18 INFO - no previously-included directories found matching 'dist' 05:24:18 INFO - no previously-included directories found matching '.git*' 05:24:18 INFO - Successfully installed jsonschema functools32 05:24:18 INFO - Cleaning up... 05:24:18 INFO - Return code: 0 05:24:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:18 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 0x227a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25393b0>, '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 0x253a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253a740>, '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': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:24:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:24:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:24:18 INFO - Using env: {'DISPLAY': ':0', 05:24:18 INFO - 'HOME': '/home/cltbld', 05:24:18 INFO - 'LANG': 'en_US.UTF-8', 05:24:18 INFO - 'LANGUAGE': 'en_US:en', 05:24:18 INFO - 'LOGNAME': 'cltbld', 05:24:18 INFO - 'MAIL': '/var/mail/cltbld', 05:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:18 INFO - 'MOZ_NO_REMOTE': '1', 05:24:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:18 INFO - 'NO_EM_RESTART': '1', 05:24:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:18 INFO - 'PWD': '/builds/slave/test', 05:24:18 INFO - 'SHELL': '/bin/bash', 05:24:18 INFO - 'SHLVL': '1', 05:24:18 INFO - 'TERM': 'linux', 05:24:18 INFO - 'TMOUT': '86400', 05:24:18 INFO - 'USER': 'cltbld', 05:24:18 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 05:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:18 INFO - '_': '/tools/buildbot/bin/python'} 05:24:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:18 INFO - Downloading/unpacking blobuploader==1.2.4 05:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:20 INFO - Downloading blobuploader-1.2.4.tar.gz 05:24:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:24:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:24:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:21 INFO - Downloading docopt-0.6.1.tar.gz 05:24:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:24:21 INFO - Installing collected packages: blobuploader, requests, docopt 05:24:21 INFO - Running setup.py install for blobuploader 05:24:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:24:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:24:21 INFO - Running setup.py install for requests 05:24:22 INFO - Running setup.py install for docopt 05:24:22 INFO - Successfully installed blobuploader requests docopt 05:24:22 INFO - Cleaning up... 05:24:22 INFO - Return code: 0 05:24:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:22 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 0x227a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25393b0>, '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 0x253a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253a740>, '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': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:24:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:24:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:24:22 INFO - Using env: {'DISPLAY': ':0', 05:24:22 INFO - 'HOME': '/home/cltbld', 05:24:22 INFO - 'LANG': 'en_US.UTF-8', 05:24:22 INFO - 'LANGUAGE': 'en_US:en', 05:24:22 INFO - 'LOGNAME': 'cltbld', 05:24:22 INFO - 'MAIL': '/var/mail/cltbld', 05:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:22 INFO - 'MOZ_NO_REMOTE': '1', 05:24:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:22 INFO - 'NO_EM_RESTART': '1', 05:24:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:22 INFO - 'PWD': '/builds/slave/test', 05:24:22 INFO - 'SHELL': '/bin/bash', 05:24:22 INFO - 'SHLVL': '1', 05:24:22 INFO - 'TERM': 'linux', 05:24:22 INFO - 'TMOUT': '86400', 05:24:22 INFO - 'USER': 'cltbld', 05:24:22 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 05:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:22 INFO - '_': '/tools/buildbot/bin/python'} 05:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:24:22 INFO - Running setup.py (path:/tmp/pip-SAN6pF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:24:22 INFO - Running setup.py (path:/tmp/pip-9Us_oq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:24:22 INFO - Running setup.py (path:/tmp/pip-kzz6CJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:24:22 INFO - Running setup.py (path:/tmp/pip-RU7N1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:24:22 INFO - Running setup.py (path:/tmp/pip-XlNRt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:24:22 INFO - Running setup.py (path:/tmp/pip-eOXhJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:24:22 INFO - Running setup.py (path:/tmp/pip-bnwzHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:24:22 INFO - Running setup.py (path:/tmp/pip-d6Zjx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:24:23 INFO - Running setup.py (path:/tmp/pip-XwVFbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:24:23 INFO - Running setup.py (path:/tmp/pip-M93v9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:24:23 INFO - Running setup.py (path:/tmp/pip-oaC0ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:24:23 INFO - Running setup.py (path:/tmp/pip-rdQVw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:24:23 INFO - Running setup.py (path:/tmp/pip-YNaupy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:24:23 INFO - Running setup.py (path:/tmp/pip-DU43MX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:23 INFO - Running setup.py (path:/tmp/pip-t9RuBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:24:23 INFO - Running setup.py (path:/tmp/pip-aZKJhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:24:23 INFO - Running setup.py (path:/tmp/pip-pA5H7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:24:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:24:23 INFO - Running setup.py install for manifestparser 05:24:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:24:23 INFO - Running setup.py install for mozcrash 05:24:23 INFO - Running setup.py install for mozdebug 05:24:23 INFO - Running setup.py install for mozdevice 05:24:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for mozfile 05:24:24 INFO - Running setup.py install for mozhttpd 05:24:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for mozinfo 05:24:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for mozInstall 05:24:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for mozleak 05:24:24 INFO - Running setup.py install for mozlog 05:24:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for moznetwork 05:24:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Running setup.py install for mozprocess 05:24:24 INFO - Running setup.py install for mozprofile 05:24:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:24:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:24:25 INFO - Running setup.py install for mozrunner 05:24:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:24:25 INFO - Running setup.py install for mozscreenshot 05:24:25 INFO - Running setup.py install for moztest 05:24:25 INFO - Running setup.py install for mozversion 05:24:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:24:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:24:25 INFO - Cleaning up... 05:24:25 INFO - Return code: 0 05:24:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:25 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 0x227a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25393b0>, '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 0x253a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253a740>, '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': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:24:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:24:25 INFO - Using env: {'DISPLAY': ':0', 05:24:25 INFO - 'HOME': '/home/cltbld', 05:24:25 INFO - 'LANG': 'en_US.UTF-8', 05:24:25 INFO - 'LANGUAGE': 'en_US:en', 05:24:25 INFO - 'LOGNAME': 'cltbld', 05:24:25 INFO - 'MAIL': '/var/mail/cltbld', 05:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:25 INFO - 'MOZ_NO_REMOTE': '1', 05:24:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:25 INFO - 'NO_EM_RESTART': '1', 05:24:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:25 INFO - 'PWD': '/builds/slave/test', 05:24:25 INFO - 'SHELL': '/bin/bash', 05:24:25 INFO - 'SHLVL': '1', 05:24:25 INFO - 'TERM': 'linux', 05:24:25 INFO - 'TMOUT': '86400', 05:24:25 INFO - 'USER': 'cltbld', 05:24:25 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 05:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:25 INFO - '_': '/tools/buildbot/bin/python'} 05:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:24:25 INFO - Running setup.py (path:/tmp/pip-V2W3hR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:24:25 INFO - Running setup.py (path:/tmp/pip-Lth_LI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:24:25 INFO - Running setup.py (path:/tmp/pip-mj_kKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:24:25 INFO - Running setup.py (path:/tmp/pip-XNOleC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:24:25 INFO - Running setup.py (path:/tmp/pip-2znB_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:24:26 INFO - Running setup.py (path:/tmp/pip-f2rcT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:24:26 INFO - Running setup.py (path:/tmp/pip-tOg4qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:24:26 INFO - Running setup.py (path:/tmp/pip-mH0V0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:24:26 INFO - Running setup.py (path:/tmp/pip-7TWDrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:24:26 INFO - Running setup.py (path:/tmp/pip-Pj_GIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:24:26 INFO - Running setup.py (path:/tmp/pip-xu7Mng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:24:26 INFO - Running setup.py (path:/tmp/pip-Cyv2oI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:24:26 INFO - Running setup.py (path:/tmp/pip-EcWWty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:24:26 INFO - Running setup.py (path:/tmp/pip-utiyqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:26 INFO - Running setup.py (path:/tmp/pip-TxQZxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:24:26 INFO - Running setup.py (path:/tmp/pip-_ULT8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:24:26 INFO - Running setup.py (path:/tmp/pip-crDpsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:24:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:28 INFO - Downloading blessings-1.6.tar.gz 05:24:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:24:28 INFO - Installing collected packages: blessings 05:24:28 INFO - Running setup.py install for blessings 05:24:29 INFO - Successfully installed blessings 05:24:29 INFO - Cleaning up... 05:24:29 INFO - Return code: 0 05:24:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:24:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:29 INFO - Reading from file tmpfile_stdout 05:24:29 INFO - Current package versions: 05:24:29 INFO - argparse == 1.2.1 05:24:29 INFO - blessings == 1.6 05:24:29 INFO - blobuploader == 1.2.4 05:24:29 INFO - docopt == 0.6.1 05:24:29 INFO - functools32 == 3.2.3-2 05:24:29 INFO - jsonschema == 2.5.1 05:24:29 INFO - manifestparser == 1.1 05:24:29 INFO - mozInstall == 1.12 05:24:29 INFO - mozcrash == 0.17 05:24:29 INFO - mozdebug == 0.1 05:24:29 INFO - mozdevice == 0.48 05:24:29 INFO - mozfile == 1.2 05:24:29 INFO - mozhttpd == 0.7 05:24:29 INFO - mozinfo == 0.9 05:24:29 INFO - mozleak == 0.1 05:24:29 INFO - mozlog == 3.2 05:24:29 INFO - moznetwork == 0.27 05:24:29 INFO - mozprocess == 0.23 05:24:29 INFO - mozprofile == 0.28 05:24:29 INFO - mozrunner == 6.12 05:24:29 INFO - mozscreenshot == 0.1 05:24:29 INFO - mozsystemmonitor == 0.3 05:24:29 INFO - moztest == 0.7 05:24:29 INFO - mozversion == 1.4 05:24:29 INFO - psutil == 3.1.1 05:24:29 INFO - requests == 1.2.3 05:24:29 INFO - wsgiref == 0.1.2 05:24:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:29 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 0x227a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25393b0>, '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 0x253a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253a740>, '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': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:24:29 INFO - Using env: {'DISPLAY': ':0', 05:24:29 INFO - 'HOME': '/home/cltbld', 05:24:29 INFO - 'LANG': 'en_US.UTF-8', 05:24:29 INFO - 'LANGUAGE': 'en_US:en', 05:24:29 INFO - 'LOGNAME': 'cltbld', 05:24:29 INFO - 'MAIL': '/var/mail/cltbld', 05:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:29 INFO - 'MOZ_NO_REMOTE': '1', 05:24:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:29 INFO - 'NO_EM_RESTART': '1', 05:24:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:29 INFO - 'PWD': '/builds/slave/test', 05:24:29 INFO - 'SHELL': '/bin/bash', 05:24:29 INFO - 'SHLVL': '1', 05:24:29 INFO - 'TERM': 'linux', 05:24:29 INFO - 'TMOUT': '86400', 05:24:29 INFO - 'USER': 'cltbld', 05:24:29 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 05:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:29 INFO - '_': '/tools/buildbot/bin/python'} 05:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:24:29 INFO - Cleaning up... 05:24:29 INFO - Return code: 0 05:24:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x227a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23dd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x253b180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25393b0>, '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 0x253a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253a740>, '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': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:24: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'] in /builds/slave/test/build 05:24: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 05:24:29 INFO - Using env: {'DISPLAY': ':0', 05:24:29 INFO - 'HOME': '/home/cltbld', 05:24:29 INFO - 'LANG': 'en_US.UTF-8', 05:24:29 INFO - 'LANGUAGE': 'en_US:en', 05:24:29 INFO - 'LOGNAME': 'cltbld', 05:24:29 INFO - 'MAIL': '/var/mail/cltbld', 05:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:29 INFO - 'MOZ_NO_REMOTE': '1', 05:24:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:29 INFO - 'NO_EM_RESTART': '1', 05:24:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:29 INFO - 'PWD': '/builds/slave/test', 05:24:29 INFO - 'SHELL': '/bin/bash', 05:24:29 INFO - 'SHLVL': '1', 05:24:29 INFO - 'TERM': 'linux', 05:24:29 INFO - 'TMOUT': '86400', 05:24:29 INFO - 'USER': 'cltbld', 05:24:29 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 05:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:29 INFO - '_': '/tools/buildbot/bin/python'} 05:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:24:29 INFO - Cleaning up... 05:24:29 INFO - Return code: 0 05:24:29 INFO - Running post-action listener: _resource_record_post_action 05:24:29 INFO - Running post-action listener: _start_resource_monitoring 05:24:29 INFO - Starting resource monitoring. 05:24:29 INFO - ##### 05:24:29 INFO - ##### Finished create-virtualenv step (success) 05:24:29 INFO - ##### 05:24:29 INFO - ##### 05:24:29 INFO - ##### Running install step. 05:24:29 INFO - ##### 05:24:29 INFO - Running pre-action listener: _resource_record_pre_action 05:24:29 INFO - Running main action method: install 05:24:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:29 INFO - Reading from file tmpfile_stdout 05:24:29 INFO - Detecting whether we're running mozinstall >=1.0... 05:24:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:24:30 INFO - Reading from file tmpfile_stdout 05:24:30 INFO - Output received: 05:24:30 INFO - Usage: mozinstall [options] installer 05:24:30 INFO - Options: 05:24:30 INFO - -h, --help show this help message and exit 05:24:30 INFO - -d DEST, --destination=DEST 05:24:30 INFO - Directory to install application into. [default: 05:24:30 INFO - "/builds/slave/test"] 05:24:30 INFO - --app=APP Application being installed. [default: firefox] 05:24:30 INFO - mkdir: /builds/slave/test/build/application 05:24:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:24:47 INFO - Reading from file tmpfile_stdout 05:24:47 INFO - Output received: 05:24:47 INFO - /builds/slave/test/build/application/firefox/firefox 05:24:47 INFO - Running post-action listener: _resource_record_post_action 05:24:47 INFO - ##### 05:24:47 INFO - ##### Finished install step (success) 05:24:47 INFO - ##### 05:24:47 INFO - ##### 05:24:47 INFO - ##### Running run-tests step. 05:24:47 INFO - ##### 05:24:47 INFO - Running pre-action listener: _resource_record_pre_action 05:24:47 INFO - Running main action method: run_tests 05:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:24:47 INFO - Python 2.7.3 05:24:47 INFO - Return code: 0 05:24:47 INFO - grabbing minidump binary from tooltool 05:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:47 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 0x25393b0>, '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 0x253a2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x253a740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:24:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:24:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:24:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:24:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:24:47 INFO - Return code: 0 05:24:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:24:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:24:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:24:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:24:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-063 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:24:47 INFO - Using env: {'DISPLAY': ':0', 05:24:47 INFO - 'HOME': '/home/cltbld', 05:24:47 INFO - 'LANG': 'en_US.UTF-8', 05:24:47 INFO - 'LANGUAGE': 'en_US:en', 05:24:47 INFO - 'LOGNAME': 'cltbld', 05:24:47 INFO - 'MAIL': '/var/mail/cltbld', 05:24:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:24:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:47 INFO - 'MOZ_NO_REMOTE': '1', 05:24:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:24:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:47 INFO - 'NO_EM_RESTART': '1', 05:24:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:47 INFO - 'PWD': '/builds/slave/test', 05:24:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:24:47 INFO - 'SHELL': '/bin/bash', 05:24:47 INFO - 'SHLVL': '1', 05:24:47 INFO - 'TERM': 'linux', 05:24:47 INFO - 'TMOUT': '86400', 05:24:47 INFO - 'USER': 'cltbld', 05:24:47 INFO - 'XDG_SESSION_COOKIE': '33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259', 05:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:47 INFO - '_': '/tools/buildbot/bin/python'} 05:24:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:24:47 INFO - mozversion application_buildid: 20160717023020 05:24:47 INFO - mozversion application_changeset: e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 05:24:47 INFO - mozversion application_display_name: Nightly 05:24:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:24:47 INFO - mozversion application_name: Firefox 05:24:47 INFO - mozversion application_remotingname: firefox 05:24:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:24:47 INFO - mozversion application_vendor: Mozilla 05:24:47 INFO - mozversion application_version: 50.0a1 05:24:47 INFO - mozversion platform_buildid: 20160717023020 05:24:47 INFO - mozversion platform_changeset: e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 05:24:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:24:47 INFO - mozversion platform_version: 50.0a1 05:24:47 INFO - using testdate: 1468758287 05:24:47 INFO - actual date: 1468758287 05:24:47 INFO - starting webserver on 'localhost:38700' 05:24:47 INFO - SUITE-START | Running 2 tests 05:24:47 INFO - TEST-START | dromaeo_css 05:24:47 INFO - operating with platform_type : linux_ 05:24:47 INFO - Initialising browser for dromaeo_css test... 05:24:47 INFO - TEST-INFO | started process 3292 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGwLxzw/profile http://localhost:38700/getInfo.html) 05:25:08 INFO - PROCESS | 3292 | JavaScript error: , line 0: uncaught exception: undefined 05:25:10 INFO - PROCESS | 3292 | __metrics Screen width/height:1600/1200 05:25:10 INFO - PROCESS | 3292 | colorDepth:24 05:25:10 INFO - PROCESS | 3292 | Browser inner width/height: 1024/697 05:25:10 INFO - PROCESS | 3292 | __metrics 05:25:11 ERROR - PROCESS | 3292 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:25:12 INFO - TEST-INFO | 3292: exit 0 05:25:12 INFO - Browser initialized. 05:25:12 INFO - Running cycle 1/1 for dromaeo_css test... 05:25:12 INFO - TEST-INFO | started process 3530 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGwLxzw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:24 INFO - PROCESS | 3530 | RSS: Main: 168345600 05:25:24 INFO - PROCESS | 3530 | 05:26:14 INFO - PROCESS | 3530 | 1468758374031 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:28:21 INFO - PROCESS | 3530 | Cycle 1(1): loaded http://localhost:38700/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38700/tests/dromaeo/cssquery-ext.html) 05:28:22 INFO - PROCESS | 3530 | RSS: Main: 172187648 05:28:22 INFO - PROCESS | 3530 | 05:31:24 INFO - PROCESS | 3530 | Cycle 1(1): loaded http://localhost:38700/tests/dromaeo/cssquery-ext.html (next: http://localhost:38700/tests/dromaeo/cssquery-jquery.html) 05:31:25 INFO - PROCESS | 3530 | RSS: Main: 169541632 05:31:25 INFO - PROCESS | 3530 | 05:34:27 INFO - PROCESS | 3530 | Cycle 1(1): loaded http://localhost:38700/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38700/tests/dromaeo/cssquery-mootools.html) 05:34:28 INFO - PROCESS | 3530 | RSS: Main: 173182976 05:34:28 INFO - PROCESS | 3530 | 05:37:29 INFO - PROCESS | 3530 | Cycle 1(1): loaded http://localhost:38700/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38700/tests/dromaeo/cssquery-prototype.html) 05:37:30 INFO - PROCESS | 3530 | RSS: Main: 167288832 05:37:30 INFO - PROCESS | 3530 | 05:40:32 INFO - PROCESS | 3530 | Cycle 1(1): loaded http://localhost:38700/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38700/tests/dromaeo/cssquery-yui.html) 05:40:33 INFO - PROCESS | 3530 | RSS: Main: 169533440 05:40:33 INFO - PROCESS | 3530 | 05:43:35 INFO - PROCESS | 3530 | Cycle 1(1): loaded http://localhost:38700/tests/dromaeo/cssquery-yui.html (next: http://localhost:38700/tests/dromaeo/cssquery-dojo.html) 05:43:36 INFO - PROCESS | 3530 | RSS: Main: 172568576 05:43:36 INFO - PROCESS | 3530 | 05:43:36 INFO - PROCESS | 3530 | __start_tp_report 05:43:36 INFO - PROCESS | 3530 | _x_x_mozilla_page_load 05:43:36 INFO - PROCESS | 3530 | _x_x_mozilla_page_load_details 05:43:36 INFO - PROCESS | 3530 | |i|pagename|runs| 05:43:36 INFO - PROCESS | 3530 | |0;dojo.html;3062.754979601632;3075.2158199659084;3089.860956256968;3095.4428202923473;3100.1629560018796;5338.62629615927;5383.327084114486;5383.892322153554;5410;5422.1595652673805;5354.357477102743;5447.972760136204;5467.562594992401;5512.80705175319;5529.142982837657;4931.235658472933;4976.328195693589;5061.924071138936;5078.060558796635;5159.871003224912;623.0787492162816;4886.340344053543;4941;4997.575206107467;5078.365204349457;4947.52998465147;4967.577755890772;4970.453250142481;4988.800447982077;4994.87512812179;4997.250412438127;5008;5012.699238045729;5013.022460620181;5037.596992240612;4983;5024.949750502521;5054.039732450822;5058.4435712071645;5080.13637681595;5534.308211473566;5546.362168350651;5562.3047119110115;5579.917889500423;5590.385057643656;5702.45826646468;5735.713214339266;5899.1446240295345;5902.911456328158;5905.911411328833;5782.074868021096;5807.970960145214;5826.446487583673;5835.095560188178;5879.794207202727;5395.568354531628;5406.297181366436;5412.269343638637;5413.350397952231;5413.53984911283;5832.766689332464;5865.384134665798;5906.40935906406;5946.405359464019;5957.195778569924;5671.1493276008105;5673.375928647845;5677.80127695537;5701.486866182064;5705.372409035002;5809.767609295581;5813.6221145625395;5819.5053420459735;5829.271341082364;5846.239988801429;5761.8559536011935;5781.335146458211;5801.50687191585;5809.9419005809405;5823.679697616587;5774.047282198474;5831.067029275296;5839.737211825478;5851.093080572518;5856.180134781134;4337.891552711144;4436.223660859337;4450.310201918708;4464.0402313502755;4497.3254011897825;3866.613338666111;3869.5356557212754;3869.845206191777;3912.941305880414;3922.431247469101;5524.861878453071;5528.419515950767;5590.161475778585;5592.440755924375;5598.720063996784;5753.194552762617;5820.796272130539;5851.531877449794;5857.736401861926;5866.589338746332;5486.341639003265;5534.557235421156;5554.6944918029085;5581.720913954286;5582.106862901997;4987.775550100254;5007.324011258506;5028.76795185182;5032.245163225472;5072.9746351268;5863;5899.056151015817;5935.643861368331;5947.881042379128;5965.970170149121;5745.339285982082;5846.912296315473;5852.853678657897;5855.3559108497175;5857.912131318119;6019.367966363468;6023.5181185505935;6024.3975602439405;6025.337212906665;6031.728572214349;5592.328920529562;5618.803341883015;5623.493885550321;5719.199312096393;5721.313442386941;5700.458456446631;5718.1708652246025;5763.221965034667;5767.250257466502;5816.854578635568;5587.245721827501;5595.356533998643;5606.187102870143;5606.607537472338;5609.915851262316;5491.039068162976;5568.91646625309;5574.219609254788;5607.747651355618;5653.123765816305;5518.117101263779;5556.944430555643;5581.665100094007;5593.972030139823;5596.272484576979;5614.691191984317;5647.1529270609735;5672.319321681424;5692;5711.057675483498;5469.671819690831;5557.888842223214;5588.441155884379;5610.7194640268635;5626.746796394254 05:43:36 INFO - PROCESS | 3530 | |1;ext.html;541.2206422751235;573.8766165274467;592.2981267198369;598.1476396135506;616.2820314333801;42783.57216427835;42817.78591107044;43934.560654393485;44036.779816100876;44070.55929440709;42021.57978420215;42864;42913.35629965553;42973.14053718924;43216;8789.165029322221;8945.821083578325;8972.147645973622;8973.237274831647;8975.147361000694;8648.091950345217;8678.826423471526;8725.607347669371;8726.214640682338;8751.606177722018;8780.648774049063;8784.868226976601;8792.604332805007;8833.602487888029;8836.0722124177;8695.913040869573;8714.869276960851;8737.606807693637;8771.254443372321;8781.868271975925;8801.515916624647;8801.779955501164;8841.69054083104;8880.55597220143;8916.465012099296;42203;42605;42745.786271068755;42830.78584607088;43071.56928430707;3118.6413562440157;3238.3037646906027;3247.447933851251;3466.757326987112;3583.2654305867236;31349.37301253962;32215.83892080548;32363.352732945445;32387.35225295481;32880.67119328824;4125.711200215986;4218.7468751874985;4223.408722778813;4247.575242475758;4259.914801703949;359027;360838;361698;362759;363525;62676;63795.681021594595;63797.681011595574;63907;63930;32019;32402.67597324044;32464.67535324664;32609.184770380456;52864.20703689448;17398.782085254163;17628.1185940705;17634.5591360217;17649.7352539712;17683.115844207994;12655.114142010156;12859.742805143846;12870.485180592672;12924.41840117197;12939.547115851088;20239;20255.594888102154;20278;20470.18119275242;20521;7146;7276;7293.817654558572;7294.197638259787;7548.849023019508;858.2447446247262;926;941.1200527506803;953.952302384878;959.2661613865398;34312.65687343144;34336.65663343335;34352.141196470286;34415.59090863616;34756;971.489967766928;982.7346616413529;994.7761753605525;1006.9647562335283;1088.2545456362259;17160.485185444457;17900.194491247665;17953.102344882704;17976.37082702099;18018.90990545039;580030;584533;584803;586155;588448;62388.37611623917;63623.36376636174;64076.67961660255;64176.358236417975;64226.35773642204;1355.1327150623608;1361.7753070743415;1364.883984861298;1367.4256812138926;1372.8009438631343;31775.523367149974;33627.49558756572;34096.65903340935;34297.44484829216;34415.13962150966;54755.452445475035;54789;54964.72517637386;55483;55564.72217638886;461578;466590;466628;469123;472109;28708;29067.41865162727;29490;29502.704972949996;29512;41549.792251038554;41715.37426938538;42173.367399488416;42549.57450425456;42644.78677606592;41895.7905210472;43078.64450127912;43614;43643.56356436395;43665.34501982536;42718;43028.78485607552;43168.56831431646;43876;44334.55665443433;43467.34798977955;43922.560774393125;44311.33532996943;44420.333694993955;44454.77772611116;35778.28443431169;39491.605083949944;39508;39605.40591891066;39777.40333894936;28990.420191596473;29172.9789457368;30181.245468863453;30324.848375758862;30370.54444183295 05:43:36 INFO - PROCESS | 3530 | |2;jquery.html;396.2036307022883;397.3027337023524;397.4356413892271;401.20761496045316;416.6770752666683;14146;18491;18657.25370985159;20157.697634535492;20166.59666806663;17202.139893005362;17238.138093095356;17550.561235969108;19468.70796938047;19772.40682779519;5611.607187496877;5689.146628005811;5750.079987202049;5755.9424405756035;5800.7679692812235;5587.7485513152005;5679;5696.458836410535;5712.485876271135;5738.684372359518;5541.085720856054;5611.915821262684;5629.155626656014;5647.971760141194;5692.516136128414;5626.662400255977;5652.971735141339;5655.604107712462;5658.151277308396;5664.660120392769;4935.35840340757;5085.847424577269;5207.791688332462;5244.475552444725;5262.789488420459;13388.263645499494;13477;17888.373906913195;22801.77198228013;23077.538449231088;2260;2327.2552783109413;2413.974061024075;2603.778678812303;2607.739226077396;9759.414435133915;10020.298579099466;10168.389896606228;10274.383536987805;10459.163266938627;1918.7026010968323;1927.3254360973688;1941.7766956799867;1943.5141214696325;2012.9496762580907;77390;78370;83793.58103209505;84726;85138.57430712807;10577.524011419504;10903.890961090447;11098.2786118902;11385.14611404148;11426.428678566204;15366.154861482726;15393.615159621098;15395.846041539666;15441.845581544265;15447.150406727513;8369.539675317916;8534.317254619615;8571.614277357534;8648.827023459495;8715.084916083846;8049.154838741965;8098.230668086522;8109;8240.8351832963;8284.171582841667;4368.497622773358;4504.34686970394;4615.730674064659;4620.0990806793025;4734.621230301567;1941.3981665683684;1948.4446932624135;1964.2830366916257;2010.3164923925642;2041.499832541037;883.9116088391238;950.4154944709093;978.5254151736402;999.3554157568328;1015.664830605913;19766;19880;20210.39368818938;20391;20657.793422065886;1010.3735683875897;1030.78381479819;1032.200044965158;1051.942143182117;1065.8347956066823;10700.625478108228;10701.143908487302;10847.294925829796;11049.447527623748;11333.659990200142;463958;465674;477983;487519;491047;93958;95154;97116.51441742746;98030;98557;878.3412440669498;893.718478679214;908.5911339896941;909.8908131024319;942.330945029037;16893.66212675764;17079.914600426917;17495;17614.29542818273;17680.73478897792;31280.21799455032;35831.82084089563;45515.54484455245;46221.79587105585;46494.53505464902;56588.43411565832;57031.14453283287;62020.689896550226;62496.375036249054;62562;8639.524826134622;8715.433496822687;8764.079771623889;8887.111288871061;8900.510471923848;10044.447555384524;12726;12918.095733298578;13099.476020959055;13559.98300127514;11587.420628968686;11602.767944640891;11812.114091443347;11867.169298149047;12267.141300108906;11578.536858525564;11603;11895.881041189477;11896.345700986529;12007.519699211934;11882.940585297018;12015.75968480643;12035.699107522381;12047;12612.747745045232;8531.061583225715;8538.274246689101;8734;8878.600462979051;9050.818983620422;8657.091005444337;10641.733956651145;10772.030517253485;10856.945715271373;11019.724506887393 05:43:36 INFO - PROCESS | 3530 | |3;mootools.html;1389.3470069067537;1469.9412023519055;1605.486244401791;1761.462753860073;1782.732590111484;7928.207179282069;8804.735857924257;8812.295016398688;9483.81032379352;9496.38273512222;8882.911170888288;9122.726318210463;9138.45169289843;9146.86279705805;9155.67955121571;2384.165542060278;2400.8799560022017;2405.302462285935;2408.7832095111435;2420.0561780905373;2418.504206637635;2426.5632186206476;2441.7680320369536;2445.1930862815316;2459.606462965926;2397.856128632279;2430.1858877276136;2444.4377793107597;2452.3256104571205;2456.5455390752677;2356.7525409832065;2368.928932132039;2370.3126093432884;2382.2257766225957;2388.653645221442;2326.174208156097;2338.3452633262714;2347.9882600587057;2349.447879748258;2354.5173239485866;9096.727098187064;9104.68133615327;9109.134632209996;9125.863112053325;9182.17360437564;5422.566194704415;5516.062269414209;5517.144842549411;5521.889562208774;5534.778608855641;3507.8772242971627;3611.566612006576;3615.9096022599383;3650.908727281813;3662.3224703429682;1258.7293731847606;1289.0654275650172;1303.2962200411766;1310.9868901311056;1320.3860205004623;12608.180468269533;12616.873831261753;12635.93682031584;12655.860972512426;12718.491260349574;4384.386185933972;4445.444319460067;4551.340055691906;4620.376249206314;4730.550597693214;9932.354396964174;9975.850362244571;9989;9997.950010249902;10005.099541041196;5375.731213439312;5436.728163591804;5446.264754258124;5465.890682186334;5469.097598896217;4408.9859332353735;4831.396075490564;4929.926051109236;4948.62885283598;5094.566961808214;3726.869559565402;3787.6780473660046;3853.2486165197515;3882.6893848492055;3884.2231553689376;1150.8791576884307;1152.7060599547062;1159.3507635724022;1165.8950694437372;1172.9589464368705;917.2707697380598;988.826955282837;989.3223142147652;1031.1132426113536;1043.4052590023614;6196.442320191204;6208.844778880474;6278.277998030194;6304.369563043659;6334.398232167937;473.0704166313211;477.84947741462025;485.27936015017673;496.75162418790603;497.8133200049981;4247.68142389322;4351.934720979188;4405.559444055569;4424.3142312941545;4433.844815431445;11243.100551955824;11612.593559225556;11677.357745324089;11710.76578468426;11758;11183.720406989727;11397.544098235978;11473.942630286796;11520.94239528797;11567.942160289313;8560.143985601515;8608.569571521399;8616.698415555551;8618.827623447496;8768.342374321952;6122.326544080059;6169.722362493789;6198.473129784019;6213.844653883689;6261.906071408841;10484.580616775243;10625.734356641145;10694.893051069701;10832.18758593093;10857;10606.893931060591;10803.837942431028;10853.674389768004;10897.945510272399;10922.781544369227;6775.390214880685;7190.604516751629;7259.891101633374;7428.4800063995;7657.272559106876;8504.40469167177;8603.268722158688;8690.695825646071;8694.782630434289;8724.91275087241;7568.924310756822;7887.84224315522;8252.422330436812;8270.048944371474;8371.204735550113;7808.648610812414;8075.030996280249;8076.83846323082;8106.189381062082;8188.50868947865;7351.338379545653;7356.485046046725;7423.035005449257;7487.401007919462;7494.100707914867;5253.345196263165;5299.894002119859;5406.080966235828;5518.972405137949;5520.613557050968;5498.862528436821;5514.80698175562;5580.35825880029;5617.8033768817895;5683.772649094155 05:43:36 INFO - PROCESS | 3530 | |4;prototype.html;334.5548017443941;337.7770671356904;344.9689527942485;345.8806711684468;349.6730556929273;5434.429384914586;5454.754536045877;5534.833954981352;5614.242077319561;5709.6574205547695;5584.469475399841;5606.691631960251;5606.906653202627;5622.578306626999;5660.688662123582;4383.671224658156;4414.183376075436;4418.2268103082;4428.6678499112695;4433.157700036999;4343.370211319357;4393.560643935597;4400.845970391037;4431.446069241345;4446.733196008234;4388.495323037843;4394.143806525752;4400.075984043355;4412.933805992912;4424.446944131983;4414.602685758265;4414.631464246062;4418.680192898738;4425.5353187915125;4437.822487100512;3638.890833275006;3834.865779697725;3929.764214147132;3930.410438434229;3944.9211015779524;5425.782968681248;5497.5601951844155;5511.200875873016;5511.311086114236;5524.944750552483;4978.626603004753;4994.675346102492;5016.347874776292;5025.572826309768;5025.673331233458;5613.007382379278;5860.736266867959;5870.295564532284;5877.118432235156;5878.323992740847;4159.334506478979;4159.854405095837;4179.874603761892;4191.664666826677;4203.91592168158;6093.939060609381;6100.755969761204;6103.115048317969;6106.01554494791;6111.541634377395;4174.20690068886;4294.570542945743;4296.958944501399;4306.2894622387585;4332.3068309070395;4329.19909816685;4334.869953901388;4348.108637729258;4361.323994780814;4362.650987920959;4885.560299572984;4976.353074100344;4990.126727822689;5026.74866256693;5044.1172794761505;5833.329167145833;5860.238169037998;5870.882582348329;5903.852403689943;5951.732172052268;1474.1965628732366;1496.5510346895887;1497.7903093566908;1500.2423775993054;1506.3372116268806;891.5809569502323;920.7007722490217;932.2681694869494;937.2970272295778;948.7296120605732;5009.799608015712;5033.188052301169;5036.79852805891;5061.633358993125;5100.69395836251;5306.787728490817;5342.584215182979;5384.273123128406;5406.351237851404;5424.620276580601;5057.924131138036;5074.441811400743;5108.9601686996175;5224.425313215544;5694.629849059798;3171.33401985581;3205.3589282143666;3217.4530329843983;3247.895715456756;3258.446064169097;5990.281166260077;6223.288595636236;6223.346548612421;6262.9666105093065;6288.465480434214;6046.27444706638;6057.273127224673;6067.75728970845;6101.328853826164;6117.357677443804;6208.534359922934;6214.5960512566535;6233.628601707617;6248.250209974741;6251.74993000284;5834.416558344132;6029.728662210124;6055.727492262947;6059.33347331802;6072.99795533732;5904.1143828424365;5927.614705044159;5967.552433567413;5984.611000284967;5989.880202396015;5523.447655234444;6130;6155.4767844731905;6231.594946328474;6275.905861412174;4141.6065473779945;4205.957940420557;4218;4218.472690913636;4237.660987120961;6064.029190513189;6067.302260239952;6074.514038876968;6114.5414093942245;6114.908276375947;5745.597808153556;5770.8845823082465;5780.913286300793;5786.77209274424;5912.39344000151;5781.508571771446;5827.251824452563;5840.883182336414;5856.0630299151935;5880.529557635465;5528.19841123043;5766.509846663081;5785.450382213683;5802.65184088956;5812;5864.44287792659;5873.3832947539895;5894.6757928314355;5899;5926.8738939601335;5717.914231286451;5751.1373294006235;5780.132980053026;5796.507296879644;5830.708464576839 05:43:36 INFO - PROCESS | 3530 | |5;yui.html;749.722602637024;750.5376875726188;788.51506323611;802.9076656184533;846.5767116441779;15726.528204153865;15880.205989700513;16535.83464165358;16558.089305088215;16571.91714041428;13959.302034898265;13963.232022238773;14033.50882719105;14046.929765351184;14061.578152655411;512.928190053391;790.5691398187972;810;820.6717313074759;823.3783493462428;793.9682412703485;797.131127071492;797.6649807081011;797.7925739307764;798.1978111997435;843.0389356134012;845.6786421159951;847.3771777743384;850.7745447456429;854.4958474500074;843.582426698785;843.7342237195326;843.7932706486889;843.9915600843974;846.4371193156544;607.4684650930436;612.7212118486078;613.6410200032941;615.1848800339568;618.5762752514541;15666.295016724274;15982.680346393123;15982.76025859613;15983.600409989727;16013.759793603105;13832.792508112387;14047.157170569697;14209.786853197107;14211;14466.927665361605;12540.686482837911;12618.621441356774;12661.556845510362;12775.61673149807;13116.606501804961;3964.3892092133865;3976.184882099192;4016.477857878457;4059.6752259819145;4108.4453598764085;86348.56825715894;86587.5670621643;87139;87485;91994.08005919922;11531.250468719507;11542.538298468135;11662.650120496399;11667.824982625267;11874.76250475003;23025.88487057554;23066.308010759705;23196;23215.651765223534;23477.765222347898;8029.5583742894705;8382.790430239293;8455.619497122645;8769.693060742844;8856.690015849414;4533.070720502289;4625.884352891205;4677.111348843709;4889.804407823647;4906.334520993637;3276.557664715233;3380.797152170878;3387.4072037393357;3392.355452464024;3398.575178102737;614.8862460444832;623.9968800155971;629.9338569450233;631.6715308039794;635.3678090300181;595.9880802383929;657.3590749019668;663.0882536512296;679.8504329047602;691.6818263598801;9305;9733.172680322252;9823.263255255884;9852.211823054138;9902.752431189134;667.8397184675642;672.5695554844904;673.2627772589008;677.11298199359;688.5524409134102;4650.279206722963;4714.292856071616;4769.546893045155;4829.396325459317;4890.779914903766;122087;122137;122456.38771806262;123294;124778.37610811887;103939.48030259952;106178.46910765396;106201.46899265454;106957;107805;88608.5569572148;90331;91686;91966;93019.53490232641;9285.35002549815;9354.719358418986;9410.482423466776;9445.52772361393;9445.858312125461;53701.462985369646;54334.72832635811;54355.45644543504;54690;55822.162667559205;71293.64353178408;79077.60461197657;80568.59715701384;82266;83523;10542.736431589272;11172.273802202832;13183.340832958122;13292.734145317232;13340.666483337605;14674.119552826865;15084.849151508344;15552.533423997298;15815.604609884385;15979.280932358304;13941.790873136708;14164.645883852574;14183.503577374742;14203.72669568664;14270.500532481314;14195.432182712575;14315.212663303202;14627.707445851236;14691.706165876409;14732.189729564976;14395.352209150404;14456.855431445972;14585.781213281596;14700.338484768425;14854.257287135817;11746.295222286348;11780.813821770622;11948.70128246766;11958.222715523463;12468;11117.832627573987;11121.610743623934;11914.702132446759;12347.13570050088;12435.502579896704 05:43:36 INFO - PROCESS | 3530 | __end_tp_report 05:43:36 INFO - PROCESS | 3530 | __start_cc_report 05:43:36 INFO - PROCESS | 3530 | _x_x_mozilla_cycle_collect,366 05:43:36 INFO - PROCESS | 3530 | __end_cc_report 05:43:36 INFO - PROCESS | 3530 | __startTimestamp1468759416234__endTimestamp 05:43:36 INFO - PROCESS | 3530 | 05:43:36 INFO - PROCESS | 3530 | ------- Summary: start ------- 05:43:36 INFO - PROCESS | 3530 | Number of tests: 6 05:43:36 INFO - PROCESS | 3530 | 05:43:36 INFO - PROCESS | 3530 | [#0] dojo.html Cycles:165 Average:5371.01 Median:5592.33 stddev:714.90 (12.8%) stddev-sans-first:693.78 05:43:36 INFO - PROCESS | 3530 | Values: 3062.8 3075.2 3089.9 3095.4 3100.2 5338.6 5383.3 5383.9 5410.0 5422.2 5354.4 5448.0 5467.6 5512.8 5529.1 4931.2 4976.3 5061.9 5078.1 5159.9 623.1 4886.3 4941.0 4997.6 5078.4 4947.5 4967.6 4970.5 4988.8 4994.9 4997.3 5008.0 5012.7 5013.0 5037.6 4983.0 5024.9 5054.0 5058.4 5080.1 5534.3 5546.4 5562.3 5579.9 5590.4 5702.5 5735.7 5899.1 5902.9 5905.9 5782.1 5808.0 5826.4 5835.1 5879.8 5395.6 5406.3 5412.3 5413.4 5413.5 5832.8 5865.4 5906.4 5946.4 5957.2 5671.1 5673.4 5677.8 5701.5 5705.4 5809.8 5813.6 5819.5 5829.3 5846.2 5761.9 5781.3 5801.5 5809.9 5823.7 5774.0 5831.1 5839.7 5851.1 5856.2 4337.9 4436.2 4450.3 4464.0 4497.3 3866.6 3869.5 3869.8 3912.9 3922.4 5524.9 5528.4 5590.2 5592.4 5598.7 5753.2 5820.8 5851.5 5857.7 5866.6 5486.3 5534.6 5554.7 5581.7 5582.1 4987.8 5007.3 5028.8 5032.2 5073.0 5863.0 5899.1 5935.6 5947.9 5966.0 5745.3 5846.9 5852.9 5855.4 5857.9 6019.4 6023.5 6024.4 6025.3 6031.7 5592.3 5618.8 5623.5 5719.2 5721.3 5700.5 5718.2 5763.2 5767.3 5816.9 5587.2 5595.4 5606.2 5606.6 5609.9 5491.0 5568.9 5574.2 5607.7 5653.1 5518.1 5556.9 5581.7 5594.0 5596.3 5614.7 5647.2 5672.3 5692.0 5711.1 5469.7 5557.9 5588.4 5610.7 5626.7 05:43:36 INFO - PROCESS | 3530 | 05:43:36 INFO - PROCESS | 3530 | [#1] ext.html Cycles:180 Average:62886.83 Median:42888.68 stddev:127558.10 (297.4%) stddev-sans-first:127830.04 05:43:36 INFO - PROCESS | 3530 | Values: 541.2 573.9 592.3 598.1 616.3 42783.6 42817.8 43934.6 44036.8 44070.6 42021.6 42864.0 42913.4 42973.1 43216.0 8789.2 8945.8 8972.1 8973.2 8975.1 8648.1 8678.8 8725.6 8726.2 8751.6 8780.6 8784.9 8792.6 8833.6 8836.1 8695.9 8714.9 8737.6 8771.3 8781.9 8801.5 8801.8 8841.7 8880.6 8916.5 42203.0 42605.0 42745.8 42830.8 43071.6 3118.6 3238.3 3247.4 3466.8 3583.3 31349.4 32215.8 32363.4 32387.4 32880.7 4125.7 4218.7 4223.4 4247.6 4259.9 359027.0 360838.0 361698.0 362759.0 363525.0 62676.0 63795.7 63797.7 63907.0 63930.0 32019.0 32402.7 32464.7 32609.2 52864.2 17398.8 17628.1 17634.6 17649.7 17683.1 12655.1 12859.7 12870.5 12924.4 12939.5 20239.0 20255.6 20278.0 20470.2 20521.0 7146.0 7276.0 7293.8 7294.2 7548.8 858.2 926.0 941.1 954.0 959.3 34312.7 34336.7 34352.1 34415.6 34756.0 971.5 982.7 994.8 1007.0 1088.3 17160.5 17900.2 17953.1 17976.4 18018.9 580030.0 584533.0 584803.0 586155.0 588448.0 62388.4 63623.4 64076.7 64176.4 64226.4 1355.1 1361.8 1364.9 1367.4 1372.8 31775.5 33627.5 34096.7 34297.4 34415.1 54755.5 54789.0 54964.7 55483.0 55564.7 461578.0 466590.0 466628.0 469123.0 472109.0 28708.0 29067.4 29490.0 29502.7 29512.0 41549.8 41715.4 42173.4 42549.6 42644.8 41895.8 43078.6 43614.0 43643.6 43665.3 42718.0 43028.8 43168.6 43876.0 44334.6 43467.3 43922.6 44311.3 44420.3 44454.8 35778.3 39491.6 39508.0 39605.4 39777.4 28990.4 29173.0 30181.2 30324.8 30370.5 05:43:36 INFO - PROCESS | 3530 | 05:43:36 INFO - PROCESS | 3530 | [#2] jquery.html Cycles:180 Average:28655.74 Median:18114.01 stddev:78959.57 (435.9%) stddev-sans-first:79152.56 05:43:36 INFO - PROCESS | 3530 | Values: 396.2 397.3 397.4 401.2 416.7 14146.0 18491.0 18657.3 20157.7 20166.6 17202.1 17238.1 17550.6 19468.7 19772.4 5611.6 5689.1 5750.1 5755.9 5800.8 5587.7 5679.0 5696.5 5712.5 5738.7 5541.1 5611.9 5629.2 5648.0 5692.5 5626.7 5653.0 5655.6 5658.2 5664.7 4935.4 5085.8 5207.8 5244.5 5262.8 13388.3 13477.0 17888.4 22801.8 23077.5 2260.0 2327.3 2414.0 2603.8 2607.7 9759.4 10020.3 10168.4 10274.4 10459.2 1918.7 1927.3 1941.8 1943.5 2012.9 77390.0 78370.0 83793.6 84726.0 85138.6 10577.5 10903.9 11098.3 11385.1 11426.4 15366.2 15393.6 15395.8 15441.8 15447.2 8369.5 8534.3 8571.6 8648.8 8715.1 8049.2 8098.2 8109.0 8240.8 8284.2 4368.5 4504.3 4615.7 4620.1 4734.6 1941.4 1948.4 1964.3 2010.3 2041.5 883.9 950.4 978.5 999.4 1015.7 19766.0 19880.0 20210.4 20391.0 20657.8 1010.4 1030.8 1032.2 1051.9 1065.8 10700.6 10701.1 10847.3 11049.4 11333.7 463958.0 465674.0 477983.0 487519.0 491047.0 93958.0 95154.0 97116.5 98030.0 98557.0 878.3 893.7 908.6 909.9 942.3 16893.7 17079.9 17495.0 17614.3 17680.7 31280.2 35831.8 45515.5 46221.8 46494.5 56588.4 57031.1 62020.7 62496.4 62562.0 8639.5 8715.4 8764.1 8887.1 8900.5 10044.4 12726.0 12918.1 13099.5 13560.0 11587.4 11602.8 11812.1 11867.2 12267.1 11578.5 11603.0 11895.9 11896.3 12007.5 11882.9 12015.8 12035.7 12047.0 12612.7 8531.1 8538.3 8734.0 8878.6 9050.8 8657.1 10641.7 10772.0 10856.9 11019.7 05:43:36 INFO - PROCESS | 3530 | 05:43:36 INFO - PROCESS | 3530 | [#3] mootools.html Cycles:180 Average:5985.68 Median:2654.62 stddev:3439.91 (129.6%) stddev-sans-first:3432.22 05:43:36 INFO - PROCESS | 3530 | Values: 1389.3 1469.9 1605.5 1761.5 1782.7 7928.2 8804.7 8812.3 9483.8 9496.4 8882.9 9122.7 9138.5 9146.9 9155.7 2384.2 2400.9 2405.3 2408.8 2420.1 2418.5 2426.6 2441.8 2445.2 2459.6 2397.9 2430.2 2444.4 2452.3 2456.5 2356.8 2368.9 2370.3 2382.2 2388.7 2326.2 2338.3 2348.0 2349.4 2354.5 9096.7 9104.7 9109.1 9125.9 9182.2 5422.6 5516.1 5517.1 5521.9 5534.8 3507.9 3611.6 3615.9 3650.9 3662.3 1258.7 1289.1 1303.3 1311.0 1320.4 12608.2 12616.9 12635.9 12655.9 12718.5 4384.4 4445.4 4551.3 4620.4 4730.6 9932.4 9975.9 9989.0 9998.0 10005.1 5375.7 5436.7 5446.3 5465.9 5469.1 4409.0 4831.4 4929.9 4948.6 5094.6 3726.9 3787.7 3853.2 3882.7 3884.2 1150.9 1152.7 1159.4 1165.9 1173.0 917.3 988.8 989.3 1031.1 1043.4 6196.4 6208.8 6278.3 6304.4 6334.4 473.1 477.8 485.3 496.8 497.8 4247.7 4351.9 4405.6 4424.3 4433.8 11243.1 11612.6 11677.4 11710.8 11758.0 11183.7 11397.5 11473.9 11520.9 11567.9 8560.1 8608.6 8616.7 8618.8 8768.3 6122.3 6169.7 6198.5 6213.8 6261.9 10484.6 10625.7 10694.9 10832.2 10857.0 10606.9 10803.8 10853.7 10897.9 10922.8 6775.4 7190.6 7259.9 7428.5 7657.3 8504.4 8603.3 8690.7 8694.8 8724.9 7568.9 7887.8 8252.4 8270.0 8371.2 7808.6 8075.0 8076.8 8106.2 8188.5 7351.3 7356.5 7423.0 7487.4 7494.1 5253.3 5299.9 5406.1 5519.0 5520.6 5498.9 5514.8 5580.4 5617.8 5683.8 05:43:36 INFO - PROCESS | 3530 | 05:43:36 INFO - PROCESS | 3530 | [#4] prototype.html Cycles:180 Average:4896.51 Median:5524.20 stddev:1437.31 (26.0%) stddev-sans-first:1399.97 05:43:36 INFO - PROCESS | 3530 | Values: 334.6 337.8 345.0 345.9 349.7 5434.4 5454.8 5534.8 5614.2 5709.7 5584.5 5606.7 5606.9 5622.6 5660.7 4383.7 4414.2 4418.2 4428.7 4433.2 4343.4 4393.6 4400.8 4431.4 4446.7 4388.5 4394.1 4400.1 4412.9 4424.4 4414.6 4414.6 4418.7 4425.5 4437.8 3638.9 3834.9 3929.8 3930.4 3944.9 5425.8 5497.6 5511.2 5511.3 5524.9 4978.6 4994.7 5016.3 5025.6 5025.7 5613.0 5860.7 5870.3 5877.1 5878.3 4159.3 4159.9 4179.9 4191.7 4203.9 6093.9 6100.8 6103.1 6106.0 6111.5 4174.2 4294.6 4297.0 4306.3 4332.3 4329.2 4334.9 4348.1 4361.3 4362.7 4885.6 4976.4 4990.1 5026.7 5044.1 5833.3 5860.2 5870.9 5903.9 5951.7 1474.2 1496.6 1497.8 1500.2 1506.3 891.6 920.7 932.3 937.3 948.7 5009.8 5033.2 5036.8 5061.6 5100.7 5306.8 5342.6 5384.3 5406.4 5424.6 5057.9 5074.4 5109.0 5224.4 5694.6 3171.3 3205.4 3217.5 3247.9 3258.4 5990.3 6223.3 6223.3 6263.0 6288.5 6046.3 6057.3 6067.8 6101.3 6117.4 6208.5 6214.6 6233.6 6248.3 6251.7 5834.4 6029.7 6055.7 6059.3 6073.0 5904.1 5927.6 5967.6 5984.6 5989.9 5523.4 6130.0 6155.5 6231.6 6275.9 4141.6 4206.0 4218.0 4218.5 4237.7 6064.0 6067.3 6074.5 6114.5 6114.9 5745.6 5770.9 5780.9 5786.8 5912.4 5781.5 5827.3 5840.9 5856.1 5880.5 5528.2 5766.5 5785.5 5802.7 5812.0 5864.4 5873.4 5894.7 5899.0 5926.9 5717.9 5751.1 5780.1 5796.5 5830.7 05:43:36 INFO - PROCESS | 3530 | 05:43:36 INFO - PROCESS | 3530 | [#5] yui.html Cycles:180 Average:22109.17 Median:4579.48 stddev:32372.54 (706.9%) stddev-sans-first:32423.63 05:43:36 INFO - PROCESS | 3530 | Values: 749.7 750.5 788.5 802.9 846.6 15726.5 15880.2 16535.8 16558.1 16571.9 13959.3 13963.2 14033.5 14046.9 14061.6 512.9 790.6 810.0 820.7 823.4 794.0 797.1 797.7 797.8 798.2 843.0 845.7 847.4 850.8 854.5 843.6 843.7 843.8 844.0 846.4 607.5 612.7 613.6 615.2 618.6 15666.3 15982.7 15982.8 15983.6 16013.8 13832.8 14047.2 14209.8 14211.0 14466.9 12540.7 12618.6 12661.6 12775.6 13116.6 3964.4 3976.2 4016.5 4059.7 4108.4 86348.6 86587.6 87139.0 87485.0 91994.1 11531.3 11542.5 11662.7 11667.8 11874.8 23025.9 23066.3 23196.0 23215.7 23477.8 8029.6 8382.8 8455.6 8769.7 8856.7 4533.1 4625.9 4677.1 4889.8 4906.3 3276.6 3380.8 3387.4 3392.4 3398.6 614.9 624.0 629.9 631.7 635.4 596.0 657.4 663.1 679.9 691.7 9305.0 9733.2 9823.3 9852.2 9902.8 667.8 672.6 673.3 677.1 688.6 4650.3 4714.3 4769.5 4829.4 4890.8 122087.0 122137.0 122456.4 123294.0 124778.4 103939.5 106178.5 106201.5 106957.0 107805.0 88608.6 90331.0 91686.0 91966.0 93019.5 9285.4 9354.7 9410.5 9445.5 9445.9 53701.5 54334.7 54355.5 54690.0 55822.2 71293.6 79077.6 80568.6 82266.0 83523.0 10542.7 11172.3 13183.3 13292.7 13340.7 14674.1 15084.8 15552.5 15815.6 15979.3 13941.8 14164.6 14183.5 14203.7 14270.5 14195.4 14315.2 14627.7 14691.7 14732.2 14395.4 14456.9 14585.8 14700.3 14854.3 11746.3 11780.8 11948.7 11958.2 12468.0 11117.8 11121.6 11914.7 12347.1 12435.5 05:43:36 INFO - PROCESS | 3530 | -------- Summary: end -------- 05:43:36 INFO - PROCESS | 3530 | 05:43:37 INFO - TEST-INFO | 3530: exit 0 05:43:37 INFO - TEST-OK | dromaeo_css | took 1129438ms 05:43:37 INFO - TEST-START | kraken 05:43:37 INFO - operating with platform_type : linux_ 05:43:37 INFO - Initialising browser for kraken test... 05:43:37 INFO - TEST-INFO | started process 3772 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg02BWZ/profile http://localhost:38700/getInfo.html) 05:43:42 INFO - PROCESS | 3772 | JavaScript error: , line 0: uncaught exception: undefined 05:43:44 INFO - PROCESS | 3772 | __metrics Screen width/height:1600/1200 05:43:44 INFO - PROCESS | 3772 | colorDepth:24 05:43:44 INFO - PROCESS | 3772 | Browser inner width/height: 1024/697 05:43:44 INFO - PROCESS | 3772 | __metrics 05:43:45 ERROR - PROCESS | 3772 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:43:46 INFO - TEST-INFO | 3772: exit 0 05:43:46 INFO - Browser initialized. 05:43:46 INFO - Running cycle 1/1 for kraken test... 05:43:46 INFO - TEST-INFO | started process 3897 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg02BWZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:58 INFO - PROCESS | 3897 | RSS: Main: 168386560 05:43:58 INFO - PROCESS | 3897 | 05:44:00 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38700/tests/kraken/driver.html?testName=audio-beat-detection) 05:44:01 INFO - PROCESS | 3897 | RSS: Main: 173805568 05:44:01 INFO - PROCESS | 3897 | 05:44:04 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38700/tests/kraken/driver.html?testName=audio-dft) 05:44:05 INFO - PROCESS | 3897 | RSS: Main: 172556288 05:44:05 INFO - PROCESS | 3897 | 05:44:07 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38700/tests/kraken/driver.html?testName=audio-fft) 05:44:08 INFO - PROCESS | 3897 | RSS: Main: 167231488 05:44:08 INFO - PROCESS | 3897 | 05:44:10 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38700/tests/kraken/driver.html?testName=audio-oscillator) 05:44:11 INFO - PROCESS | 3897 | RSS: Main: 168185856 05:44:11 INFO - PROCESS | 3897 | 05:44:13 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38700/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:44:14 INFO - PROCESS | 3897 | RSS: Main: 168796160 05:44:14 INFO - PROCESS | 3897 | 05:44:17 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38700/tests/kraken/driver.html?testName=imaging-darkroom) 05:44:17 INFO - PROCESS | 3897 | RSS: Main: 168624128 05:44:17 INFO - PROCESS | 3897 | 05:44:21 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38700/tests/kraken/driver.html?testName=imaging-desaturate) 05:44:21 INFO - PROCESS | 3897 | RSS: Main: 166047744 05:44:21 INFO - PROCESS | 3897 | 05:44:24 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38700/tests/kraken/driver.html?testName=json-parse-financial) 05:44:25 INFO - PROCESS | 3897 | RSS: Main: 168460288 05:44:25 INFO - PROCESS | 3897 | 05:44:27 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38700/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:44:27 INFO - PROCESS | 3897 | RSS: Main: 168300544 05:44:27 INFO - PROCESS | 3897 | 05:44:29 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38700/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:44:30 INFO - PROCESS | 3897 | RSS: Main: 168497152 05:44:30 INFO - PROCESS | 3897 | 05:44:32 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38700/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:44:32 INFO - PROCESS | 3897 | RSS: Main: 168833024 05:44:32 INFO - PROCESS | 3897 | 05:44:35 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38700/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:44:36 INFO - PROCESS | 3897 | RSS: Main: 169689088 05:44:37 INFO - PROCESS | 3897 | 05:44:38 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38700/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:44:39 INFO - PROCESS | 3897 | RSS: Main: 168935424 05:44:39 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | Cycle 1(1): loaded http://localhost:38700/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38700/tests/kraken/driver.html?testName=ai-astar) 05:44:41 INFO - PROCESS | 3897 | RSS: Main: 169832448 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | __start_tp_report 05:44:41 INFO - PROCESS | 3897 | _x_x_mozilla_page_load 05:44:41 INFO - PROCESS | 3897 | _x_x_mozilla_page_load_details 05:44:41 INFO - PROCESS | 3897 | |i|pagename|runs| 05:44:41 INFO - PROCESS | 3897 | |0;ai-astar;103;112;106;112;110;112;112;110;112;113 05:44:41 INFO - PROCESS | 3897 | |1;audio-beat-detection;150;120;160;182;118;148;150;125;185;123 05:44:41 INFO - PROCESS | 3897 | |2;audio-dft;163;162;163;162;163;162;163;167;168;166 05:44:41 INFO - PROCESS | 3897 | |3;audio-fft;76;76;74;165;76;76;75;75;191;76 05:44:41 INFO - PROCESS | 3897 | |4;audio-oscillator;84;85;145;84;85;84;151;85;85;152 05:44:41 INFO - PROCESS | 3897 | |5;imaging-gaussian-blur;118;118;118;117;117;118;118;117;117;122 05:44:41 INFO - PROCESS | 3897 | |6;imaging-darkroom;148;148;148;147;148;148;149;148;148;147 05:44:41 INFO - PROCESS | 3897 | |7;imaging-desaturate;95;97;97;97;95;96;95;95;95;96 05:44:41 INFO - PROCESS | 3897 | |8;json-parse-financial;52;54;53;54;54;53;53;54;54;52 05:44:41 INFO - PROCESS | 3897 | |9;json-stringify-tinderbox;64;64;64;64;65;65;64;64;64;64 05:44:41 INFO - PROCESS | 3897 | |10;stanford-crypto-aes;75;77;77;75;76;79;76;78;79;76 05:44:41 INFO - PROCESS | 3897 | |11;stanford-crypto-ccm;122;128;127;128;143;136;124;125;156;126 05:44:41 INFO - PROCESS | 3897 | |12;stanford-crypto-pbkdf2;165;162;157;155;163;164;159;157;159;156 05:44:41 INFO - PROCESS | 3897 | |13;stanford-crypto-sha256-iterative;67;64;63;63;64;62;63;62;63;62 05:44:41 INFO - PROCESS | 3897 | __end_tp_report 05:44:41 INFO - PROCESS | 3897 | __start_cc_report 05:44:41 INFO - PROCESS | 3897 | _x_x_mozilla_cycle_collect,908 05:44:41 INFO - PROCESS | 3897 | __end_cc_report 05:44:41 INFO - PROCESS | 3897 | __startTimestamp1468759481435__endTimestamp 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | ------- Summary: start ------- 05:44:41 INFO - PROCESS | 3897 | Number of tests: 14 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#0] ai-astar Cycles:10 Average:110.20 Median:112.00 stddev:3.22 (2.9%) stddev-sans-first:2.12 05:44:41 INFO - PROCESS | 3897 | Values: 103.0 112.0 106.0 112.0 110.0 112.0 112.0 110.0 112.0 113.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#1] audio-beat-detection Cycles:10 Average:146.10 Median:150.00 stddev:24.67 (16.4%) stddev-sans-first:26.13 05:44:41 INFO - PROCESS | 3897 | Values: 150.0 120.0 160.0 182.0 118.0 148.0 150.0 125.0 185.0 123.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#2] audio-dft Cycles:10 Average:163.90 Median:163.00 stddev:2.23 (1.4%) stddev-sans-first:2.35 05:44:41 INFO - PROCESS | 3897 | Values: 163.0 162.0 163.0 162.0 163.0 162.0 163.0 167.0 168.0 166.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#3] audio-fft Cycles:10 Average:96.00 Median:76.00 stddev:43.66 (57.4%) stddev-sans-first:45.70 05:44:41 INFO - PROCESS | 3897 | Values: 76.0 76.0 74.0 165.0 76.0 76.0 75.0 75.0 191.0 76.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#4] audio-oscillator Cycles:10 Average:104.00 Median:84.50 stddev:31.34 (37.1%) stddev-sans-first:32.39 05:44:41 INFO - PROCESS | 3897 | Values: 84.0 85.0 145.0 84.0 85.0 84.0 151.0 85.0 85.0 152.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:1.49 (1.3%) stddev-sans-first:1.58 05:44:41 INFO - PROCESS | 3897 | Values: 118.0 118.0 118.0 117.0 117.0 118.0 118.0 117.0 117.0 122.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 05:44:41 INFO - PROCESS | 3897 | Values: 148.0 148.0 148.0 147.0 148.0 148.0 149.0 148.0 148.0 147.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.92 (1.0%) stddev-sans-first:0.93 05:44:41 INFO - PROCESS | 3897 | Values: 95.0 97.0 97.0 97.0 95.0 96.0 95.0 95.0 95.0 96.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.73 05:44:41 INFO - PROCESS | 3897 | Values: 52.0 54.0 53.0 54.0 54.0 53.0 53.0 54.0 54.0 52.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 05:44:41 INFO - PROCESS | 3897 | Values: 64.0 64.0 64.0 64.0 65.0 65.0 64.0 64.0 64.0 64.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#10] stanford-crypto-aes Cycles:10 Average:76.80 Median:77.00 stddev:1.48 (1.9%) stddev-sans-first:1.41 05:44:41 INFO - PROCESS | 3897 | Values: 75.0 77.0 77.0 75.0 76.0 79.0 76.0 78.0 79.0 76.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#11] stanford-crypto-ccm Cycles:10 Average:131.50 Median:128.00 stddev:10.63 (8.3%) stddev-sans-first:10.70 05:44:41 INFO - PROCESS | 3897 | Values: 122.0 128.0 127.0 128.0 143.0 136.0 124.0 125.0 156.0 126.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.70 Median:160.50 stddev:3.56 (2.2%) stddev-sans-first:3.22 05:44:41 INFO - PROCESS | 3897 | Values: 165.0 162.0 157.0 155.0 163.0 164.0 159.0 157.0 159.0 156.0 05:44:41 INFO - PROCESS | 3897 | 05:44:41 INFO - PROCESS | 3897 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.00 stddev:1.49 (2.4%) stddev-sans-first:0.78 05:44:41 INFO - PROCESS | 3897 | Values: 67.0 64.0 63.0 63.0 64.0 62.0 63.0 62.0 63.0 62.0 05:44:41 INFO - PROCESS | 3897 | -------- Summary: end -------- 05:44:41 INFO - PROCESS | 3897 | 05:44:53 INFO - Browser shutdown timed out after 5 seconds, terminating process. 05:44:53 INFO - Terminating psutil.Process(pid=3897, name='firefox') 05:44:56 INFO - TEST-INFO | 3897: exit 15 05:44:56 INFO - TEST-OK | kraken | took 79525ms 05:44:56 INFO - SUITE-END | took 1208s 05:44:57 INFO - Completed test suite (00:20:09) 05:44:57 INFO - javascript benchmark 05:44:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7350.594099019147, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3062.754979601632, 3075.2158199659084, 3089.860956256968, 3095.4428202923473, 3100.1629560018796, 5338.62629615927, 5383.327084114486, 5383.892322153554, 5410.0, 5422.1595652673805, 5354.357477102743, 5447.972760136204, 5467.562594992401, 5512.80705175319, 5529.142982837657, 4931.235658472933, 4976.328195693589, 5061.924071138936, 5078.060558796635, 5159.871003224912, 623.0787492162816, 4886.340344053543, 4941.0, 4997.575206107467, 5078.365204349457, 4947.52998465147, 4967.577755890772, 4970.453250142481, 4988.800447982077, 4994.87512812179, 4997.250412438127, 5008.0, 5012.699238045729, 5013.022460620181, 5037.596992240612, 4983.0, 5024.949750502521, 5054.039732450822, 5058.4435712071645, 5080.13637681595, 5534.308211473566, 5546.362168350651, 5562.3047119110115, 5579.917889500423, 5590.385057643656, 5702.45826646468, 5735.713214339266, 5899.1446240295345, 5902.911456328158, 5905.911411328833, 5782.074868021096, 5807.970960145214, 5826.446487583673, 5835.095560188178, 5879.794207202727, 5395.568354531628, 5406.297181366436, 5412.269343638637, 5413.350397952231, 5413.53984911283, 5832.766689332464, 5865.384134665798, 5906.40935906406, 5946.405359464019, 5957.195778569924, 5671.1493276008105, 5673.375928647845, 5677.80127695537, 5701.486866182064, 5705.372409035002, 5809.767609295581, 5813.6221145625395, 5819.5053420459735, 5829.271341082364, 5846.239988801429, 5761.8559536011935, 5781.335146458211, 5801.50687191585, 5809.9419005809405, 5823.679697616587, 5774.047282198474, 5831.067029275296, 5839.737211825478, 5851.093080572518, 5856.180134781134, 4337.891552711144, 4436.223660859337, 4450.310201918708, 4464.0402313502755, 4497.3254011897825, 3866.613338666111, 3869.5356557212754, 3869.845206191777, 3912.941305880414, 3922.431247469101, 5524.861878453071, 5528.419515950767, 5590.161475778585, 5592.440755924375, 5598.720063996784, 5753.194552762617, 5820.796272130539, 5851.531877449794, 5857.736401861926, 5866.589338746332, 5486.341639003265, 5534.557235421156, 5554.6944918029085, 5581.720913954286, 5582.106862901997, 4987.775550100254, 5007.324011258506, 5028.76795185182, 5032.245163225472, 5072.9746351268, 5863.0, 5899.056151015817, 5935.643861368331, 5947.881042379128, 5965.970170149121, 5745.339285982082, 5846.912296315473, 5852.853678657897, 5855.3559108497175, 5857.912131318119, 6019.367966363468, 6023.5181185505935, 6024.3975602439405, 6025.337212906665, 6031.728572214349, 5592.328920529562, 5618.803341883015, 5623.493885550321, 5719.199312096393, 5721.313442386941, 5700.458456446631, 5718.1708652246025, 5763.221965034667, 5767.250257466502, 5816.854578635568, 5587.245721827501, 5595.356533998643, 5606.187102870143, 5606.607537472338, 5609.915851262316, 5491.039068162976, 5568.91646625309, 5574.219609254788, 5607.747651355618, 5653.123765816305, 5518.117101263779, 5556.944430555643, 5581.665100094007, 5593.972030139823, 5596.272484576979, 5614.691191984317, 5647.1529270609735, 5672.319321681424, 5692.0, 5711.057675483498, 5469.671819690831, 5557.888842223214, 5588.441155884379, 5610.7194640268635, 5626.746796394254], "value": 5324.164903422168, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.2206422751235, 573.8766165274467, 592.2981267198369, 598.1476396135506, 616.2820314333801, 42783.57216427835, 42817.78591107044, 43934.560654393485, 44036.779816100876, 44070.55929440709, 42021.57978420215, 42864.0, 42913.35629965553, 42973.14053718924, 43216.0, 8789.165029322221, 8945.821083578325, 8972.147645973622, 8973.237274831647, 8975.147361000694, 8648.091950345217, 8678.826423471526, 8725.607347669371, 8726.214640682338, 8751.606177722018, 8780.648774049063, 8784.868226976601, 8792.604332805007, 8833.602487888029, 8836.0722124177, 8695.913040869573, 8714.869276960851, 8737.606807693637, 8771.254443372321, 8781.868271975925, 8801.515916624647, 8801.779955501164, 8841.69054083104, 8880.55597220143, 8916.465012099296, 42203.0, 42605.0, 42745.786271068755, 42830.78584607088, 43071.56928430707, 3118.6413562440157, 3238.3037646906027, 3247.447933851251, 3466.757326987112, 3583.2654305867236, 31349.37301253962, 32215.83892080548, 32363.352732945445, 32387.35225295481, 32880.67119328824, 4125.711200215986, 4218.7468751874985, 4223.408722778813, 4247.575242475758, 4259.914801703949, 359027.0, 360838.0, 361698.0, 362759.0, 363525.0, 62676.0, 63795.681021594595, 63797.681011595574, 63907.0, 63930.0, 32019.0, 32402.67597324044, 32464.67535324664, 32609.184770380456, 52864.20703689448, 17398.782085254163, 17628.1185940705, 17634.5591360217, 17649.7352539712, 17683.115844207994, 12655.114142010156, 12859.742805143846, 12870.485180592672, 12924.41840117197, 12939.547115851088, 20239.0, 20255.594888102154, 20278.0, 20470.18119275242, 20521.0, 7146.0, 7276.0, 7293.817654558572, 7294.197638259787, 7548.849023019508, 858.2447446247262, 926.0, 941.1200527506803, 953.952302384878, 959.2661613865398, 34312.65687343144, 34336.65663343335, 34352.141196470286, 34415.59090863616, 34756.0, 971.489967766928, 982.7346616413529, 994.7761753605525, 1006.9647562335283, 1088.2545456362259, 17160.485185444457, 17900.194491247665, 17953.102344882704, 17976.37082702099, 18018.90990545039, 580030.0, 584533.0, 584803.0, 586155.0, 588448.0, 62388.37611623917, 63623.36376636174, 64076.67961660255, 64176.358236417975, 64226.35773642204, 1355.1327150623608, 1361.7753070743415, 1364.883984861298, 1367.4256812138926, 1372.8009438631343, 31775.523367149974, 33627.49558756572, 34096.65903340935, 34297.44484829216, 34415.13962150966, 54755.452445475035, 54789.0, 54964.72517637386, 55483.0, 55564.72217638886, 461578.0, 466590.0, 466628.0, 469123.0, 472109.0, 28708.0, 29067.41865162727, 29490.0, 29502.704972949996, 29512.0, 41549.792251038554, 41715.37426938538, 42173.367399488416, 42549.57450425456, 42644.78677606592, 41895.7905210472, 43078.64450127912, 43614.0, 43643.56356436395, 43665.34501982536, 42718.0, 43028.78485607552, 43168.56831431646, 43876.0, 44334.55665443433, 43467.34798977955, 43922.560774393125, 44311.33532996943, 44420.333694993955, 44454.77772611116, 35778.28443431169, 39491.605083949944, 39508.0, 39605.40591891066, 39777.40333894936, 28990.420191596473, 29172.9789457368, 30181.245468863453, 30324.848375758862, 30370.54444183295], "value": 20175.856879799016, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.2036307022883, 397.3027337023524, 397.4356413892271, 401.20761496045316, 416.6770752666683, 14146.0, 18491.0, 18657.25370985159, 20157.697634535492, 20166.59666806663, 17202.139893005362, 17238.138093095356, 17550.561235969108, 19468.70796938047, 19772.40682779519, 5611.607187496877, 5689.146628005811, 5750.079987202049, 5755.9424405756035, 5800.7679692812235, 5587.7485513152005, 5679.0, 5696.458836410535, 5712.485876271135, 5738.684372359518, 5541.085720856054, 5611.915821262684, 5629.155626656014, 5647.971760141194, 5692.516136128414, 5626.662400255977, 5652.971735141339, 5655.604107712462, 5658.151277308396, 5664.660120392769, 4935.35840340757, 5085.847424577269, 5207.791688332462, 5244.475552444725, 5262.789488420459, 13388.263645499494, 13477.0, 17888.373906913195, 22801.77198228013, 23077.538449231088, 2260.0, 2327.2552783109413, 2413.974061024075, 2603.778678812303, 2607.739226077396, 9759.414435133915, 10020.298579099466, 10168.389896606228, 10274.383536987805, 10459.163266938627, 1918.7026010968323, 1927.3254360973688, 1941.7766956799867, 1943.5141214696325, 2012.9496762580907, 77390.0, 78370.0, 83793.58103209505, 84726.0, 85138.57430712807, 10577.524011419504, 10903.890961090447, 11098.2786118902, 11385.14611404148, 11426.428678566204, 15366.154861482726, 15393.615159621098, 15395.846041539666, 15441.845581544265, 15447.150406727513, 8369.539675317916, 8534.317254619615, 8571.614277357534, 8648.827023459495, 8715.084916083846, 8049.154838741965, 8098.230668086522, 8109.0, 8240.8351832963, 8284.171582841667, 4368.497622773358, 4504.34686970394, 4615.730674064659, 4620.0990806793025, 4734.621230301567, 1941.3981665683684, 1948.4446932624135, 1964.2830366916257, 2010.3164923925642, 2041.499832541037, 883.9116088391238, 950.4154944709093, 978.5254151736402, 999.3554157568328, 1015.664830605913, 19766.0, 19880.0, 20210.39368818938, 20391.0, 20657.793422065886, 1010.3735683875897, 1030.78381479819, 1032.200044965158, 1051.942143182117, 1065.8347956066823, 10700.625478108228, 10701.143908487302, 10847.294925829796, 11049.447527623748, 11333.659990200142, 463958.0, 465674.0, 477983.0, 487519.0, 491047.0, 93958.0, 95154.0, 97116.51441742746, 98030.0, 98557.0, 878.3412440669498, 893.718478679214, 908.5911339896941, 909.8908131024319, 942.330945029037, 16893.66212675764, 17079.914600426917, 17495.0, 17614.29542818273, 17680.73478897792, 31280.21799455032, 35831.82084089563, 45515.54484455245, 46221.79587105585, 46494.53505464902, 56588.43411565832, 57031.14453283287, 62020.689896550226, 62496.375036249054, 62562.0, 8639.524826134622, 8715.433496822687, 8764.079771623889, 8887.111288871061, 8900.510471923848, 10044.447555384524, 12726.0, 12918.095733298578, 13099.476020959055, 13559.98300127514, 11587.420628968686, 11602.767944640891, 11812.114091443347, 11867.169298149047, 12267.141300108906, 11578.536858525564, 11603.0, 11895.881041189477, 11896.345700986529, 12007.519699211934, 11882.940585297018, 12015.75968480643, 12035.699107522381, 12047.0, 12612.747745045232, 8531.061583225715, 8538.274246689101, 8734.0, 8878.600462979051, 9050.818983620422, 8657.091005444337, 10641.733956651145, 10772.030517253485, 10856.945715271373, 11019.724506887393], "value": 9124.976994746865, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.3470069067537, 1469.9412023519055, 1605.486244401791, 1761.462753860073, 1782.732590111484, 7928.207179282069, 8804.735857924257, 8812.295016398688, 9483.81032379352, 9496.38273512222, 8882.911170888288, 9122.726318210463, 9138.45169289843, 9146.86279705805, 9155.67955121571, 2384.165542060278, 2400.8799560022017, 2405.302462285935, 2408.7832095111435, 2420.0561780905373, 2418.504206637635, 2426.5632186206476, 2441.7680320369536, 2445.1930862815316, 2459.606462965926, 2397.856128632279, 2430.1858877276136, 2444.4377793107597, 2452.3256104571205, 2456.5455390752677, 2356.7525409832065, 2368.928932132039, 2370.3126093432884, 2382.2257766225957, 2388.653645221442, 2326.174208156097, 2338.3452633262714, 2347.9882600587057, 2349.447879748258, 2354.5173239485866, 9096.727098187064, 9104.68133615327, 9109.134632209996, 9125.863112053325, 9182.17360437564, 5422.566194704415, 5516.062269414209, 5517.144842549411, 5521.889562208774, 5534.778608855641, 3507.8772242971627, 3611.566612006576, 3615.9096022599383, 3650.908727281813, 3662.3224703429682, 1258.7293731847606, 1289.0654275650172, 1303.2962200411766, 1310.9868901311056, 1320.3860205004623, 12608.180468269533, 12616.873831261753, 12635.93682031584, 12655.860972512426, 12718.491260349574, 4384.386185933972, 4445.444319460067, 4551.340055691906, 4620.376249206314, 4730.550597693214, 9932.354396964174, 9975.850362244571, 9989.0, 9997.950010249902, 10005.099541041196, 5375.731213439312, 5436.728163591804, 5446.264754258124, 5465.890682186334, 5469.097598896217, 4408.9859332353735, 4831.396075490564, 4929.926051109236, 4948.62885283598, 5094.566961808214, 3726.869559565402, 3787.6780473660046, 3853.2486165197515, 3882.6893848492055, 3884.2231553689376, 1150.8791576884307, 1152.7060599547062, 1159.3507635724022, 1165.8950694437372, 1172.9589464368705, 917.2707697380598, 988.826955282837, 989.3223142147652, 1031.1132426113536, 1043.4052590023614, 6196.442320191204, 6208.844778880474, 6278.277998030194, 6304.369563043659, 6334.398232167937, 473.0704166313211, 477.84947741462025, 485.27936015017673, 496.75162418790603, 497.8133200049981, 4247.68142389322, 4351.934720979188, 4405.559444055569, 4424.3142312941545, 4433.844815431445, 11243.100551955824, 11612.593559225556, 11677.357745324089, 11710.76578468426, 11758.0, 11183.720406989727, 11397.544098235978, 11473.942630286796, 11520.94239528797, 11567.942160289313, 8560.143985601515, 8608.569571521399, 8616.698415555551, 8618.827623447496, 8768.342374321952, 6122.326544080059, 6169.722362493789, 6198.473129784019, 6213.844653883689, 6261.906071408841, 10484.580616775243, 10625.734356641145, 10694.893051069701, 10832.18758593093, 10857.0, 10606.893931060591, 10803.837942431028, 10853.674389768004, 10897.945510272399, 10922.781544369227, 6775.390214880685, 7190.604516751629, 7259.891101633374, 7428.4800063995, 7657.272559106876, 8504.40469167177, 8603.268722158688, 8690.695825646071, 8694.782630434289, 8724.91275087241, 7568.924310756822, 7887.84224315522, 8252.422330436812, 8270.048944371474, 8371.204735550113, 7808.648610812414, 8075.030996280249, 8076.83846323082, 8106.189381062082, 8188.50868947865, 7351.338379545653, 7356.485046046725, 7423.035005449257, 7487.401007919462, 7494.100707914867, 5253.345196263165, 5299.894002119859, 5406.080966235828, 5518.972405137949, 5520.613557050968, 5498.862528436821, 5514.80698175562, 5580.35825880029, 5617.8033768817895, 5683.772649094155], "value": 4703.725584037526, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.5548017443941, 337.7770671356904, 344.9689527942485, 345.8806711684468, 349.6730556929273, 5434.429384914586, 5454.754536045877, 5534.833954981352, 5614.242077319561, 5709.6574205547695, 5584.469475399841, 5606.691631960251, 5606.906653202627, 5622.578306626999, 5660.688662123582, 4383.671224658156, 4414.183376075436, 4418.2268103082, 4428.6678499112695, 4433.157700036999, 4343.370211319357, 4393.560643935597, 4400.845970391037, 4431.446069241345, 4446.733196008234, 4388.495323037843, 4394.143806525752, 4400.075984043355, 4412.933805992912, 4424.446944131983, 4414.602685758265, 4414.631464246062, 4418.680192898738, 4425.5353187915125, 4437.822487100512, 3638.890833275006, 3834.865779697725, 3929.764214147132, 3930.410438434229, 3944.9211015779524, 5425.782968681248, 5497.5601951844155, 5511.200875873016, 5511.311086114236, 5524.944750552483, 4978.626603004753, 4994.675346102492, 5016.347874776292, 5025.572826309768, 5025.673331233458, 5613.007382379278, 5860.736266867959, 5870.295564532284, 5877.118432235156, 5878.323992740847, 4159.334506478979, 4159.854405095837, 4179.874603761892, 4191.664666826677, 4203.91592168158, 6093.939060609381, 6100.755969761204, 6103.115048317969, 6106.01554494791, 6111.541634377395, 4174.20690068886, 4294.570542945743, 4296.958944501399, 4306.2894622387585, 4332.3068309070395, 4329.19909816685, 4334.869953901388, 4348.108637729258, 4361.323994780814, 4362.650987920959, 4885.560299572984, 4976.353074100344, 4990.126727822689, 5026.74866256693, 5044.1172794761505, 5833.329167145833, 5860.238169037998, 5870.882582348329, 5903.852403689943, 5951.732172052268, 1474.1965628732366, 1496.5510346895887, 1497.7903093566908, 1500.2423775993054, 1506.3372116268806, 891.5809569502323, 920.7007722490217, 932.2681694869494, 937.2970272295778, 948.7296120605732, 5009.799608015712, 5033.188052301169, 5036.79852805891, 5061.633358993125, 5100.69395836251, 5306.787728490817, 5342.584215182979, 5384.273123128406, 5406.351237851404, 5424.620276580601, 5057.924131138036, 5074.441811400743, 5108.9601686996175, 5224.425313215544, 5694.629849059798, 3171.33401985581, 3205.3589282143666, 3217.4530329843983, 3247.895715456756, 3258.446064169097, 5990.281166260077, 6223.288595636236, 6223.346548612421, 6262.9666105093065, 6288.465480434214, 6046.27444706638, 6057.273127224673, 6067.75728970845, 6101.328853826164, 6117.357677443804, 6208.534359922934, 6214.5960512566535, 6233.628601707617, 6248.250209974741, 6251.74993000284, 5834.416558344132, 6029.728662210124, 6055.727492262947, 6059.33347331802, 6072.99795533732, 5904.1143828424365, 5927.614705044159, 5967.552433567413, 5984.611000284967, 5989.880202396015, 5523.447655234444, 6130.0, 6155.4767844731905, 6231.594946328474, 6275.905861412174, 4141.6065473779945, 4205.957940420557, 4218.0, 4218.472690913636, 4237.660987120961, 6064.029190513189, 6067.302260239952, 6074.514038876968, 6114.5414093942245, 6114.908276375947, 5745.597808153556, 5770.8845823082465, 5780.913286300793, 5786.77209274424, 5912.39344000151, 5781.508571771446, 5827.251824452563, 5840.883182336414, 5856.0630299151935, 5880.529557635465, 5528.19841123043, 5766.509846663081, 5785.450382213683, 5802.65184088956, 5812.0, 5864.44287792659, 5873.3832947539895, 5894.6757928314355, 5899.0, 5926.8738939601335, 5717.914231286451, 5751.1373294006235, 5780.132980053026, 5796.507296879644, 5830.708464576839], "value": 4432.365454961779, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.722602637024, 750.5376875726188, 788.51506323611, 802.9076656184533, 846.5767116441779, 15726.528204153865, 15880.205989700513, 16535.83464165358, 16558.089305088215, 16571.91714041428, 13959.302034898265, 13963.232022238773, 14033.50882719105, 14046.929765351184, 14061.578152655411, 512.928190053391, 790.5691398187972, 810.0, 820.6717313074759, 823.3783493462428, 793.9682412703485, 797.131127071492, 797.6649807081011, 797.7925739307764, 798.1978111997435, 843.0389356134012, 845.6786421159951, 847.3771777743384, 850.7745447456429, 854.4958474500074, 843.582426698785, 843.7342237195326, 843.7932706486889, 843.9915600843974, 846.4371193156544, 607.4684650930436, 612.7212118486078, 613.6410200032941, 615.1848800339568, 618.5762752514541, 15666.295016724274, 15982.680346393123, 15982.76025859613, 15983.600409989727, 16013.759793603105, 13832.792508112387, 14047.157170569697, 14209.786853197107, 14211.0, 14466.927665361605, 12540.686482837911, 12618.621441356774, 12661.556845510362, 12775.61673149807, 13116.606501804961, 3964.3892092133865, 3976.184882099192, 4016.477857878457, 4059.6752259819145, 4108.4453598764085, 86348.56825715894, 86587.5670621643, 87139.0, 87485.0, 91994.08005919922, 11531.250468719507, 11542.538298468135, 11662.650120496399, 11667.824982625267, 11874.76250475003, 23025.88487057554, 23066.308010759705, 23196.0, 23215.651765223534, 23477.765222347898, 8029.5583742894705, 8382.790430239293, 8455.619497122645, 8769.693060742844, 8856.690015849414, 4533.070720502289, 4625.884352891205, 4677.111348843709, 4889.804407823647, 4906.334520993637, 3276.557664715233, 3380.797152170878, 3387.4072037393357, 3392.355452464024, 3398.575178102737, 614.8862460444832, 623.9968800155971, 629.9338569450233, 631.6715308039794, 635.3678090300181, 595.9880802383929, 657.3590749019668, 663.0882536512296, 679.8504329047602, 691.6818263598801, 9305.0, 9733.172680322252, 9823.263255255884, 9852.211823054138, 9902.752431189134, 667.8397184675642, 672.5695554844904, 673.2627772589008, 677.11298199359, 688.5524409134102, 4650.279206722963, 4714.292856071616, 4769.546893045155, 4829.396325459317, 4890.779914903766, 122087.0, 122137.0, 122456.38771806262, 123294.0, 124778.37610811887, 103939.48030259952, 106178.46910765396, 106201.46899265454, 106957.0, 107805.0, 88608.5569572148, 90331.0, 91686.0, 91966.0, 93019.53490232641, 9285.35002549815, 9354.719358418986, 9410.482423466776, 9445.52772361393, 9445.858312125461, 53701.462985369646, 54334.72832635811, 54355.45644543504, 54690.0, 55822.162667559205, 71293.64353178408, 79077.60461197657, 80568.59715701384, 82266.0, 83523.0, 10542.736431589272, 11172.273802202832, 13183.340832958122, 13292.734145317232, 13340.666483337605, 14674.119552826865, 15084.849151508344, 15552.533423997298, 15815.604609884385, 15979.280932358304, 13941.790873136708, 14164.645883852574, 14183.503577374742, 14203.72669568664, 14270.500532481314, 14195.432182712575, 14315.212663303202, 14627.707445851236, 14691.706165876409, 14732.189729564976, 14395.352209150404, 14456.855431445972, 14585.781213281596, 14700.338484768425, 14854.257287135817, 11746.295222286348, 11780.813821770622, 11948.70128246766, 11958.222715523463, 12468.0, 11117.832627573987, 11121.610743623934, 11914.702132446759, 12347.13570050088, 12435.502579896704], "value": 7717.8988313012105, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 106.0, 112.0, 110.0, 112.0, 112.0, 110.0, 112.0, 113.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 120.0, 160.0, 182.0, 118.0, 148.0, 150.0, 125.0, 185.0, 123.0], "value": 146.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 162.0, 163.0, 162.0, 163.0, 167.0, 168.0, 166.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 74.0, 165.0, 76.0, 76.0, 75.0, 75.0, 191.0, 76.0], "value": 96.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 145.0, 84.0, 85.0, 84.0, 151.0, 85.0, 85.0, 152.0], "value": 104.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 122.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 148.0, 148.0, 149.0, 148.0, 148.0, 147.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 97.0, 97.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 53.0, 54.0, 54.0, 53.0, 53.0, 54.0, 54.0, 52.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 64.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 75.0, 76.0, 79.0, 76.0, 78.0, 79.0, 76.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 128.0, 127.0, 128.0, 143.0, 136.0, 124.0, 125.0, 156.0, 126.0], "value": 131.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 157.0, 155.0, 163.0, 164.0, 159.0, 157.0, 159.0, 156.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 63.0, 63.0, 64.0, 62.0, 63.0, 62.0, 63.0, 62.0], "value": 63.3, "unit": "score"}]}]} 05:44:57 INFO - Return code: 0 05:44:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:44:57 ERROR - # TBPL SUCCESS # 05:44:57 INFO - Running post-action listener: _resource_record_post_action 05:44:57 INFO - ##### 05:44:57 INFO - ##### Finished run-tests step (success) 05:44:57 INFO - ##### 05:44:57 INFO - Running post-run listener: _resource_record_post_run 05:44:57 INFO - Total resource usage - Wall time: 1227s; CPU: 14.0%; Read bytes: 12869632; Write bytes: 472776704; Read time: 12392; Write time: 2805760 05:44:57 INFO - TinderboxPrint: CPU usage
13.6% 05:44:57 INFO - TinderboxPrint: I/O read bytes / time
12,869,632 / 12,392 05:44:57 INFO - TinderboxPrint: I/O write bytes / time
472,776,704 / 2,805,760 05:44:57 INFO - TinderboxPrint: CPU idle
8,461.5 (86.4%) 05:44:57 INFO - TinderboxPrint: CPU user
1,209.0 (12.3%) 05:44:57 INFO - TinderboxPrint: Swap in / out
0 / 0 05:44:57 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 11694080; Read time: 0; Write time: 15052 05:44:57 INFO - run-tests - Wall time: 1210s; CPU: 14.0%; Read bytes: 10715136; Write bytes: 461082624; Read time: 12004; Write time: 2790708 05:44:57 INFO - Running post-run listener: _upload_blobber_files 05:44:57 INFO - Blob upload gear active. 05:44:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:44:57 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 05:44:57 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:44:57 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:44:58 INFO - (blobuploader) - INFO - Open directory for files ... 05:44:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:44:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:45:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:45:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:45:07 INFO - (blobuploader) - INFO - Done attempting. 05:45:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:45:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:45:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:45:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:45:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:45:07 INFO - (blobuploader) - INFO - Done attempting. 05:45:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:45:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:45:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:45:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:45:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:45:08 INFO - (blobuploader) - INFO - Done attempting. 05:45:08 INFO - (blobuploader) - INFO - Iteration through files over. 05:45:08 INFO - Return code: 0 05:45:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:45:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:45:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3deae9c47bfde32c35de0190244942e72b2fc6b3e1a143d93bf3e8ce345054b5542acde82f533d51331968bd392d9b09f90badceabcd372fd9dd7cc144ed235d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9886bcea545167863462eb0d9adeccf9b31e968b1ce7593018b37ad51cdd199e81a22d42e3845014c40c5c6f4a533250b387b07bbd86bdb2a4e2bff5d934381", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea264e7af76a7e16e6eaf77847ac515c96455d4ba5712ecedfd7d2cbbfcd4bd19e1003f8372a5ebae251179690ca6e9641412ddf7433bde45ffec6ee7f6e17de"} 05:45:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:45:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:45:08 INFO - Contents: 05:45:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3deae9c47bfde32c35de0190244942e72b2fc6b3e1a143d93bf3e8ce345054b5542acde82f533d51331968bd392d9b09f90badceabcd372fd9dd7cc144ed235d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9886bcea545167863462eb0d9adeccf9b31e968b1ce7593018b37ad51cdd199e81a22d42e3845014c40c5c6f4a533250b387b07bbd86bdb2a4e2bff5d934381", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea264e7af76a7e16e6eaf77847ac515c96455d4ba5712ecedfd7d2cbbfcd4bd19e1003f8372a5ebae251179690ca6e9641412ddf7433bde45ffec6ee7f6e17de"} 05:45:08 INFO - Running post-run listener: copy_logs_to_upload_dir 05:45:08 INFO - Copying logs to upload dir... 05:45:08 INFO - mkdir: /builds/slave/test/build/upload/logs 05:45:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1312.334027 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2016-07-17 05:45:08.617903) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 05:45:08.621359) ========= 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=33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3deae9c47bfde32c35de0190244942e72b2fc6b3e1a143d93bf3e8ce345054b5542acde82f533d51331968bd392d9b09f90badceabcd372fd9dd7cc144ed235d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9886bcea545167863462eb0d9adeccf9b31e968b1ce7593018b37ad51cdd199e81a22d42e3845014c40c5c6f4a533250b387b07bbd86bdb2a4e2bff5d934381", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea264e7af76a7e16e6eaf77847ac515c96455d4ba5712ecedfd7d2cbbfcd4bd19e1003f8372a5ebae251179690ca6e9641412ddf7433bde45ffec6ee7f6e17de"} build_url:https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011365 build_url: 'https://queue.taskcluster.net/v1/task/EjoZQp3HSCO3rCIRNGvjCg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3deae9c47bfde32c35de0190244942e72b2fc6b3e1a143d93bf3e8ce345054b5542acde82f533d51331968bd392d9b09f90badceabcd372fd9dd7cc144ed235d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9886bcea545167863462eb0d9adeccf9b31e968b1ce7593018b37ad51cdd199e81a22d42e3845014c40c5c6f4a533250b387b07bbd86bdb2a4e2bff5d934381", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea264e7af76a7e16e6eaf77847ac515c96455d4ba5712ecedfd7d2cbbfcd4bd19e1003f8372a5ebae251179690ca6e9641412ddf7433bde45ffec6ee7f6e17de"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 05:45:08.648113) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:45:08.648654) ========= 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=33d5923976266a1c518c4bf7000001ab-1468758046.429437-1255255259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004386 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:45:08.731452) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 05:45:08.732201) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 05:45:08.732825) ========= ========= Total master_lag: 0.16 =========