builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-010 starttime: 1468758194.51 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.507354) ========= 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.507788) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.508131) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.525406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.525711) ========= 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=eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-17 05:23:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.2M=0s 2016-07-17 05:23:15 (43.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.196513 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.736092) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.736415) ========= 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=eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015856 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.785513) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:14.785864) ========= 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=eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-17 05:23:15,319 truncating revision to first 12 chars 2016-07-17 05:23:15,319 Setting DEBUG logging. 2016-07-17 05:23:15,319 attempt 1/10 2016-07-17 05:23:15,319 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,792 unpacking tar archive at: fx-team-e7a27a7538b2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.789783 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:15.589569) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:15.589969) ========= script_repo_revision: e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:15.590394) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:15.590688) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-17 05:23:15.602350) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-17 05:23:15.602683) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464 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 --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', 05:23:16 INFO - 'system_bits': '32', 05:23:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:23:16 INFO - 'title': 'talos-linux64-ix-010', 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:16 INFO - Running post-action listener: _resource_record_post_action 05:23:16 INFO - ##### 05:23:16 INFO - ##### Finished clobber step (success) 05:23:16 INFO - ##### 05:23:16 INFO - ##### 05:23:16 INFO - ##### Running read-buildbot-config 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: read_buildbot_config 05:23:16 INFO - Using buildbot properties: 05:23:16 INFO - { 05:23:16 INFO - "project": "", 05:23:16 INFO - "product": "firefox", 05:23:16 INFO - "script_repo_revision": "production", 05:23:16 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:23:16 INFO - "repository": "", 05:23:16 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:23:16 INFO - "buildid": "20160717023020", 05:23:16 INFO - "pgo_build": "True", 05:23:16 INFO - "basedir": "/builds/slave/test", 05:23:16 INFO - "buildnumber": 907, 05:23:16 INFO - "slavename": "talos-linux64-ix-010", 05:23:16 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:23:16 INFO - "platform": "ubuntu64_hw", 05:23:16 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:23:16 INFO - "branch": "fx-team", 05:23:16 INFO - "repo_path": "integration/fx-team", 05:23:16 INFO - "revision": "e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8", 05:23:16 INFO - "stage_platform": "linux64-pgo", 05:23:16 INFO - "builduid": "801c31bf1a9244e7a44a6698d076107e", 05:23:16 INFO - "slavebuilddir": "test" 05:23:16 INFO - } 05:23:16 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:16 INFO - Running post-action listener: _resource_record_post_action 05:23:16 INFO - ##### 05:23:16 INFO - ##### Finished read-buildbot-config step (success) 05:23:16 INFO - ##### 05:23:16 INFO - ##### 05:23:16 INFO - ##### Running download-and-extract 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: download_and_extract 05:23:16 INFO - mkdir: /builds/slave/test/build/tests 05:23: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:23:16 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:16 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:16 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:16 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:16 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:17 INFO - Downloaded 1361 bytes. 05:23:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:23:17 INFO - Using the following test package requirements: 05:23:17 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:23:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:23:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:23:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:17 INFO - u'jsshell-linux-x86_64.zip'], 05:23:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:23:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:23:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:23:17 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:23:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:23:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:23:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:23:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:17 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:17 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:17 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:17 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:17 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:18 INFO - Downloaded 24333242 bytes. 05:23:18 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:18 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:19 INFO - Return code: 0 05:23:19 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:19 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:19 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:19 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:19 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:19 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:19 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:21 INFO - Downloaded 24333242 bytes. 05:23:21 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:21 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:22 INFO - Return code: 0 05:23: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:23:22 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:22 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:22 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:22 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:22 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:23 INFO - Downloaded 14403317 bytes. 05:23:23 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:23 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:23 INFO - Return code: 0 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.tar.bz2 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.tar.bz2 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.tar.bz2 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.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:23:23 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:25 INFO - Downloaded 60814853 bytes. 05:23:25 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:25 INFO - mkdir: /builds/slave/test/properties 05:23:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:23:25 INFO - Writing to file /builds/slave/test/properties/build_url 05:23:25 INFO - Contents: 05:23:25 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:25 INFO - Running post-action listener: _resource_record_post_action 05:23:25 INFO - Running post-action listener: set_extra_try_arguments 05:23:25 INFO - ##### 05:23:25 INFO - ##### Finished download-and-extract step (success) 05:23:25 INFO - ##### 05:23:25 INFO - ##### 05:23:25 INFO - ##### Running populate-webroot step. 05:23:25 INFO - ##### 05:23:25 INFO - Running pre-action listener: _resource_record_pre_action 05:23:25 INFO - Running main action method: populate_webroot 05:23:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:23:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:23:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:23:25 INFO - '--tppagecycles', 05:23:25 INFO - '7'], 05:23:25 INFO - 'tests': ['tsvgm']}}, 05:23:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:23:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:23:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:23:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:23:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:23:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:23:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:25 INFO - 'talos_options': ['--disable-e10s'], 05:23:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:23:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:25 INFO - 'talos_options': ['--disable-e10s'], 05:23:25 INFO - 'tests': ['damp', 'tps']}, 05:23:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:25 INFO - 'tests': ['damp', 'tps']}, 05:23:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:23:25 INFO - 'tests': ['dromaeo_dom']}, 05:23:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:23:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:23:25 INFO - 'tests': ['basic_compositor_video']}, 05:23:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:23:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:23:25 INFO - 'tests': ['a11yr', 05:23:25 INFO - 'ts_paint', 05:23:25 INFO - 'tpaint', 05:23:25 INFO - 'sessionrestore', 05:23:25 INFO - 'sessionrestore_no_auto_restore', 05:23:25 INFO - 'tabpaint']}, 05:23:25 INFO - 'other-e10s': {'tests': ['a11yr', 05:23:25 INFO - 'ts_paint', 05:23:25 INFO - 'tpaint', 05:23:25 INFO - 'sessionrestore', 05:23:25 INFO - 'sessionrestore_no_auto_restore', 05:23:25 INFO - 'tabpaint']}, 05:23:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:23:25 INFO - 'ts_paint', 05:23:25 INFO - 'tpaint', 05:23:25 INFO - 'sessionrestore', 05:23:25 INFO - 'sessionrestore_no_auto_restore', 05:23:25 INFO - 'tabpaint']}, 05:23:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:23:25 INFO - 'ts_paint', 05:23:25 INFO - 'tpaint', 05:23:25 INFO - 'sessionrestore', 05:23:25 INFO - 'sessionrestore_no_auto_restore', 05:23:25 INFO - 'tabpaint']}, 05:23:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:23:25 INFO - 'tests': ['a11yr', 05:23:25 INFO - 'ts_paint', 05:23:25 INFO - 'tpaint', 05:23:25 INFO - 'sessionrestore', 05:23:25 INFO - 'sessionrestore_no_auto_restore', 05:23:25 INFO - 'tabpaint']}, 05:23:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:23:25 INFO - 'tests': ['a11yr', 05:23:25 INFO - 'ts_paint', 05:23:25 INFO - 'tpaint', 05:23:25 INFO - 'sessionrestore', 05:23:25 INFO - 'sessionrestore_no_auto_restore', 05:23:25 INFO - 'tabpaint']}, 05:23:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:23:25 INFO - 'tests': ['tsvgx', 05:23:25 INFO - 'tsvgr_opacity', 05:23:25 INFO - 'tart', 05:23:25 INFO - 'tscrollx', 05:23:25 INFO - 'cart']}, 05:23:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:23:25 INFO - 'tsvgr_opacity', 05:23:25 INFO - 'tart', 05:23:25 INFO - 'tscrollx', 05:23:25 INFO - 'cart']}, 05:23:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:25 INFO - 'talos_options': ['--disable-e10s'], 05:23:25 INFO - 'tests': ['tp5o']}, 05:23:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:25 INFO - 'tests': ['tp5o']}, 05:23:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:25 INFO - 'talos_options': ['--disable-e10s', 05:23:25 INFO - '--xperf_path', 05:23:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:25 INFO - 'tests': ['tp5n']}, 05:23:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:23:25 INFO - 'talos_options': ['--xperf_path', 05:23:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:23:25 INFO - 'tests': ['tp5n']}}, 05:23:25 INFO - 'talos.zip': {'path': '', 05:23:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:23:25 INFO - Running post-action listener: _resource_record_post_action 05:23:25 INFO - ##### 05:23:25 INFO - ##### Finished populate-webroot step (success) 05:23:25 INFO - ##### 05:23:25 INFO - ##### 05:23:25 INFO - ##### Running create-virtualenv step. 05:23:25 INFO - ##### 05:23:25 INFO - Running pre-action listener: _resource_record_pre_action 05:23:25 INFO - Running main action method: create_virtualenv 05:23:25 INFO - Creating virtualenv /builds/slave/test/build/venv 05:23:25 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:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:23:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:23:26 INFO - Using real prefix '/usr' 05:23:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:23:26 INFO - Installing distribute.............................................................................................................................................................................................done. 05:23:28 INFO - Installing pip.................done. 05:23:28 INFO - Return code: 0 05:23:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:23:28 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:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:28 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:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:28 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 0x1893030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b523b0>, '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 0x1b532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b53740>, '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': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', '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:23:28 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:23:28 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:23:28 INFO - Using env: {'DISPLAY': ':0', 05:23:28 INFO - 'HOME': '/home/cltbld', 05:23:28 INFO - 'LANG': 'en_US.UTF-8', 05:23:28 INFO - 'LANGUAGE': 'en_US:en', 05:23:28 INFO - 'LOGNAME': 'cltbld', 05:23:28 INFO - 'MAIL': '/var/mail/cltbld', 05:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:28 INFO - 'MOZ_NO_REMOTE': '1', 05:23:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:28 INFO - 'NO_EM_RESTART': '1', 05:23:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:28 INFO - 'PWD': '/builds/slave/test', 05:23:28 INFO - 'SHELL': '/bin/bash', 05:23:28 INFO - 'SHLVL': '1', 05:23:28 INFO - 'TERM': 'linux', 05:23:28 INFO - 'TMOUT': '86400', 05:23:28 INFO - 'USER': 'cltbld', 05:23:28 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', 05:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:28 INFO - '_': '/tools/buildbot/bin/python'} 05:23:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:28 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:23:28 INFO - Cleaning up... 05:23:28 INFO - Return code: 0 05:23:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:23:28 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:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:28 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:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:28 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 0x1893030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b523b0>, '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 0x1b532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b53740>, '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': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', '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:23:28 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:23:28 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:23:28 INFO - Using env: {'DISPLAY': ':0', 05:23:28 INFO - 'HOME': '/home/cltbld', 05:23:28 INFO - 'LANG': 'en_US.UTF-8', 05:23:28 INFO - 'LANGUAGE': 'en_US:en', 05:23:28 INFO - 'LOGNAME': 'cltbld', 05:23:28 INFO - 'MAIL': '/var/mail/cltbld', 05:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:28 INFO - 'MOZ_NO_REMOTE': '1', 05:23:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:28 INFO - 'NO_EM_RESTART': '1', 05:23:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:28 INFO - 'PWD': '/builds/slave/test', 05:23:28 INFO - 'SHELL': '/bin/bash', 05:23:28 INFO - 'SHLVL': '1', 05:23:28 INFO - 'TERM': 'linux', 05:23:28 INFO - 'TMOUT': '86400', 05:23:28 INFO - 'USER': 'cltbld', 05:23:28 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', 05:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:28 INFO - '_': '/tools/buildbot/bin/python'} 05:23:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:28 INFO - Downloading/unpacking psutil>=3.1.1 05:23:28 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:23:28 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:23:28 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:23:28 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:23:28 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:23:28 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:23:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:23:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:30 INFO - Installing collected packages: psutil 05:23:30 INFO - Running setup.py install for psutil 05:23:31 INFO - building 'psutil._psutil_linux' extension 05:23:31 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:23:31 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:23:31 INFO - building 'psutil._psutil_posix' extension 05:23:31 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:23:31 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:23:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:23:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:23:31 INFO - Successfully installed psutil 05:23:31 INFO - Cleaning up... 05:23:31 INFO - Return code: 0 05:23:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:31 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 0x1893030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b523b0>, '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 0x1b532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b53740>, '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': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', '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:23:31 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:23:31 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:23:31 INFO - Using env: {'DISPLAY': ':0', 05:23:31 INFO - 'HOME': '/home/cltbld', 05:23:31 INFO - 'LANG': 'en_US.UTF-8', 05:23:31 INFO - 'LANGUAGE': 'en_US:en', 05:23:31 INFO - 'LOGNAME': 'cltbld', 05:23:31 INFO - 'MAIL': '/var/mail/cltbld', 05:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:31 INFO - 'MOZ_NO_REMOTE': '1', 05:23:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:31 INFO - 'NO_EM_RESTART': '1', 05:23:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:31 INFO - 'PWD': '/builds/slave/test', 05:23:31 INFO - 'SHELL': '/bin/bash', 05:23:31 INFO - 'SHLVL': '1', 05:23:31 INFO - 'TERM': 'linux', 05:23:31 INFO - 'TMOUT': '86400', 05:23:31 INFO - 'USER': 'cltbld', 05:23:31 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', 05:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:31 INFO - '_': '/tools/buildbot/bin/python'} 05:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:23:31 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:23:31 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:23:31 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:23:31 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:23:31 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:23:31 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:23:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:23:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:23:33 INFO - Installing collected packages: mozsystemmonitor 05:23:33 INFO - Running setup.py install for mozsystemmonitor 05:23:33 INFO - Successfully installed mozsystemmonitor 05:23:33 INFO - Cleaning up... 05:23:33 INFO - Return code: 0 05:23:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:23:33 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:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:33 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:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:33 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 0x1893030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b523b0>, '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 0x1b532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b53740>, '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': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', '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:23:33 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:23:33 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:23:33 INFO - Using env: {'DISPLAY': ':0', 05:23:33 INFO - 'HOME': '/home/cltbld', 05:23:33 INFO - 'LANG': 'en_US.UTF-8', 05:23:33 INFO - 'LANGUAGE': 'en_US:en', 05:23:33 INFO - 'LOGNAME': 'cltbld', 05:23:33 INFO - 'MAIL': '/var/mail/cltbld', 05:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:33 INFO - 'MOZ_NO_REMOTE': '1', 05:23:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:33 INFO - 'NO_EM_RESTART': '1', 05:23:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:33 INFO - 'PWD': '/builds/slave/test', 05:23:33 INFO - 'SHELL': '/bin/bash', 05:23:33 INFO - 'SHLVL': '1', 05:23:33 INFO - 'TERM': 'linux', 05:23:33 INFO - 'TMOUT': '86400', 05:23:33 INFO - 'USER': 'cltbld', 05:23:33 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', 05:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:33 INFO - '_': '/tools/buildbot/bin/python'} 05:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:34 INFO - Downloading/unpacking jsonschema==2.5.1 05:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:23:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:36 INFO - Downloading functools32-3.2.3-2.tar.gz 05:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:23:36 INFO - warning: no files found matching '*.txt' 05:23:36 INFO - no previously-included directories found matching 'build' 05:23:36 INFO - no previously-included directories found matching 'dist' 05:23:36 INFO - no previously-included directories found matching '.git*' 05:23:36 INFO - Installing collected packages: jsonschema, functools32 05:23:36 INFO - Running setup.py install for functools32 05:23:36 INFO - warning: no files found matching '*.txt' 05:23:36 INFO - no previously-included directories found matching 'build' 05:23:36 INFO - no previously-included directories found matching 'dist' 05:23:36 INFO - no previously-included directories found matching '.git*' 05:23:36 INFO - Successfully installed jsonschema functools32 05:23:36 INFO - Cleaning up... 05:23:36 INFO - Return code: 0 05:23:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:36 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 0x1893030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b523b0>, '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 0x1b532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b53740>, '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': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', '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:23:36 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:23:36 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:23:36 INFO - Using env: {'DISPLAY': ':0', 05:23:36 INFO - 'HOME': '/home/cltbld', 05:23:36 INFO - 'LANG': 'en_US.UTF-8', 05:23:36 INFO - 'LANGUAGE': 'en_US:en', 05:23:36 INFO - 'LOGNAME': 'cltbld', 05:23:36 INFO - 'MAIL': '/var/mail/cltbld', 05:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:36 INFO - 'MOZ_NO_REMOTE': '1', 05:23:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:36 INFO - 'NO_EM_RESTART': '1', 05:23:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:36 INFO - 'PWD': '/builds/slave/test', 05:23:36 INFO - 'SHELL': '/bin/bash', 05:23:36 INFO - 'SHLVL': '1', 05:23:36 INFO - 'TERM': 'linux', 05:23:36 INFO - 'TMOUT': '86400', 05:23:36 INFO - 'USER': 'cltbld', 05:23:36 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', 05:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:36 INFO - '_': '/tools/buildbot/bin/python'} 05:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:36 INFO - Downloading/unpacking blobuploader==1.2.4 05:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:36 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:23:38 INFO - Downloading blobuploader-1.2.4.tar.gz 05:23:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:23:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:23:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:23:39 INFO - Downloading docopt-0.6.1.tar.gz 05:23:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:23:39 INFO - Installing collected packages: blobuploader, requests, docopt 05:23:39 INFO - Running setup.py install for blobuploader 05:23:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:23:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:23:39 INFO - Running setup.py install for requests 05:23:39 INFO - Running setup.py install for docopt 05:23:39 INFO - Successfully installed blobuploader requests docopt 05:23:39 INFO - Cleaning up... 05:23:39 INFO - Return code: 0 05:23:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:39 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 0x1893030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b523b0>, '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 0x1b532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b53740>, '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': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', '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:23:39 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:23:39 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:23:39 INFO - Using env: {'DISPLAY': ':0', 05:23:39 INFO - 'HOME': '/home/cltbld', 05:23:39 INFO - 'LANG': 'en_US.UTF-8', 05:23:39 INFO - 'LANGUAGE': 'en_US:en', 05:23:39 INFO - 'LOGNAME': 'cltbld', 05:23:39 INFO - 'MAIL': '/var/mail/cltbld', 05:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:39 INFO - 'MOZ_NO_REMOTE': '1', 05:23:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:39 INFO - 'NO_EM_RESTART': '1', 05:23:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:39 INFO - 'PWD': '/builds/slave/test', 05:23:39 INFO - 'SHELL': '/bin/bash', 05:23:39 INFO - 'SHLVL': '1', 05:23:39 INFO - 'TERM': 'linux', 05:23:39 INFO - 'TMOUT': '86400', 05:23:39 INFO - 'USER': 'cltbld', 05:23:39 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', 05:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:39 INFO - '_': '/tools/buildbot/bin/python'} 05:23:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:40 INFO - Running setup.py (path:/tmp/pip-Mj_8H5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:40 INFO - Running setup.py (path:/tmp/pip-FT4iEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:40 INFO - Running setup.py (path:/tmp/pip-NHAn9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:40 INFO - Running setup.py (path:/tmp/pip-IZD_wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:40 INFO - Running setup.py (path:/tmp/pip-1h_xW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:40 INFO - Running setup.py (path:/tmp/pip-B29mS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:40 INFO - Running setup.py (path:/tmp/pip-64AZsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:40 INFO - Running setup.py (path:/tmp/pip-yTIZoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:40 INFO - Running setup.py (path:/tmp/pip-G7xgDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:40 INFO - Running setup.py (path:/tmp/pip-BzTNIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:40 INFO - Running setup.py (path:/tmp/pip-LzZUud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:40 INFO - Running setup.py (path:/tmp/pip-Zdker4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:40 INFO - Running setup.py (path:/tmp/pip-0HZNfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:41 INFO - Running setup.py (path:/tmp/pip-ZGSIvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:41 INFO - Running setup.py (path:/tmp/pip-yWuQBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:41 INFO - Running setup.py (path:/tmp/pip-NOOfqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:41 INFO - Running setup.py (path:/tmp/pip-GCtF4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:23:41 INFO - Running setup.py install for manifestparser 05:23:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:23:41 INFO - Running setup.py install for mozcrash 05:23:41 INFO - Running setup.py install for mozdebug 05:23:41 INFO - Running setup.py install for mozdevice 05:23:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:23:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:23:41 INFO - Running setup.py install for mozfile 05:23:41 INFO - Running setup.py install for mozhttpd 05:23:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:23:41 INFO - Running setup.py install for mozinfo 05:23:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:23:42 INFO - Running setup.py install for mozInstall 05:23:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:23:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:23:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:23:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:23:42 INFO - Running setup.py install for mozleak 05:23:42 INFO - Running setup.py install for mozlog 05:23:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:23:42 INFO - Running setup.py install for moznetwork 05:23:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:23:42 INFO - Running setup.py install for mozprocess 05:23:42 INFO - Running setup.py install for mozprofile 05:23:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:23:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:23:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:23:42 INFO - Running setup.py install for mozrunner 05:23:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:23:42 INFO - Running setup.py install for mozscreenshot 05:23:42 INFO - Running setup.py install for moztest 05:23:43 INFO - Running setup.py install for mozversion 05:23:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:23:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:23:43 INFO - Cleaning up... 05:23:43 INFO - Return code: 0 05:23:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:43 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 0x1893030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b523b0>, '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 0x1b532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b53740>, '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': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', '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:23:43 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:23:43 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:23:43 INFO - Using env: {'DISPLAY': ':0', 05:23:43 INFO - 'HOME': '/home/cltbld', 05:23:43 INFO - 'LANG': 'en_US.UTF-8', 05:23:43 INFO - 'LANGUAGE': 'en_US:en', 05:23:43 INFO - 'LOGNAME': 'cltbld', 05:23:43 INFO - 'MAIL': '/var/mail/cltbld', 05:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:43 INFO - 'MOZ_NO_REMOTE': '1', 05:23:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:43 INFO - 'NO_EM_RESTART': '1', 05:23:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:43 INFO - 'PWD': '/builds/slave/test', 05:23:43 INFO - 'SHELL': '/bin/bash', 05:23:43 INFO - 'SHLVL': '1', 05:23:43 INFO - 'TERM': 'linux', 05:23:43 INFO - 'TMOUT': '86400', 05:23:43 INFO - 'USER': 'cltbld', 05:23:43 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', 05:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:43 INFO - '_': '/tools/buildbot/bin/python'} 05:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:23:43 INFO - Running setup.py (path:/tmp/pip-zpt8vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:23:43 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:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:23:43 INFO - Running setup.py (path:/tmp/pip-QTQzDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:23:43 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:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:23:43 INFO - Running setup.py (path:/tmp/pip-IVd_r7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:23:43 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:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:23:43 INFO - Running setup.py (path:/tmp/pip-V8lqx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:23:43 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:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:23:43 INFO - Running setup.py (path:/tmp/pip-3SIP57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:23:43 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:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:23:43 INFO - Running setup.py (path:/tmp/pip-lz7ajO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:23:43 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:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:23:43 INFO - Running setup.py (path:/tmp/pip-3O42w5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:23:43 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:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:23:43 INFO - Running setup.py (path:/tmp/pip-S6pqDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:23:43 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:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:23:43 INFO - Running setup.py (path:/tmp/pip-HMnyjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:23:43 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:23:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:23:43 INFO - Running setup.py (path:/tmp/pip-cgYhuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:23:44 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:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:23:44 INFO - Running setup.py (path:/tmp/pip-qHY7Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:23:44 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:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:23:44 INFO - Running setup.py (path:/tmp/pip-u_ioAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:23:44 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:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:23:44 INFO - Running setup.py (path:/tmp/pip-fGdNwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:23:44 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:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:23:44 INFO - Running setup.py (path:/tmp/pip-iRD5Cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:23:44 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:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:44 INFO - Running setup.py (path:/tmp/pip-U8CxcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:23:44 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:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:23:44 INFO - Running setup.py (path:/tmp/pip-114xM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:23:44 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:23:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:23:44 INFO - Running setup.py (path:/tmp/pip-qfBuRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:23:44 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:23:44 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:23:44 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:23:44 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:23:44 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:23:44 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:23:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:23:44 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:23:44 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:23:44 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:23:44 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:23:44 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:23:44 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:23:46 INFO - Downloading blessings-1.6.tar.gz 05:23:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:23:46 INFO - Installing collected packages: blessings 05:23:46 INFO - Running setup.py install for blessings 05:23:46 INFO - Successfully installed blessings 05:23:46 INFO - Cleaning up... 05:23:47 INFO - Return code: 0 05:23:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:23:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:47 INFO - Reading from file tmpfile_stdout 05:23:47 INFO - Current package versions: 05:23:47 INFO - argparse == 1.2.1 05:23:47 INFO - blessings == 1.6 05:23:47 INFO - blobuploader == 1.2.4 05:23:47 INFO - docopt == 0.6.1 05:23:47 INFO - functools32 == 3.2.3-2 05:23:47 INFO - jsonschema == 2.5.1 05:23:47 INFO - manifestparser == 1.1 05:23:47 INFO - mozInstall == 1.12 05:23:47 INFO - mozcrash == 0.17 05:23:47 INFO - mozdebug == 0.1 05:23:47 INFO - mozdevice == 0.48 05:23:47 INFO - mozfile == 1.2 05:23:47 INFO - mozhttpd == 0.7 05:23:47 INFO - mozinfo == 0.9 05:23:47 INFO - mozleak == 0.1 05:23:47 INFO - mozlog == 3.2 05:23:47 INFO - moznetwork == 0.27 05:23:47 INFO - mozprocess == 0.23 05:23:47 INFO - mozprofile == 0.28 05:23:47 INFO - mozrunner == 6.12 05:23:47 INFO - mozscreenshot == 0.1 05:23:47 INFO - mozsystemmonitor == 0.3 05:23:47 INFO - moztest == 0.7 05:23:47 INFO - mozversion == 1.4 05:23:47 INFO - psutil == 3.1.1 05:23:47 INFO - requests == 1.2.3 05:23:47 INFO - wsgiref == 0.1.2 05:23:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:23: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:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:47 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 0x1893030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b523b0>, '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 0x1b532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b53740>, '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': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', '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:23:47 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:23:47 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:23:47 INFO - Using env: {'DISPLAY': ':0', 05:23:47 INFO - 'HOME': '/home/cltbld', 05:23:47 INFO - 'LANG': 'en_US.UTF-8', 05:23:47 INFO - 'LANGUAGE': 'en_US:en', 05:23:47 INFO - 'LOGNAME': 'cltbld', 05:23:47 INFO - 'MAIL': '/var/mail/cltbld', 05:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:47 INFO - 'MOZ_NO_REMOTE': '1', 05:23:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:47 INFO - 'NO_EM_RESTART': '1', 05:23:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:47 INFO - 'PWD': '/builds/slave/test', 05:23:47 INFO - 'SHELL': '/bin/bash', 05:23:47 INFO - 'SHLVL': '1', 05:23:47 INFO - 'TERM': 'linux', 05:23:47 INFO - 'TMOUT': '86400', 05:23:47 INFO - 'USER': 'cltbld', 05:23:47 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', 05:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:47 INFO - '_': '/tools/buildbot/bin/python'} 05:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:47 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:23:47 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:23:47 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:23:47 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:23:47 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:23:47 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:23:47 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:23:47 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:23:47 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:23:47 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:23:47 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:23:47 INFO - Cleaning up... 05:23:47 INFO - Return code: 0 05:23:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:23: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:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:23:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23: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:23:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:23:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:23:47 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 0x1893030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b54180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b523b0>, '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 0x1b532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b53740>, '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': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', '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:23:47 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:23:47 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:23:47 INFO - Using env: {'DISPLAY': ':0', 05:23:47 INFO - 'HOME': '/home/cltbld', 05:23:47 INFO - 'LANG': 'en_US.UTF-8', 05:23:47 INFO - 'LANGUAGE': 'en_US:en', 05:23:47 INFO - 'LOGNAME': 'cltbld', 05:23:47 INFO - 'MAIL': '/var/mail/cltbld', 05:23:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:23:47 INFO - 'MOZ_NO_REMOTE': '1', 05:23:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:23:47 INFO - 'NO_EM_RESTART': '1', 05:23:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:23:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:23:47 INFO - 'PWD': '/builds/slave/test', 05:23:47 INFO - 'SHELL': '/bin/bash', 05:23:47 INFO - 'SHLVL': '1', 05:23:47 INFO - 'TERM': 'linux', 05:23:47 INFO - 'TMOUT': '86400', 05:23:47 INFO - 'USER': 'cltbld', 05:23:47 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', 05:23:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:23:47 INFO - '_': '/tools/buildbot/bin/python'} 05:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:23:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:23:47 INFO - Cleaning up... 05:23:47 INFO - Return code: 0 05:23:47 INFO - Running post-action listener: _resource_record_post_action 05:23:47 INFO - Running post-action listener: _start_resource_monitoring 05:23:47 INFO - Starting resource monitoring. 05:23:47 INFO - ##### 05:23:47 INFO - ##### Finished create-virtualenv step (success) 05:23:47 INFO - ##### 05:23:47 INFO - ##### 05:23:47 INFO - ##### Running install step. 05:23:47 INFO - ##### 05:23:47 INFO - Running pre-action listener: _resource_record_pre_action 05:23:47 INFO - Running main action method: install 05:23:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:23:47 INFO - Reading from file tmpfile_stdout 05:23:47 INFO - Detecting whether we're running mozinstall >=1.0... 05:23:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:23:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:23:48 INFO - Reading from file tmpfile_stdout 05:23:48 INFO - Output received: 05:23:48 INFO - Usage: mozinstall [options] installer 05:23:48 INFO - Options: 05:23:48 INFO - -h, --help show this help message and exit 05:23:48 INFO - -d DEST, --destination=DEST 05:23:48 INFO - Directory to install application into. [default: 05:23:48 INFO - "/builds/slave/test"] 05:23:48 INFO - --app=APP Application being installed. [default: firefox] 05:23:48 INFO - mkdir: /builds/slave/test/build/application 05:23:48 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:23:48 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:05 INFO - Reading from file tmpfile_stdout 05:24:05 INFO - Output received: 05:24:05 INFO - /builds/slave/test/build/application/firefox/firefox 05:24:05 INFO - Running post-action listener: _resource_record_post_action 05:24:05 INFO - ##### 05:24:05 INFO - ##### Finished install step (success) 05:24:05 INFO - ##### 05:24:05 INFO - ##### 05:24:05 INFO - ##### Running run-tests step. 05:24:05 INFO - ##### 05:24:05 INFO - Running pre-action listener: _resource_record_pre_action 05:24:05 INFO - Running main action method: run_tests 05:24:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:24:05 INFO - Python 2.7.3 05:24:05 INFO - Return code: 0 05:24:05 INFO - grabbing minidump binary from tooltool 05:24:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:05 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 0x1b523b0>, '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 0x1b532b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b53740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:24:05 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:05 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:05 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:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:24:05 INFO - Return code: 0 05:24:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:24:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:24:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:24:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:24:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:24:05 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', '--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-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:24:05 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 --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-010 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:24:05 INFO - Using env: {'DISPLAY': ':0', 05:24:05 INFO - 'HOME': '/home/cltbld', 05:24:05 INFO - 'LANG': 'en_US.UTF-8', 05:24:05 INFO - 'LANGUAGE': 'en_US:en', 05:24:05 INFO - 'LOGNAME': 'cltbld', 05:24:05 INFO - 'MAIL': '/var/mail/cltbld', 05:24:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:24:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:24:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:05 INFO - 'MOZ_NO_REMOTE': '1', 05:24:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:24:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:24:05 INFO - 'NO_EM_RESTART': '1', 05:24:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:24:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:05 INFO - 'PWD': '/builds/slave/test', 05:24:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:24:05 INFO - 'SHELL': '/bin/bash', 05:24:05 INFO - 'SHLVL': '1', 05:24:05 INFO - 'TERM': 'linux', 05:24:05 INFO - 'TMOUT': '86400', 05:24:05 INFO - 'USER': 'cltbld', 05:24:05 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464', 05:24:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:05 INFO - '_': '/tools/buildbot/bin/python'} 05:24:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--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-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:24:05 INFO - mozversion application_buildid: 20160717023020 05:24:05 INFO - mozversion application_changeset: e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 05:24:05 INFO - mozversion application_display_name: Nightly 05:24:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:24:05 INFO - mozversion application_name: Firefox 05:24:05 INFO - mozversion application_remotingname: firefox 05:24:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:24:05 INFO - mozversion application_vendor: Mozilla 05:24:05 INFO - mozversion application_version: 50.0a1 05:24:05 INFO - mozversion platform_buildid: 20160717023020 05:24:05 INFO - mozversion platform_changeset: e7a27a7538b2bee268abdb08f4f3a6e41c5c58c8 05:24:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:24:05 INFO - mozversion platform_version: 50.0a1 05:24:05 INFO - using testdate: 1468758245 05:24:05 INFO - actual date: 1468758245 05:24:05 INFO - starting webserver on 'localhost:52216' 05:24:05 INFO - SUITE-START | Running 2 tests 05:24:05 INFO - TEST-START | dromaeo_css 05:24:05 INFO - operating with platform_type : linux_ 05:24:05 INFO - Initialising browser for dromaeo_css test... 05:24:05 INFO - TEST-INFO | started process 15361 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4nYNdE/profile http://localhost:52216/getInfo.html) 05:24:09 INFO - PROCESS | 15361 | JavaScript error: , line 0: uncaught exception: undefined 05:24:10 INFO - PROCESS | 15361 | __metrics Screen width/height:1600/1200 05:24:10 INFO - PROCESS | 15361 | colorDepth:24 05:24:10 INFO - PROCESS | 15361 | Browser inner width/height: 1024/697 05:24:10 INFO - PROCESS | 15361 | __metrics 05:24:10 INFO - PROCESS | 15361 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:24:10 INFO - PROCESS | 15361 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:24:11 INFO - PROCESS | 15361 | console.error: PushService: 05:24:11 INFO - PROCESS | 15361 | stateChangeProcessEnqueue: Error transitioning state 05:24:11 INFO - PROCESS | 15361 | UnknownError 05:24:11 INFO - TEST-INFO | 15361: exit 0 05:24:11 INFO - Browser initialized. 05:24:11 INFO - Running cycle 1/1 for dromaeo_css test... 05:24:11 INFO - TEST-INFO | started process 15445 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4nYNdE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:23 INFO - PROCESS | 15445 | RSS: Main: 157900800 05:24:23 INFO - PROCESS | 15445 | 05:25:15 INFO - PROCESS | 15445 | 1468758315217 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:27:22 INFO - PROCESS | 15445 | Cycle 1(1): loaded http://localhost:52216/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52216/tests/dromaeo/cssquery-ext.html) 05:27:24 INFO - PROCESS | 15445 | RSS: Main: 238440448 05:27:24 INFO - PROCESS | 15445 | 05:30:26 INFO - PROCESS | 15445 | Cycle 1(1): loaded http://localhost:52216/tests/dromaeo/cssquery-ext.html (next: http://localhost:52216/tests/dromaeo/cssquery-jquery.html) 05:30:27 INFO - PROCESS | 15445 | RSS: Main: 241635328 05:30:27 INFO - PROCESS | 15445 | 05:33:29 INFO - PROCESS | 15445 | Cycle 1(1): loaded http://localhost:52216/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52216/tests/dromaeo/cssquery-mootools.html) 05:33:29 INFO - PROCESS | 15445 | RSS: Main: 238546944 05:33:29 INFO - PROCESS | 15445 | 05:36:31 INFO - PROCESS | 15445 | Cycle 1(1): loaded http://localhost:52216/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52216/tests/dromaeo/cssquery-prototype.html) 05:36:32 INFO - PROCESS | 15445 | RSS: Main: 239804416 05:36:32 INFO - PROCESS | 15445 | 05:39:34 INFO - PROCESS | 15445 | Cycle 1(1): loaded http://localhost:52216/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52216/tests/dromaeo/cssquery-yui.html) 05:39:35 INFO - PROCESS | 15445 | RSS: Main: 201080832 05:39:35 INFO - PROCESS | 15445 | 05:42:38 INFO - PROCESS | 15445 | Cycle 1(1): loaded http://localhost:52216/tests/dromaeo/cssquery-yui.html (next: http://localhost:52216/tests/dromaeo/cssquery-dojo.html) 05:42:38 INFO - PROCESS | 15445 | RSS: Main: 281866240 05:42:38 INFO - PROCESS | 15445 | 05:42:38 INFO - PROCESS | 15445 | __start_tp_report 05:42:38 INFO - PROCESS | 15445 | _x_x_mozilla_page_load 05:42:38 INFO - PROCESS | 15445 | _x_x_mozilla_page_load_details 05:42:38 INFO - PROCESS | 15445 | |i|pagename|runs| 05:42:38 INFO - PROCESS | 15445 | |0;dojo.html;2994.7753918456115;3013.1864396612928;3035.1956731466157;3037.5443683447493;3052.938941221176;5130.333056702628;5132.999065182291;5209.817656382027;5241.10901146805;5244.947550524489;5237.973810130953;5251.107311757001;5319.787208511664;5355.036093503168;5379.757910894009;4726.550977657125;4801.975990120044;4812.374391329122;4843.733594652293;5027.321311622939;4930.359053323063;4949.356583644121;4982.451930287665;5000.07498612756;5014.047331007116;4751.501092385284;4760.285957106427;4800.087983283165;4815.012922350945;4922.901541969159;4693.413323334583;4697.88255293617;4712.0811441768865;4777.713337199744;4897.485763994765;4697.577218050357;4734.881627959294;4735.052989402134;4782.258749893737;4795.664303498756;5171.198464238049;5186.507281808223;5214.374275086976;5239.528442440199;5276.472352764731;5648.802291919763;5666.660000399989;5674.914876276859;5678.659280443187;5681.829545113653;5648.039833228361;5648.858778530528;5683.829485115453;5695.34503532095;5698.601097923147;5186.559142472895;5214.270002199695;5221.843344699665;5226.189940559219;5313.043652142613;5722.141678748221;5725.742341594639;5777.364489906107;5780.075187969905;5783.132530120515;5565.499105080562;5568.693721845257;5569.582281328917;5571.331440227118;5572.331320241597;5436.782528698808;5589.580781441408;5651.9152212716845;5661.518770904437;5682.6306290091015;5636.210930469655;5665.773369065191;5667.858303542444;5668.178114173422;5669.716514174274;5393.110136827458;5681.346645135863;5689.516391106719;5690.573207009491;5697.800576979786;4240.49114106303;4262.957370426318;4285.807138678767;4328.372386004012;4329.935050974238;3800.7909564974193;3833.4058220975794;3845.6346647068485;3874.1864208515963;3880.049387899983;5178.948210517922;5303.231031500488;5309.33633295838;5310.707911064928;5355.544778693777;5536.916946245809;5547.5007249347755;5561.416051314634;5585.162225666182;5608.747606357723;5250.894982100336;5284.9471505284455;5286.788528458896;5287.07476191665;5291.788328466818;4741.881452963704;4811.158047341702;4852.660313778002;4855.223164293696;4895.04546613407;5723.513501352431;5778.711064446761;5780.393058728773;5807.0998995155815;5830.5044071254415;5617.859553511032;5619.2133101365025;5619.634723742944;5621.550275977994;5623.550115990629;5650;5682.204491371294;5751.194832723506;5776.624519406225;5786.537077033911;5477.041517734301;5506.614536982373;5511.063119269654;5515.4760297771645;5577.330720313588;5575.888482230252;5597.860053498533;5608.327000759935;5615.045442274904;5615.887682246251;5257.658252213594;5431.83704488866;5480.205370221375;5482.862928426821;5524.06090964545;5432.59255555827;5465.535429488538;5467.507924286833;5479.068558345011;5522.889542209214;5373.2746079278795;5379.2469054333205;5424.105022671373;5424.566034717134;5461.344638643388;5410;5528.695921724344;5533.418991006048;5579;5595.300587426572;5458.836234912959;5502.614816962774;5503.889922201453;5510.531604813636;5519.061759500974 05:42:38 INFO - PROCESS | 15445 | |1;ext.html;596.0939372154326;608.3034925010862;624.8968920128182;630.010882913825;630.9968450157749;45365.092698146014;47059.294110588286;47099.52900470994;47120;49637.00725985478;46752.532474675245;46962.76518617402;46980.29529557059;47202.29196562054;47228.29157562631;9054.592543335566;9217.585208665592;9218.953905230464;9221.170094691477;9223.400478968846;9109.635614575409;9190.31072669549;9195.402298850588;9196.448213107202;9197.402168859036;9205.861912071325;9254;9255.490947997858;9257.16685498305;9258.907410925873;9141.725748227564;9261.72214833556;9270.814583708288;9276.675316363928;9277;9068.909310906873;9124.0419755926;9134.771630709285;9135.817283654289;9141.31440141992;44823.32765008527;45233.54766452326;45248.3212751809;45249.321260181125;45298;3577.3918433866306;3764.7741135531683;3833.9616603833883;3918.2555314490155;4153.460050193486;33292.833535832164;33646.32707345839;33845.83077084624;33876;34066.48900266498;4253.80857861397;4312.266914624522;4342.913141737147;4427.822887084512;4435.135148646015;357922;357982;358777;359328;359512.9999999974;63657.36342636514;63736.36263637397;64251.35748642547;64361;64369;56692;63152.68423657852;64673.67663161748;66866.66566667135;66992.33007669958;17116.914415427844;17240.224189911485;17282.13589320529;17294.567635809206;17304.480865574053;13284.667883302802;13377.398017089059;13387.397567109503;13388.263645499395;13389.397477113553;16586.170691465377;18402.35591754309;19302.71045934312;19452.805471945383;19671.409857704293;6138.5396095292135;6252.687365631791;6263.78076767311;6378.585391949508;6436;990.1433468835374;1014.4369874719454;1051.3324039234988;1116.1963386361806;1154.203599516331;33320.83339583335;33546.3290734184;33760.37442116414;34021.489677654856;34422;1102.4102105373586;1110.622388387952;1110.9944450277858;1122.4724379541603;1162.8546431696038;17486.21312040961;17538;17818.287268509113;17917.19372628235;18013.729794052837;517985;524785;529062;530516;531706;62371;62465.37534624687;62491;62651.37348626547;62666;1215.3558613934629;1318.3935389720837;1339.2031741114022;1342.90599658023;1381.799639052352;34016.319673606886;35291;35462.46806297856;35862.820685896404;35886.461703073954;54107;54777.45222547724;54868.72565637146;54913.450865490835;54932.17601735897;416848;418710;419935;420288;420671;49051;49244.2613360807;50603;51364.22953655773;51636.48363516317;48573.51426485831;49264.507354927424;51923.22115168351;52827.47172528225;54023.729881351916;44340;44365.55634443614;44882.77558612186;44934.55065449434;45929;44134.37153273219;44686.55313446824;45085.77457112693;46057.76971115123;46059.53940460552;44109.77945110382;44398;45342.77328613336;45343.093138137716;45493.77253113713;37018.44797968525;39167.804160979016;39322.60677393304;40263.396049058705;40425.595744042184;29203.96890854947;29768.553471698375;29940.251493712833;29990.550141747455;30183.547246790877 05:42:38 INFO - PROCESS | 15445 | |2;jquery.html;376.3038378998851;402.071215492213;405.95737447568007;407.9755214687119;419.0989372848376;14268.230799080628;19122.33071842486;20148.8992555037;20263.493412664673;20286;19348.70976935347;20303.289384871532;20314.28899988497;20514.076866541003;20554.177832886668;5253.212018197263;5340.62615616906;5501.229827824108;5515.227868098469;5522.392536820947;5390;5412.404635490093;5418.349798024243;5447;5462;5454.209139674745;5461.0716178249795;5818.738156782955;6152.107944348067;6153.7538498460235;6005.819825405245;6178.042403427454;6185.072239164117;6191.659458729768;6215.440610345068;5925.73334199962;5938.673372964486;5975.565864192606;5984.820455386345;5986.97006514969;14632.707345853023;14950.177740224284;15118.697626047417;17703.73444398335;24115.517689646284;2445.0464318915638;2462.5074985002893;2723.360010397555;2847.729465700776;3027.8948183912935;10486.79026419475;10497.737556561071;10567.73580660482;10785.78428431427;10931.781364372668;1893.8390236829887;1897.7627796525435;1956.2859556262003;2008.98995505023;2041.469218003315;62944.68527657378;65133;78082;82230.58884705538;86533.13466865313;11162.441877906072;11249.550017999352;11380.430978451044;11441.37072461006;11716.121290903046;15925.12411817338;16040.919795400949;16044.037357758572;16070.678586428206;16077;8202.138775428493;8300.128486508951;8342.37432192588;8363.707270245633;8563.914360856312;7756.844863102707;7760.611969401507;7853.214678532101;7954.085280192852;7975.920240797634;4861.1979023460735;4872.537108974641;4891.486393928586;5000.449950505442;5041.143005689041;2027.756669199676;2027.7668068172054;2046.334941144134;2058.5779915117364;2065.917363305481;936.0545848692733;992.5483904823288;1007.5012868630075;1029.325791606499;1041.4272150317295;20365.389038328874;20928.895355523124;21072.578548428944;21112.366629001153;21205.681914771292;961.9855702164473;993.6174572789528;1018.8013337399284;1044.6970378590124;1064.842741272132;10617.522211500502;10832.079273261861;11165.83251251232;11240.943795281135;11373.431328433546;504145;511816;515868;520515;523356;102726.48636756769;103308;103771.48114259381;104186.47906760417;104317;1061.7292590389402;1064.6327017179215;1066.4827558634056;1070.0957690751302;1075.2473268711776;17870.285188592312;17904.462866114038;18202.089895504905;18266.36067737623;18476;40017.79991100026;42758.78620606877;42998.35502467528;43676.34485482784;44000.33999489946;51241.48758512367;60628;63220.103396898165;63363;63722.68138659277;7681.654325555475;7802.648880800264;7862.33170180541;8506.702265420681;9207.079292070739;9402.905970940203;13582;13611.319434028457;13887.166769993833;14147.787783183054;11764.235324703866;11882.881171188177;12040.75918481643;12084;12374;11779.234349767237;11902.583409580624;12032.939835300767;12079.064411087298;12225.388730563614;12116.33360165164;12175;12387.504499819906;12546.560870369492;12733.61799146027;7765.107012693386;7985.6007199638625;8005.479643823133;8094.271515563628;8279.337652987626;11551.480183391935;11976.520939162336;12008.63974080779;12144.39278036077;12371.814422783485 05:42:38 INFO - PROCESS | 15445 | |3;mootools.html;1221.309959872672;1328.681116532032;1362.4413990263993;1369.760291948909;1796.0391190712962;8538.445001074933;9016.68441604544;9043.95478022609;9247.029061948499;9258.67594634188;8936.419132756366;9146.725598232064;9150.816983660323;9153.176214140725;9163.679271225508;2372;2410.349205714456;2411.1681469892947;2415.2754173747803;2416.565018296706;2386.677798497207;2405.398650337416;2425.126954296452;2428.4778772563895;2429.465517586128;2369.478714682767;2398.1846172301416;2405.7714517120844;2411.6503107049466;2411.987940060306;2336.123953517431;2339.988300058506;2342.5666251743482;2351.1418332308754;2380.33350661815;2275.590393729128;2332.2769941318247;2332.6267797152373;2337.0651739304244;2343.1798870395396;7949.364050875916;8194.467359621605;9147.542622868896;9223.723288301362;9274.814503709888;4941.382327209099;5074.847754567369;5368.785248590052;5550.58370622205;5554.583406244547;3589.533360663097;3621.529201203853;3642.0894776305922;3686.0416291764336;3691.24329512452;1205.421397729095;1218.7928052231052;1230.6554164833772;1241.6213055018216;1247.3451437995034;12912.418941147671;13018.218906865619;13036.86963130366;13068.803967940488;13081.934590327082;4644.256918892961;4690;4815.422149342101;4819.566239038503;4974.875628109254;11001.504932278067;11026.779464410667;11042.392668403154;11051.778964420666;11056.336619802838;5600.775968961278;5627.155926611041;5631.267935168484;5636.210930469738;5638.154276858422;5031;5132.050570644443;5150.562202212779;5190.2993095932325;5192.428832828386;3961.465202197724;3976.721629485908;4015.0765323975647;4048.7773172475795;4100.220958017967;1243.564752336675;1257.0948916779903;1257.4592925042136;1269.206745783885;1281.2696762845276;957.5499515227971;999.1906555689915;1002.1682003936716;1009.1876039787894;1023.3603997501561;6465.418112369815;6538.869222615521;6540.6729663516635;6570.408663220333;6578.506612004119;467.98213884800424;475.6123759136362;475.63613835415936;478.3709422109984;481.3670023918536;4272.850450234289;4404.185225733186;4411.500269724863;4473.150101480708;4482.013956929471;11033.6138235163;11469.71325716847;11479.4260286987;11551.826722599168;11567.537298508134;11382.601608943647;11399.772004559862;11402.372869492092;11416.714582135513;11424.143189260838;8006.439549231498;8331;8347.624356903954;8494.362922780816;8502.192291732275;6322.683865806784;6523.054157147282;6589.374009469093;6693.1298931138645;6740.191177058784;10496.265261431627;10709.946450267698;10748.570057197625;10777.191710621892;10906.018458338787;10851.782964340828;10882.183836212474;10966.616168434066;10967.50646220906;11068.169887258327;8104.189581041848;8190.672373105009;8257.21556452136;8259.545724984942;8263.628136733741;8893;9010.819783604422;9022.5939832709;9027.548622568715;9033.232175264913;7909.52542847431;7932.881006785018;8190.426670133033;8223.671053157806;8468.872966905377;7921.603919804102;8104.108548059827;8132.959335203286;8211.753647390588;8289.461185022954;7428.6285685716575;7477.102747670315;7531.510451820614;7533.660985255567;7533.698652053857;5508.3114610673665;5590.972045139748;5603.439656034364;5614.382417934105;5702.714864256854;5494.972525137348;5592.636478628876;5595.272614560081;5732;5738.48353648157 05:42:38 INFO - PROCESS | 15445 | |4;prototype.html;329.6950320953118;334.5901270943095;341.75905986279673;342.2624244752557;343.0634368174884;5297.549708274796;5336.786528538859;5338.652987555806;5369.24830523726;5388;5057.342545469084;5158.742062896849;5164.875250473457;5176.2235664650325;5340.25236466894;4075.7962101894936;4125.46368972034;4190.7485550866895;4206.705530612859;4227.112306415656;4168.854090106847;4198.895027624319;4205.201011807762;4207.600277973587;4208.242516347056;4085.53016403113;4176.45706058212;4176.7911604419805;4177.4778152730905;4183.79081045948;4144.300836656987;4152.8131234094535;4163.063310755087;4183.9121828324705;4197.790110494463;3695.9260814783825;3702.1670124222105;3708.5735140458924;3709.5733990591157;3721.683656889141;4978.904640978979;5171.741412929338;5194.532492075694;5197.558207552363;5203.323567936144;4721.268203428474;4743.667943243974;4748.833790817339;4751.691140075884;4755.809767609291;5464.562834973192;5500.257465242181;5505.467484956885;5508.14623733318;5509.641873278224;3943.3887747398912;4009.799510024516;4045.656119229869;4055.2295063938054;4066.979665101685;5666.603337766358;5675.432456754333;5692.37383887772;5695.515881150107;5696.800611978602;4131.938020929689;4138.730982486129;4142.337226043879;4142.606452387018;4146.958530414658;4015.8393664253686;4180.4708151568475;4188.602082802129;4192.790360481964;4195.937060944088;4720.598749106364;4721.244600863845;4722.480527142012;4726.740029298421;4729.905401891943;5507.449255074461;5527.281453411111;5530.723463826793;5534.501894829405;5537.224788529609;1326.270551196838;1371.2663724907127;1429.0925262458206;1448.9565313040625;1449.2029383838847;1003.7540802503433;1021.0810270756378;1021.918246540275;1025.1542477456128;1028.3469996552094;5266.1574148136115;5301.3373328333955;5306.177542480922;5310;5328.3605967284175;5606.887862242732;5617.32592088952;5632.239647647515;5639.238702775155;5641.238432811518;5101.234814777738;5326.520613144836;5333.2266821310695;5339.679619222859;5343.759530821045;2608.4652646207483;2640.2475294540964;2657.3356660834793;2683.4633073385403;2692.367293685982;5137.280780690707;5157.819476318311;5164.3802743671;5171.715555644476;5536.390996990328;5769.451902069297;5806.519337568893;5822.94177058224;5824.184614154022;5846.210761547221;5842.020569509581;5874.825034993019;5881.676507792027;5908.084246941817;5920.79277225295;5506.977301311735;5696.145578163143;5699.487046165865;5712.371639119777;5743.655380677173;5602.215689803349;5631.746571404378;5641.323041235078;5654.236678048411;5654.886902261849;5604.579656525695;5848;5861.706914654335;5882.794102206402;5901.881962360814;3873.3996230584303;4036.5761595033273;4040.5555388907783;4043.353063509824;4051.521194763949;5596.804111856066;5720.456556627114;5728.770849166153;5729.512991395612;5743.10981797822;5597.356304025089;5601.131824567198;5607.243022192115;5622.971885140548;5633.464820842013;5345.65253258537;5569.888602228014;5604.887902242014;5605.32736071674;5609.775608975595;5512.3660779010925;5512.972435137799;5514.283143191359;5524.779008839762;5527.088030474926;5622.156676498558;5628.382801997091;5634.352049514195;5636.577256705846;5641.746121424628;5274.7890084396195;5529.972350138223;5533.944660553343;5534.1975413565615;5537.535796570487 05:42:38 INFO - PROCESS | 15445 | |5;yui.html;757.9772606821796;835.8244768598594;840.5629072882101;854.7777577829762;896.3546246702372;15964.920175399122;16559;16591.585210369733;16594.75107873383;16675;15430.845691543082;15634.452794152206;15634.765478517831;15661.843381566181;15674.608134796636;674.2549482821486;783.964721587527;795.2921899509427;811.168552233959;820.5938060659981;818.7380038387719;822.4695071678764;822.7943014246439;823.0617096509983;823.069930977997;867.3928250224836;876.3208513402101;876.5091548732698;876.6318146378536;877.1535468273115;711.491283732134;843.3084870406269;869.695606537713;873.2010210657272;873.52829472085;630.5271046714964;633.0915136778738;633.1452539072262;633.2907143998705;633.3603060908515;15117.924410377987;15133.092014479056;15136.318865650963;15151.848481515264;15163.241837908061;14536.854631453656;14785.482508112273;14896.478623248242;14915.925420372938;14918.2540872957;13019.739605207937;13117.934410327982;13161.934190329082;13170.407331670001;13315.800262996063;4419.867403977886;4524.343970124346;4584.518625544303;4620.19146649335;4635.443746750378;78789;78941;78944.60527697325;79194;79425.60287198643;11273.436328183558;11463.369514676773;11465.770684586261;11620.186586939002;12229.3273869938;22107;22211.22260720867;22231.888840555694;22280;22291.77708222897;8742.956285218534;8755.430896991675;8821;8839.425437346552;8904.154105359981;4546.954530454653;4864.951350486521;4955.529224723646;5068.4424713281505;5121.641485096082;3436.106612280825;3513.80674062929;3556.395412779782;3558.057114864563;3562.2697347043795;584.1704779213527;599.7541008186622;606.3542327421283;614.5206738743786;615.218672286203;660.6465540935673;677.915260592426;679.1239301301365;686.6532401137393;692.8371832619331;9554.761130971643;9595.42427454355;9630.27772917034;9642.951785241028;9926.900730992598;636.0872148466965;640.8654182621608;643.0326569435645;643.7939859244997;690.5925503952592;4680.672352935262;4700.318453824177;4707.811687532529;4713.245880659147;4716.221823399101;117231;117447;119541.40229298797;123119;123917;62554;63275.36724632695;72932.2706772936;88366;105385.47307263415;83723;84125.15874841296;89674;91899.5405022984;94214.52892735493;10278.434686092336;10582.365058096231;10613.840792387966;10624.787504250025;10650.414227217578;53583;54605.180922285406;55819.72090139523;55973.72013139908;58302.416975831395;79561.60219198867;83098;84374.15625843909;85241.04968978417;86232;13552.796708049584;13895.444182233003;13970.371333289822;13970.508027488771;13980.58058258254;14406.927965360106;14460.13239205651;14483.348249328596;14653.487127950471;14714.779278311047;10841.295315804447;11228.494717737885;13199.406026728628;14245.21651309188;14828.258587070819;14868.62828429298;15008.174550399834;15023.549293521211;15147.394104236148;15246.237688115329;9391.906080939103;12874.935625321814;13688.384022719201;14285.64285892861;14916.179610121548;11528.293479875108;11579.197905510096;11759.823602646138;12254.4485498151;12666.746665066832;12039.337836419081;12133.75732485363;12265.325407102695;12646.620601381974;12821.935890320488 05:42:38 INFO - PROCESS | 15445 | __end_tp_report 05:42:38 INFO - PROCESS | 15445 | __start_cc_report 05:42:38 INFO - PROCESS | 15445 | _x_x_mozilla_cycle_collect,955 05:42:38 INFO - PROCESS | 15445 | __end_cc_report 05:42:38 INFO - PROCESS | 15445 | __startTimestamp1468759358693__endTimestamp 05:42:38 INFO - PROCESS | 15445 | 05:42:38 INFO - PROCESS | 15445 | ------- Summary: start ------- 05:42:38 INFO - PROCESS | 15445 | Number of tests: 6 05:42:38 INFO - PROCESS | 15445 | 05:42:38 INFO - PROCESS | 15445 | [#0] dojo.html Cycles:165 Average:5223.92 Median:5431.84 stddev:588.77 (10.8%) stddev-sans-first:564.01 05:42:38 INFO - PROCESS | 15445 | Values: 2994.8 3013.2 3035.2 3037.5 3052.9 5130.3 5133.0 5209.8 5241.1 5244.9 5238.0 5251.1 5319.8 5355.0 5379.8 4726.6 4802.0 4812.4 4843.7 5027.3 4930.4 4949.4 4982.5 5000.1 5014.0 4751.5 4760.3 4800.1 4815.0 4922.9 4693.4 4697.9 4712.1 4777.7 4897.5 4697.6 4734.9 4735.1 4782.3 4795.7 5171.2 5186.5 5214.4 5239.5 5276.5 5648.8 5666.7 5674.9 5678.7 5681.8 5648.0 5648.9 5683.8 5695.3 5698.6 5186.6 5214.3 5221.8 5226.2 5313.0 5722.1 5725.7 5777.4 5780.1 5783.1 5565.5 5568.7 5569.6 5571.3 5572.3 5436.8 5589.6 5651.9 5661.5 5682.6 5636.2 5665.8 5667.9 5668.2 5669.7 5393.1 5681.3 5689.5 5690.6 5697.8 4240.5 4263.0 4285.8 4328.4 4329.9 3800.8 3833.4 3845.6 3874.2 3880.0 5178.9 5303.2 5309.3 5310.7 5355.5 5536.9 5547.5 5561.4 5585.2 5608.7 5250.9 5284.9 5286.8 5287.1 5291.8 4741.9 4811.2 4852.7 4855.2 4895.0 5723.5 5778.7 5780.4 5807.1 5830.5 5617.9 5619.2 5619.6 5621.6 5623.6 5650.0 5682.2 5751.2 5776.6 5786.5 5477.0 5506.6 5511.1 5515.5 5577.3 5575.9 5597.9 5608.3 5615.0 5615.9 5257.7 5431.8 5480.2 5482.9 5524.1 5432.6 5465.5 5467.5 5479.1 5522.9 5373.3 5379.2 5424.1 5424.6 5461.3 5410.0 5528.7 5533.4 5579.0 5595.3 5458.8 5502.6 5503.9 5510.5 5519.1 05:42:38 INFO - PROCESS | 15445 | 05:42:38 INFO - PROCESS | 15445 | [#1] ext.html Cycles:180 Average:61932.32 Median:45248.82 stddev:116318.37 (257.1%) stddev-sans-first:116553.51 05:42:38 INFO - PROCESS | 15445 | Values: 596.1 608.3 624.9 630.0 631.0 45365.1 47059.3 47099.5 47120.0 49637.0 46752.5 46962.8 46980.3 47202.3 47228.3 9054.6 9217.6 9219.0 9221.2 9223.4 9109.6 9190.3 9195.4 9196.4 9197.4 9205.9 9254.0 9255.5 9257.2 9258.9 9141.7 9261.7 9270.8 9276.7 9277.0 9068.9 9124.0 9134.8 9135.8 9141.3 44823.3 45233.5 45248.3 45249.3 45298.0 3577.4 3764.8 3834.0 3918.3 4153.5 33292.8 33646.3 33845.8 33876.0 34066.5 4253.8 4312.3 4342.9 4427.8 4435.1 357922.0 357982.0 358777.0 359328.0 359513.0 63657.4 63736.4 64251.4 64361.0 64369.0 56692.0 63152.7 64673.7 66866.7 66992.3 17116.9 17240.2 17282.1 17294.6 17304.5 13284.7 13377.4 13387.4 13388.3 13389.4 16586.2 18402.4 19302.7 19452.8 19671.4 6138.5 6252.7 6263.8 6378.6 6436.0 990.1 1014.4 1051.3 1116.2 1154.2 33320.8 33546.3 33760.4 34021.5 34422.0 1102.4 1110.6 1111.0 1122.5 1162.9 17486.2 17538.0 17818.3 17917.2 18013.7 517985.0 524785.0 529062.0 530516.0 531706.0 62371.0 62465.4 62491.0 62651.4 62666.0 1215.4 1318.4 1339.2 1342.9 1381.8 34016.3 35291.0 35462.5 35862.8 35886.5 54107.0 54777.5 54868.7 54913.5 54932.2 416848.0 418710.0 419935.0 420288.0 420671.0 49051.0 49244.3 50603.0 51364.2 51636.5 48573.5 49264.5 51923.2 52827.5 54023.7 44340.0 44365.6 44882.8 44934.6 45929.0 44134.4 44686.6 45085.8 46057.8 46059.5 44109.8 44398.0 45342.8 45343.1 45493.8 37018.4 39167.8 39322.6 40263.4 40425.6 29204.0 29768.6 29940.3 29990.6 30183.5 05:42:38 INFO - PROCESS | 15445 | 05:42:38 INFO - PROCESS | 15445 | [#2] jquery.html Cycles:180 Average:29976.75 Median:21000.74 stddev:85010.86 (404.8%) stddev-sans-first:85220.28 05:42:38 INFO - PROCESS | 15445 | Values: 376.3 402.1 406.0 408.0 419.1 14268.2 19122.3 20148.9 20263.5 20286.0 19348.7 20303.3 20314.3 20514.1 20554.2 5253.2 5340.6 5501.2 5515.2 5522.4 5390.0 5412.4 5418.3 5447.0 5462.0 5454.2 5461.1 5818.7 6152.1 6153.8 6005.8 6178.0 6185.1 6191.7 6215.4 5925.7 5938.7 5975.6 5984.8 5987.0 14632.7 14950.2 15118.7 17703.7 24115.5 2445.0 2462.5 2723.4 2847.7 3027.9 10486.8 10497.7 10567.7 10785.8 10931.8 1893.8 1897.8 1956.3 2009.0 2041.5 62944.7 65133.0 78082.0 82230.6 86533.1 11162.4 11249.6 11380.4 11441.4 11716.1 15925.1 16040.9 16044.0 16070.7 16077.0 8202.1 8300.1 8342.4 8363.7 8563.9 7756.8 7760.6 7853.2 7954.1 7975.9 4861.2 4872.5 4891.5 5000.4 5041.1 2027.8 2027.8 2046.3 2058.6 2065.9 936.1 992.5 1007.5 1029.3 1041.4 20365.4 20928.9 21072.6 21112.4 21205.7 962.0 993.6 1018.8 1044.7 1064.8 10617.5 10832.1 11165.8 11240.9 11373.4 504145.0 511816.0 515868.0 520515.0 523356.0 102726.5 103308.0 103771.5 104186.5 104317.0 1061.7 1064.6 1066.5 1070.1 1075.2 17870.3 17904.5 18202.1 18266.4 18476.0 40017.8 42758.8 42998.4 43676.3 44000.3 51241.5 60628.0 63220.1 63363.0 63722.7 7681.7 7802.6 7862.3 8506.7 9207.1 9402.9 13582.0 13611.3 13887.2 14147.8 11764.2 11882.9 12040.8 12084.0 12374.0 11779.2 11902.6 12032.9 12079.1 12225.4 12116.3 12175.0 12387.5 12546.6 12733.6 7765.1 7985.6 8005.5 8094.3 8279.3 11551.5 11976.5 12008.6 12144.4 12371.8 05:42:38 INFO - PROCESS | 15445 | 05:42:38 INFO - PROCESS | 15445 | [#3] mootools.html Cycles:180 Average:6092.36 Median:2582.81 stddev:3508.86 (135.9%) stddev-sans-first:3499.60 05:42:38 INFO - PROCESS | 15445 | Values: 1221.3 1328.7 1362.4 1369.8 1796.0 8538.4 9016.7 9044.0 9247.0 9258.7 8936.4 9146.7 9150.8 9153.2 9163.7 2372.0 2410.3 2411.2 2415.3 2416.6 2386.7 2405.4 2425.1 2428.5 2429.5 2369.5 2398.2 2405.8 2411.7 2412.0 2336.1 2340.0 2342.6 2351.1 2380.3 2275.6 2332.3 2332.6 2337.1 2343.2 7949.4 8194.5 9147.5 9223.7 9274.8 4941.4 5074.8 5368.8 5550.6 5554.6 3589.5 3621.5 3642.1 3686.0 3691.2 1205.4 1218.8 1230.7 1241.6 1247.3 12912.4 13018.2 13036.9 13068.8 13081.9 4644.3 4690.0 4815.4 4819.6 4974.9 11001.5 11026.8 11042.4 11051.8 11056.3 5600.8 5627.2 5631.3 5636.2 5638.2 5031.0 5132.1 5150.6 5190.3 5192.4 3961.5 3976.7 4015.1 4048.8 4100.2 1243.6 1257.1 1257.5 1269.2 1281.3 957.5 999.2 1002.2 1009.2 1023.4 6465.4 6538.9 6540.7 6570.4 6578.5 468.0 475.6 475.6 478.4 481.4 4272.9 4404.2 4411.5 4473.2 4482.0 11033.6 11469.7 11479.4 11551.8 11567.5 11382.6 11399.8 11402.4 11416.7 11424.1 8006.4 8331.0 8347.6 8494.4 8502.2 6322.7 6523.1 6589.4 6693.1 6740.2 10496.3 10709.9 10748.6 10777.2 10906.0 10851.8 10882.2 10966.6 10967.5 11068.2 8104.2 8190.7 8257.2 8259.5 8263.6 8893.0 9010.8 9022.6 9027.5 9033.2 7909.5 7932.9 8190.4 8223.7 8468.9 7921.6 8104.1 8133.0 8211.8 8289.5 7428.6 7477.1 7531.5 7533.7 7533.7 5508.3 5591.0 5603.4 5614.4 5702.7 5495.0 5592.6 5595.3 5732.0 5738.5 05:42:38 INFO - PROCESS | 15445 | 05:42:38 INFO - PROCESS | 15445 | [#4] prototype.html Cycles:180 Average:4660.47 Median:5286.17 stddev:1365.26 (25.8%) stddev-sans-first:1329.84 05:42:38 INFO - PROCESS | 15445 | Values: 329.7 334.6 341.8 342.3 343.1 5297.5 5336.8 5338.7 5369.2 5388.0 5057.3 5158.7 5164.9 5176.2 5340.3 4075.8 4125.5 4190.7 4206.7 4227.1 4168.9 4198.9 4205.2 4207.6 4208.2 4085.5 4176.5 4176.8 4177.5 4183.8 4144.3 4152.8 4163.1 4183.9 4197.8 3695.9 3702.2 3708.6 3709.6 3721.7 4978.9 5171.7 5194.5 5197.6 5203.3 4721.3 4743.7 4748.8 4751.7 4755.8 5464.6 5500.3 5505.5 5508.1 5509.6 3943.4 4009.8 4045.7 4055.2 4067.0 5666.6 5675.4 5692.4 5695.5 5696.8 4131.9 4138.7 4142.3 4142.6 4147.0 4015.8 4180.5 4188.6 4192.8 4195.9 4720.6 4721.2 4722.5 4726.7 4729.9 5507.4 5527.3 5530.7 5534.5 5537.2 1326.3 1371.3 1429.1 1449.0 1449.2 1003.8 1021.1 1021.9 1025.2 1028.3 5266.2 5301.3 5306.2 5310.0 5328.4 5606.9 5617.3 5632.2 5639.2 5641.2 5101.2 5326.5 5333.2 5339.7 5343.8 2608.5 2640.2 2657.3 2683.5 2692.4 5137.3 5157.8 5164.4 5171.7 5536.4 5769.5 5806.5 5822.9 5824.2 5846.2 5842.0 5874.8 5881.7 5908.1 5920.8 5507.0 5696.1 5699.5 5712.4 5743.7 5602.2 5631.7 5641.3 5654.2 5654.9 5604.6 5848.0 5861.7 5882.8 5901.9 3873.4 4036.6 4040.6 4043.4 4051.5 5596.8 5720.5 5728.8 5729.5 5743.1 5597.4 5601.1 5607.2 5623.0 5633.5 5345.7 5569.9 5604.9 5605.3 5609.8 5512.4 5513.0 5514.3 5524.8 5527.1 5622.2 5628.4 5634.4 5636.6 5641.7 5274.8 5530.0 5533.9 5534.2 5537.5 05:42:38 INFO - PROCESS | 15445 | 05:42:38 INFO - PROCESS | 15445 | [#5] yui.html Cycles:180 Average:21168.97 Median:4658.06 stddev:30092.72 (646.0%) stddev-sans-first:30138.11 05:42:38 INFO - PROCESS | 15445 | Values: 758.0 835.8 840.6 854.8 896.4 15964.9 16559.0 16591.6 16594.8 16675.0 15430.8 15634.5 15634.8 15661.8 15674.6 674.3 784.0 795.3 811.2 820.6 818.7 822.5 822.8 823.1 823.1 867.4 876.3 876.5 876.6 877.2 711.5 843.3 869.7 873.2 873.5 630.5 633.1 633.1 633.3 633.4 15117.9 15133.1 15136.3 15151.8 15163.2 14536.9 14785.5 14896.5 14915.9 14918.3 13019.7 13117.9 13161.9 13170.4 13315.8 4419.9 4524.3 4584.5 4620.2 4635.4 78789.0 78941.0 78944.6 79194.0 79425.6 11273.4 11463.4 11465.8 11620.2 12229.3 22107.0 22211.2 22231.9 22280.0 22291.8 8743.0 8755.4 8821.0 8839.4 8904.2 4547.0 4865.0 4955.5 5068.4 5121.6 3436.1 3513.8 3556.4 3558.1 3562.3 584.2 599.8 606.4 614.5 615.2 660.6 677.9 679.1 686.7 692.8 9554.8 9595.4 9630.3 9643.0 9926.9 636.1 640.9 643.0 643.8 690.6 4680.7 4700.3 4707.8 4713.2 4716.2 117231.0 117447.0 119541.4 123119.0 123917.0 62554.0 63275.4 72932.3 88366.0 105385.5 83723.0 84125.2 89674.0 91899.5 94214.5 10278.4 10582.4 10613.8 10624.8 10650.4 53583.0 54605.2 55819.7 55973.7 58302.4 79561.6 83098.0 84374.2 85241.0 86232.0 13552.8 13895.4 13970.4 13970.5 13980.6 14406.9 14460.1 14483.3 14653.5 14714.8 10841.3 11228.5 13199.4 14245.2 14828.3 14868.6 15008.2 15023.5 15147.4 15246.2 9391.9 12874.9 13688.4 14285.6 14916.2 11528.3 11579.2 11759.8 12254.4 12666.7 12039.3 12133.8 12265.3 12646.6 12821.9 05:42:38 INFO - PROCESS | 15445 | -------- Summary: end -------- 05:42:38 INFO - PROCESS | 15445 | 05:42:39 INFO - TEST-INFO | 15445: exit 0 05:42:39 INFO - TEST-OK | dromaeo_css | took 1113911ms 05:42:39 INFO - TEST-START | kraken 05:42:39 INFO - operating with platform_type : linux_ 05:42:39 INFO - Initialising browser for kraken test... 05:42:39 INFO - TEST-INFO | started process 15642 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQKIo5f/profile http://localhost:52216/getInfo.html) 05:42:42 INFO - PROCESS | 15642 | JavaScript error: , line 0: uncaught exception: undefined 05:42:43 INFO - PROCESS | 15642 | __metrics Screen width/height:1600/1200 05:42:43 INFO - PROCESS | 15642 | colorDepth:24 05:42:43 INFO - PROCESS | 15642 | Browser inner width/height: 1024/697 05:42:43 INFO - PROCESS | 15642 | __metrics 05:42:44 INFO - PROCESS | 15642 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:42:44 INFO - PROCESS | 15642 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:42:45 INFO - TEST-INFO | 15642: exit 0 05:42:45 INFO - Browser initialized. 05:42:45 INFO - Running cycle 1/1 for kraken test... 05:42:45 INFO - TEST-INFO | started process 15725 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQKIo5f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:57 INFO - PROCESS | 15725 | RSS: Main: 158007296 05:42:57 INFO - PROCESS | 15725 | 05:42:59 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52216/tests/kraken/driver.html?testName=audio-beat-detection) 05:43:00 INFO - PROCESS | 15725 | RSS: Main: 217604096 05:43:00 INFO - PROCESS | 15725 | 05:43:03 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52216/tests/kraken/driver.html?testName=audio-dft) 05:43:04 INFO - PROCESS | 15725 | RSS: Main: 205742080 05:43:04 INFO - PROCESS | 15725 | 05:43:06 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52216/tests/kraken/driver.html?testName=audio-fft) 05:43:07 INFO - PROCESS | 15725 | RSS: Main: 269094912 05:43:07 INFO - PROCESS | 15725 | 05:43:09 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52216/tests/kraken/driver.html?testName=audio-oscillator) 05:43:10 INFO - PROCESS | 15725 | RSS: Main: 181403648 05:43:10 INFO - PROCESS | 15725 | 05:43:12 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52216/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:43:12 INFO - PROCESS | 15725 | RSS: Main: 184930304 05:43:12 INFO - PROCESS | 15725 | 05:43:16 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52216/tests/kraken/driver.html?testName=imaging-darkroom) 05:43:16 INFO - PROCESS | 15725 | RSS: Main: 219709440 05:43:16 INFO - PROCESS | 15725 | 05:43:20 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52216/tests/kraken/driver.html?testName=imaging-desaturate) 05:43:20 INFO - PROCESS | 15725 | RSS: Main: 221601792 05:43:20 INFO - PROCESS | 15725 | 05:43:23 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52216/tests/kraken/driver.html?testName=json-parse-financial) 05:43:24 INFO - PROCESS | 15725 | RSS: Main: 221134848 05:43:24 INFO - PROCESS | 15725 | 05:43:25 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52216/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:43:26 INFO - PROCESS | 15725 | RSS: Main: 209825792 05:43:26 INFO - PROCESS | 15725 | 05:43:27 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52216/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:43:28 INFO - PROCESS | 15725 | RSS: Main: 212766720 05:43:28 INFO - PROCESS | 15725 | 05:43:30 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52216/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:43:31 INFO - PROCESS | 15725 | RSS: Main: 210493440 05:43:31 INFO - PROCESS | 15725 | 05:43:33 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52216/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:43:34 INFO - PROCESS | 15725 | RSS: Main: 224399360 05:43:34 INFO - PROCESS | 15725 | 05:43:36 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52216/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:43:37 INFO - PROCESS | 15725 | RSS: Main: 225374208 05:43:37 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | Cycle 1(1): loaded http://localhost:52216/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52216/tests/kraken/driver.html?testName=ai-astar) 05:43:39 INFO - PROCESS | 15725 | RSS: Main: 268013568 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | __start_tp_report 05:43:39 INFO - PROCESS | 15725 | _x_x_mozilla_page_load 05:43:39 INFO - PROCESS | 15725 | _x_x_mozilla_page_load_details 05:43:39 INFO - PROCESS | 15725 | |i|pagename|runs| 05:43:39 INFO - PROCESS | 15725 | |0;ai-astar;102;112;111;110;111;110;110;109;110;110 05:43:39 INFO - PROCESS | 15725 | |1;audio-beat-detection;144;115;124;179;122;119;144;115;156;120 05:43:39 INFO - PROCESS | 15725 | |2;audio-dft;160;160;159;161;161;159;160;162;162;161 05:43:39 INFO - PROCESS | 15725 | |3;audio-fft;75;75;74;122;75;77;74;74;100;75 05:43:39 INFO - PROCESS | 15725 | |4;audio-oscillator;84;84;109;84;87;85;124;84;84;121 05:43:39 INFO - PROCESS | 15725 | |5;imaging-gaussian-blur;116;117;117;116;117;117;116;116;118;117 05:43:39 INFO - PROCESS | 15725 | |6;imaging-darkroom;146;147;146;147;146;148;146;146;146;146 05:43:39 INFO - PROCESS | 15725 | |7;imaging-desaturate;96;95;94;95;95;95;95;96;96;96 05:43:39 INFO - PROCESS | 15725 | |8;json-parse-financial;52;50;51;51;51;51;51;51;53;51 05:43:39 INFO - PROCESS | 15725 | |9;json-stringify-tinderbox;64;65;64;64;65;63;63;63;67;64 05:43:39 INFO - PROCESS | 15725 | |10;stanford-crypto-aes;75;74;74;76;74;74;75;75;74;74 05:43:39 INFO - PROCESS | 15725 | |11;stanford-crypto-ccm;119;118;118;123;120;118;126;118;162;133 05:43:39 INFO - PROCESS | 15725 | |12;stanford-crypto-pbkdf2;154;153;159;162;162;158;159;158;161;153 05:43:39 INFO - PROCESS | 15725 | |13;stanford-crypto-sha256-iterative;66;61;63;62;62;61;64;60;61;64 05:43:39 INFO - PROCESS | 15725 | __end_tp_report 05:43:39 INFO - PROCESS | 15725 | __start_cc_report 05:43:39 INFO - PROCESS | 15725 | _x_x_mozilla_cycle_collect,1637 05:43:39 INFO - PROCESS | 15725 | __end_cc_report 05:43:39 INFO - PROCESS | 15725 | __startTimestamp1468759419375__endTimestamp 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | ------- Summary: start ------- 05:43:39 INFO - PROCESS | 15725 | Number of tests: 14 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:2.76 (2.5%) stddev-sans-first:0.87 05:43:39 INFO - PROCESS | 15725 | Values: 102.0 112.0 111.0 110.0 111.0 110.0 110.0 109.0 110.0 110.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:134.00 stddev:21.33 (15.9%) stddev-sans-first:22.30 05:43:39 INFO - PROCESS | 15725 | Values: 144.0 115.0 124.0 179.0 122.0 119.0 144.0 115.0 156.0 120.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 05:43:39 INFO - PROCESS | 15725 | Values: 160.0 160.0 159.0 161.0 161.0 159.0 160.0 162.0 162.0 161.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#3] audio-fft Cycles:10 Average:82.10 Median:75.00 stddev:16.11 (21.5%) stddev-sans-first:16.89 05:43:39 INFO - PROCESS | 15725 | Values: 75.0 75.0 74.0 122.0 75.0 77.0 74.0 74.0 100.0 75.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:84.00 stddev:16.60 (19.8%) stddev-sans-first:17.16 05:43:39 INFO - PROCESS | 15725 | Values: 84.0 84.0 109.0 84.0 87.0 85.0 124.0 84.0 84.0 121.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 05:43:39 INFO - PROCESS | 15725 | Values: 116.0 117.0 117.0 116.0 117.0 117.0 116.0 116.0 118.0 117.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 05:43:39 INFO - PROCESS | 15725 | Values: 146.0 147.0 146.0 147.0 146.0 148.0 146.0 146.0 146.0 146.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.67 05:43:39 INFO - PROCESS | 15725 | Values: 96.0 95.0 94.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.00 stddev:0.79 (1.5%) stddev-sans-first:0.78 05:43:39 INFO - PROCESS | 15725 | Values: 52.0 50.0 51.0 51.0 51.0 51.0 51.0 51.0 53.0 51.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:1.23 (1.9%) stddev-sans-first:1.30 05:43:39 INFO - PROCESS | 15725 | Values: 64.0 65.0 64.0 64.0 65.0 63.0 63.0 63.0 67.0 64.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:74.50 stddev:0.71 (0.9%) stddev-sans-first:0.73 05:43:39 INFO - PROCESS | 15725 | Values: 75.0 74.0 74.0 76.0 74.0 74.0 75.0 75.0 74.0 74.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#11] stanford-crypto-ccm Cycles:10 Average:125.50 Median:121.50 stddev:13.71 (11.3%) stddev-sans-first:14.34 05:43:39 INFO - PROCESS | 15725 | Values: 119.0 118.0 118.0 123.0 120.0 118.0 126.0 118.0 162.0 133.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.90 Median:159.00 stddev:3.48 (2.2%) stddev-sans-first:3.39 05:43:39 INFO - PROCESS | 15725 | Values: 154.0 153.0 159.0 162.0 162.0 158.0 159.0 158.0 161.0 153.0 05:43:39 INFO - PROCESS | 15725 | 05:43:39 INFO - PROCESS | 15725 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:62.50 stddev:1.84 (2.9%) stddev-sans-first:1.41 05:43:39 INFO - PROCESS | 15725 | Values: 66.0 61.0 63.0 62.0 62.0 61.0 64.0 60.0 61.0 64.0 05:43:39 INFO - PROCESS | 15725 | -------- Summary: end -------- 05:43:39 INFO - PROCESS | 15725 | 05:43:40 INFO - TEST-INFO | 15725: exit 0 05:43:40 INFO - TEST-OK | kraken | took 60934ms 05:43:40 INFO - SUITE-END | took 1174s 05:43:40 INFO - Completed test suite (00:19:34) 05:43:40 INFO - javascript benchmark 05:43:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2994.7753918456115, 3013.1864396612928, 3035.1956731466157, 3037.5443683447493, 3052.938941221176, 5130.333056702628, 5132.999065182291, 5209.817656382027, 5241.10901146805, 5244.947550524489, 5237.973810130953, 5251.107311757001, 5319.787208511664, 5355.036093503168, 5379.757910894009, 4726.550977657125, 4801.975990120044, 4812.374391329122, 4843.733594652293, 5027.321311622939, 4930.359053323063, 4949.356583644121, 4982.451930287665, 5000.07498612756, 5014.047331007116, 4751.501092385284, 4760.285957106427, 4800.087983283165, 4815.012922350945, 4922.901541969159, 4693.413323334583, 4697.88255293617, 4712.0811441768865, 4777.713337199744, 4897.485763994765, 4697.577218050357, 4734.881627959294, 4735.052989402134, 4782.258749893737, 4795.664303498756, 5171.198464238049, 5186.507281808223, 5214.374275086976, 5239.528442440199, 5276.472352764731, 5648.802291919763, 5666.660000399989, 5674.914876276859, 5678.659280443187, 5681.829545113653, 5648.039833228361, 5648.858778530528, 5683.829485115453, 5695.34503532095, 5698.601097923147, 5186.559142472895, 5214.270002199695, 5221.843344699665, 5226.189940559219, 5313.043652142613, 5722.141678748221, 5725.742341594639, 5777.364489906107, 5780.075187969905, 5783.132530120515, 5565.499105080562, 5568.693721845257, 5569.582281328917, 5571.331440227118, 5572.331320241597, 5436.782528698808, 5589.580781441408, 5651.9152212716845, 5661.518770904437, 5682.6306290091015, 5636.210930469655, 5665.773369065191, 5667.858303542444, 5668.178114173422, 5669.716514174274, 5393.110136827458, 5681.346645135863, 5689.516391106719, 5690.573207009491, 5697.800576979786, 4240.49114106303, 4262.957370426318, 4285.807138678767, 4328.372386004012, 4329.935050974238, 3800.7909564974193, 3833.4058220975794, 3845.6346647068485, 3874.1864208515963, 3880.049387899983, 5178.948210517922, 5303.231031500488, 5309.33633295838, 5310.707911064928, 5355.544778693777, 5536.916946245809, 5547.5007249347755, 5561.416051314634, 5585.162225666182, 5608.747606357723, 5250.894982100336, 5284.9471505284455, 5286.788528458896, 5287.07476191665, 5291.788328466818, 4741.881452963704, 4811.158047341702, 4852.660313778002, 4855.223164293696, 4895.04546613407, 5723.513501352431, 5778.711064446761, 5780.393058728773, 5807.0998995155815, 5830.5044071254415, 5617.859553511032, 5619.2133101365025, 5619.634723742944, 5621.550275977994, 5623.550115990629, 5650.0, 5682.204491371294, 5751.194832723506, 5776.624519406225, 5786.537077033911, 5477.041517734301, 5506.614536982373, 5511.063119269654, 5515.4760297771645, 5577.330720313588, 5575.888482230252, 5597.860053498533, 5608.327000759935, 5615.045442274904, 5615.887682246251, 5257.658252213594, 5431.83704488866, 5480.205370221375, 5482.862928426821, 5524.06090964545, 5432.59255555827, 5465.535429488538, 5467.507924286833, 5479.068558345011, 5522.889542209214, 5373.2746079278795, 5379.2469054333205, 5424.105022671373, 5424.566034717134, 5461.344638643388, 5410.0, 5528.695921724344, 5533.418991006048, 5579.0, 5595.300587426572, 5458.836234912959, 5502.614816962774, 5503.889922201453, 5510.531604813636, 5519.061759500974], "value": 5184.213860507569, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.0939372154326, 608.3034925010862, 624.8968920128182, 630.010882913825, 630.9968450157749, 45365.092698146014, 47059.294110588286, 47099.52900470994, 47120.0, 49637.00725985478, 46752.532474675245, 46962.76518617402, 46980.29529557059, 47202.29196562054, 47228.29157562631, 9054.592543335566, 9217.585208665592, 9218.953905230464, 9221.170094691477, 9223.400478968846, 9109.635614575409, 9190.31072669549, 9195.402298850588, 9196.448213107202, 9197.402168859036, 9205.861912071325, 9254.0, 9255.490947997858, 9257.16685498305, 9258.907410925873, 9141.725748227564, 9261.72214833556, 9270.814583708288, 9276.675316363928, 9277.0, 9068.909310906873, 9124.0419755926, 9134.771630709285, 9135.817283654289, 9141.31440141992, 44823.32765008527, 45233.54766452326, 45248.3212751809, 45249.321260181125, 45298.0, 3577.3918433866306, 3764.7741135531683, 3833.9616603833883, 3918.2555314490155, 4153.460050193486, 33292.833535832164, 33646.32707345839, 33845.83077084624, 33876.0, 34066.48900266498, 4253.80857861397, 4312.266914624522, 4342.913141737147, 4427.822887084512, 4435.135148646015, 357922.0, 357982.0, 358777.0, 359328.0, 359512.9999999974, 63657.36342636514, 63736.36263637397, 64251.35748642547, 64361.0, 64369.0, 56692.0, 63152.68423657852, 64673.67663161748, 66866.66566667135, 66992.33007669958, 17116.914415427844, 17240.224189911485, 17282.13589320529, 17294.567635809206, 17304.480865574053, 13284.667883302802, 13377.398017089059, 13387.397567109503, 13388.263645499395, 13389.397477113553, 16586.170691465377, 18402.35591754309, 19302.71045934312, 19452.805471945383, 19671.409857704293, 6138.5396095292135, 6252.687365631791, 6263.78076767311, 6378.585391949508, 6436.0, 990.1433468835374, 1014.4369874719454, 1051.3324039234988, 1116.1963386361806, 1154.203599516331, 33320.83339583335, 33546.3290734184, 33760.37442116414, 34021.489677654856, 34422.0, 1102.4102105373586, 1110.622388387952, 1110.9944450277858, 1122.4724379541603, 1162.8546431696038, 17486.21312040961, 17538.0, 17818.287268509113, 17917.19372628235, 18013.729794052837, 517985.0, 524785.0, 529062.0, 530516.0, 531706.0, 62371.0, 62465.37534624687, 62491.0, 62651.37348626547, 62666.0, 1215.3558613934629, 1318.3935389720837, 1339.2031741114022, 1342.90599658023, 1381.799639052352, 34016.319673606886, 35291.0, 35462.46806297856, 35862.820685896404, 35886.461703073954, 54107.0, 54777.45222547724, 54868.72565637146, 54913.450865490835, 54932.17601735897, 416848.0, 418710.0, 419935.0, 420288.0, 420671.0, 49051.0, 49244.2613360807, 50603.0, 51364.22953655773, 51636.48363516317, 48573.51426485831, 49264.507354927424, 51923.22115168351, 52827.47172528225, 54023.729881351916, 44340.0, 44365.55634443614, 44882.77558612186, 44934.55065449434, 45929.0, 44134.37153273219, 44686.55313446824, 45085.77457112693, 46057.76971115123, 46059.53940460552, 44109.77945110382, 44398.0, 45342.77328613336, 45343.093138137716, 45493.77253113713, 37018.44797968525, 39167.804160979016, 39322.60677393304, 40263.396049058705, 40425.595744042184, 29203.96890854947, 29768.553471698375, 29940.251493712833, 29990.550141747455, 30183.547246790877], "value": 21279.498941337722, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.3038378998851, 402.071215492213, 405.95737447568007, 407.9755214687119, 419.0989372848376, 14268.230799080628, 19122.33071842486, 20148.8992555037, 20263.493412664673, 20286.0, 19348.70976935347, 20303.289384871532, 20314.28899988497, 20514.076866541003, 20554.177832886668, 5253.212018197263, 5340.62615616906, 5501.229827824108, 5515.227868098469, 5522.392536820947, 5390.0, 5412.404635490093, 5418.349798024243, 5447.0, 5462.0, 5454.209139674745, 5461.0716178249795, 5818.738156782955, 6152.107944348067, 6153.7538498460235, 6005.819825405245, 6178.042403427454, 6185.072239164117, 6191.659458729768, 6215.440610345068, 5925.73334199962, 5938.673372964486, 5975.565864192606, 5984.820455386345, 5986.97006514969, 14632.707345853023, 14950.177740224284, 15118.697626047417, 17703.73444398335, 24115.517689646284, 2445.0464318915638, 2462.5074985002893, 2723.360010397555, 2847.729465700776, 3027.8948183912935, 10486.79026419475, 10497.737556561071, 10567.73580660482, 10785.78428431427, 10931.781364372668, 1893.8390236829887, 1897.7627796525435, 1956.2859556262003, 2008.98995505023, 2041.469218003315, 62944.68527657378, 65133.0, 78082.0, 82230.58884705538, 86533.13466865313, 11162.441877906072, 11249.550017999352, 11380.430978451044, 11441.37072461006, 11716.121290903046, 15925.12411817338, 16040.919795400949, 16044.037357758572, 16070.678586428206, 16077.0, 8202.138775428493, 8300.128486508951, 8342.37432192588, 8363.707270245633, 8563.914360856312, 7756.844863102707, 7760.611969401507, 7853.214678532101, 7954.085280192852, 7975.920240797634, 4861.1979023460735, 4872.537108974641, 4891.486393928586, 5000.449950505442, 5041.143005689041, 2027.756669199676, 2027.7668068172054, 2046.334941144134, 2058.5779915117364, 2065.917363305481, 936.0545848692733, 992.5483904823288, 1007.5012868630075, 1029.325791606499, 1041.4272150317295, 20365.389038328874, 20928.895355523124, 21072.578548428944, 21112.366629001153, 21205.681914771292, 961.9855702164473, 993.6174572789528, 1018.8013337399284, 1044.6970378590124, 1064.842741272132, 10617.522211500502, 10832.079273261861, 11165.83251251232, 11240.943795281135, 11373.431328433546, 504145.0, 511816.0, 515868.0, 520515.0, 523356.0, 102726.48636756769, 103308.0, 103771.48114259381, 104186.47906760417, 104317.0, 1061.7292590389402, 1064.6327017179215, 1066.4827558634056, 1070.0957690751302, 1075.2473268711776, 17870.285188592312, 17904.462866114038, 18202.089895504905, 18266.36067737623, 18476.0, 40017.79991100026, 42758.78620606877, 42998.35502467528, 43676.34485482784, 44000.33999489946, 51241.48758512367, 60628.0, 63220.103396898165, 63363.0, 63722.68138659277, 7681.654325555475, 7802.648880800264, 7862.33170180541, 8506.702265420681, 9207.079292070739, 9402.905970940203, 13582.0, 13611.319434028457, 13887.166769993833, 14147.787783183054, 11764.235324703866, 11882.881171188177, 12040.75918481643, 12084.0, 12374.0, 11779.234349767237, 11902.583409580624, 12032.939835300767, 12079.064411087298, 12225.388730563614, 12116.33360165164, 12175.0, 12387.504499819906, 12546.560870369492, 12733.61799146027, 7765.107012693386, 7985.6007199638625, 8005.479643823133, 8094.271515563628, 8279.337652987626, 11551.480183391935, 11976.520939162336, 12008.63974080779, 12144.39278036077, 12371.814422783485], "value": 9376.080634328666, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1221.309959872672, 1328.681116532032, 1362.4413990263993, 1369.760291948909, 1796.0391190712962, 8538.445001074933, 9016.68441604544, 9043.95478022609, 9247.029061948499, 9258.67594634188, 8936.419132756366, 9146.725598232064, 9150.816983660323, 9153.176214140725, 9163.679271225508, 2372.0, 2410.349205714456, 2411.1681469892947, 2415.2754173747803, 2416.565018296706, 2386.677798497207, 2405.398650337416, 2425.126954296452, 2428.4778772563895, 2429.465517586128, 2369.478714682767, 2398.1846172301416, 2405.7714517120844, 2411.6503107049466, 2411.987940060306, 2336.123953517431, 2339.988300058506, 2342.5666251743482, 2351.1418332308754, 2380.33350661815, 2275.590393729128, 2332.2769941318247, 2332.6267797152373, 2337.0651739304244, 2343.1798870395396, 7949.364050875916, 8194.467359621605, 9147.542622868896, 9223.723288301362, 9274.814503709888, 4941.382327209099, 5074.847754567369, 5368.785248590052, 5550.58370622205, 5554.583406244547, 3589.533360663097, 3621.529201203853, 3642.0894776305922, 3686.0416291764336, 3691.24329512452, 1205.421397729095, 1218.7928052231052, 1230.6554164833772, 1241.6213055018216, 1247.3451437995034, 12912.418941147671, 13018.218906865619, 13036.86963130366, 13068.803967940488, 13081.934590327082, 4644.256918892961, 4690.0, 4815.422149342101, 4819.566239038503, 4974.875628109254, 11001.504932278067, 11026.779464410667, 11042.392668403154, 11051.778964420666, 11056.336619802838, 5600.775968961278, 5627.155926611041, 5631.267935168484, 5636.210930469738, 5638.154276858422, 5031.0, 5132.050570644443, 5150.562202212779, 5190.2993095932325, 5192.428832828386, 3961.465202197724, 3976.721629485908, 4015.0765323975647, 4048.7773172475795, 4100.220958017967, 1243.564752336675, 1257.0948916779903, 1257.4592925042136, 1269.206745783885, 1281.2696762845276, 957.5499515227971, 999.1906555689915, 1002.1682003936716, 1009.1876039787894, 1023.3603997501561, 6465.418112369815, 6538.869222615521, 6540.6729663516635, 6570.408663220333, 6578.506612004119, 467.98213884800424, 475.6123759136362, 475.63613835415936, 478.3709422109984, 481.3670023918536, 4272.850450234289, 4404.185225733186, 4411.500269724863, 4473.150101480708, 4482.013956929471, 11033.6138235163, 11469.71325716847, 11479.4260286987, 11551.826722599168, 11567.537298508134, 11382.601608943647, 11399.772004559862, 11402.372869492092, 11416.714582135513, 11424.143189260838, 8006.439549231498, 8331.0, 8347.624356903954, 8494.362922780816, 8502.192291732275, 6322.683865806784, 6523.054157147282, 6589.374009469093, 6693.1298931138645, 6740.191177058784, 10496.265261431627, 10709.946450267698, 10748.570057197625, 10777.191710621892, 10906.018458338787, 10851.782964340828, 10882.183836212474, 10966.616168434066, 10967.50646220906, 11068.169887258327, 8104.189581041848, 8190.672373105009, 8257.21556452136, 8259.545724984942, 8263.628136733741, 8893.0, 9010.819783604422, 9022.5939832709, 9027.548622568715, 9033.232175264913, 7909.52542847431, 7932.881006785018, 8190.426670133033, 8223.671053157806, 8468.872966905377, 7921.603919804102, 8104.108548059827, 8132.959335203286, 8211.753647390588, 8289.461185022954, 7428.6285685716575, 7477.102747670315, 7531.510451820614, 7533.660985255567, 7533.698652053857, 5508.3114610673665, 5590.972045139748, 5603.439656034364, 5614.382417934105, 5702.714864256854, 5494.972525137348, 5592.636478628876, 5595.272614560081, 5732.0, 5738.48353648157], "value": 4760.249330197279, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.6950320953118, 334.5901270943095, 341.75905986279673, 342.2624244752557, 343.0634368174884, 5297.549708274796, 5336.786528538859, 5338.652987555806, 5369.24830523726, 5388.0, 5057.342545469084, 5158.742062896849, 5164.875250473457, 5176.2235664650325, 5340.25236466894, 4075.7962101894936, 4125.46368972034, 4190.7485550866895, 4206.705530612859, 4227.112306415656, 4168.854090106847, 4198.895027624319, 4205.201011807762, 4207.600277973587, 4208.242516347056, 4085.53016403113, 4176.45706058212, 4176.7911604419805, 4177.4778152730905, 4183.79081045948, 4144.300836656987, 4152.8131234094535, 4163.063310755087, 4183.9121828324705, 4197.790110494463, 3695.9260814783825, 3702.1670124222105, 3708.5735140458924, 3709.5733990591157, 3721.683656889141, 4978.904640978979, 5171.741412929338, 5194.532492075694, 5197.558207552363, 5203.323567936144, 4721.268203428474, 4743.667943243974, 4748.833790817339, 4751.691140075884, 4755.809767609291, 5464.562834973192, 5500.257465242181, 5505.467484956885, 5508.14623733318, 5509.641873278224, 3943.3887747398912, 4009.799510024516, 4045.656119229869, 4055.2295063938054, 4066.979665101685, 5666.603337766358, 5675.432456754333, 5692.37383887772, 5695.515881150107, 5696.800611978602, 4131.938020929689, 4138.730982486129, 4142.337226043879, 4142.606452387018, 4146.958530414658, 4015.8393664253686, 4180.4708151568475, 4188.602082802129, 4192.790360481964, 4195.937060944088, 4720.598749106364, 4721.244600863845, 4722.480527142012, 4726.740029298421, 4729.905401891943, 5507.449255074461, 5527.281453411111, 5530.723463826793, 5534.501894829405, 5537.224788529609, 1326.270551196838, 1371.2663724907127, 1429.0925262458206, 1448.9565313040625, 1449.2029383838847, 1003.7540802503433, 1021.0810270756378, 1021.918246540275, 1025.1542477456128, 1028.3469996552094, 5266.1574148136115, 5301.3373328333955, 5306.177542480922, 5310.0, 5328.3605967284175, 5606.887862242732, 5617.32592088952, 5632.239647647515, 5639.238702775155, 5641.238432811518, 5101.234814777738, 5326.520613144836, 5333.2266821310695, 5339.679619222859, 5343.759530821045, 2608.4652646207483, 2640.2475294540964, 2657.3356660834793, 2683.4633073385403, 2692.367293685982, 5137.280780690707, 5157.819476318311, 5164.3802743671, 5171.715555644476, 5536.390996990328, 5769.451902069297, 5806.519337568893, 5822.94177058224, 5824.184614154022, 5846.210761547221, 5842.020569509581, 5874.825034993019, 5881.676507792027, 5908.084246941817, 5920.79277225295, 5506.977301311735, 5696.145578163143, 5699.487046165865, 5712.371639119777, 5743.655380677173, 5602.215689803349, 5631.746571404378, 5641.323041235078, 5654.236678048411, 5654.886902261849, 5604.579656525695, 5848.0, 5861.706914654335, 5882.794102206402, 5901.881962360814, 3873.3996230584303, 4036.5761595033273, 4040.5555388907783, 4043.353063509824, 4051.521194763949, 5596.804111856066, 5720.456556627114, 5728.770849166153, 5729.512991395612, 5743.10981797822, 5597.356304025089, 5601.131824567198, 5607.243022192115, 5622.971885140548, 5633.464820842013, 5345.65253258537, 5569.888602228014, 5604.887902242014, 5605.32736071674, 5609.775608975595, 5512.3660779010925, 5512.972435137799, 5514.283143191359, 5524.779008839762, 5527.088030474926, 5622.156676498558, 5628.382801997091, 5634.352049514195, 5636.577256705846, 5641.746121424628, 5274.7890084396195, 5529.972350138223, 5533.944660553343, 5534.1975413565615, 5537.535796570487], "value": 4230.18646273024, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.9772606821796, 835.8244768598594, 840.5629072882101, 854.7777577829762, 896.3546246702372, 15964.920175399122, 16559.0, 16591.585210369733, 16594.75107873383, 16675.0, 15430.845691543082, 15634.452794152206, 15634.765478517831, 15661.843381566181, 15674.608134796636, 674.2549482821486, 783.964721587527, 795.2921899509427, 811.168552233959, 820.5938060659981, 818.7380038387719, 822.4695071678764, 822.7943014246439, 823.0617096509983, 823.069930977997, 867.3928250224836, 876.3208513402101, 876.5091548732698, 876.6318146378536, 877.1535468273115, 711.491283732134, 843.3084870406269, 869.695606537713, 873.2010210657272, 873.52829472085, 630.5271046714964, 633.0915136778738, 633.1452539072262, 633.2907143998705, 633.3603060908515, 15117.924410377987, 15133.092014479056, 15136.318865650963, 15151.848481515264, 15163.241837908061, 14536.854631453656, 14785.482508112273, 14896.478623248242, 14915.925420372938, 14918.2540872957, 13019.739605207937, 13117.934410327982, 13161.934190329082, 13170.407331670001, 13315.800262996063, 4419.867403977886, 4524.343970124346, 4584.518625544303, 4620.19146649335, 4635.443746750378, 78789.0, 78941.0, 78944.60527697325, 79194.0, 79425.60287198643, 11273.436328183558, 11463.369514676773, 11465.770684586261, 11620.186586939002, 12229.3273869938, 22107.0, 22211.22260720867, 22231.888840555694, 22280.0, 22291.77708222897, 8742.956285218534, 8755.430896991675, 8821.0, 8839.425437346552, 8904.154105359981, 4546.954530454653, 4864.951350486521, 4955.529224723646, 5068.4424713281505, 5121.641485096082, 3436.106612280825, 3513.80674062929, 3556.395412779782, 3558.057114864563, 3562.2697347043795, 584.1704779213527, 599.7541008186622, 606.3542327421283, 614.5206738743786, 615.218672286203, 660.6465540935673, 677.915260592426, 679.1239301301365, 686.6532401137393, 692.8371832619331, 9554.761130971643, 9595.42427454355, 9630.27772917034, 9642.951785241028, 9926.900730992598, 636.0872148466965, 640.8654182621608, 643.0326569435645, 643.7939859244997, 690.5925503952592, 4680.672352935262, 4700.318453824177, 4707.811687532529, 4713.245880659147, 4716.221823399101, 117231.0, 117447.0, 119541.40229298797, 123119.0, 123917.0, 62554.0, 63275.36724632695, 72932.2706772936, 88366.0, 105385.47307263415, 83723.0, 84125.15874841296, 89674.0, 91899.5405022984, 94214.52892735493, 10278.434686092336, 10582.365058096231, 10613.840792387966, 10624.787504250025, 10650.414227217578, 53583.0, 54605.180922285406, 55819.72090139523, 55973.72013139908, 58302.416975831395, 79561.60219198867, 83098.0, 84374.15625843909, 85241.04968978417, 86232.0, 13552.796708049584, 13895.444182233003, 13970.371333289822, 13970.508027488771, 13980.58058258254, 14406.927965360106, 14460.13239205651, 14483.348249328596, 14653.487127950471, 14714.779278311047, 10841.295315804447, 11228.494717737885, 13199.406026728628, 14245.21651309188, 14828.258587070819, 14868.62828429298, 15008.174550399834, 15023.549293521211, 15147.394104236148, 15246.237688115329, 9391.906080939103, 12874.935625321814, 13688.384022719201, 14285.64285892861, 14916.179610121548, 11528.293479875108, 11579.197905510096, 11759.823602646138, 12254.4485498151, 12666.746665066832, 12039.337836419081, 12133.75732485363, 12265.325407102695, 12646.620601381974, 12821.935890320488], "value": 7745.308718661119, "unit": "score"}], "name": "dromaeo_css", "value": 7378.3085540029215, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 111.0, 110.0, 111.0, 110.0, 110.0, 109.0, 110.0, 110.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 124.0, 179.0, 122.0, 119.0, 144.0, 115.0, 156.0, 120.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 161.0, 161.0, 159.0, 160.0, 162.0, 162.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 122.0, 75.0, 77.0, 74.0, 74.0, 100.0, 75.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 109.0, 84.0, 87.0, 85.0, 124.0, 84.0, 84.0, 121.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 118.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 146.0, 148.0, 146.0, 146.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 51.0, 51.0, 51.0, 51.0, 51.0, 51.0, 53.0, 51.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 64.0, 65.0, 63.0, 63.0, 63.0, 67.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 76.0, 74.0, 74.0, 75.0, 75.0, 74.0, 74.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 118.0, 123.0, 120.0, 118.0, 126.0, 118.0, 162.0, 133.0], "value": 125.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 159.0, 162.0, 162.0, 158.0, 159.0, 158.0, 161.0, 153.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 61.0, 63.0, 62.0, 62.0, 61.0, 64.0, 60.0, 61.0, 64.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1474.6000000000001, "alertThreshold": 2.0}]} 05:43:40 INFO - Return code: 0 05:43:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:43:40 INFO - # TBPL SUCCESS # 05:43:40 INFO - Running post-action listener: _resource_record_post_action 05:43:40 INFO - ##### 05:43:40 INFO - ##### Finished run-tests step (success) 05:43:40 INFO - ##### 05:43:40 INFO - Running post-run listener: _resource_record_post_run 05:43:40 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 77824; Write bytes: 565682176; Read time: 32; Write time: 592228 05:43:40 INFO - TinderboxPrint: CPU usage
13.0% 05:43:40 INFO - TinderboxPrint: I/O read bytes / time
77,824 / 32 05:43:40 INFO - TinderboxPrint: I/O write bytes / time
565,682,176 / 592,228 05:43:40 INFO - TinderboxPrint: CPU idle
8,280.3 (87.0%) 05:43:40 INFO - TinderboxPrint: CPU user
1,187.8 (12.5%) 05:43:40 INFO - TinderboxPrint: Swap in / out
0 / 0 05:43:40 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 270184448; Read time: 0; Write time: 356604 05:43:40 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 73728; Write bytes: 295317504; Read time: 16; Write time: 235600 05:43:41 INFO - Running post-run listener: _upload_blobber_files 05:43:41 INFO - Blob upload gear active. 05:43:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:43:41 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:43:41 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:43:41 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:43:41 INFO - (blobuploader) - INFO - Open directory for files ... 05:43:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:43:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:43:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:42 INFO - (blobuploader) - INFO - Done attempting. 05:43:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:43:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:43:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:45 INFO - (blobuploader) - INFO - Done attempting. 05:43:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:43:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:43:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:43:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:43:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:43:46 INFO - (blobuploader) - INFO - Done attempting. 05:43:46 INFO - (blobuploader) - INFO - Iteration through files over. 05:43:46 INFO - Return code: 0 05:43:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:43:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:43:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/794aacf2d9fffd75875b586acaef913572a39fc257a0ade98b0a5b33264c07243125ce5ce426a2445ca0ce06d490a561113026a85b81b0cb34e68c98018fad61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2832d9e0522ff23b2f9ae5af3534b5ff19c865e774865089822b47359e3fe5ff68e72f302c0d1ba89133094bbcacaf25fafeb1f72e7b8a4d8889e9d4db720f1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c7e44047038231ca6396e1910220c7d1a25fb6e3ba8d1adb1195067ef8676698f25773406c2dbe2f41e0b9478f17704d43b114e462a5b8a51f3b11f9eadc959"} 05:43:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:43:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:43:46 INFO - Contents: 05:43:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/794aacf2d9fffd75875b586acaef913572a39fc257a0ade98b0a5b33264c07243125ce5ce426a2445ca0ce06d490a561113026a85b81b0cb34e68c98018fad61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2832d9e0522ff23b2f9ae5af3534b5ff19c865e774865089822b47359e3fe5ff68e72f302c0d1ba89133094bbcacaf25fafeb1f72e7b8a4d8889e9d4db720f1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c7e44047038231ca6396e1910220c7d1a25fb6e3ba8d1adb1195067ef8676698f25773406c2dbe2f41e0b9478f17704d43b114e462a5b8a51f3b11f9eadc959"} 05:43:46 INFO - Running post-run listener: copy_logs_to_upload_dir 05:43:46 INFO - Copying logs to upload dir... 05:43:46 INFO - mkdir: /builds/slave/test/build/upload/logs 05:43:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.500795 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-07-17 05:43:46.120103) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 05:43:46.123446) ========= 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=eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/794aacf2d9fffd75875b586acaef913572a39fc257a0ade98b0a5b33264c07243125ce5ce426a2445ca0ce06d490a561113026a85b81b0cb34e68c98018fad61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2832d9e0522ff23b2f9ae5af3534b5ff19c865e774865089822b47359e3fe5ff68e72f302c0d1ba89133094bbcacaf25fafeb1f72e7b8a4d8889e9d4db720f1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c7e44047038231ca6396e1910220c7d1a25fb6e3ba8d1adb1195067ef8676698f25773406c2dbe2f41e0b9478f17704d43b114e462a5b8a51f3b11f9eadc959"} 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.011283 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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/794aacf2d9fffd75875b586acaef913572a39fc257a0ade98b0a5b33264c07243125ce5ce426a2445ca0ce06d490a561113026a85b81b0cb34e68c98018fad61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2832d9e0522ff23b2f9ae5af3534b5ff19c865e774865089822b47359e3fe5ff68e72f302c0d1ba89133094bbcacaf25fafeb1f72e7b8a4d8889e9d4db720f1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c7e44047038231ca6396e1910220c7d1a25fb6e3ba8d1adb1195067ef8676698f25773406c2dbe2f41e0b9478f17704d43b114e462a5b8a51f3b11f9eadc959"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-17 05:43:46.148919) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:43:46.149252) ========= 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=eca2ab57546de7b41f256b19000001ef-1468755749.984720-1353276464 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004417 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-17 05:43:46.230747) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 05:43:46.231085) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-17 05:43:46.231386) ========= ========= Total master_lag: 0.17 =========