builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-001 starttime: 1468451283.52 results: success (0) buildid: 20160713154542 builduid: df9637022caf4d3383bef82a269a1255 revision: 31cab0ba8bbf1d88b08abeae45bcdf317645355a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:03.518986) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:03.519468) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:03.519752) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:03.799514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:03.799779) ========= 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=8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-13 16:08:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 114M=0s 2016-07-13 16:08:04 (114 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109961 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:04.050696) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:04.050975) ========= 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=8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019511 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:04.101998) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 16:08:04.102337) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 31cab0ba8bbf1d88b08abeae45bcdf317645355a --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 31cab0ba8bbf1d88b08abeae45bcdf317645355a --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=8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-13 16:08:04,672 truncating revision to first 12 chars 2016-07-13 16:08:04,672 Setting DEBUG logging. 2016-07-13 16:08:04,672 attempt 1/10 2016-07-13 16:08:04,672 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/31cab0ba8bbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 16:08:05,719 unpacking tar archive at: fx-team-31cab0ba8bbf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.367858 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 16:08:05.488887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:05.489629) ========= script_repo_revision: 31cab0ba8bbf1d88b08abeae45bcdf317645355a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:05.491197) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:05.491931) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 16:08:05.503587) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-13 16:08:05.504313) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:08:06 INFO - MultiFileLogger online at 20160713 16:08:06 in /builds/slave/test 16:08:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 16:08:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:08:06 INFO - {'append_to_log': False, 16:08:06 INFO - 'base_work_dir': '/builds/slave/test', 16:08:06 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 16:08:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:08:06 INFO - 'branch': 'Fx-Team-Non-PGO', 16:08:06 INFO - 'buildbot_json_path': 'buildprops.json', 16:08:06 INFO - 'config_files': ('talos/linux_config.py',), 16:08:06 INFO - 'default_actions': ('clobber', 16:08:06 INFO - 'read-buildbot-config', 16:08:06 INFO - 'download-and-extract', 16:08:06 INFO - 'populate-webroot', 16:08:06 INFO - 'create-virtualenv', 16:08:06 INFO - 'install', 16:08:06 INFO - 'run-tests'), 16:08:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:08:06 INFO - 'download_minidump_stackwalk': True, 16:08:06 INFO - 'download_symbols': 'ondemand', 16:08:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:08:06 INFO - 'tooltool.py': '/tools/tooltool.py', 16:08:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:08:06 INFO - '/tools/misc-python/virtualenv.py')}, 16:08:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:08:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:08:06 INFO - 'installer_path': 'installer.exe', 16:08:06 INFO - 'log_level': 'info', 16:08:06 INFO - 'log_name': 'talos', 16:08:06 INFO - 'log_to_console': True, 16:08:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:08:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:08:06 INFO - 'opt_config_files': (), 16:08:06 INFO - 'pip_index': False, 16:08:06 INFO - 'sps_profile': False, 16:08:06 INFO - 'sps_profile_interval': 0, 16:08:06 INFO - 'suite': 'dromaeojs', 16:08:06 INFO - 'system_bits': '32', 16:08:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:08:06 INFO - 'title': 'talos-linux64-ix-001', 16:08:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:08:06 INFO - 'use_talos_json': True, 16:08:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:08:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:08:06 INFO - 'work_dir': 'build'} 16:08:06 INFO - ##### 16:08:06 INFO - ##### Running clobber step. 16:08:06 INFO - ##### 16:08:06 INFO - Running pre-action listener: _resource_record_pre_action 16:08:06 INFO - Running main action method: clobber 16:08:06 INFO - rmtree: /builds/slave/test/build 16:08:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:08:06 INFO - Running post-action listener: _resource_record_post_action 16:08:06 INFO - ##### 16:08:06 INFO - ##### Finished clobber step (success) 16:08:06 INFO - ##### 16:08:06 INFO - ##### 16:08:06 INFO - ##### Running read-buildbot-config step. 16:08:06 INFO - ##### 16:08:06 INFO - Running pre-action listener: _resource_record_pre_action 16:08:06 INFO - Running main action method: read_buildbot_config 16:08:06 INFO - Using buildbot properties: 16:08:06 INFO - { 16:08:06 INFO - "project": "", 16:08:06 INFO - "product": "firefox", 16:08:06 INFO - "script_repo_revision": "production", 16:08:06 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 16:08:06 INFO - "repository": "", 16:08:06 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 16:08:06 INFO - "buildid": "20160713154542", 16:08:06 INFO - "pgo_build": "False", 16:08:06 INFO - "basedir": "/builds/slave/test", 16:08:06 INFO - "buildnumber": 1806, 16:08:06 INFO - "slavename": "talos-linux64-ix-001", 16:08:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:08:06 INFO - "platform": "ubuntu64_hw", 16:08:06 INFO - "scheduler": "tests-fx-team-linux64-talos", 16:08:06 INFO - "branch": "fx-team", 16:08:06 INFO - "repo_path": "integration/fx-team", 16:08:06 INFO - "revision": "31cab0ba8bbf1d88b08abeae45bcdf317645355a", 16:08:06 INFO - "stage_platform": "linux64", 16:08:06 INFO - "builduid": "df9637022caf4d3383bef82a269a1255", 16:08:06 INFO - "slavebuilddir": "test" 16:08:06 INFO - } 16:08:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:08:06 INFO - Running post-action listener: _resource_record_post_action 16:08:06 INFO - ##### 16:08:06 INFO - ##### Finished read-buildbot-config step (success) 16:08:06 INFO - ##### 16:08:06 INFO - ##### 16:08:06 INFO - ##### Running download-and-extract step. 16:08:06 INFO - ##### 16:08:06 INFO - Running pre-action listener: _resource_record_pre_action 16:08:06 INFO - Running main action method: download_and_extract 16:08:06 INFO - mkdir: /builds/slave/test/build/tests 16:08:06 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')]} 16:08:06 INFO - https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:08:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/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 16:08:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/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 16:08:10 INFO - Downloaded 1361 bytes. 16:08:10 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:10 INFO - Using the following test package requirements: 16:08:10 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:08:10 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:08:10 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:08:10 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:10 INFO - u'jsshell-linux-x86_64.zip'], 16:08:10 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:08:10 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:08:10 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:08:10 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:08:10 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:08:10 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:10 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:08:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:08:10 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')]} 16:08:10 INFO - https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:08:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/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 16:08:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/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 16:08:12 INFO - Downloaded 22755900 bytes. 16:08:12 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'] 16:08:12 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 16:08:14 INFO - Return code: 0 16:08:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:08:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:14 INFO - https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:08:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/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 16:08:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/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 16:08:15 INFO - Downloaded 22755900 bytes. 16:08:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:08:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:08:17 INFO - Return code: 0 16:08: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')]} 16:08:17 INFO - https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:08:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/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 16:08:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/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 16:08:18 INFO - Downloaded 14403317 bytes. 16:08:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:08:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:08:18 INFO - Return code: 0 16:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:18 INFO - https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:08:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/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 16:08:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BDmzb8qoSxqaBHjLDKjRQw/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 16:08:20 INFO - Downloaded 57045518 bytes. 16:08:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:20 INFO - mkdir: /builds/slave/test/properties 16:08:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:08:20 INFO - Writing to file /builds/slave/test/properties/build_url 16:08:20 INFO - Contents: 16:08:20 INFO - build_url:https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:21 INFO - Running post-action listener: _resource_record_post_action 16:08:21 INFO - Running post-action listener: set_extra_try_arguments 16:08:21 INFO - ##### 16:08:21 INFO - ##### Finished download-and-extract step (success) 16:08:21 INFO - ##### 16:08:21 INFO - ##### 16:08:21 INFO - ##### Running populate-webroot step. 16:08:21 INFO - ##### 16:08:21 INFO - Running pre-action listener: _resource_record_pre_action 16:08:21 INFO - Running main action method: populate_webroot 16:08:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:08:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:08:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:08:21 INFO - '--tppagecycles', 16:08:21 INFO - '7'], 16:08:21 INFO - 'tests': ['tsvgm']}}, 16:08:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:08:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:08:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:08:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:08:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:08:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:08:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:21 INFO - 'talos_options': ['--disable-e10s'], 16:08:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:21 INFO - 'talos_options': ['--disable-e10s'], 16:08:21 INFO - 'tests': ['damp', 'tps']}, 16:08:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:21 INFO - 'tests': ['damp', 'tps']}, 16:08:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:08:21 INFO - 'tests': ['dromaeo_dom']}, 16:08:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:08:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:08:21 INFO - 'tests': ['basic_compositor_video']}, 16:08:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:08:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:08:21 INFO - 'tests': ['a11yr', 16:08:21 INFO - 'ts_paint', 16:08:21 INFO - 'tpaint', 16:08:21 INFO - 'sessionrestore', 16:08:21 INFO - 'sessionrestore_no_auto_restore', 16:08:21 INFO - 'tabpaint']}, 16:08:21 INFO - 'other-e10s': {'tests': ['a11yr', 16:08:21 INFO - 'ts_paint', 16:08:21 INFO - 'tpaint', 16:08:21 INFO - 'sessionrestore', 16:08:21 INFO - 'sessionrestore_no_auto_restore', 16:08:21 INFO - 'tabpaint']}, 16:08:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:08:21 INFO - 'ts_paint', 16:08:21 INFO - 'tpaint', 16:08:21 INFO - 'sessionrestore', 16:08:21 INFO - 'sessionrestore_no_auto_restore', 16:08:21 INFO - 'tabpaint']}, 16:08:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:08:21 INFO - 'ts_paint', 16:08:21 INFO - 'tpaint', 16:08:21 INFO - 'sessionrestore', 16:08:21 INFO - 'sessionrestore_no_auto_restore', 16:08:21 INFO - 'tabpaint']}, 16:08:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:08:21 INFO - 'tests': ['a11yr', 16:08:21 INFO - 'ts_paint', 16:08:21 INFO - 'tpaint', 16:08:21 INFO - 'sessionrestore', 16:08:21 INFO - 'sessionrestore_no_auto_restore', 16:08:21 INFO - 'tabpaint']}, 16:08:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:08:21 INFO - 'tests': ['a11yr', 16:08:21 INFO - 'ts_paint', 16:08:21 INFO - 'tpaint', 16:08:21 INFO - 'sessionrestore', 16:08:21 INFO - 'sessionrestore_no_auto_restore', 16:08:21 INFO - 'tabpaint']}, 16:08:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:08:21 INFO - 'tests': ['tsvgx', 16:08:21 INFO - 'tsvgr_opacity', 16:08:21 INFO - 'tart', 16:08:21 INFO - 'tscrollx', 16:08:21 INFO - 'cart']}, 16:08:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:08:21 INFO - 'tsvgr_opacity', 16:08:21 INFO - 'tart', 16:08:21 INFO - 'tscrollx', 16:08:21 INFO - 'cart']}, 16:08:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:21 INFO - 'talos_options': ['--disable-e10s'], 16:08:21 INFO - 'tests': ['tp5o']}, 16:08:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:21 INFO - 'tests': ['tp5o']}, 16:08:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:21 INFO - 'talos_options': ['--disable-e10s', 16:08:21 INFO - '--xperf_path', 16:08:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:21 INFO - 'tests': ['tp5n']}, 16:08:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:21 INFO - 'talos_options': ['--xperf_path', 16:08:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:21 INFO - 'tests': ['tp5n']}}, 16:08:21 INFO - 'talos.zip': {'path': '', 16:08:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:08:21 INFO - Running post-action listener: _resource_record_post_action 16:08:21 INFO - ##### 16:08:21 INFO - ##### Finished populate-webroot step (success) 16:08:21 INFO - ##### 16:08:21 INFO - ##### 16:08:21 INFO - ##### Running create-virtualenv step. 16:08:21 INFO - ##### 16:08:21 INFO - Running pre-action listener: _resource_record_pre_action 16:08:21 INFO - Running main action method: create_virtualenv 16:08:21 INFO - Creating virtualenv /builds/slave/test/build/venv 16:08:21 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 16:08:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:08:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:08:21 INFO - Using real prefix '/usr' 16:08:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:08:22 INFO - Installing distribute.............................................................................................................................................................................................done. 16:08:23 INFO - Installing pip.................done. 16:08:23 INFO - Return code: 0 16:08:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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')]} 16:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:23 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 0xabe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d3b0>, '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 0xd7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd7e740>, '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': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', '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 16:08:23 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 16:08:23 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 16:08:23 INFO - Using env: {'DISPLAY': ':0', 16:08:23 INFO - 'HOME': '/home/cltbld', 16:08:23 INFO - 'LANG': 'en_US.UTF-8', 16:08:23 INFO - 'LANGUAGE': 'en_US:en', 16:08:23 INFO - 'LOGNAME': 'cltbld', 16:08:23 INFO - 'MAIL': '/var/mail/cltbld', 16:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:23 INFO - 'MOZ_NO_REMOTE': '1', 16:08:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:23 INFO - 'NO_EM_RESTART': '1', 16:08:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:23 INFO - 'PWD': '/builds/slave/test', 16:08:23 INFO - 'SHELL': '/bin/bash', 16:08:23 INFO - 'SHLVL': '1', 16:08:23 INFO - 'TERM': 'linux', 16:08:23 INFO - 'TMOUT': '86400', 16:08:23 INFO - 'USER': 'cltbld', 16:08:23 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', 16:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:24 INFO - '_': '/tools/buildbot/bin/python'} 16:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:08:24 INFO - Cleaning up... 16:08:24 INFO - Return code: 0 16:08:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xabe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d3b0>, '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 0xd7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd7e740>, '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': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', '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 16:08:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:08:24 INFO - Using env: {'DISPLAY': ':0', 16:08:24 INFO - 'HOME': '/home/cltbld', 16:08:24 INFO - 'LANG': 'en_US.UTF-8', 16:08:24 INFO - 'LANGUAGE': 'en_US:en', 16:08:24 INFO - 'LOGNAME': 'cltbld', 16:08:24 INFO - 'MAIL': '/var/mail/cltbld', 16:08:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:24 INFO - 'MOZ_NO_REMOTE': '1', 16:08:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:24 INFO - 'NO_EM_RESTART': '1', 16:08:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:24 INFO - 'PWD': '/builds/slave/test', 16:08:24 INFO - 'SHELL': '/bin/bash', 16:08:24 INFO - 'SHLVL': '1', 16:08:24 INFO - 'TERM': 'linux', 16:08:24 INFO - 'TMOUT': '86400', 16:08:24 INFO - 'USER': 'cltbld', 16:08:24 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', 16:08:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:24 INFO - '_': '/tools/buildbot/bin/python'} 16:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:24 INFO - Downloading/unpacking psutil>=3.1.1 16:08:24 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 16:08:24 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 16:08:24 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 16:08:24 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 16:08:24 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 16:08:24 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 16:08:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:08:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:26 INFO - Installing collected packages: psutil 16:08:26 INFO - Running setup.py install for psutil 16:08:26 INFO - building 'psutil._psutil_linux' extension 16:08:26 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 16:08:26 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 16:08:26 INFO - building 'psutil._psutil_posix' extension 16:08:26 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 16:08:26 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 16:08:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:26 INFO - Successfully installed psutil 16:08:26 INFO - Cleaning up... 16:08:26 INFO - Return code: 0 16:08:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xabe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d3b0>, '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 0xd7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd7e740>, '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': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', '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 16:08:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:08:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:08:27 INFO - Using env: {'DISPLAY': ':0', 16:08:27 INFO - 'HOME': '/home/cltbld', 16:08:27 INFO - 'LANG': 'en_US.UTF-8', 16:08:27 INFO - 'LANGUAGE': 'en_US:en', 16:08:27 INFO - 'LOGNAME': 'cltbld', 16:08:27 INFO - 'MAIL': '/var/mail/cltbld', 16:08:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:27 INFO - 'MOZ_NO_REMOTE': '1', 16:08:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:27 INFO - 'NO_EM_RESTART': '1', 16:08:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:27 INFO - 'PWD': '/builds/slave/test', 16:08:27 INFO - 'SHELL': '/bin/bash', 16:08:27 INFO - 'SHLVL': '1', 16:08:27 INFO - 'TERM': 'linux', 16:08:27 INFO - 'TMOUT': '86400', 16:08:27 INFO - 'USER': 'cltbld', 16:08:27 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', 16:08:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:27 INFO - '_': '/tools/buildbot/bin/python'} 16:08:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:08:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:08:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:08:29 INFO - Installing collected packages: mozsystemmonitor 16:08:29 INFO - Running setup.py install for mozsystemmonitor 16:08:29 INFO - Successfully installed mozsystemmonitor 16:08:29 INFO - Cleaning up... 16:08:29 INFO - Return code: 0 16:08:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xabe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d3b0>, '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 0xd7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd7e740>, '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': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', '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 16:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:08:29 INFO - Using env: {'DISPLAY': ':0', 16:08:29 INFO - 'HOME': '/home/cltbld', 16:08:29 INFO - 'LANG': 'en_US.UTF-8', 16:08:29 INFO - 'LANGUAGE': 'en_US:en', 16:08:29 INFO - 'LOGNAME': 'cltbld', 16:08:29 INFO - 'MAIL': '/var/mail/cltbld', 16:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:29 INFO - 'MOZ_NO_REMOTE': '1', 16:08:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:29 INFO - 'NO_EM_RESTART': '1', 16:08:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:29 INFO - 'PWD': '/builds/slave/test', 16:08:29 INFO - 'SHELL': '/bin/bash', 16:08:29 INFO - 'SHLVL': '1', 16:08:29 INFO - 'TERM': 'linux', 16:08:29 INFO - 'TMOUT': '86400', 16:08:29 INFO - 'USER': 'cltbld', 16:08:29 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', 16:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:29 INFO - '_': '/tools/buildbot/bin/python'} 16:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:29 INFO - Downloading/unpacking jsonschema==2.5.1 16:08:29 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 16:08:29 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 16:08:29 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 16:08:29 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 16:08:29 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 16:08:29 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 16:08:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:08:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:31 INFO - Downloading functools32-3.2.3-2.tar.gz 16:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:08:31 INFO - warning: no files found matching '*.txt' 16:08:31 INFO - no previously-included directories found matching 'build' 16:08:31 INFO - no previously-included directories found matching 'dist' 16:08:31 INFO - no previously-included directories found matching '.git*' 16:08:31 INFO - Installing collected packages: jsonschema, functools32 16:08:31 INFO - Running setup.py install for functools32 16:08:31 INFO - warning: no files found matching '*.txt' 16:08:31 INFO - no previously-included directories found matching 'build' 16:08:31 INFO - no previously-included directories found matching 'dist' 16:08:31 INFO - no previously-included directories found matching '.git*' 16:08:31 INFO - Successfully installed jsonschema functools32 16:08:31 INFO - Cleaning up... 16:08:31 INFO - Return code: 0 16:08:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:08: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')]} 16:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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')]} 16:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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', '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 0xabe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d3b0>, '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 0xd7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd7e740>, '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': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', '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 16:08: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:08: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 blobuploader==1.2.4 16:08:31 INFO - Using env: {'DISPLAY': ':0', 16:08:31 INFO - 'HOME': '/home/cltbld', 16:08:31 INFO - 'LANG': 'en_US.UTF-8', 16:08:31 INFO - 'LANGUAGE': 'en_US:en', 16:08:31 INFO - 'LOGNAME': 'cltbld', 16:08:31 INFO - 'MAIL': '/var/mail/cltbld', 16:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:31 INFO - 'MOZ_NO_REMOTE': '1', 16:08:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:31 INFO - 'NO_EM_RESTART': '1', 16:08:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:31 INFO - 'PWD': '/builds/slave/test', 16:08:31 INFO - 'SHELL': '/bin/bash', 16:08:31 INFO - 'SHLVL': '1', 16:08:31 INFO - 'TERM': 'linux', 16:08:31 INFO - 'TMOUT': '86400', 16:08:31 INFO - 'USER': 'cltbld', 16:08:31 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', 16:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:31 INFO - '_': '/tools/buildbot/bin/python'} 16:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:32 INFO - Downloading/unpacking blobuploader==1.2.4 16:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:34 INFO - Downloading blobuploader-1.2.4.tar.gz 16:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:08:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:08:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:34 INFO - Downloading docopt-0.6.1.tar.gz 16:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:08:34 INFO - Installing collected packages: blobuploader, requests, docopt 16:08:34 INFO - Running setup.py install for blobuploader 16:08:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:08:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:08:34 INFO - Running setup.py install for requests 16:08:35 INFO - Running setup.py install for docopt 16:08:35 INFO - Successfully installed blobuploader requests docopt 16:08:35 INFO - Cleaning up... 16:08:35 INFO - Return code: 0 16:08:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:35 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 0xabe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d3b0>, '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 0xd7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd7e740>, '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': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', '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 16:08:35 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 16:08:35 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 16:08:35 INFO - Using env: {'DISPLAY': ':0', 16:08:35 INFO - 'HOME': '/home/cltbld', 16:08:35 INFO - 'LANG': 'en_US.UTF-8', 16:08:35 INFO - 'LANGUAGE': 'en_US:en', 16:08:35 INFO - 'LOGNAME': 'cltbld', 16:08:35 INFO - 'MAIL': '/var/mail/cltbld', 16:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:35 INFO - 'MOZ_NO_REMOTE': '1', 16:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:35 INFO - 'NO_EM_RESTART': '1', 16:08:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:35 INFO - 'PWD': '/builds/slave/test', 16:08:35 INFO - 'SHELL': '/bin/bash', 16:08:35 INFO - 'SHLVL': '1', 16:08:35 INFO - 'TERM': 'linux', 16:08:35 INFO - 'TMOUT': '86400', 16:08:35 INFO - 'USER': 'cltbld', 16:08:35 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', 16:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:35 INFO - '_': '/tools/buildbot/bin/python'} 16:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:35 INFO - Running setup.py (path:/tmp/pip-03ytH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:35 INFO - Running setup.py (path:/tmp/pip-w75GGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:35 INFO - Running setup.py (path:/tmp/pip-p0uM4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:35 INFO - Running setup.py (path:/tmp/pip-yZNISl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:35 INFO - Running setup.py (path:/tmp/pip-t5NZwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:35 INFO - Running setup.py (path:/tmp/pip-Hu38JG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:36 INFO - Running setup.py (path:/tmp/pip-yr1AqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:36 INFO - Running setup.py (path:/tmp/pip-tAcqo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:36 INFO - Running setup.py (path:/tmp/pip-VzFNC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:36 INFO - Running setup.py (path:/tmp/pip-_Jx3L9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:36 INFO - Running setup.py (path:/tmp/pip-xZRG51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:36 INFO - Running setup.py (path:/tmp/pip-k2aJYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:36 INFO - Running setup.py (path:/tmp/pip-Lqp8nA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:36 INFO - Running setup.py (path:/tmp/pip-hpwAtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:36 INFO - Running setup.py (path:/tmp/pip-5GpOOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:36 INFO - Running setup.py (path:/tmp/pip-UOmmQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:36 INFO - Running setup.py (path:/tmp/pip-4yWEiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:08:36 INFO - Running setup.py install for manifestparser 16:08:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:08:36 INFO - Running setup.py install for mozcrash 16:08:37 INFO - Running setup.py install for mozdebug 16:08:37 INFO - Running setup.py install for mozdevice 16:08:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:08:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:08:37 INFO - Running setup.py install for mozfile 16:08:37 INFO - Running setup.py install for mozhttpd 16:08:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:08:37 INFO - Running setup.py install for mozinfo 16:08:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:08:37 INFO - Running setup.py install for mozInstall 16:08:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:08:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:08:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:08:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:08:37 INFO - Running setup.py install for mozleak 16:08:37 INFO - Running setup.py install for mozlog 16:08:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:08:37 INFO - Running setup.py install for moznetwork 16:08:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:08:37 INFO - Running setup.py install for mozprocess 16:08:38 INFO - Running setup.py install for mozprofile 16:08:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:08:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:08:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:08:38 INFO - Running setup.py install for mozrunner 16:08:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:08:38 INFO - Running setup.py install for mozscreenshot 16:08:38 INFO - Running setup.py install for moztest 16:08:38 INFO - Running setup.py install for mozversion 16:08:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:08:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:08:38 INFO - Cleaning up... 16:08:38 INFO - Return code: 0 16:08:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:38 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 0xabe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d3b0>, '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 0xd7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd7e740>, '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': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', '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 16:08:38 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 16:08:38 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 16:08:38 INFO - Using env: {'DISPLAY': ':0', 16:08:38 INFO - 'HOME': '/home/cltbld', 16:08:38 INFO - 'LANG': 'en_US.UTF-8', 16:08:38 INFO - 'LANGUAGE': 'en_US:en', 16:08:38 INFO - 'LOGNAME': 'cltbld', 16:08:38 INFO - 'MAIL': '/var/mail/cltbld', 16:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:38 INFO - 'MOZ_NO_REMOTE': '1', 16:08:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:38 INFO - 'NO_EM_RESTART': '1', 16:08:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:38 INFO - 'PWD': '/builds/slave/test', 16:08:38 INFO - 'SHELL': '/bin/bash', 16:08:38 INFO - 'SHLVL': '1', 16:08:38 INFO - 'TERM': 'linux', 16:08:38 INFO - 'TMOUT': '86400', 16:08:38 INFO - 'USER': 'cltbld', 16:08:38 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', 16:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:38 INFO - '_': '/tools/buildbot/bin/python'} 16:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:38 INFO - Running setup.py (path:/tmp/pip-0QNCUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:38 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)) 16:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:38 INFO - Running setup.py (path:/tmp/pip-uDzu5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:39 INFO - Running setup.py (path:/tmp/pip-QC4ZJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:39 INFO - Running setup.py (path:/tmp/pip-yoxEq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:39 INFO - Running setup.py (path:/tmp/pip-j6rPD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:39 INFO - Running setup.py (path:/tmp/pip-uC2Pp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:39 INFO - Running setup.py (path:/tmp/pip-5qGf9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:39 INFO - Running setup.py (path:/tmp/pip-j68aCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:39 INFO - Running setup.py (path:/tmp/pip-V7CGkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:39 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)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:39 INFO - Running setup.py (path:/tmp/pip-4JzL1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:39 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)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:39 INFO - Running setup.py (path:/tmp/pip-Uqoywt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:39 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)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:39 INFO - Running setup.py (path:/tmp/pip-Q9gTa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:39 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)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:39 INFO - Running setup.py (path:/tmp/pip-HjRIGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:39 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)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:39 INFO - Running setup.py (path:/tmp/pip-x6fvkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:39 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)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:39 INFO - Running setup.py (path:/tmp/pip-K1mdYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:39 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)) 16:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:39 INFO - Running setup.py (path:/tmp/pip-RmMNww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:40 INFO - Running setup.py (path:/tmp/pip-BiIR_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:08:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:42 INFO - Downloading blessings-1.6.tar.gz 16:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:08:42 INFO - Installing collected packages: blessings 16:08:42 INFO - Running setup.py install for blessings 16:08:42 INFO - Successfully installed blessings 16:08:42 INFO - Cleaning up... 16:08:42 INFO - Return code: 0 16:08:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:08:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:42 INFO - Reading from file tmpfile_stdout 16:08:42 INFO - Current package versions: 16:08:42 INFO - argparse == 1.2.1 16:08:42 INFO - blessings == 1.6 16:08:42 INFO - blobuploader == 1.2.4 16:08:42 INFO - docopt == 0.6.1 16:08:42 INFO - functools32 == 3.2.3-2 16:08:42 INFO - jsonschema == 2.5.1 16:08:42 INFO - manifestparser == 1.1 16:08:42 INFO - mozInstall == 1.12 16:08:42 INFO - mozcrash == 0.17 16:08:42 INFO - mozdebug == 0.1 16:08:42 INFO - mozdevice == 0.48 16:08:42 INFO - mozfile == 1.2 16:08:42 INFO - mozhttpd == 0.7 16:08:42 INFO - mozinfo == 0.9 16:08:42 INFO - mozleak == 0.1 16:08:42 INFO - mozlog == 3.2 16:08:42 INFO - moznetwork == 0.27 16:08:42 INFO - mozprocess == 0.23 16:08:42 INFO - mozprofile == 0.28 16:08:42 INFO - mozrunner == 6.12 16:08:42 INFO - mozscreenshot == 0.1 16:08:42 INFO - mozsystemmonitor == 0.3 16:08:42 INFO - moztest == 0.7 16:08:42 INFO - mozversion == 1.4 16:08:42 INFO - psutil == 3.1.1 16:08:42 INFO - requests == 1.2.3 16:08:42 INFO - wsgiref == 0.1.2 16:08:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xabe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d3b0>, '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 0xd7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd7e740>, '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': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', '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 16:08:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:08:42 INFO - Using env: {'DISPLAY': ':0', 16:08:42 INFO - 'HOME': '/home/cltbld', 16:08:42 INFO - 'LANG': 'en_US.UTF-8', 16:08:42 INFO - 'LANGUAGE': 'en_US:en', 16:08:42 INFO - 'LOGNAME': 'cltbld', 16:08:42 INFO - 'MAIL': '/var/mail/cltbld', 16:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:42 INFO - 'MOZ_NO_REMOTE': '1', 16:08:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:42 INFO - 'NO_EM_RESTART': '1', 16:08:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:42 INFO - 'PWD': '/builds/slave/test', 16:08:42 INFO - 'SHELL': '/bin/bash', 16:08:42 INFO - 'SHLVL': '1', 16:08:42 INFO - 'TERM': 'linux', 16:08:42 INFO - 'TMOUT': '86400', 16:08:42 INFO - 'USER': 'cltbld', 16:08:42 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', 16:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:42 INFO - '_': '/tools/buildbot/bin/python'} 16:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:42 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)) 16:08:42 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)) 16:08:42 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)) 16:08:42 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)) 16:08:42 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)) 16:08:42 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)) 16:08:42 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)) 16:08:42 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)) 16:08:42 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)) 16:08:42 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)) 16:08:42 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)) 16:08:42 INFO - Cleaning up... 16:08:42 INFO - Return code: 0 16:08:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:42 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 0xabe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc218a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd7f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd7d3b0>, '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 0xd7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd7e740>, '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': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', '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 16:08:42 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 16:08:42 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 16:08:42 INFO - Using env: {'DISPLAY': ':0', 16:08:42 INFO - 'HOME': '/home/cltbld', 16:08:42 INFO - 'LANG': 'en_US.UTF-8', 16:08:42 INFO - 'LANGUAGE': 'en_US:en', 16:08:42 INFO - 'LOGNAME': 'cltbld', 16:08:42 INFO - 'MAIL': '/var/mail/cltbld', 16:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:42 INFO - 'MOZ_NO_REMOTE': '1', 16:08:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:42 INFO - 'NO_EM_RESTART': '1', 16:08:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:42 INFO - 'PWD': '/builds/slave/test', 16:08:42 INFO - 'SHELL': '/bin/bash', 16:08:42 INFO - 'SHLVL': '1', 16:08:42 INFO - 'TERM': 'linux', 16:08:42 INFO - 'TMOUT': '86400', 16:08:42 INFO - 'USER': 'cltbld', 16:08:42 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', 16:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:42 INFO - '_': '/tools/buildbot/bin/python'} 16:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:08:42 INFO - Cleaning up... 16:08:42 INFO - Return code: 0 16:08:42 INFO - Running post-action listener: _resource_record_post_action 16:08:42 INFO - Running post-action listener: _start_resource_monitoring 16:08:42 INFO - Starting resource monitoring. 16:08:42 INFO - ##### 16:08:42 INFO - ##### Finished create-virtualenv step (success) 16:08:42 INFO - ##### 16:08:42 INFO - ##### 16:08:42 INFO - ##### Running install step. 16:08:42 INFO - ##### 16:08:42 INFO - Running pre-action listener: _resource_record_pre_action 16:08:42 INFO - Running main action method: install 16:08:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:43 INFO - Reading from file tmpfile_stdout 16:08:43 INFO - Detecting whether we're running mozinstall >=1.0... 16:08:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:08:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:08:43 INFO - Reading from file tmpfile_stdout 16:08:43 INFO - Output received: 16:08:43 INFO - Usage: mozinstall [options] installer 16:08:43 INFO - Options: 16:08:43 INFO - -h, --help show this help message and exit 16:08:43 INFO - -d DEST, --destination=DEST 16:08:43 INFO - Directory to install application into. [default: 16:08:43 INFO - "/builds/slave/test"] 16:08:43 INFO - --app=APP Application being installed. [default: firefox] 16:08:43 INFO - mkdir: /builds/slave/test/build/application 16:08:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:08:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:08:59 INFO - Reading from file tmpfile_stdout 16:08:59 INFO - Output received: 16:08:59 INFO - /builds/slave/test/build/application/firefox/firefox 16:08:59 INFO - Running post-action listener: _resource_record_post_action 16:08:59 INFO - ##### 16:08:59 INFO - ##### Finished install step (success) 16:08:59 INFO - ##### 16:08:59 INFO - ##### 16:08:59 INFO - ##### Running run-tests step. 16:08:59 INFO - ##### 16:08:59 INFO - Running pre-action listener: _resource_record_pre_action 16:08:59 INFO - Running main action method: run_tests 16:08:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:08:59 INFO - Python 2.7.3 16:08:59 INFO - Return code: 0 16:08:59 INFO - grabbing minidump binary from tooltool 16:08:59 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')]} 16:08:59 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 0xd7d3b0>, '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 0xd7e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd7e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:08:59 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 16:08:59 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 16:08:59 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 16:08:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:08:59 INFO - Return code: 0 16:08:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:08:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:08:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:08:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:08:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:08:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 16:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --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 16:08:59 INFO - Using env: {'DISPLAY': ':0', 16:08:59 INFO - 'HOME': '/home/cltbld', 16:08:59 INFO - 'LANG': 'en_US.UTF-8', 16:08:59 INFO - 'LANGUAGE': 'en_US:en', 16:08:59 INFO - 'LOGNAME': 'cltbld', 16:08:59 INFO - 'MAIL': '/var/mail/cltbld', 16:08:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:08:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:59 INFO - 'MOZ_NO_REMOTE': '1', 16:08:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:08:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:59 INFO - 'NO_EM_RESTART': '1', 16:08:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:59 INFO - 'PWD': '/builds/slave/test', 16:08:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:08:59 INFO - 'SHELL': '/bin/bash', 16:08:59 INFO - 'SHLVL': '1', 16:08:59 INFO - 'TERM': 'linux', 16:08:59 INFO - 'TMOUT': '86400', 16:08:59 INFO - 'USER': 'cltbld', 16:08:59 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511', 16:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:59 INFO - '_': '/tools/buildbot/bin/python'} 16:08:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--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 16:08:59 INFO - mozversion application_buildid: 20160713154542 16:08:59 INFO - mozversion application_changeset: 31cab0ba8bbf1d88b08abeae45bcdf317645355a 16:08:59 INFO - mozversion application_display_name: Nightly 16:08:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:08:59 INFO - mozversion application_name: Firefox 16:08:59 INFO - mozversion application_remotingname: firefox 16:08:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:08:59 INFO - mozversion application_vendor: Mozilla 16:08:59 INFO - mozversion application_version: 50.0a1 16:08:59 INFO - mozversion platform_buildid: 20160713154542 16:08:59 INFO - mozversion platform_changeset: 31cab0ba8bbf1d88b08abeae45bcdf317645355a 16:08:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:08:59 INFO - mozversion platform_version: 50.0a1 16:08:59 INFO - using testdate: 1468451339 16:08:59 INFO - actual date: 1468451339 16:08:59 INFO - starting webserver on 'localhost:52474' 16:08:59 INFO - SUITE-START | Running 2 tests 16:08:59 INFO - TEST-START | dromaeo_css 16:08:59 INFO - operating with platform_type : linux_ 16:08:59 INFO - Initialising browser for dromaeo_css test... 16:08:59 INFO - TEST-INFO | started process 1248 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp81W6u_/profile http://localhost:52474/getInfo.html) 16:09:04 INFO - PROCESS | 1248 | __metrics Screen width/height:1600/1200 16:09:04 INFO - PROCESS | 1248 | colorDepth:24 16:09:04 INFO - PROCESS | 1248 | Browser inner width/height: 1024/697 16:09:04 INFO - PROCESS | 1248 | __metrics 16:09:05 INFO - PROCESS | 1248 | 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" 16:09:05 INFO - PROCESS | 1248 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:09:06 INFO - TEST-INFO | 1248: exit 0 16:09:06 INFO - Browser initialized. 16:09:06 INFO - Running cycle 1/1 for dromaeo_css test... 16:09:06 INFO - TEST-INFO | started process 1332 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp81W6u_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:17 INFO - PROCESS | 1332 | RSS: Main: 159100928 16:09:17 INFO - PROCESS | 1332 | 16:10:09 INFO - PROCESS | 1332 | 1468451409634 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] 16:12:21 INFO - PROCESS | 1332 | Cycle 1(1): loaded http://localhost:52474/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52474/tests/dromaeo/cssquery-ext.html) 16:12:22 INFO - PROCESS | 1332 | RSS: Main: 259829760 16:12:22 INFO - PROCESS | 1332 | 16:15:25 INFO - PROCESS | 1332 | Cycle 1(1): loaded http://localhost:52474/tests/dromaeo/cssquery-ext.html (next: http://localhost:52474/tests/dromaeo/cssquery-jquery.html) 16:15:26 INFO - PROCESS | 1332 | RSS: Main: 241401856 16:15:26 INFO - PROCESS | 1332 | 16:18:28 INFO - PROCESS | 1332 | Cycle 1(1): loaded http://localhost:52474/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52474/tests/dromaeo/cssquery-mootools.html) 16:18:28 INFO - PROCESS | 1332 | RSS: Main: 241377280 16:18:28 INFO - PROCESS | 1332 | 16:21:31 INFO - PROCESS | 1332 | Cycle 1(1): loaded http://localhost:52474/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52474/tests/dromaeo/cssquery-prototype.html) 16:21:31 INFO - PROCESS | 1332 | RSS: Main: 242745344 16:21:31 INFO - PROCESS | 1332 | 16:24:34 INFO - PROCESS | 1332 | Cycle 1(1): loaded http://localhost:52474/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52474/tests/dromaeo/cssquery-yui.html) 16:24:35 INFO - PROCESS | 1332 | RSS: Main: 226238464 16:24:35 INFO - PROCESS | 1332 | 16:27:38 INFO - PROCESS | 1332 | Cycle 1(1): loaded http://localhost:52474/tests/dromaeo/cssquery-yui.html (next: http://localhost:52474/tests/dromaeo/cssquery-dojo.html) 16:27:38 INFO - PROCESS | 1332 | RSS: Main: 285999104 16:27:38 INFO - PROCESS | 1332 | 16:27:38 INFO - PROCESS | 1332 | __start_tp_report 16:27:38 INFO - PROCESS | 1332 | _x_x_mozilla_page_load 16:27:38 INFO - PROCESS | 1332 | _x_x_mozilla_page_load_details 16:27:38 INFO - PROCESS | 1332 | |i|pagename|runs| 16:27:38 INFO - PROCESS | 1332 | |0;dojo.html;2585.1856665150476;2605.413781899074;2632.105084271348;2642.07527365422;2647.6425682532854;4490.932636010462;4578.473475550312;4646.767661616923;4709.599684026862;4723.433188017439;4558.931616025762;4591.88520286993;4595.494495605489;4604.815807367701;4634.050019745952;4192.224438478884;4201.936970945423;4261.914761704764;4285.164392943378;4302.634276086521;4283.121959998209;4340.066885619591;4364.236258654739;4408.603225709686;4410.779461026952;4168.062186008155;4208.978955105235;4210.51579068408;4304.978475107604;4339.522652508222;4198.055437526563;4212.262854000538;4218.388333691598;4229.830806767725;4271.572842715735;4204.873853784391;4233.936490952638;4255.063885945087;4296.785160741951;4300.333448315516;4594.379758732562;4615.884602884921;4616.930746038812;4649.116667833101;4659.32439796231;4760.214564596838;4760.64295177863;4919.188333924898;4986.376702912136;5071.822486212965;5050.292958985745;5075.086484432801;5078.7460626968505;5080.59355251579;5087.872803179913;4351.477822661301;4656.138614356355;4705.6470764692785;4707.152712511713;4716.127516409475;4971.353724015927;5054.1407960646775;5078.466760990117;5082.415522214993;5151.098557752378;4888.902221955541;4933.161362568372;4937.061958227925;4941.925871111936;4944.975275123673;4787.952120478751;4799.592034677092;4971.527704868032;5024.296598476216;5035.320231768737;4813.350197723333;4857.4171099467585;4875.171220892456;4876.049170411807;4880.926196236822;4823.686460380064;4826.444958829709;4831.323614693945;4834.371531700856;4877.244027175793;3973.205358928226;4126.339785634284;4128.4632997710105;4137.317342638431;4172.436721042621;3550.4851796489365;3643.3988391915036;3655.926881462356;3660.377735784923;3724;4256.638185754184;4265.3815196796295;4267.4238977737605;4300.182965236595;4308.784560771949;4728.668993170445;4960.479149689303;4967.081089998362;4970.254461830755;5005.399352077774;4127.814248358831;4202.810873510699;4222.0711443483;4253.043065310342;4262.87211383659;4391;4414.779261037;4416.933745993812;4442.355858400514;4464.553544645574;4581.725096494221;4679.53204679543;4688.109259240733;4691.014886873726;4695.342652028787;4890.315355850113;4907.55831975124;4941.2835138905375;4942.20924652054;4959.652824302409;4670.673052886265;4693.319468676955;4698.3657206278085;4701.435827700561;4751.762411879323;4725.787339569659;4799.472058073679;4879.536444037811;4880.46314905353;4934.284528743384;4866.391701037371;4907.705537667752;4927.236278376858;4927.975360123177;4982.626303027215;4798.832040878552;4804.375431193923;4805.183118869731;4810.831620893252;4811.061842940593;4639.536046395334;4705.5059218781535;4706.152892479249;4794.184988552024;4844.0554091951735;4704.529547045268;4709.740964246862;4724.929126063043;4765.904681906273;4766.7854946526795;4837.540433658797;4891.0951473976;4893.070316639827;4893.535114164149;4945.159322915185;4542.841000564964;4612.976935115303;4654.627629789677;4677.251639737626;4775.594074503706 16:27:38 INFO - PROCESS | 1332 | |1;ext.html;574.6552068758743;581.1457157977774;582.0774073094143;586.2466730251627;586.806353903212;39503.80248098759;41153.382699259535;41199.382009269844;44046.77976610113;44286;40480.190396192134;40914.18171636565;40927;40949.181016379654;40972;7576.469647124703;7992.3606111510935;8130.308923741489;8130.674773009073;10217.335873168258;10074.445905475195;10438.217133714963;10450.37297762133;10457.84313235302;10475.895241047607;10463.424511651856;10512.264141510097;10513.211509136807;10514.526846291936;10527.842082368732;7715.845683086363;10215.744606384826;10376.688699338994;10456.215783816167;10481.895181048169;10364;10407.843882341771;10429.322094063937;10441.530131144116;10442.112420444271;39043.60956390428;39404.60595394038;39414.21171576552;39463.60536394628;39477;2476.938076548083;3094.9535756963664;3192.9680703193135;3196.9680303197138;3453.2575496268173;29702.85148574265;29976.250593735112;30452.8477357614;30744.538831917314;30871.536926946115;3004.2339203502966;3023.1686286271233;3025.848707564613;3067.938641227163;3099.1012606344134;268990;274916;274965.6251718749;275239;275529;48553.27170092452;56016.71991640016;57792.133118003265;58322.708386458646;58575.41424585785;55580.72209638926;61627.38372616306;61675;61703.6914815432;61712.69143654253;14460.783088253685;14539.20034398097;14629.707405851823;14648.926755366156;14652.19412932299;9912.55393507294;11015.504302306415;11194.552217911192;11245.606403775992;11534.59628912984;19163.32928347501;19185;19327.80672193288;19334.903325483283;19374;6891.965540172267;7136.857262854714;7166.892496612555;7231.493795434374;7255.45584081196;793.5952664141214;838.4256784102909;898.7169041751828;922.1424075609614;984.3552473130111;26859.328516786845;27220.183394498195;27408.077486293525;27645.447091058068;27897.58153627697;918.6371383303558;923.8954774868903;952.3857112162616;958.7459323279337;1050.1861057680205;14409;14545.854541454662;14631.780523292158;14885.627859303604;14894.180820054784;368890;374824;375132;375326;375456;49130.26304605434;50220.74889625528;50263.49736502661;50317.74841125771;50419.24371134436;1075.800976819291;1117.6144230240554;1128.2440764687515;1193.093249130667;1209.1233855454725;19390.51523711862;27877;28478.288042798267;29169;29173;41322.79338603288;46056.309155363364;47918;47976.52023479721;52316.81465482828;344726;345472;345637;346603;348862;31578;32567.837160814044;35541.82229088838;38402.61597383991;38621.6137838618;40079;40980.79509602433;41008.79495602503;41045.794771025954;41398;33141.337173256885;35391.29217415586;36115.81942090273;36174.4573831398;36261;35562.46656300209;35910.64089359073;35972.64027359798;36150;36165.819170903975;34178.82910585431;35449;36507.81746091359;36584.08539786527;39481.605183947795;30661;33432.498512521845;33720.156996074315;33749.493757594144;33996.150096247795;25163.622545661463;25478.363040923385;25673.301523288577;25677;25990.480190396465 16:27:38 INFO - PROCESS | 1332 | |2;jquery.html;362.7478902162996;369.366536390091;376.969842412607;392.9096307849196;398.05859143126514;16415.179241037946;17663.558411039714;18080;18633;18633.254669813206;17148.14259287034;17539.473815785514;17906.37327693531;17911;18001.279948802032;5060.06388818068;5220.086484865153;5246.5278124968745;5269.209618557209;5270;3693.3721267384476;3816.293985612657;5132.743362831862;6368.458681012096;6386.329435409261;6410.358964103599;6437.324080971501;6444.806655800334;6447.677616119198;6461.676916154173;6150.876982460348;6341.4292713656;6370.87258254837;6370.936290637081;6372.203474565679;3438.7420943429092;5017.122003649347;5332.013577488177;5341.946580534183;5545.528630066449;21332;21422.464438389008;21672;21699.56600867974;21897.343079707633;1756.920938557768;1865.1140708163514;1977.624251392231;2245.1805091141778;2505.837120587174;13548.119372240773;13682.247476388795;13949.930250348683;14182.43270269188;14489.492867749683;1686.5614940115536;1707.6499317639855;1780.5459607800058;1803.9098045097824;1835.5686413692772;47730.78816876469;52257.47742522564;60015.09977350343;63000;63060.36939630637;9305.11601397866;9480.715578532656;9563.378380405251;10078.899211007943;10291.53688084023;13925.164490130624;13956.371963261678;14015.439382424793;14039.859601403856;14042.85957140436;7384.630768461556;7574.431917606202;7599.924000760032;7606.885896711554;7680.3087722104165;7289.307515786098;7333.1933487316355;7439.218882017419;7453.888191677129;7475.514091584029;6326.557141000178;6339.017452294902;6374.107624932513;6418.326075761977;6453.354664533509;1734.4276388791668;1764.7264673975299;1784.4022252545283;1801.5497524492648;1835.6971099768389;785.7328508307203;826.8470332988417;850.1540966738136;892.6920212526668;928.4382948316171;17592.29630814753;17637.294508219784;17763.700221321702;18404.35584754527;18596.233357830984;849.8980122385353;879.62176264207;882.770479675289;897.4704924094685;905.3119629081945;10030.548625311878;10415.270931034755;10423.843642345371;10448.32085914413;10485.633002844865;373354;373818;374466;376288;379299;85971.14028859757;86313;89468.5526572363;89865;90561.54719226362;894.2711689972781;908.3142227618106;921.0881227584644;947.5688561704408;949.8907625623003;16720.33118675288;16760.66478670444;16767.32930682759;16958.660826783147;17524.299028038735;37221.813890930374;39365.2126957465;39520.01199970024;39582.40626390664;39739.40390894197;47602.28596570985;53696.194557082454;53707.731461342446;53777.19334210068;53940;7079.43364530826;7367.1527774304495;7387;7706.036745406825;7781.221877812173;10295.794084118426;11083.72290692739;11098.5560577576;11111.83332250033;11210.27133236337;9653.324267301503;9694.175995040501;9700;10170.38977661313;10397.636082737068;9177.495237761721;9324.393914395543;9797.706068817946;10004.049615286827;10133.594656213669;9411.152996230372;9670.226381889574;9781.462019588991;9831.001544528988;10057.446840423847;7366.152892417441;7553.848923021619;7584.54492730438;7596.164421913474;7694.07671079474;9752.707418777449;9914.058164474363;9954.950225248827;9987.101160895554;10176.18590512772 16:27:38 INFO - PROCESS | 1332 | |3;mootools.html;1232.346856166232;1308.5223893278953;1387.6947071644238;1422.6372275069862;1702.072370558045;6670.966645166774;7614.847703045943;7813.179616140309;7847.607619619018;8052.758417247477;7789.844203115934;7918.841623167533;7932.563708996003;7934.563599002067;7943.841123177547;1823.854091672663;2030.9289174878882;2072.3575691535634;2118.4280244334022;2119.9258025969016;2121.077331360863;2136.134865379524;2137.1665050630268;2137.2733270687963;2141.0793358855685;2135.0712440088532;2155.5473350596394;2169.425102347879;2178.3247193370025;2181.967270490944;1799.6400719856015;1842.935497257603;1854.1285595769966;1863.617958318555;2039.7552293724773;1992.4520756791853;2061.134323584083;2061.9278325258692;2072.875627462357;2077.9480512987143;7839.294463498312;7884.960575197145;7900.960495197544;7904.762857114296;7912.841743165162;4292.24885645014;4385.912281754347;4399.912001759979;4406;4407.757573333465;2589.572720501115;2646.259047466712;2689.40833016736;2704.4726278375724;2708.891644334224;810.6716779704229;830.3813658824155;838.9580520973988;843.6372359885247;843.843888880559;10594.523246453928;10629.893701062967;10636.095931845724;10636.989485998818;10646.201534884915;3770.6040865709256;4013.8795836124964;4208.789560521962;4220.662347012293;4221.704480686322;9038.728838134866;9134.451932884049;9226.953865230631;9228.35401521887;9232.8615070774;4569.977150114228;4574.542545745466;4576.176288268143;4576.839810606612;4579.496255411902;3566.28674265148;4017.5580686124504;4126.958730412717;4128.587141285848;4198.244316023145;3606.314800187956;3667.394879844796;3705.055210921198;3738.6261373862394;3764.4729737836724;1007.9748006299754;1153.4175241502987;1170.8126699728173;1175.2948231061296;1184.3959580613778;768.3161985832502;796.6693822063894;819.9385046121446;820.6512232301249;825.9876101858476;5138.537531622172;5299.867503312448;5377.5160235578205;5404.891902161935;5474.808381706621;841.9663213471515;842.4649754815987;864.1919804982361;886.8226354728951;908.2597682888556;3456.107828178666;3568.6252943440563;3570.839477169878;3619.2179172764;3649.416093425092;9517.762055948519;9940;9977.451240181714;9992.150667193224;9999.50002499872;9148.359614826899;9544.570494327772;9553.140217380469;9882.357646752938;9889.060539248903;7295.671694773748;7420.703171873065;7467.626618669045;7467.999999999892;7495.887561686579;5393.164059570773;5477.698726569917;5542.528885044816;5632.7183640816975;5687.488126068673;9270.072992700945;9273.53632318395;9295;9296.907030929604;9303.674371396968;9131.406458580172;9207.125323094295;9248.16766491019;9467;9502.952485237529;6906.412954898688;6913.861722765416;7028.929710702828;7035.929640703527;7054.6472676367;7519.43604229674;7538;7546.2831031051965;7563.092428908566;7590.620468976639;6772.0857684212;6895.586264824126;6935.167779866449;6962.617056061965;7057.858842823218;6799.796006119825;6844.863102738017;6864.21061577925;6927.58434493932;6944.583325000516;6389.8722025560155;6515.153030106056;6563.803085907431;6590;6620.966895165493;4451.132029254394;4489.483709373464;4538.954610453853;4541.114482675978;4663.323818046297;4641.86074417768;4653.069386122721;4697.694649847749;4734.313524538991;4735.715857048588 16:27:38 INFO - PROCESS | 1332 | |4;prototype.html;299.719762022509;303.65231809578034;311.7428121799516;312.24281118288144;313.76781181925384;4638.721676699401;4753.833615823447;4773.379460670112;4832.685875418096;4834.25069114287;4539.6368290536675;4710.481846996828;4759.833405830796;4769.546893045155;4779.187538118519;3801.923961520768;3809.5047643806265;3810.8285127169206;3810.9618903811024;3839.7696138231795;3592.317459682665;3654;3761.793101379423;3803.7337386382965;3819.2934307153264;3761.078535758743;3775;3777.376732839078;3782.3948168293077;3799.4300854871713;3768.981155094221;3770.924581508368;3775.452559378902;3780.9054773630605;3805.8667946621736;3359.8320083995945;3367.6800703933086;3370.6460821613623;3378.983105084483;3381.188514756441;4586.119690926704;4685.320628508881;4691.249400095969;4706.74112923789;4708.223143181371;4298.656107511391;4304.504981927056;4307.138572285556;4310.224159651293;4325.84859529915;4963.652544321899;4974.253861920704;4997.275395067695;4997.30037794709;5034.723090230036;3539.451594851703;3661.5240018797654;3670.687991520724;3686.889393318178;3687.4284485904727;5182.818601348972;5211.7394130293715;5217.113090774577;5231.189165679288;5237.13587258102;3725.012871589031;3752.512173417438;3752.9437058444146;3757.1358587524473;3763.6612704856693;3698.9445158322646;3764.642358975893;3773.603771603997;3774.9056273592832;3809.809509524568;4280.422143010654;4295.9570404295555;4298.4412026436985;4302.161078589645;4302.849400270976;4982.975085124624;4990.451050384455;4994.600431965434;5051.6716413433005;5068.974655126701;1221.9511219551296;1267.645059383374;1309.1490531154825;1312.8949684025256;1320.8679132086716;892.0054139634261;900.3607438718583;906.428949761646;907.9105073911333;916.6745805239044;4352.347147927836;4354.259775838115;4354.99835037943;4359.607635312837;4370.737755734666;5062.557171206188;5065.695108725521;5093.821716239914;5096.159133742891;5104.846854594368;4380.846670366522;4386.429764130707;4405.647548196137;4407.206702793528;4421.314696222091;2609.7390260973752;2760.054161815915;2760.5721113227482;2763.6559762687375;2771.2240572639694;4917.7786999585105;4919.827806026843;4927.90144197114;4930.161872481687;4946.9010619787405;5044.848654540369;5265.631405801557;5273.3935597406025;5299.947000530023;5310.575153987672;4945.604351651859;4959.330490383896;4969.950300497021;4981.153203955337;4981.9252711209365;5206.8958620828125;5219.086659834438;5233.685978841282;5250.343707036621;5269.681340210295;4864.537868902448;5129.589632829439;5138.280640710378;5141.28022076902;5148.897022059613;4940.431850337113;4945.208766597327;4964.106460837084;4966.801327946841;4998.925016124688;3663.249033947981;3678.1907980244305;3717.052151701407;3719.683826874746;3719.75821571597;5059.797608095635;5234.424213336454;5280.6303558750515;5287.57699384056;5300.761465734128;5083.593312535062;5085.46602606721;5092.8472145835685;5112.539871411591;5113.642045056959;5071.594272458121;5090.949090509048;5102.260172275073;5110.66780659256;5110.8466745997675;4764.166270902509;4983.725895075806;4985.252212168204;5014.724190169576;5040.294358789699;5070.225421102585;5096.719680417613;5097.898042039213;5098.490150984871;5098.5411312982005;4964.726940018334;4977.751112444435;4986.252062190701;5001.674891132065;5002.324221385731 16:27:38 INFO - PROCESS | 1332 | |5;yui.html;593.0525289312119;615.9414855588723;621.7668374359612;660.3363619562339;705.6083873450236;13169.078164528486;14267.286635668226;14283.857161428383;14298.428062877472;14307.213103279328;11764.941175294112;13244.668883277898;13465.932670336635;13514.59456216315;13577.932110339434;618.4155972605889;761.9352355049828;765.5789315876274;793.5794029164523;800.7237503061464;785.7367781793106;790.0361558898164;792.7304716396424;792.7978365516785;793.7221972309703;835.4736515994915;836.3977935886152;837.0833936839151;837.3970741066422;837.6733074101105;655.4952686431445;789.3290702902516;804.8390321935577;809.1463506001166;815.1318845429657;596.7404179182026;597.9760809567656;598.1207624791549;598.1625723986454;598.3836648252307;13106.475740970349;13119.672008199776;13134.802977955338;13158.14472059313;13158.473661053546;12428.813567796491;12590.24458532491;12780.297083660394;12823.294718790463;12866.228026318451;11596.884031159665;11695.649130526097;11831.822522662167;11903.523859045628;12216.816747748791;3804.372278574032;3826.885193444201;3838.405047217686;3905.4337121117564;3929.4105884117325;59031.11453328161;59074.40925590732;59107.40892591062;59246.40753592452;59390.10914836281;10058.195344372432;10121.797564048678;10190.388576685422;10251.897481025244;10352.171826253882;18057.819421805612;18328.633427331377;18342.266309347742;18367.35714249995;18373.5406614833;6298.905516417258;6580.901286480706;6624.470042396693;6659.53383263167;6683.030960510796;3479.9708016148534;3494.4408894577255;3586.2110335726097;3723.0878434783654;3787.223619158121;2883.0774152271492;2995.7303842654264;2996.235959830273;3002.789804713693;3003.294225856922;396.5617992118725;465.9883502912454;490.9582685471775;494.27341807542854;538.5530010091614;446.8056395467982;467.9485256621769;470.2757753060249;483.3909274314389;503.1848405582979;7321.74373896911;7366.926330736624;7557.130929943017;7603.9619801901745;7662.003939487942;432.34931428200355;472.6006524486803;480.2819784422311;500.9774560144729;506.8707479592754;3467.2545402738533;3541.964580354215;3678.797666128335;3723.4414837774552;3742.345089609362;56393;62498.37501625017;65196.674016629615;104002.4799876011;104189;86850.56574717212;87400.5629971846;87937;88525.4916860804;88856;76861;79379.60919951502;79690;80219.3901274444;81254.78117828237;7586;7626.4661473698525;7955;7967.362610991223;8231.300339471212;31198;37854.62145378511;42198.78900605477;42290;42488.362674559285;61026.38973610207;61861.38138618556;62971.685141574;63269.68365158145;63433;10554.736131596772;10566.41884696349;10684.57261709523;10799.568017279535;11029;7902.367810575255;11877.940610296893;12385.62843114708;12397.62807115788;12598.622041338775;10158.339707918962;11390.715232118931;11652.067834573045;11680.182387232811;11936.641900742992;11440.885591143982;11545.595904143314;11726.120540959291;11999.580014699444;12097.69755756078;10781.083607893417;10807.243492955418;11345;11379.317240965569;11449.942750286194;9317.487538185465;9587;9724.27067969891;9868.407895526005;10413.531391087268;9517.381370210915;9528.628548183833;9725.367851089657;9739.9026009739;9764.511774411108 16:27:38 INFO - PROCESS | 1332 | __end_tp_report 16:27:38 INFO - PROCESS | 1332 | __start_cc_report 16:27:38 INFO - PROCESS | 1332 | _x_x_mozilla_cycle_collect,967 16:27:38 INFO - PROCESS | 1332 | __end_cc_report 16:27:38 INFO - PROCESS | 1332 | __startTimestamp1468452458426__endTimestamp 16:27:38 INFO - PROCESS | 1332 | 16:27:38 INFO - PROCESS | 1332 | ------- Summary: start ------- 16:27:38 INFO - PROCESS | 1332 | Number of tests: 6 16:27:38 INFO - PROCESS | 1332 | 16:27:38 INFO - PROCESS | 1332 | [#0] dojo.html Cycles:165 Average:4561.40 Median:4698.37 stddev:475.18 (10.1%) stddev-sans-first:450.64 16:27:38 INFO - PROCESS | 1332 | Values: 2585.2 2605.4 2632.1 2642.1 2647.6 4490.9 4578.5 4646.8 4709.6 4723.4 4558.9 4591.9 4595.5 4604.8 4634.1 4192.2 4201.9 4261.9 4285.2 4302.6 4283.1 4340.1 4364.2 4408.6 4410.8 4168.1 4209.0 4210.5 4305.0 4339.5 4198.1 4212.3 4218.4 4229.8 4271.6 4204.9 4233.9 4255.1 4296.8 4300.3 4594.4 4615.9 4616.9 4649.1 4659.3 4760.2 4760.6 4919.2 4986.4 5071.8 5050.3 5075.1 5078.7 5080.6 5087.9 4351.5 4656.1 4705.6 4707.2 4716.1 4971.4 5054.1 5078.5 5082.4 5151.1 4888.9 4933.2 4937.1 4941.9 4945.0 4788.0 4799.6 4971.5 5024.3 5035.3 4813.4 4857.4 4875.2 4876.0 4880.9 4823.7 4826.4 4831.3 4834.4 4877.2 3973.2 4126.3 4128.5 4137.3 4172.4 3550.5 3643.4 3655.9 3660.4 3724.0 4256.6 4265.4 4267.4 4300.2 4308.8 4728.7 4960.5 4967.1 4970.3 5005.4 4127.8 4202.8 4222.1 4253.0 4262.9 4391.0 4414.8 4416.9 4442.4 4464.6 4581.7 4679.5 4688.1 4691.0 4695.3 4890.3 4907.6 4941.3 4942.2 4959.7 4670.7 4693.3 4698.4 4701.4 4751.8 4725.8 4799.5 4879.5 4880.5 4934.3 4866.4 4907.7 4927.2 4928.0 4982.6 4798.8 4804.4 4805.2 4810.8 4811.1 4639.5 4705.5 4706.2 4794.2 4844.1 4704.5 4709.7 4724.9 4765.9 4766.8 4837.5 4891.1 4893.1 4893.5 4945.2 4542.8 4613.0 4654.6 4677.3 4775.6 16:27:38 INFO - PROCESS | 1332 | 16:27:38 INFO - PROCESS | 1332 | [#1] ext.html Cycles:180 Average:49530.94 Median:345554.50 stddev:87713.13 (25.4%) stddev-sans-first:87882.17 16:27:38 INFO - PROCESS | 1332 | Values: 574.7 581.1 582.1 586.2 586.8 39503.8 41153.4 41199.4 44046.8 44286.0 40480.2 40914.2 40927.0 40949.2 40972.0 7576.5 7992.4 8130.3 8130.7 10217.3 10074.4 10438.2 10450.4 10457.8 10475.9 10463.4 10512.3 10513.2 10514.5 10527.8 7715.8 10215.7 10376.7 10456.2 10481.9 10364.0 10407.8 10429.3 10441.5 10442.1 39043.6 39404.6 39414.2 39463.6 39477.0 2476.9 3095.0 3193.0 3197.0 3453.3 29702.9 29976.3 30452.8 30744.5 30871.5 3004.2 3023.2 3025.8 3067.9 3099.1 268990.0 274916.0 274965.6 275239.0 275529.0 48553.3 56016.7 57792.1 58322.7 58575.4 55580.7 61627.4 61675.0 61703.7 61712.7 14460.8 14539.2 14629.7 14648.9 14652.2 9912.6 11015.5 11194.6 11245.6 11534.6 19163.3 19185.0 19327.8 19334.9 19374.0 6892.0 7136.9 7166.9 7231.5 7255.5 793.6 838.4 898.7 922.1 984.4 26859.3 27220.2 27408.1 27645.4 27897.6 918.6 923.9 952.4 958.7 1050.2 14409.0 14545.9 14631.8 14885.6 14894.2 368890.0 374824.0 375132.0 375326.0 375456.0 49130.3 50220.7 50263.5 50317.7 50419.2 1075.8 1117.6 1128.2 1193.1 1209.1 19390.5 27877.0 28478.3 29169.0 29173.0 41322.8 46056.3 47918.0 47976.5 52316.8 344726.0 345472.0 345637.0 346603.0 348862.0 31578.0 32567.8 35541.8 38402.6 38621.6 40079.0 40980.8 41008.8 41045.8 41398.0 33141.3 35391.3 36115.8 36174.5 36261.0 35562.5 35910.6 35972.6 36150.0 36165.8 34178.8 35449.0 36507.8 36584.1 39481.6 30661.0 33432.5 33720.2 33749.5 33996.2 25163.6 25478.4 25673.3 25677.0 25990.5 16:27:38 INFO - PROCESS | 1332 | 16:27:38 INFO - PROCESS | 1332 | [#2] jquery.html Cycles:180 Average:24339.78 Median:5176.41 stddev:62158.64 (1200.8%) stddev-sans-first:62306.94 16:27:38 INFO - PROCESS | 1332 | Values: 362.7 369.4 377.0 392.9 398.1 16415.2 17663.6 18080.0 18633.0 18633.3 17148.1 17539.5 17906.4 17911.0 18001.3 5060.1 5220.1 5246.5 5269.2 5270.0 3693.4 3816.3 5132.7 6368.5 6386.3 6410.4 6437.3 6444.8 6447.7 6461.7 6150.9 6341.4 6370.9 6370.9 6372.2 3438.7 5017.1 5332.0 5341.9 5545.5 21332.0 21422.5 21672.0 21699.6 21897.3 1756.9 1865.1 1977.6 2245.2 2505.8 13548.1 13682.2 13949.9 14182.4 14489.5 1686.6 1707.6 1780.5 1803.9 1835.6 47730.8 52257.5 60015.1 63000.0 63060.4 9305.1 9480.7 9563.4 10078.9 10291.5 13925.2 13956.4 14015.4 14039.9 14042.9 7384.6 7574.4 7599.9 7606.9 7680.3 7289.3 7333.2 7439.2 7453.9 7475.5 6326.6 6339.0 6374.1 6418.3 6453.4 1734.4 1764.7 1784.4 1801.5 1835.7 785.7 826.8 850.2 892.7 928.4 17592.3 17637.3 17763.7 18404.4 18596.2 849.9 879.6 882.8 897.5 905.3 10030.5 10415.3 10423.8 10448.3 10485.6 373354.0 373818.0 374466.0 376288.0 379299.0 85971.1 86313.0 89468.6 89865.0 90561.5 894.3 908.3 921.1 947.6 949.9 16720.3 16760.7 16767.3 16958.7 17524.3 37221.8 39365.2 39520.0 39582.4 39739.4 47602.3 53696.2 53707.7 53777.2 53940.0 7079.4 7367.2 7387.0 7706.0 7781.2 10295.8 11083.7 11098.6 11111.8 11210.3 9653.3 9694.2 9700.0 10170.4 10397.6 9177.5 9324.4 9797.7 10004.0 10133.6 9411.2 9670.2 9781.5 9831.0 10057.4 7366.2 7553.8 7584.5 7596.2 7694.1 9752.7 9914.1 9955.0 9987.1 10176.2 16:27:38 INFO - PROCESS | 1332 | 16:27:38 INFO - PROCESS | 1332 | [#3] mootools.html Cycles:180 Average:5166.96 Median:5476.25 stddev:2969.83 (54.2%) stddev-sans-first:2963.44 16:27:38 INFO - PROCESS | 1332 | Values: 1232.3 1308.5 1387.7 1422.6 1702.1 6671.0 7614.8 7813.2 7847.6 8052.8 7789.8 7918.8 7932.6 7934.6 7943.8 1823.9 2030.9 2072.4 2118.4 2119.9 2121.1 2136.1 2137.2 2137.3 2141.1 2135.1 2155.5 2169.4 2178.3 2182.0 1799.6 1842.9 1854.1 1863.6 2039.8 1992.5 2061.1 2061.9 2072.9 2077.9 7839.3 7885.0 7901.0 7904.8 7912.8 4292.2 4385.9 4399.9 4406.0 4407.8 2589.6 2646.3 2689.4 2704.5 2708.9 810.7 830.4 839.0 843.6 843.8 10594.5 10629.9 10636.1 10637.0 10646.2 3770.6 4013.9 4208.8 4220.7 4221.7 9038.7 9134.5 9227.0 9228.4 9232.9 4570.0 4574.5 4576.2 4576.8 4579.5 3566.3 4017.6 4127.0 4128.6 4198.2 3606.3 3667.4 3705.1 3738.6 3764.5 1008.0 1153.4 1170.8 1175.3 1184.4 768.3 796.7 819.9 820.7 826.0 5138.5 5299.9 5377.5 5404.9 5474.8 842.0 842.5 864.2 886.8 908.3 3456.1 3568.6 3570.8 3619.2 3649.4 9517.8 9940.0 9977.5 9992.2 9999.5 9148.4 9544.6 9553.1 9882.4 9889.1 7295.7 7420.7 7467.6 7468.0 7495.9 5393.2 5477.7 5542.5 5632.7 5687.5 9270.1 9273.5 9295.0 9296.9 9303.7 9131.4 9207.1 9248.2 9467.0 9503.0 6906.4 6913.9 7028.9 7035.9 7054.6 7519.4 7538.0 7546.3 7563.1 7590.6 6772.1 6895.6 6935.2 6962.6 7057.9 6799.8 6844.9 6864.2 6927.6 6944.6 6389.9 6515.2 6563.8 6590.0 6621.0 4451.1 4489.5 4539.0 4541.1 4663.3 4641.9 4653.1 4697.7 4734.3 4735.7 16:27:38 INFO - PROCESS | 1332 | 16:27:38 INFO - PROCESS | 1332 | [#4] prototype.html Cycles:180 Average:4202.22 Median:4766.86 stddev:1209.75 (25.4%) stddev-sans-first:1177.15 16:27:38 INFO - PROCESS | 1332 | Values: 299.7 303.7 311.7 312.2 313.8 4638.7 4753.8 4773.4 4832.7 4834.3 4539.6 4710.5 4759.8 4769.5 4779.2 3801.9 3809.5 3810.8 3811.0 3839.8 3592.3 3654.0 3761.8 3803.7 3819.3 3761.1 3775.0 3777.4 3782.4 3799.4 3769.0 3770.9 3775.5 3780.9 3805.9 3359.8 3367.7 3370.6 3379.0 3381.2 4586.1 4685.3 4691.2 4706.7 4708.2 4298.7 4304.5 4307.1 4310.2 4325.8 4963.7 4974.3 4997.3 4997.3 5034.7 3539.5 3661.5 3670.7 3686.9 3687.4 5182.8 5211.7 5217.1 5231.2 5237.1 3725.0 3752.5 3752.9 3757.1 3763.7 3698.9 3764.6 3773.6 3774.9 3809.8 4280.4 4296.0 4298.4 4302.2 4302.8 4983.0 4990.5 4994.6 5051.7 5069.0 1222.0 1267.6 1309.1 1312.9 1320.9 892.0 900.4 906.4 907.9 916.7 4352.3 4354.3 4355.0 4359.6 4370.7 5062.6 5065.7 5093.8 5096.2 5104.8 4380.8 4386.4 4405.6 4407.2 4421.3 2609.7 2760.1 2760.6 2763.7 2771.2 4917.8 4919.8 4927.9 4930.2 4946.9 5044.8 5265.6 5273.4 5299.9 5310.6 4945.6 4959.3 4970.0 4981.2 4981.9 5206.9 5219.1 5233.7 5250.3 5269.7 4864.5 5129.6 5138.3 5141.3 5148.9 4940.4 4945.2 4964.1 4966.8 4998.9 3663.2 3678.2 3717.1 3719.7 3719.8 5059.8 5234.4 5280.6 5287.6 5300.8 5083.6 5085.5 5092.8 5112.5 5113.6 5071.6 5090.9 5102.3 5110.7 5110.8 4764.2 4983.7 4985.3 5014.7 5040.3 5070.2 5096.7 5097.9 5098.5 5098.5 4964.7 4977.8 4986.3 5001.7 5002.3 16:27:38 INFO - PROCESS | 1332 | 16:27:38 INFO - PROCESS | 1332 | [#5] yui.html Cycles:180 Average:17161.35 Median:466.97 stddev:24416.78 (5228.8%) stddev-sans-first:24453.58 16:27:38 INFO - PROCESS | 1332 | Values: 593.1 615.9 621.8 660.3 705.6 13169.1 14267.3 14283.9 14298.4 14307.2 11764.9 13244.7 13465.9 13514.6 13577.9 618.4 761.9 765.6 793.6 800.7 785.7 790.0 792.7 792.8 793.7 835.5 836.4 837.1 837.4 837.7 655.5 789.3 804.8 809.1 815.1 596.7 598.0 598.1 598.2 598.4 13106.5 13119.7 13134.8 13158.1 13158.5 12428.8 12590.2 12780.3 12823.3 12866.2 11596.9 11695.6 11831.8 11903.5 12216.8 3804.4 3826.9 3838.4 3905.4 3929.4 59031.1 59074.4 59107.4 59246.4 59390.1 10058.2 10121.8 10190.4 10251.9 10352.2 18057.8 18328.6 18342.3 18367.4 18373.5 6298.9 6580.9 6624.5 6659.5 6683.0 3480.0 3494.4 3586.2 3723.1 3787.2 2883.1 2995.7 2996.2 3002.8 3003.3 396.6 466.0 491.0 494.3 538.6 446.8 467.9 470.3 483.4 503.2 7321.7 7366.9 7557.1 7604.0 7662.0 432.3 472.6 480.3 501.0 506.9 3467.3 3542.0 3678.8 3723.4 3742.3 56393.0 62498.4 65196.7 104002.5 104189.0 86850.6 87400.6 87937.0 88525.5 88856.0 76861.0 79379.6 79690.0 80219.4 81254.8 7586.0 7626.5 7955.0 7967.4 8231.3 31198.0 37854.6 42198.8 42290.0 42488.4 61026.4 61861.4 62971.7 63269.7 63433.0 10554.7 10566.4 10684.6 10799.6 11029.0 7902.4 11877.9 12385.6 12397.6 12598.6 10158.3 11390.7 11652.1 11680.2 11936.6 11440.9 11545.6 11726.1 11999.6 12097.7 10781.1 10807.2 11345.0 11379.3 11449.9 9317.5 9587.0 9724.3 9868.4 10413.5 9517.4 9528.6 9725.4 9739.9 9764.5 16:27:38 INFO - PROCESS | 1332 | -------- Summary: end -------- 16:27:38 INFO - PROCESS | 1332 | 16:27:39 INFO - TEST-INFO | 1332: exit 0 16:27:39 INFO - TEST-OK | dromaeo_css | took 1119330ms 16:27:39 INFO - TEST-START | kraken 16:27:39 INFO - operating with platform_type : linux_ 16:27:39 INFO - Initialising browser for kraken test... 16:27:39 INFO - TEST-INFO | started process 1524 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp4YX2q/profile http://localhost:52474/getInfo.html) 16:27:43 INFO - PROCESS | 1524 | __metrics Screen width/height:1600/1200 16:27:43 INFO - PROCESS | 1524 | colorDepth:24 16:27:43 INFO - PROCESS | 1524 | Browser inner width/height: 1024/697 16:27:43 INFO - PROCESS | 1524 | __metrics 16:27:44 INFO - PROCESS | 1524 | 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" 16:27:44 INFO - PROCESS | 1524 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:27:44 INFO - PROCESS | 1524 | console.error: PushService: 16:27:44 INFO - PROCESS | 1524 | stateChangeProcessEnqueue: Error transitioning state 16:27:44 INFO - PROCESS | 1524 | UnknownError 16:27:45 INFO - TEST-INFO | 1524: exit 0 16:27:45 INFO - Browser initialized. 16:27:45 INFO - Running cycle 1/1 for kraken test... 16:27:45 INFO - TEST-INFO | started process 1604 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp4YX2q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:27:57 INFO - PROCESS | 1604 | RSS: Main: 158912512 16:27:57 INFO - PROCESS | 1604 | 16:28:00 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52474/tests/kraken/driver.html?testName=audio-beat-detection) 16:28:00 INFO - PROCESS | 1604 | RSS: Main: 217083904 16:28:00 INFO - PROCESS | 1604 | 16:28:03 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52474/tests/kraken/driver.html?testName=audio-dft) 16:28:04 INFO - PROCESS | 1604 | RSS: Main: 205041664 16:28:04 INFO - PROCESS | 1604 | 16:28:07 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52474/tests/kraken/driver.html?testName=audio-fft) 16:28:08 INFO - PROCESS | 1604 | RSS: Main: 271523840 16:28:08 INFO - PROCESS | 1604 | 16:28:10 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52474/tests/kraken/driver.html?testName=audio-oscillator) 16:28:10 INFO - PROCESS | 1604 | RSS: Main: 182140928 16:28:10 INFO - PROCESS | 1604 | 16:28:13 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52474/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:28:13 INFO - PROCESS | 1604 | RSS: Main: 186171392 16:28:13 INFO - PROCESS | 1604 | 16:28:16 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52474/tests/kraken/driver.html?testName=imaging-darkroom) 16:28:17 INFO - PROCESS | 1604 | RSS: Main: 222707712 16:28:17 INFO - PROCESS | 1604 | 16:28:21 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52474/tests/kraken/driver.html?testName=imaging-desaturate) 16:28:21 INFO - PROCESS | 1604 | RSS: Main: 218206208 16:28:21 INFO - PROCESS | 1604 | 16:28:24 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52474/tests/kraken/driver.html?testName=json-parse-financial) 16:28:25 INFO - PROCESS | 1604 | RSS: Main: 221364224 16:28:25 INFO - PROCESS | 1604 | 16:28:26 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52474/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:28:27 INFO - PROCESS | 1604 | RSS: Main: 206172160 16:28:27 INFO - PROCESS | 1604 | 16:28:29 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52474/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:28:29 INFO - PROCESS | 1604 | RSS: Main: 210853888 16:28:29 INFO - PROCESS | 1604 | 16:28:32 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52474/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:28:32 INFO - PROCESS | 1604 | RSS: Main: 211795968 16:28:32 INFO - PROCESS | 1604 | 16:28:35 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:28:36 INFO - PROCESS | 1604 | RSS: Main: 225353728 16:28:36 INFO - PROCESS | 1604 | 16:28:38 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:28:39 INFO - PROCESS | 1604 | RSS: Main: 226836480 16:28:39 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | Cycle 1(1): loaded http://localhost:52474/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52474/tests/kraken/driver.html?testName=ai-astar) 16:28:41 INFO - PROCESS | 1604 | RSS: Main: 269099008 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | __start_tp_report 16:28:41 INFO - PROCESS | 1604 | _x_x_mozilla_page_load 16:28:41 INFO - PROCESS | 1604 | _x_x_mozilla_page_load_details 16:28:41 INFO - PROCESS | 1604 | |i|pagename|runs| 16:28:41 INFO - PROCESS | 1604 | |0;ai-astar;105;113;114;115;116;114;114;115;114;115 16:28:41 INFO - PROCESS | 1604 | |1;audio-beat-detection;145;121;122;175;128;126;148;121;164;121 16:28:41 INFO - PROCESS | 1604 | |2;audio-dft;161;161;162;163;161;162;161;161;161;163 16:28:41 INFO - PROCESS | 1604 | |3;audio-fft;77;75;75;126;76;77;75;76;102;75 16:28:41 INFO - PROCESS | 1604 | |4;audio-oscillator;85;93;111;84;85;85;118;84;85;123 16:28:41 INFO - PROCESS | 1604 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 16:28:41 INFO - PROCESS | 1604 | |6;imaging-darkroom;147;149;149;147;148;148;147;147;147;146 16:28:41 INFO - PROCESS | 1604 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;95 16:28:41 INFO - PROCESS | 1604 | |8;json-parse-financial;64;63;65;64;64;65;65;65;64;65 16:28:41 INFO - PROCESS | 1604 | |9;json-stringify-tinderbox;65;65;65;65;66;66;66;66;66;65 16:28:41 INFO - PROCESS | 1604 | |10;stanford-crypto-aes;78;78;78;79;80;78;81;78;91;81 16:28:41 INFO - PROCESS | 1604 | |11;stanford-crypto-ccm;127;127;128;130;127;128;135;141;170;130 16:28:41 INFO - PROCESS | 1604 | |12;stanford-crypto-pbkdf2;167;168;164;168;168;170;166;165;168;171 16:28:41 INFO - PROCESS | 1604 | |13;stanford-crypto-sha256-iterative;67;68;67;67;66;70;68;69;69;65 16:28:41 INFO - PROCESS | 1604 | __end_tp_report 16:28:41 INFO - PROCESS | 1604 | __start_cc_report 16:28:41 INFO - PROCESS | 1604 | _x_x_mozilla_cycle_collect,1938 16:28:41 INFO - PROCESS | 1604 | __end_cc_report 16:28:41 INFO - PROCESS | 1604 | __startTimestamp1468452521639__endTimestamp 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | ------- Summary: start ------- 16:28:41 INFO - PROCESS | 1604 | Number of tests: 14 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.50 stddev:3.10 (2.7%) stddev-sans-first:0.88 16:28:41 INFO - PROCESS | 1604 | Values: 105.0 113.0 114.0 115.0 116.0 114.0 114.0 115.0 114.0 115.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:136.50 stddev:19.87 (14.6%) stddev-sans-first:20.87 16:28:41 INFO - PROCESS | 1604 | Values: 145.0 121.0 122.0 175.0 128.0 126.0 148.0 121.0 164.0 121.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 16:28:41 INFO - PROCESS | 1604 | Values: 161.0 161.0 162.0 163.0 161.0 162.0 161.0 161.0 161.0 163.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#3] audio-fft Cycles:10 Average:83.40 Median:75.50 stddev:17.11 (22.7%) stddev-sans-first:17.99 16:28:41 INFO - PROCESS | 1604 | Values: 77.0 75.0 75.0 126.0 76.0 77.0 75.0 76.0 102.0 75.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:85.00 stddev:15.68 (18.5%) stddev-sans-first:16.19 16:28:41 INFO - PROCESS | 1604 | Values: 85.0 93.0 111.0 84.0 85.0 85.0 118.0 84.0 85.0 123.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:28:41 INFO - PROCESS | 1604 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:147.50 stddev:0.97 (0.7%) stddev-sans-first:1.01 16:28:41 INFO - PROCESS | 1604 | Values: 147.0 149.0 149.0 147.0 148.0 148.0 147.0 147.0 147.0 146.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:28:41 INFO - PROCESS | 1604 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 16:28:41 INFO - PROCESS | 1604 | Values: 64.0 63.0 65.0 64.0 64.0 65.0 65.0 65.0 64.0 65.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 16:28:41 INFO - PROCESS | 1604 | Values: 65.0 65.0 65.0 65.0 66.0 66.0 66.0 66.0 66.0 65.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:79.50 stddev:3.99 (5.0%) stddev-sans-first:4.16 16:28:41 INFO - PROCESS | 1604 | Values: 78.0 78.0 78.0 79.0 80.0 78.0 81.0 78.0 91.0 81.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#11] stanford-crypto-ccm Cycles:10 Average:134.30 Median:130.00 stddev:13.32 (10.2%) stddev-sans-first:13.86 16:28:41 INFO - PROCESS | 1604 | Values: 127.0 127.0 128.0 130.0 127.0 128.0 135.0 141.0 170.0 130.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.50 Median:168.00 stddev:2.12 (1.3%) stddev-sans-first:2.24 16:28:41 INFO - PROCESS | 1604 | Values: 167.0 168.0 164.0 168.0 168.0 170.0 166.0 165.0 168.0 171.0 16:28:41 INFO - PROCESS | 1604 | 16:28:41 INFO - PROCESS | 1604 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:1.51 (2.2%) stddev-sans-first:1.58 16:28:41 INFO - PROCESS | 1604 | Values: 67.0 68.0 67.0 67.0 66.0 70.0 68.0 69.0 69.0 65.0 16:28:41 INFO - PROCESS | 1604 | -------- Summary: end -------- 16:28:41 INFO - PROCESS | 1604 | 16:28:42 INFO - TEST-INFO | 1604: exit 0 16:28:42 INFO - TEST-OK | kraken | took 63578ms 16:28:42 INFO - SUITE-END | took 1182s 16:28:43 INFO - Completed test suite (00:19:43) 16:28:43 INFO - javascript benchmark 16:28:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2585.1856665150476, 2605.413781899074, 2632.105084271348, 2642.07527365422, 2647.6425682532854, 4490.932636010462, 4578.473475550312, 4646.767661616923, 4709.599684026862, 4723.433188017439, 4558.931616025762, 4591.88520286993, 4595.494495605489, 4604.815807367701, 4634.050019745952, 4192.224438478884, 4201.936970945423, 4261.914761704764, 4285.164392943378, 4302.634276086521, 4283.121959998209, 4340.066885619591, 4364.236258654739, 4408.603225709686, 4410.779461026952, 4168.062186008155, 4208.978955105235, 4210.51579068408, 4304.978475107604, 4339.522652508222, 4198.055437526563, 4212.262854000538, 4218.388333691598, 4229.830806767725, 4271.572842715735, 4204.873853784391, 4233.936490952638, 4255.063885945087, 4296.785160741951, 4300.333448315516, 4594.379758732562, 4615.884602884921, 4616.930746038812, 4649.116667833101, 4659.32439796231, 4760.214564596838, 4760.64295177863, 4919.188333924898, 4986.376702912136, 5071.822486212965, 5050.292958985745, 5075.086484432801, 5078.7460626968505, 5080.59355251579, 5087.872803179913, 4351.477822661301, 4656.138614356355, 4705.6470764692785, 4707.152712511713, 4716.127516409475, 4971.353724015927, 5054.1407960646775, 5078.466760990117, 5082.415522214993, 5151.098557752378, 4888.902221955541, 4933.161362568372, 4937.061958227925, 4941.925871111936, 4944.975275123673, 4787.952120478751, 4799.592034677092, 4971.527704868032, 5024.296598476216, 5035.320231768737, 4813.350197723333, 4857.4171099467585, 4875.171220892456, 4876.049170411807, 4880.926196236822, 4823.686460380064, 4826.444958829709, 4831.323614693945, 4834.371531700856, 4877.244027175793, 3973.205358928226, 4126.339785634284, 4128.4632997710105, 4137.317342638431, 4172.436721042621, 3550.4851796489365, 3643.3988391915036, 3655.926881462356, 3660.377735784923, 3724.0, 4256.638185754184, 4265.3815196796295, 4267.4238977737605, 4300.182965236595, 4308.784560771949, 4728.668993170445, 4960.479149689303, 4967.081089998362, 4970.254461830755, 5005.399352077774, 4127.814248358831, 4202.810873510699, 4222.0711443483, 4253.043065310342, 4262.87211383659, 4391.0, 4414.779261037, 4416.933745993812, 4442.355858400514, 4464.553544645574, 4581.725096494221, 4679.53204679543, 4688.109259240733, 4691.014886873726, 4695.342652028787, 4890.315355850113, 4907.55831975124, 4941.2835138905375, 4942.20924652054, 4959.652824302409, 4670.673052886265, 4693.319468676955, 4698.3657206278085, 4701.435827700561, 4751.762411879323, 4725.787339569659, 4799.472058073679, 4879.536444037811, 4880.46314905353, 4934.284528743384, 4866.391701037371, 4907.705537667752, 4927.236278376858, 4927.975360123177, 4982.626303027215, 4798.832040878552, 4804.375431193923, 4805.183118869731, 4810.831620893252, 4811.061842940593, 4639.536046395334, 4705.5059218781535, 4706.152892479249, 4794.184988552024, 4844.0554091951735, 4704.529547045268, 4709.740964246862, 4724.929126063043, 4765.904681906273, 4766.7854946526795, 4837.540433658797, 4891.0951473976, 4893.070316639827, 4893.535114164149, 4945.159322915185, 4542.841000564964, 4612.976935115303, 4654.627629789677, 4677.251639737626, 4775.594074503706], "value": 4531.74476426127, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.6552068758743, 581.1457157977774, 582.0774073094143, 586.2466730251627, 586.806353903212, 39503.80248098759, 41153.382699259535, 41199.382009269844, 44046.77976610113, 44286.0, 40480.190396192134, 40914.18171636565, 40927.0, 40949.181016379654, 40972.0, 7576.469647124703, 7992.3606111510935, 8130.308923741489, 8130.674773009073, 10217.335873168258, 10074.445905475195, 10438.217133714963, 10450.37297762133, 10457.84313235302, 10475.895241047607, 10463.424511651856, 10512.264141510097, 10513.211509136807, 10514.526846291936, 10527.842082368732, 7715.845683086363, 10215.744606384826, 10376.688699338994, 10456.215783816167, 10481.895181048169, 10364.0, 10407.843882341771, 10429.322094063937, 10441.530131144116, 10442.112420444271, 39043.60956390428, 39404.60595394038, 39414.21171576552, 39463.60536394628, 39477.0, 2476.938076548083, 3094.9535756963664, 3192.9680703193135, 3196.9680303197138, 3453.2575496268173, 29702.85148574265, 29976.250593735112, 30452.8477357614, 30744.538831917314, 30871.536926946115, 3004.2339203502966, 3023.1686286271233, 3025.848707564613, 3067.938641227163, 3099.1012606344134, 268990.0, 274916.0, 274965.6251718749, 275239.0, 275529.0, 48553.27170092452, 56016.71991640016, 57792.133118003265, 58322.708386458646, 58575.41424585785, 55580.72209638926, 61627.38372616306, 61675.0, 61703.6914815432, 61712.69143654253, 14460.783088253685, 14539.20034398097, 14629.707405851823, 14648.926755366156, 14652.19412932299, 9912.55393507294, 11015.504302306415, 11194.552217911192, 11245.606403775992, 11534.59628912984, 19163.32928347501, 19185.0, 19327.80672193288, 19334.903325483283, 19374.0, 6891.965540172267, 7136.857262854714, 7166.892496612555, 7231.493795434374, 7255.45584081196, 793.5952664141214, 838.4256784102909, 898.7169041751828, 922.1424075609614, 984.3552473130111, 26859.328516786845, 27220.183394498195, 27408.077486293525, 27645.447091058068, 27897.58153627697, 918.6371383303558, 923.8954774868903, 952.3857112162616, 958.7459323279337, 1050.1861057680205, 14409.0, 14545.854541454662, 14631.780523292158, 14885.627859303604, 14894.180820054784, 368890.0, 374824.0, 375132.0, 375326.0, 375456.0, 49130.26304605434, 50220.74889625528, 50263.49736502661, 50317.74841125771, 50419.24371134436, 1075.800976819291, 1117.6144230240554, 1128.2440764687515, 1193.093249130667, 1209.1233855454725, 19390.51523711862, 27877.0, 28478.288042798267, 29169.0, 29173.0, 41322.79338603288, 46056.309155363364, 47918.0, 47976.52023479721, 52316.81465482828, 344726.0, 345472.0, 345637.0, 346603.0, 348862.0, 31578.0, 32567.837160814044, 35541.82229088838, 38402.61597383991, 38621.6137838618, 40079.0, 40980.79509602433, 41008.79495602503, 41045.794771025954, 41398.0, 33141.337173256885, 35391.29217415586, 36115.81942090273, 36174.4573831398, 36261.0, 35562.46656300209, 35910.64089359073, 35972.64027359798, 36150.0, 36165.819170903975, 34178.82910585431, 35449.0, 36507.81746091359, 36584.08539786527, 39481.605183947795, 30661.0, 33432.498512521845, 33720.156996074315, 33749.493757594144, 33996.150096247795, 25163.622545661463, 25478.363040923385, 25673.301523288577, 25677.0, 25990.480190396465], "value": 18474.194042319312, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.7478902162996, 369.366536390091, 376.969842412607, 392.9096307849196, 398.05859143126514, 16415.179241037946, 17663.558411039714, 18080.0, 18633.0, 18633.254669813206, 17148.14259287034, 17539.473815785514, 17906.37327693531, 17911.0, 18001.279948802032, 5060.06388818068, 5220.086484865153, 5246.5278124968745, 5269.209618557209, 5270.0, 3693.3721267384476, 3816.293985612657, 5132.743362831862, 6368.458681012096, 6386.329435409261, 6410.358964103599, 6437.324080971501, 6444.806655800334, 6447.677616119198, 6461.676916154173, 6150.876982460348, 6341.4292713656, 6370.87258254837, 6370.936290637081, 6372.203474565679, 3438.7420943429092, 5017.122003649347, 5332.013577488177, 5341.946580534183, 5545.528630066449, 21332.0, 21422.464438389008, 21672.0, 21699.56600867974, 21897.343079707633, 1756.920938557768, 1865.1140708163514, 1977.624251392231, 2245.1805091141778, 2505.837120587174, 13548.119372240773, 13682.247476388795, 13949.930250348683, 14182.43270269188, 14489.492867749683, 1686.5614940115536, 1707.6499317639855, 1780.5459607800058, 1803.9098045097824, 1835.5686413692772, 47730.78816876469, 52257.47742522564, 60015.09977350343, 63000.0, 63060.36939630637, 9305.11601397866, 9480.715578532656, 9563.378380405251, 10078.899211007943, 10291.53688084023, 13925.164490130624, 13956.371963261678, 14015.439382424793, 14039.859601403856, 14042.85957140436, 7384.630768461556, 7574.431917606202, 7599.924000760032, 7606.885896711554, 7680.3087722104165, 7289.307515786098, 7333.1933487316355, 7439.218882017419, 7453.888191677129, 7475.514091584029, 6326.557141000178, 6339.017452294902, 6374.107624932513, 6418.326075761977, 6453.354664533509, 1734.4276388791668, 1764.7264673975299, 1784.4022252545283, 1801.5497524492648, 1835.6971099768389, 785.7328508307203, 826.8470332988417, 850.1540966738136, 892.6920212526668, 928.4382948316171, 17592.29630814753, 17637.294508219784, 17763.700221321702, 18404.35584754527, 18596.233357830984, 849.8980122385353, 879.62176264207, 882.770479675289, 897.4704924094685, 905.3119629081945, 10030.548625311878, 10415.270931034755, 10423.843642345371, 10448.32085914413, 10485.633002844865, 373354.0, 373818.0, 374466.0, 376288.0, 379299.0, 85971.14028859757, 86313.0, 89468.5526572363, 89865.0, 90561.54719226362, 894.2711689972781, 908.3142227618106, 921.0881227584644, 947.5688561704408, 949.8907625623003, 16720.33118675288, 16760.66478670444, 16767.32930682759, 16958.660826783147, 17524.299028038735, 37221.813890930374, 39365.2126957465, 39520.01199970024, 39582.40626390664, 39739.40390894197, 47602.28596570985, 53696.194557082454, 53707.731461342446, 53777.19334210068, 53940.0, 7079.43364530826, 7367.1527774304495, 7387.0, 7706.036745406825, 7781.221877812173, 10295.794084118426, 11083.72290692739, 11098.5560577576, 11111.83332250033, 11210.27133236337, 9653.324267301503, 9694.175995040501, 9700.0, 10170.38977661313, 10397.636082737068, 9177.495237761721, 9324.393914395543, 9797.706068817946, 10004.049615286827, 10133.594656213669, 9411.152996230372, 9670.226381889574, 9781.462019588991, 9831.001544528988, 10057.446840423847, 7366.152892417441, 7553.848923021619, 7584.54492730438, 7596.164421913474, 7694.07671079474, 9752.707418777449, 9914.058164474363, 9954.950225248827, 9987.101160895554, 10176.18590512772], "value": 8515.183199599043, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1232.346856166232, 1308.5223893278953, 1387.6947071644238, 1422.6372275069862, 1702.072370558045, 6670.966645166774, 7614.847703045943, 7813.179616140309, 7847.607619619018, 8052.758417247477, 7789.844203115934, 7918.841623167533, 7932.563708996003, 7934.563599002067, 7943.841123177547, 1823.854091672663, 2030.9289174878882, 2072.3575691535634, 2118.4280244334022, 2119.9258025969016, 2121.077331360863, 2136.134865379524, 2137.1665050630268, 2137.2733270687963, 2141.0793358855685, 2135.0712440088532, 2155.5473350596394, 2169.425102347879, 2178.3247193370025, 2181.967270490944, 1799.6400719856015, 1842.935497257603, 1854.1285595769966, 1863.617958318555, 2039.7552293724773, 1992.4520756791853, 2061.134323584083, 2061.9278325258692, 2072.875627462357, 2077.9480512987143, 7839.294463498312, 7884.960575197145, 7900.960495197544, 7904.762857114296, 7912.841743165162, 4292.24885645014, 4385.912281754347, 4399.912001759979, 4406.0, 4407.757573333465, 2589.572720501115, 2646.259047466712, 2689.40833016736, 2704.4726278375724, 2708.891644334224, 810.6716779704229, 830.3813658824155, 838.9580520973988, 843.6372359885247, 843.843888880559, 10594.523246453928, 10629.893701062967, 10636.095931845724, 10636.989485998818, 10646.201534884915, 3770.6040865709256, 4013.8795836124964, 4208.789560521962, 4220.662347012293, 4221.704480686322, 9038.728838134866, 9134.451932884049, 9226.953865230631, 9228.35401521887, 9232.8615070774, 4569.977150114228, 4574.542545745466, 4576.176288268143, 4576.839810606612, 4579.496255411902, 3566.28674265148, 4017.5580686124504, 4126.958730412717, 4128.587141285848, 4198.244316023145, 3606.314800187956, 3667.394879844796, 3705.055210921198, 3738.6261373862394, 3764.4729737836724, 1007.9748006299754, 1153.4175241502987, 1170.8126699728173, 1175.2948231061296, 1184.3959580613778, 768.3161985832502, 796.6693822063894, 819.9385046121446, 820.6512232301249, 825.9876101858476, 5138.537531622172, 5299.867503312448, 5377.5160235578205, 5404.891902161935, 5474.808381706621, 841.9663213471515, 842.4649754815987, 864.1919804982361, 886.8226354728951, 908.2597682888556, 3456.107828178666, 3568.6252943440563, 3570.839477169878, 3619.2179172764, 3649.416093425092, 9517.762055948519, 9940.0, 9977.451240181714, 9992.150667193224, 9999.50002499872, 9148.359614826899, 9544.570494327772, 9553.140217380469, 9882.357646752938, 9889.060539248903, 7295.671694773748, 7420.703171873065, 7467.626618669045, 7467.999999999892, 7495.887561686579, 5393.164059570773, 5477.698726569917, 5542.528885044816, 5632.7183640816975, 5687.488126068673, 9270.072992700945, 9273.53632318395, 9295.0, 9296.907030929604, 9303.674371396968, 9131.406458580172, 9207.125323094295, 9248.16766491019, 9467.0, 9502.952485237529, 6906.412954898688, 6913.861722765416, 7028.929710702828, 7035.929640703527, 7054.6472676367, 7519.43604229674, 7538.0, 7546.2831031051965, 7563.092428908566, 7590.620468976639, 6772.0857684212, 6895.586264824126, 6935.167779866449, 6962.617056061965, 7057.858842823218, 6799.796006119825, 6844.863102738017, 6864.21061577925, 6927.58434493932, 6944.583325000516, 6389.8722025560155, 6515.153030106056, 6563.803085907431, 6590.0, 6620.966895165493, 4451.132029254394, 4489.483709373464, 4538.954610453853, 4541.114482675978, 4663.323818046297, 4641.86074417768, 4653.069386122721, 4697.694649847749, 4734.313524538991, 4735.715857048588], "value": 4100.151837933515, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.719762022509, 303.65231809578034, 311.7428121799516, 312.24281118288144, 313.76781181925384, 4638.721676699401, 4753.833615823447, 4773.379460670112, 4832.685875418096, 4834.25069114287, 4539.6368290536675, 4710.481846996828, 4759.833405830796, 4769.546893045155, 4779.187538118519, 3801.923961520768, 3809.5047643806265, 3810.8285127169206, 3810.9618903811024, 3839.7696138231795, 3592.317459682665, 3654.0, 3761.793101379423, 3803.7337386382965, 3819.2934307153264, 3761.078535758743, 3775.0, 3777.376732839078, 3782.3948168293077, 3799.4300854871713, 3768.981155094221, 3770.924581508368, 3775.452559378902, 3780.9054773630605, 3805.8667946621736, 3359.8320083995945, 3367.6800703933086, 3370.6460821613623, 3378.983105084483, 3381.188514756441, 4586.119690926704, 4685.320628508881, 4691.249400095969, 4706.74112923789, 4708.223143181371, 4298.656107511391, 4304.504981927056, 4307.138572285556, 4310.224159651293, 4325.84859529915, 4963.652544321899, 4974.253861920704, 4997.275395067695, 4997.30037794709, 5034.723090230036, 3539.451594851703, 3661.5240018797654, 3670.687991520724, 3686.889393318178, 3687.4284485904727, 5182.818601348972, 5211.7394130293715, 5217.113090774577, 5231.189165679288, 5237.13587258102, 3725.012871589031, 3752.512173417438, 3752.9437058444146, 3757.1358587524473, 3763.6612704856693, 3698.9445158322646, 3764.642358975893, 3773.603771603997, 3774.9056273592832, 3809.809509524568, 4280.422143010654, 4295.9570404295555, 4298.4412026436985, 4302.161078589645, 4302.849400270976, 4982.975085124624, 4990.451050384455, 4994.600431965434, 5051.6716413433005, 5068.974655126701, 1221.9511219551296, 1267.645059383374, 1309.1490531154825, 1312.8949684025256, 1320.8679132086716, 892.0054139634261, 900.3607438718583, 906.428949761646, 907.9105073911333, 916.6745805239044, 4352.347147927836, 4354.259775838115, 4354.99835037943, 4359.607635312837, 4370.737755734666, 5062.557171206188, 5065.695108725521, 5093.821716239914, 5096.159133742891, 5104.846854594368, 4380.846670366522, 4386.429764130707, 4405.647548196137, 4407.206702793528, 4421.314696222091, 2609.7390260973752, 2760.054161815915, 2760.5721113227482, 2763.6559762687375, 2771.2240572639694, 4917.7786999585105, 4919.827806026843, 4927.90144197114, 4930.161872481687, 4946.9010619787405, 5044.848654540369, 5265.631405801557, 5273.3935597406025, 5299.947000530023, 5310.575153987672, 4945.604351651859, 4959.330490383896, 4969.950300497021, 4981.153203955337, 4981.9252711209365, 5206.8958620828125, 5219.086659834438, 5233.685978841282, 5250.343707036621, 5269.681340210295, 4864.537868902448, 5129.589632829439, 5138.280640710378, 5141.28022076902, 5148.897022059613, 4940.431850337113, 4945.208766597327, 4964.106460837084, 4966.801327946841, 4998.925016124688, 3663.249033947981, 3678.1907980244305, 3717.052151701407, 3719.683826874746, 3719.75821571597, 5059.797608095635, 5234.424213336454, 5280.6303558750515, 5287.57699384056, 5300.761465734128, 5083.593312535062, 5085.46602606721, 5092.8472145835685, 5112.539871411591, 5113.642045056959, 5071.594272458121, 5090.949090509048, 5102.260172275073, 5110.66780659256, 5110.8466745997675, 4764.166270902509, 4983.725895075806, 4985.252212168204, 5014.724190169576, 5040.294358789699, 5070.225421102585, 5096.719680417613, 5097.898042039213, 5098.490150984871, 5098.5411312982005, 4964.726940018334, 4977.751112444435, 4986.252062190701, 5001.674891132065, 5002.324221385731], "value": 3822.5995154450356, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.0525289312119, 615.9414855588723, 621.7668374359612, 660.3363619562339, 705.6083873450236, 13169.078164528486, 14267.286635668226, 14283.857161428383, 14298.428062877472, 14307.213103279328, 11764.941175294112, 13244.668883277898, 13465.932670336635, 13514.59456216315, 13577.932110339434, 618.4155972605889, 761.9352355049828, 765.5789315876274, 793.5794029164523, 800.7237503061464, 785.7367781793106, 790.0361558898164, 792.7304716396424, 792.7978365516785, 793.7221972309703, 835.4736515994915, 836.3977935886152, 837.0833936839151, 837.3970741066422, 837.6733074101105, 655.4952686431445, 789.3290702902516, 804.8390321935577, 809.1463506001166, 815.1318845429657, 596.7404179182026, 597.9760809567656, 598.1207624791549, 598.1625723986454, 598.3836648252307, 13106.475740970349, 13119.672008199776, 13134.802977955338, 13158.14472059313, 13158.473661053546, 12428.813567796491, 12590.24458532491, 12780.297083660394, 12823.294718790463, 12866.228026318451, 11596.884031159665, 11695.649130526097, 11831.822522662167, 11903.523859045628, 12216.816747748791, 3804.372278574032, 3826.885193444201, 3838.405047217686, 3905.4337121117564, 3929.4105884117325, 59031.11453328161, 59074.40925590732, 59107.40892591062, 59246.40753592452, 59390.10914836281, 10058.195344372432, 10121.797564048678, 10190.388576685422, 10251.897481025244, 10352.171826253882, 18057.819421805612, 18328.633427331377, 18342.266309347742, 18367.35714249995, 18373.5406614833, 6298.905516417258, 6580.901286480706, 6624.470042396693, 6659.53383263167, 6683.030960510796, 3479.9708016148534, 3494.4408894577255, 3586.2110335726097, 3723.0878434783654, 3787.223619158121, 2883.0774152271492, 2995.7303842654264, 2996.235959830273, 3002.789804713693, 3003.294225856922, 396.5617992118725, 465.9883502912454, 490.9582685471775, 494.27341807542854, 538.5530010091614, 446.8056395467982, 467.9485256621769, 470.2757753060249, 483.3909274314389, 503.1848405582979, 7321.74373896911, 7366.926330736624, 7557.130929943017, 7603.9619801901745, 7662.003939487942, 432.34931428200355, 472.6006524486803, 480.2819784422311, 500.9774560144729, 506.8707479592754, 3467.2545402738533, 3541.964580354215, 3678.797666128335, 3723.4414837774552, 3742.345089609362, 56393.0, 62498.37501625017, 65196.674016629615, 104002.4799876011, 104189.0, 86850.56574717212, 87400.5629971846, 87937.0, 88525.4916860804, 88856.0, 76861.0, 79379.60919951502, 79690.0, 80219.3901274444, 81254.78117828237, 7586.0, 7626.4661473698525, 7955.0, 7967.362610991223, 8231.300339471212, 31198.0, 37854.62145378511, 42198.78900605477, 42290.0, 42488.362674559285, 61026.38973610207, 61861.38138618556, 62971.685141574, 63269.68365158145, 63433.0, 10554.736131596772, 10566.41884696349, 10684.57261709523, 10799.568017279535, 11029.0, 7902.367810575255, 11877.940610296893, 12385.62843114708, 12397.62807115788, 12598.622041338775, 10158.339707918962, 11390.715232118931, 11652.067834573045, 11680.182387232811, 11936.641900742992, 11440.885591143982, 11545.595904143314, 11726.120540959291, 11999.580014699444, 12097.69755756078, 10781.083607893417, 10807.243492955418, 11345.0, 11379.317240965569, 11449.942750286194, 9317.487538185465, 9587.0, 9724.27067969891, 9868.407895526005, 10413.531391087268, 9517.381370210915, 9528.628548183833, 9725.367851089657, 9739.9026009739, 9764.511774411108], "value": 6367.706367825231, "unit": "score"}], "name": "dromaeo_css", "value": 6437.293787365933, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 114.0, 115.0, 116.0, 114.0, 114.0, 115.0, 114.0, 115.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 122.0, 175.0, 128.0, 126.0, 148.0, 121.0, 164.0, 121.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 163.0, 161.0, 162.0, 161.0, 161.0, 161.0, 163.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 126.0, 76.0, 77.0, 75.0, 76.0, 102.0, 75.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 93.0, 111.0, 84.0, 85.0, 85.0, 118.0, 84.0, 85.0, 123.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 149.0, 147.0, 148.0, 148.0, 147.0, 147.0, 147.0, 146.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 64.0, 64.0, 65.0, 65.0, 65.0, 64.0, 65.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 78.0, 79.0, 80.0, 78.0, 81.0, 78.0, 91.0, 81.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 128.0, 130.0, 127.0, 128.0, 135.0, 141.0, 170.0, 130.0], "value": 134.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 164.0, 168.0, 168.0, 170.0, 166.0, 165.0, 168.0, 171.0], "value": 167.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 66.0, 70.0, 68.0, 69.0, 69.0, 65.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1529.6999999999998, "alertThreshold": 2.0}]} 16:28:43 INFO - Return code: 0 16:28:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:28:43 INFO - # TBPL SUCCESS # 16:28:43 INFO - Running post-action listener: _resource_record_post_action 16:28:43 INFO - ##### 16:28:43 INFO - ##### Finished run-tests step (success) 16:28:43 INFO - ##### 16:28:43 INFO - Running post-run listener: _resource_record_post_run 16:28:43 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 547610624; Read time: 20; Write time: 352460 16:28:43 INFO - TinderboxPrint: CPU usage
12.9% 16:28:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 16:28:43 INFO - TinderboxPrint: I/O write bytes / time
547,610,624 / 352,460 16:28:43 INFO - TinderboxPrint: CPU idle
8,337.3 (87.0%) 16:28:43 INFO - TinderboxPrint: CPU user
1,199.0 (12.5%) 16:28:43 INFO - TinderboxPrint: Swap in / out
0 / 0 16:28:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 258146304; Read time: 0; Write time: 188080 16:28:43 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 288550912; Read time: 0; Write time: 164360 16:28:44 INFO - Running post-run listener: _upload_blobber_files 16:28:44 INFO - Blob upload gear active. 16:28:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:28:44 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 16:28:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:28:44 INFO - (blobuploader) - INFO - Open directory for files ... 16:28:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:28:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:28:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:48 INFO - (blobuploader) - INFO - Done attempting. 16:28:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:28:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:28:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:49 INFO - (blobuploader) - INFO - Done attempting. 16:28:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:28:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:28:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:50 INFO - (blobuploader) - INFO - Done attempting. 16:28:50 INFO - (blobuploader) - INFO - Iteration through files over. 16:28:50 INFO - Return code: 0 16:28:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:28:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:28:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e508647b4aa19db5b6dc761a180f562afe43bb818efac67e803b04ef4fcd9b18c0ba68559819aad0523d8c93ab3110b67c5502e661d905c1c6d252ddf835ef46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/70df3f4017a220470349f75cb60cc8226e8e6c0f2e0ece3b212f9b007b82fa3a9743d3ce102cc44099e803ec2fab1eeca683d147120cef7f69bde3809e321d45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f7721536b41f8a9375e19e2f18544a9b003764460ae55de9a659d452a92f50bfa7532df10220adf040981388174e42128c26d088303c5c84f5ca30da60094fd2"} 16:28:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:28:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:28:50 INFO - Contents: 16:28:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e508647b4aa19db5b6dc761a180f562afe43bb818efac67e803b04ef4fcd9b18c0ba68559819aad0523d8c93ab3110b67c5502e661d905c1c6d252ddf835ef46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/70df3f4017a220470349f75cb60cc8226e8e6c0f2e0ece3b212f9b007b82fa3a9743d3ce102cc44099e803ec2fab1eeca683d147120cef7f69bde3809e321d45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f7721536b41f8a9375e19e2f18544a9b003764460ae55de9a659d452a92f50bfa7532df10220adf040981388174e42128c26d088303c5c84f5ca30da60094fd2"} 16:28:50 INFO - Running post-run listener: copy_logs_to_upload_dir 16:28:50 INFO - Copying logs to upload dir... 16:28:50 INFO - mkdir: /builds/slave/test/build/upload/logs 16:28:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.180630 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-13 16:28:49.702649) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 16:28:49.705650) ========= 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=8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e508647b4aa19db5b6dc761a180f562afe43bb818efac67e803b04ef4fcd9b18c0ba68559819aad0523d8c93ab3110b67c5502e661d905c1c6d252ddf835ef46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/70df3f4017a220470349f75cb60cc8226e8e6c0f2e0ece3b212f9b007b82fa3a9743d3ce102cc44099e803ec2fab1eeca683d147120cef7f69bde3809e321d45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f7721536b41f8a9375e19e2f18544a9b003764460ae55de9a659d452a92f50bfa7532df10220adf040981388174e42128c26d088303c5c84f5ca30da60094fd2"} build_url:https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011221 build_url: 'https://queue.taskcluster.net/v1/task/BDmzb8qoSxqaBHjLDKjRQw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e508647b4aa19db5b6dc761a180f562afe43bb818efac67e803b04ef4fcd9b18c0ba68559819aad0523d8c93ab3110b67c5502e661d905c1c6d252ddf835ef46", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/70df3f4017a220470349f75cb60cc8226e8e6c0f2e0ece3b212f9b007b82fa3a9743d3ce102cc44099e803ec2fab1eeca683d147120cef7f69bde3809e321d45", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f7721536b41f8a9375e19e2f18544a9b003764460ae55de9a659d452a92f50bfa7532df10220adf040981388174e42128c26d088303c5c84f5ca30da60094fd2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 16:28:49.732043) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:28:49.732619) ========= 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=8887e36bb8e381bfd44124ac000001a8-1468450433.861465-1272320511 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004442 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:28:49.815306) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 16:28:49.815610) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 16:28:49.815889) ========= ========= Total master_lag: 0.30 =========