builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-007 starttime: 1469070031.84 results: success (0) buildid: 20160720183003 builduid: c6ab273cb65340f691365fb6e4d19407 revision: 8d4f43219dcfeb6b102f974e2617a31c1745a989 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:31.840397) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:31.840918) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:31.841194) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:32.515040) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:32.515602) ========= 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=923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-20 20:00:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-07-20 20:00:33 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.308427 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:32.839122) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:32.839450) ========= 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=923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017865 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:32.889454) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:32.889820) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 8d4f43219dcfeb6b102f974e2617a31c1745a989 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 8d4f43219dcfeb6b102f974e2617a31c1745a989 --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=923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-20 20:00:33,266 truncating revision to first 12 chars 2016-07-20 20:00:33,266 Setting DEBUG logging. 2016-07-20 20:00:33,266 attempt 1/10 2016-07-20 20:00:33,266 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/8d4f43219dcf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 20:00:33,574 unpacking tar archive at: autoland-8d4f43219dcf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.924653 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:33.828326) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:33.828636) ========= script_repo_revision: 8d4f43219dcfeb6b102f974e2617a31c1745a989 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:33.829017) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:33.829322) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 20:00:33.840104) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-20 20:00:33.840509) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:00:34 INFO - MultiFileLogger online at 20160720 20:00:34 in /builds/slave/test 20:00:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 20:00:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:00:34 INFO - {'append_to_log': False, 20:00:34 INFO - 'base_work_dir': '/builds/slave/test', 20:00:34 INFO - 'blob_upload_branch': 'Autoland', 20:00:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:00:34 INFO - 'branch': 'Autoland', 20:00:34 INFO - 'buildbot_json_path': 'buildprops.json', 20:00:34 INFO - 'config_files': ('talos/linux_config.py',), 20:00:34 INFO - 'default_actions': ('clobber', 20:00:34 INFO - 'read-buildbot-config', 20:00:34 INFO - 'download-and-extract', 20:00:34 INFO - 'populate-webroot', 20:00:34 INFO - 'create-virtualenv', 20:00:34 INFO - 'install', 20:00:34 INFO - 'run-tests'), 20:00:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:00:34 INFO - 'download_minidump_stackwalk': True, 20:00:34 INFO - 'download_symbols': 'ondemand', 20:00:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:00:34 INFO - 'tooltool.py': '/tools/tooltool.py', 20:00:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:00:34 INFO - '/tools/misc-python/virtualenv.py')}, 20:00:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:00:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:00:34 INFO - 'installer_path': 'installer.exe', 20:00:34 INFO - 'log_level': 'info', 20:00:34 INFO - 'log_name': 'talos', 20:00:34 INFO - 'log_to_console': True, 20:00:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:00:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:00:34 INFO - 'opt_config_files': (), 20:00:34 INFO - 'pip_index': False, 20:00:34 INFO - 'sps_profile': False, 20:00:34 INFO - 'sps_profile_interval': 0, 20:00:34 INFO - 'suite': 'dromaeojs', 20:00:34 INFO - 'system_bits': '32', 20:00:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:00:34 INFO - 'title': 'talos-linux64-ix-007', 20:00:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:00:34 INFO - 'use_talos_json': True, 20:00:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:00:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:00:34 INFO - 'work_dir': 'build'} 20:00:34 INFO - [mozharness: 2016-07-21 03:00:34.310326Z] Running clobber step. 20:00:34 INFO - Running pre-action listener: _resource_record_pre_action 20:00:34 INFO - Running main action method: clobber 20:00:34 INFO - rmtree: /builds/slave/test/build 20:00:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:00:34 INFO - Running post-action listener: _resource_record_post_action 20:00:34 INFO - [mozharness: 2016-07-21 03:00:34.777993Z] Finished clobber step (success) 20:00:34 INFO - [mozharness: 2016-07-21 03:00:34.778093Z] Running read-buildbot-config step. 20:00:34 INFO - Running pre-action listener: _resource_record_pre_action 20:00:34 INFO - Running main action method: read_buildbot_config 20:00:34 INFO - Using buildbot properties: 20:00:34 INFO - { 20:00:34 INFO - "project": "", 20:00:34 INFO - "product": "firefox", 20:00:34 INFO - "script_repo_revision": "production", 20:00:34 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 20:00:34 INFO - "repository": "", 20:00:34 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 20:00:34 INFO - "buildid": "20160720183003", 20:00:34 INFO - "pgo_build": "True", 20:00:34 INFO - "basedir": "/builds/slave/test", 20:00:34 INFO - "buildnumber": 55, 20:00:34 INFO - "slavename": "talos-linux64-ix-007", 20:00:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:00:34 INFO - "platform": "ubuntu64_hw", 20:00:34 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 20:00:34 INFO - "branch": "autoland", 20:00:34 INFO - "repo_path": "integration/autoland", 20:00:34 INFO - "revision": "8d4f43219dcfeb6b102f974e2617a31c1745a989", 20:00:34 INFO - "stage_platform": "linux64-pgo", 20:00:34 INFO - "builduid": "c6ab273cb65340f691365fb6e4d19407", 20:00:34 INFO - "slavebuilddir": "test" 20:00:34 INFO - } 20:00:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 20:00:34 INFO - Running post-action listener: _resource_record_post_action 20:00:34 INFO - [mozharness: 2016-07-21 03:00:34.780529Z] Finished read-buildbot-config step (success) 20:00:34 INFO - [mozharness: 2016-07-21 03:00:34.780619Z] Running download-and-extract step. 20:00:34 INFO - Running pre-action listener: _resource_record_pre_action 20:00:34 INFO - Running main action method: download_and_extract 20:00:34 INFO - mkdir: /builds/slave/test/build/tests 20:00:34 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')]} 20:00:34 INFO - https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:00:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 20:00:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 20:00:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/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 20:00:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/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 20:00:35 INFO - Downloaded 1361 bytes. 20:00:35 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 20:00:35 INFO - Using the following test package requirements: 20:00:35 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 20:00:35 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:00:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:00:35 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:00:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:00:35 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:00:35 INFO - u'jsshell-linux-x86_64.zip'], 20:00:35 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:00:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:00:35 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 20:00:35 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:00:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:00:35 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:00:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:00:35 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:00:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:00:35 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 20:00:35 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:00:35 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:00: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')]} 20:00:35 INFO - https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:00:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 20:00:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 20:00:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/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 20:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/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 20:00:36 INFO - Downloaded 24300474 bytes. 20:00:36 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'] 20:00:36 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 20:00:38 INFO - Return code: 0 20:00:38 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 20:00: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')]} 20:00:38 INFO - https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:00:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 20:00:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 20:00:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/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 20:00:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/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 20:00:39 INFO - Downloaded 24300474 bytes. 20:00:39 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'] 20:00:39 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 20:00:41 INFO - Return code: 0 20:00:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:41 INFO - https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:00:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 20:00:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 20:00:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/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 20:00:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/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 20:00:42 INFO - Downloaded 14403317 bytes. 20:00:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:00:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 20:00:42 INFO - Return code: 0 20:00: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')]} 20:00:42 INFO - https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:00:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 20:00:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 20:00:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/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 20:00:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BwzVcW3FRqeCv6-oBuhcOA/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 20:00:43 INFO - Downloaded 60858613 bytes. 20:00:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 20:00:43 INFO - mkdir: /builds/slave/test/properties 20:00:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:00:43 INFO - Writing to file /builds/slave/test/properties/build_url 20:00:43 INFO - Contents: 20:00:43 INFO - build_url:https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 20:00:44 INFO - Running post-action listener: _resource_record_post_action 20:00:44 INFO - Running post-action listener: set_extra_try_arguments 20:00:44 INFO - [mozharness: 2016-07-21 03:00:44.509788Z] Finished download-and-extract step (success) 20:00:44 INFO - [mozharness: 2016-07-21 03:00:44.510021Z] Running populate-webroot step. 20:00:44 INFO - Running pre-action listener: _resource_record_pre_action 20:00:44 INFO - Running main action method: populate_webroot 20:00:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:00:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:00:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:00:44 INFO - '--tppagecycles', 20:00:44 INFO - '7'], 20:00:44 INFO - 'tests': ['tsvgm']}}, 20:00:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:00:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:00:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:00:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:00:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:00:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:00:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:44 INFO - 'talos_options': ['--disable-e10s'], 20:00:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:00:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:00:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:44 INFO - 'talos_options': ['--disable-e10s'], 20:00:44 INFO - 'tests': ['damp', 'tps']}, 20:00:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:44 INFO - 'tests': ['damp', 'tps']}, 20:00:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:00:44 INFO - 'tests': ['dromaeo_dom']}, 20:00:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:00:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:00:44 INFO - 'tests': ['basic_compositor_video']}, 20:00:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:00:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:00:44 INFO - 'tests': ['a11yr', 20:00:44 INFO - 'ts_paint', 20:00:44 INFO - 'tpaint', 20:00:44 INFO - 'sessionrestore', 20:00:44 INFO - 'sessionrestore_no_auto_restore', 20:00:44 INFO - 'tabpaint']}, 20:00:44 INFO - 'other-e10s': {'tests': ['a11yr', 20:00:44 INFO - 'ts_paint', 20:00:44 INFO - 'tpaint', 20:00:44 INFO - 'sessionrestore', 20:00:44 INFO - 'sessionrestore_no_auto_restore', 20:00:44 INFO - 'tabpaint']}, 20:00:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:00:44 INFO - 'ts_paint', 20:00:44 INFO - 'tpaint', 20:00:44 INFO - 'sessionrestore', 20:00:44 INFO - 'sessionrestore_no_auto_restore', 20:00:44 INFO - 'tabpaint']}, 20:00:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:00:44 INFO - 'ts_paint', 20:00:44 INFO - 'tpaint', 20:00:44 INFO - 'sessionrestore', 20:00:44 INFO - 'sessionrestore_no_auto_restore', 20:00:44 INFO - 'tabpaint']}, 20:00:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:00:44 INFO - 'tests': ['a11yr', 20:00:44 INFO - 'ts_paint', 20:00:44 INFO - 'tpaint', 20:00:44 INFO - 'sessionrestore', 20:00:44 INFO - 'sessionrestore_no_auto_restore', 20:00:44 INFO - 'tabpaint']}, 20:00:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:00:44 INFO - 'tests': ['a11yr', 20:00:44 INFO - 'ts_paint', 20:00:44 INFO - 'tpaint', 20:00:44 INFO - 'sessionrestore', 20:00:44 INFO - 'sessionrestore_no_auto_restore', 20:00:44 INFO - 'tabpaint']}, 20:00:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:00:44 INFO - 'tests': ['tsvgx', 20:00:44 INFO - 'tsvgr_opacity', 20:00:44 INFO - 'tart', 20:00:44 INFO - 'tscrollx', 20:00:44 INFO - 'cart']}, 20:00:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:00:44 INFO - 'tsvgr_opacity', 20:00:44 INFO - 'tart', 20:00:44 INFO - 'tscrollx', 20:00:44 INFO - 'cart']}, 20:00:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:44 INFO - 'talos_options': ['--disable-e10s'], 20:00:44 INFO - 'tests': ['tp5o']}, 20:00:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:44 INFO - 'tests': ['tp5o']}, 20:00:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:44 INFO - 'talos_options': ['--disable-e10s', 20:00:44 INFO - '--xperf_path', 20:00:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:00:44 INFO - 'tests': ['tp5n']}, 20:00:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:00:44 INFO - 'talos_options': ['--xperf_path', 20:00:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:00:44 INFO - 'tests': ['tp5n']}}, 20:00:44 INFO - 'talos.zip': {'path': '', 20:00:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:00:44 INFO - Running post-action listener: _resource_record_post_action 20:00:44 INFO - [mozharness: 2016-07-21 03:00:44.530399Z] Finished populate-webroot step (success) 20:00:44 INFO - [mozharness: 2016-07-21 03:00:44.530487Z] Running create-virtualenv step. 20:00:44 INFO - Running pre-action listener: _resource_record_pre_action 20:00:44 INFO - Running main action method: create_virtualenv 20:00:44 INFO - Creating virtualenv /builds/slave/test/build/venv 20:00:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:00:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:00:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:00:44 INFO - Using real prefix '/usr' 20:00:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:00:45 INFO - Installing distribute.............................................................................................................................................................................................done. 20:00:47 INFO - Installing pip.................done. 20:00:47 INFO - Return code: 0 20:00:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:00:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:00:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc3b0>, '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 0x16bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd740>, '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': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', '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 20:00:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:00:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:00:47 INFO - Using env: {'DISPLAY': ':0', 20:00:47 INFO - 'HOME': '/home/cltbld', 20:00:47 INFO - 'LANG': 'en_US.UTF-8', 20:00:47 INFO - 'LANGUAGE': 'en_US:en', 20:00:47 INFO - 'LOGNAME': 'cltbld', 20:00:47 INFO - 'MAIL': '/var/mail/cltbld', 20:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:00:47 INFO - 'MOZ_NO_REMOTE': '1', 20:00:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:00:47 INFO - 'NO_EM_RESTART': '1', 20:00:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:00:47 INFO - 'PWD': '/builds/slave/test', 20:00:47 INFO - 'SHELL': '/bin/bash', 20:00:47 INFO - 'SHLVL': '1', 20:00:47 INFO - 'TERM': 'linux', 20:00:47 INFO - 'TMOUT': '86400', 20:00:47 INFO - 'USER': 'cltbld', 20:00:47 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', 20:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:00:47 INFO - '_': '/tools/buildbot/bin/python'} 20:00:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:00:47 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 20:00:47 INFO - Cleaning up... 20:00:47 INFO - Return code: 0 20:00:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:00:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:00:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc3b0>, '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 0x16bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd740>, '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': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', '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 20:00:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:00:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:00:47 INFO - Using env: {'DISPLAY': ':0', 20:00:47 INFO - 'HOME': '/home/cltbld', 20:00:47 INFO - 'LANG': 'en_US.UTF-8', 20:00:47 INFO - 'LANGUAGE': 'en_US:en', 20:00:47 INFO - 'LOGNAME': 'cltbld', 20:00:47 INFO - 'MAIL': '/var/mail/cltbld', 20:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:00:47 INFO - 'MOZ_NO_REMOTE': '1', 20:00:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:00:47 INFO - 'NO_EM_RESTART': '1', 20:00:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:00:47 INFO - 'PWD': '/builds/slave/test', 20:00:47 INFO - 'SHELL': '/bin/bash', 20:00:47 INFO - 'SHLVL': '1', 20:00:47 INFO - 'TERM': 'linux', 20:00:47 INFO - 'TMOUT': '86400', 20:00:47 INFO - 'USER': 'cltbld', 20:00:47 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', 20:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:00:47 INFO - '_': '/tools/buildbot/bin/python'} 20:00:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:00:47 INFO - Downloading/unpacking psutil>=3.1.1 20:00:47 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 20:00:47 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 20:00:47 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 20:00:47 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 20:00:47 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 20:00:47 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 20:00:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:00:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:00:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:00:49 INFO - Installing collected packages: psutil 20:00:49 INFO - Running setup.py install for psutil 20:00:49 INFO - building 'psutil._psutil_linux' extension 20:00:49 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 20:00:49 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 20:00:49 INFO - building 'psutil._psutil_posix' extension 20:00:49 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 20:00:49 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 20:00:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:00:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:00:49 INFO - Successfully installed psutil 20:00:49 INFO - Cleaning up... 20:00:50 INFO - Return code: 0 20:00:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:00:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:00:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:50 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 0x13fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc3b0>, '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 0x16bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd740>, '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': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', '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 20:00:50 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 20:00:50 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 20:00:50 INFO - Using env: {'DISPLAY': ':0', 20:00:50 INFO - 'HOME': '/home/cltbld', 20:00:50 INFO - 'LANG': 'en_US.UTF-8', 20:00:50 INFO - 'LANGUAGE': 'en_US:en', 20:00:50 INFO - 'LOGNAME': 'cltbld', 20:00:50 INFO - 'MAIL': '/var/mail/cltbld', 20:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:00:50 INFO - 'MOZ_NO_REMOTE': '1', 20:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:00:50 INFO - 'NO_EM_RESTART': '1', 20:00:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:00:50 INFO - 'PWD': '/builds/slave/test', 20:00:50 INFO - 'SHELL': '/bin/bash', 20:00:50 INFO - 'SHLVL': '1', 20:00:50 INFO - 'TERM': 'linux', 20:00:50 INFO - 'TMOUT': '86400', 20:00:50 INFO - 'USER': 'cltbld', 20:00:50 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', 20:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:00:50 INFO - '_': '/tools/buildbot/bin/python'} 20:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:00:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:00:50 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 20:00:50 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 20:00:50 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 20:00:50 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 20:00:50 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 20:00:50 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 20:00:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:00:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:00:52 INFO - Installing collected packages: mozsystemmonitor 20:00:52 INFO - Running setup.py install for mozsystemmonitor 20:00:52 INFO - Successfully installed mozsystemmonitor 20:00:52 INFO - Cleaning up... 20:00:52 INFO - Return code: 0 20:00:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:00:52 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')]} 20:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:00:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:52 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')]} 20:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:00:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:52 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 0x13fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc3b0>, '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 0x16bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd740>, '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': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', '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 20:00:52 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 20:00:52 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 20:00:52 INFO - Using env: {'DISPLAY': ':0', 20:00:52 INFO - 'HOME': '/home/cltbld', 20:00:52 INFO - 'LANG': 'en_US.UTF-8', 20:00:52 INFO - 'LANGUAGE': 'en_US:en', 20:00:52 INFO - 'LOGNAME': 'cltbld', 20:00:52 INFO - 'MAIL': '/var/mail/cltbld', 20:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:00:52 INFO - 'MOZ_NO_REMOTE': '1', 20:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:00:52 INFO - 'NO_EM_RESTART': '1', 20:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:00:52 INFO - 'PWD': '/builds/slave/test', 20:00:52 INFO - 'SHELL': '/bin/bash', 20:00:52 INFO - 'SHLVL': '1', 20:00:52 INFO - 'TERM': 'linux', 20:00:52 INFO - 'TMOUT': '86400', 20:00:52 INFO - 'USER': 'cltbld', 20:00:52 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', 20:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:00:52 INFO - '_': '/tools/buildbot/bin/python'} 20:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:00:52 INFO - Downloading/unpacking jsonschema==2.5.1 20:00:52 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 20:00:52 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 20:00:52 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 20:00:52 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 20:00:52 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 20:00:52 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 20:00:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:00:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:00:54 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 20:00:54 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 20:00:54 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 20:00:54 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 20:00:54 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 20:00:54 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 20:00:54 INFO - Downloading functools32-3.2.3-2.tar.gz 20:00:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:00:54 INFO - warning: no files found matching '*.txt' 20:00:54 INFO - no previously-included directories found matching 'build' 20:00:54 INFO - no previously-included directories found matching 'dist' 20:00:54 INFO - no previously-included directories found matching '.git*' 20:00:54 INFO - Installing collected packages: jsonschema, functools32 20:00:54 INFO - Running setup.py install for functools32 20:00:54 INFO - warning: no files found matching '*.txt' 20:00:54 INFO - no previously-included directories found matching 'build' 20:00:54 INFO - no previously-included directories found matching 'dist' 20:00:54 INFO - no previously-included directories found matching '.git*' 20:00:54 INFO - Successfully installed jsonschema functools32 20:00:54 INFO - Cleaning up... 20:00:54 INFO - Return code: 0 20:00:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:00:54 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')]} 20:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:54 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')]} 20:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc3b0>, '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 0x16bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd740>, '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': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', '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 20:00:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:00:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:00:55 INFO - Using env: {'DISPLAY': ':0', 20:00:55 INFO - 'HOME': '/home/cltbld', 20:00:55 INFO - 'LANG': 'en_US.UTF-8', 20:00:55 INFO - 'LANGUAGE': 'en_US:en', 20:00:55 INFO - 'LOGNAME': 'cltbld', 20:00:55 INFO - 'MAIL': '/var/mail/cltbld', 20:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:00:55 INFO - 'MOZ_NO_REMOTE': '1', 20:00:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:00:55 INFO - 'NO_EM_RESTART': '1', 20:00:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:00:55 INFO - 'PWD': '/builds/slave/test', 20:00:55 INFO - 'SHELL': '/bin/bash', 20:00:55 INFO - 'SHLVL': '1', 20:00:55 INFO - 'TERM': 'linux', 20:00:55 INFO - 'TMOUT': '86400', 20:00:55 INFO - 'USER': 'cltbld', 20:00:55 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', 20:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:00:55 INFO - '_': '/tools/buildbot/bin/python'} 20:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:00:55 INFO - Downloading/unpacking blobuploader==1.2.4 20:00:55 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 20:00:55 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 20:00:55 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 20:00:55 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 20:00:55 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 20:00:55 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 20:00:57 INFO - Downloading blobuploader-1.2.4.tar.gz 20:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:00:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:00:57 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 20:00:57 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 20:00:57 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 20:00:57 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 20:00:57 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 20:00:57 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 20:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:00:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:00:57 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 20:00:57 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 20:00:57 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 20:00:57 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 20:00:57 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 20:00:57 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 20:00:57 INFO - Downloading docopt-0.6.1.tar.gz 20:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:00:57 INFO - Installing collected packages: blobuploader, requests, docopt 20:00:57 INFO - Running setup.py install for blobuploader 20:00:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:00:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:00:58 INFO - Running setup.py install for requests 20:00:58 INFO - Running setup.py install for docopt 20:00:58 INFO - Successfully installed blobuploader requests docopt 20:00:58 INFO - Cleaning up... 20:00:58 INFO - Return code: 0 20:00:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:00:58 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')]} 20:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:58 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')]} 20:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:00:58 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 0x13fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc3b0>, '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 0x16bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd740>, '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': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', '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 20:00:58 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 20:00:58 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 20:00:58 INFO - Using env: {'DISPLAY': ':0', 20:00:58 INFO - 'HOME': '/home/cltbld', 20:00:58 INFO - 'LANG': 'en_US.UTF-8', 20:00:58 INFO - 'LANGUAGE': 'en_US:en', 20:00:58 INFO - 'LOGNAME': 'cltbld', 20:00:58 INFO - 'MAIL': '/var/mail/cltbld', 20:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:00:58 INFO - 'MOZ_NO_REMOTE': '1', 20:00:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:00:58 INFO - 'NO_EM_RESTART': '1', 20:00:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:00:58 INFO - 'PWD': '/builds/slave/test', 20:00:58 INFO - 'SHELL': '/bin/bash', 20:00:58 INFO - 'SHLVL': '1', 20:00:58 INFO - 'TERM': 'linux', 20:00:58 INFO - 'TMOUT': '86400', 20:00:58 INFO - 'USER': 'cltbld', 20:00:58 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', 20:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:00:58 INFO - '_': '/tools/buildbot/bin/python'} 20:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:00:58 INFO - Running setup.py (path:/tmp/pip-dTtRQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:00:58 INFO - Running setup.py (path:/tmp/pip-K8R1aF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:00:58 INFO - Running setup.py (path:/tmp/pip-IPib8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:00:58 INFO - Running setup.py (path:/tmp/pip-1rdm9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:00:59 INFO - Running setup.py (path:/tmp/pip-AkLgtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:00:59 INFO - Running setup.py (path:/tmp/pip-cP6kK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:00:59 INFO - Running setup.py (path:/tmp/pip-sLba76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:00:59 INFO - Running setup.py (path:/tmp/pip-IMD_uu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:00:59 INFO - Running setup.py (path:/tmp/pip-OvYxKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:00:59 INFO - Running setup.py (path:/tmp/pip-TvkjGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:00:59 INFO - Running setup.py (path:/tmp/pip-Q0tv6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:00:59 INFO - Running setup.py (path:/tmp/pip-O0nDDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:00:59 INFO - Running setup.py (path:/tmp/pip-i4DRJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:00:59 INFO - Running setup.py (path:/tmp/pip-X2Rvqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:00:59 INFO - Running setup.py (path:/tmp/pip-tM6Tlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:00:59 INFO - Running setup.py (path:/tmp/pip-kNOpH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:00:59 INFO - Running setup.py (path:/tmp/pip-6ugjTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:00:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:00:59 INFO - Running setup.py install for manifestparser 20:01:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:01:00 INFO - Running setup.py install for mozcrash 20:01:00 INFO - Running setup.py install for mozdebug 20:01:00 INFO - Running setup.py install for mozdevice 20:01:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:01:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:01:00 INFO - Running setup.py install for mozfile 20:01:00 INFO - Running setup.py install for mozhttpd 20:01:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:01:00 INFO - Running setup.py install for mozinfo 20:01:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:01:00 INFO - Running setup.py install for mozInstall 20:01:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:01:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:01:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:01:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:01:00 INFO - Running setup.py install for mozleak 20:01:00 INFO - Running setup.py install for mozlog 20:01:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:01:00 INFO - Running setup.py install for moznetwork 20:01:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:01:01 INFO - Running setup.py install for mozprocess 20:01:01 INFO - Running setup.py install for mozprofile 20:01:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:01:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:01:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:01:01 INFO - Running setup.py install for mozrunner 20:01:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:01:01 INFO - Running setup.py install for mozscreenshot 20:01:01 INFO - Running setup.py install for moztest 20:01:01 INFO - Running setup.py install for mozversion 20:01:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:01:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:01:01 INFO - Cleaning up... 20:01:01 INFO - Return code: 0 20:01:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:01:01 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')]} 20:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:01 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')]} 20:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:01 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 0x13fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc3b0>, '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 0x16bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd740>, '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': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', '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 20:01:01 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 20:01:01 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 20:01:01 INFO - Using env: {'DISPLAY': ':0', 20:01:01 INFO - 'HOME': '/home/cltbld', 20:01:01 INFO - 'LANG': 'en_US.UTF-8', 20:01:01 INFO - 'LANGUAGE': 'en_US:en', 20:01:01 INFO - 'LOGNAME': 'cltbld', 20:01:01 INFO - 'MAIL': '/var/mail/cltbld', 20:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:01 INFO - 'MOZ_NO_REMOTE': '1', 20:01:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:01:01 INFO - 'NO_EM_RESTART': '1', 20:01:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:01 INFO - 'PWD': '/builds/slave/test', 20:01:01 INFO - 'SHELL': '/bin/bash', 20:01:01 INFO - 'SHLVL': '1', 20:01:01 INFO - 'TERM': 'linux', 20:01:01 INFO - 'TMOUT': '86400', 20:01:01 INFO - 'USER': 'cltbld', 20:01:01 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', 20:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:01 INFO - '_': '/tools/buildbot/bin/python'} 20:01:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:01:01 INFO - Running setup.py (path:/tmp/pip-MIWsNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:01:02 INFO - Running setup.py (path:/tmp/pip-kZhU4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:01:02 INFO - Running setup.py (path:/tmp/pip-i6QjtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:01:02 INFO - Running setup.py (path:/tmp/pip-Bwa3yR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:01:02 INFO - Running setup.py (path:/tmp/pip-jOaKYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:01:02 INFO - Running setup.py (path:/tmp/pip-ebStB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:01:02 INFO - Running setup.py (path:/tmp/pip-G_8FUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:01:02 INFO - Running setup.py (path:/tmp/pip-330e1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:01:02 INFO - Running setup.py (path:/tmp/pip-aStHbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:01:02 INFO - Running setup.py (path:/tmp/pip-Zjw_7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:01:02 INFO - Running setup.py (path:/tmp/pip-um_oyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:01:02 INFO - Running setup.py (path:/tmp/pip-lMI6c9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:01:02 INFO - Running setup.py (path:/tmp/pip-onJedF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:01:02 INFO - Running setup.py (path:/tmp/pip-jyXgey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:01:02 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)) 20:01:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:01:02 INFO - Running setup.py (path:/tmp/pip-Wg4Bmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:01:03 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)) 20:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:01:03 INFO - Running setup.py (path:/tmp/pip-KzeeYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:01:03 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)) 20:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:01:03 INFO - Running setup.py (path:/tmp/pip-BOxmBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:01:03 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)) 20:01:03 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)) 20:01:03 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)) 20:01:03 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)) 20:01:03 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)) 20:01:03 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)) 20:01:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:01:03 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 20:01:03 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 20:01:03 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 20:01:03 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 20:01:03 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 20:01:03 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 20:01:05 INFO - Downloading blessings-1.6.tar.gz 20:01:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:01:05 INFO - Installing collected packages: blessings 20:01:05 INFO - Running setup.py install for blessings 20:01:05 INFO - Successfully installed blessings 20:01:05 INFO - Cleaning up... 20:01:05 INFO - Return code: 0 20:01:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:01:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:01:05 INFO - Reading from file tmpfile_stdout 20:01:05 INFO - Current package versions: 20:01:05 INFO - argparse == 1.2.1 20:01:05 INFO - blessings == 1.6 20:01:05 INFO - blobuploader == 1.2.4 20:01:05 INFO - docopt == 0.6.1 20:01:05 INFO - functools32 == 3.2.3-2 20:01:05 INFO - jsonschema == 2.5.1 20:01:05 INFO - manifestparser == 1.1 20:01:05 INFO - mozInstall == 1.12 20:01:05 INFO - mozcrash == 0.17 20:01:05 INFO - mozdebug == 0.1 20:01:05 INFO - mozdevice == 0.48 20:01:05 INFO - mozfile == 1.2 20:01:05 INFO - mozhttpd == 0.7 20:01:05 INFO - mozinfo == 0.9 20:01:05 INFO - mozleak == 0.1 20:01:05 INFO - mozlog == 3.3 20:01:05 INFO - moznetwork == 0.27 20:01:05 INFO - mozprocess == 0.23 20:01:05 INFO - mozprofile == 0.28 20:01:05 INFO - mozrunner == 6.12 20:01:05 INFO - mozscreenshot == 0.1 20:01:05 INFO - mozsystemmonitor == 0.3 20:01:05 INFO - moztest == 0.7 20:01:05 INFO - mozversion == 1.4 20:01:05 INFO - psutil == 3.1.1 20:01:05 INFO - requests == 1.2.3 20:01:05 INFO - wsgiref == 0.1.2 20:01:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:05 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 0x13fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc3b0>, '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 0x16bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd740>, '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': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', '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 20:01:05 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 20:01:05 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 20:01:05 INFO - Using env: {'DISPLAY': ':0', 20:01:05 INFO - 'HOME': '/home/cltbld', 20:01:05 INFO - 'LANG': 'en_US.UTF-8', 20:01:05 INFO - 'LANGUAGE': 'en_US:en', 20:01:05 INFO - 'LOGNAME': 'cltbld', 20:01:05 INFO - 'MAIL': '/var/mail/cltbld', 20:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:05 INFO - 'MOZ_NO_REMOTE': '1', 20:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:01:05 INFO - 'NO_EM_RESTART': '1', 20:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:05 INFO - 'PWD': '/builds/slave/test', 20:01:05 INFO - 'SHELL': '/bin/bash', 20:01:05 INFO - 'SHLVL': '1', 20:01:05 INFO - 'TERM': 'linux', 20:01:05 INFO - 'TMOUT': '86400', 20:01:05 INFO - 'USER': 'cltbld', 20:01:05 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', 20:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:05 INFO - '_': '/tools/buildbot/bin/python'} 20:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:05 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)) 20:01:05 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)) 20:01:05 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)) 20:01:05 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)) 20:01:05 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)) 20:01:05 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)) 20:01:05 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)) 20:01:05 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)) 20:01:05 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)) 20:01:05 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)) 20:01:05 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)) 20:01:05 INFO - Cleaning up... 20:01:05 INFO - Return code: 0 20:01:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:01:05 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 0x13fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16bc3b0>, '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 0x16bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd740>, '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': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', '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 20:01:05 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 20:01:05 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 20:01:05 INFO - Using env: {'DISPLAY': ':0', 20:01:05 INFO - 'HOME': '/home/cltbld', 20:01:05 INFO - 'LANG': 'en_US.UTF-8', 20:01:05 INFO - 'LANGUAGE': 'en_US:en', 20:01:05 INFO - 'LOGNAME': 'cltbld', 20:01:05 INFO - 'MAIL': '/var/mail/cltbld', 20:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:05 INFO - 'MOZ_NO_REMOTE': '1', 20:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:01:05 INFO - 'NO_EM_RESTART': '1', 20:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:05 INFO - 'PWD': '/builds/slave/test', 20:01:05 INFO - 'SHELL': '/bin/bash', 20:01:05 INFO - 'SHLVL': '1', 20:01:05 INFO - 'TERM': 'linux', 20:01:05 INFO - 'TMOUT': '86400', 20:01:05 INFO - 'USER': 'cltbld', 20:01:05 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', 20:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:05 INFO - '_': '/tools/buildbot/bin/python'} 20:01:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:01:06 INFO - Cleaning up... 20:01:06 INFO - Return code: 0 20:01:06 INFO - Running post-action listener: _resource_record_post_action 20:01:06 INFO - Running post-action listener: _start_resource_monitoring 20:01:06 INFO - Starting resource monitoring. 20:01:06 INFO - [mozharness: 2016-07-21 03:01:06.051210Z] Finished create-virtualenv step (success) 20:01:06 INFO - [mozharness: 2016-07-21 03:01:06.051693Z] Running install step. 20:01:06 INFO - Running pre-action listener: _resource_record_pre_action 20:01:06 INFO - Running main action method: install 20:01:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:01:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:01:06 INFO - Reading from file tmpfile_stdout 20:01:06 INFO - Detecting whether we're running mozinstall >=1.0... 20:01:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:01:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:01:06 INFO - Reading from file tmpfile_stdout 20:01:06 INFO - Output received: 20:01:06 INFO - Usage: mozinstall [options] installer 20:01:06 INFO - Options: 20:01:06 INFO - -h, --help show this help message and exit 20:01:06 INFO - -d DEST, --destination=DEST 20:01:06 INFO - Directory to install application into. [default: 20:01:06 INFO - "/builds/slave/test"] 20:01:06 INFO - --app=APP Application being installed. [default: firefox] 20:01:06 INFO - mkdir: /builds/slave/test/build/application 20:01:06 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'] 20:01:06 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 20:01:23 INFO - Reading from file tmpfile_stdout 20:01:23 INFO - Output received: 20:01:23 INFO - /builds/slave/test/build/application/firefox/firefox 20:01:23 INFO - Running post-action listener: _resource_record_post_action 20:01:23 INFO - [mozharness: 2016-07-21 03:01:23.591694Z] Finished install step (success) 20:01:23 INFO - [mozharness: 2016-07-21 03:01:23.591969Z] Running run-tests step. 20:01:23 INFO - Running pre-action listener: _resource_record_pre_action 20:01:23 INFO - Running main action method: run_tests 20:01:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:01:23 INFO - Python 2.7.3 20:01:23 INFO - Return code: 0 20:01:23 INFO - grabbing minidump binary from tooltool 20:01: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')]} 20:01:23 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 0x16bc3b0>, '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 0x16bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16bd740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:01:23 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 20:01:23 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 20:01:23 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 20:01:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:01:23 INFO - Return code: 0 20:01:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:01:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:01:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:01:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:01:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:01:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 20:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 20:01:23 INFO - Using env: {'DISPLAY': ':0', 20:01:23 INFO - 'HOME': '/home/cltbld', 20:01:23 INFO - 'LANG': 'en_US.UTF-8', 20:01:23 INFO - 'LANGUAGE': 'en_US:en', 20:01:23 INFO - 'LOGNAME': 'cltbld', 20:01:23 INFO - 'MAIL': '/var/mail/cltbld', 20:01:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:01:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:01:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:01:23 INFO - 'MOZ_NO_REMOTE': '1', 20:01:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:01:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:01:23 INFO - 'NO_EM_RESTART': '1', 20:01:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:01:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:01:23 INFO - 'PWD': '/builds/slave/test', 20:01:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:01:23 INFO - 'SHELL': '/bin/bash', 20:01:23 INFO - 'SHLVL': '1', 20:01:23 INFO - 'TERM': 'linux', 20:01:23 INFO - 'TMOUT': '86400', 20:01:23 INFO - 'USER': 'cltbld', 20:01:23 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823', 20:01:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:01:23 INFO - '_': '/tools/buildbot/bin/python'} 20:01:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 20:01:23 INFO - mozversion application_buildid: 20160720183003 20:01:23 INFO - mozversion application_changeset: 8d4f43219dcfeb6b102f974e2617a31c1745a989 20:01:23 INFO - mozversion application_display_name: Nightly 20:01:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:01:23 INFO - mozversion application_name: Firefox 20:01:23 INFO - mozversion application_remotingname: firefox 20:01:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:01:23 INFO - mozversion application_vendor: Mozilla 20:01:23 INFO - mozversion application_version: 50.0a1 20:01:23 INFO - mozversion platform_buildid: 20160720183003 20:01:23 INFO - mozversion platform_changeset: 8d4f43219dcfeb6b102f974e2617a31c1745a989 20:01:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:01:23 INFO - mozversion platform_version: 50.0a1 20:01:23 INFO - using testdate: 1469070083 20:01:23 INFO - actual date: 1469070083 20:01:23 INFO - starting webserver on 'localhost:34858' 20:01:23 INFO - SUITE-START | Running 2 tests 20:01:23 INFO - TEST-START | dromaeo_css 20:01:23 INFO - operating with platform_type : linux_ 20:01:23 INFO - Initialising browser for dromaeo_css test... 20:01:23 INFO - TEST-INFO | started process 12598 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcenEpA/profile http://localhost:34858/getInfo.html) 20:01:27 INFO - PROCESS | 12598 | __metrics Screen width/height:1600/1200 20:01:27 INFO - PROCESS | 12598 | colorDepth:24 20:01:27 INFO - PROCESS | 12598 | Browser inner width/height: 1024/697 20:01:27 INFO - PROCESS | 12598 | __metrics 20:01:28 INFO - PROCESS | 12598 | 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" 20:01:28 INFO - PROCESS | 12598 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:01:29 INFO - TEST-INFO | 12598: exit 0 20:01:29 INFO - Browser initialized. 20:01:29 INFO - Running cycle 1/1 for dromaeo_css test... 20:01:29 INFO - TEST-INFO | started process 12677 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcenEpA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:01:40 INFO - PROCESS | 12677 | RSS: Main: 157360128 20:01:40 INFO - PROCESS | 12677 | 20:02:32 INFO - PROCESS | 12677 | 1469070152343 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] 20:04:40 INFO - PROCESS | 12677 | Cycle 1(1): loaded http://localhost:34858/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34858/tests/dromaeo/cssquery-ext.html) 20:04:41 INFO - PROCESS | 12677 | RSS: Main: 291840000 20:04:41 INFO - PROCESS | 12677 | 20:07:43 INFO - PROCESS | 12677 | Cycle 1(1): loaded http://localhost:34858/tests/dromaeo/cssquery-ext.html (next: http://localhost:34858/tests/dromaeo/cssquery-jquery.html) 20:07:44 INFO - PROCESS | 12677 | RSS: Main: 243974144 20:07:44 INFO - PROCESS | 12677 | 20:10:46 INFO - PROCESS | 12677 | Cycle 1(1): loaded http://localhost:34858/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34858/tests/dromaeo/cssquery-mootools.html) 20:10:46 INFO - PROCESS | 12677 | RSS: Main: 184786944 20:10:46 INFO - PROCESS | 12677 | 20:13:49 INFO - PROCESS | 12677 | Cycle 1(1): loaded http://localhost:34858/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34858/tests/dromaeo/cssquery-prototype.html) 20:13:49 INFO - PROCESS | 12677 | RSS: Main: 229826560 20:13:49 INFO - PROCESS | 12677 | 20:16:52 INFO - PROCESS | 12677 | Cycle 1(1): loaded http://localhost:34858/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34858/tests/dromaeo/cssquery-yui.html) 20:16:52 INFO - PROCESS | 12677 | RSS: Main: 188641280 20:16:52 INFO - PROCESS | 12677 | 20:19:55 INFO - PROCESS | 12677 | Cycle 1(1): loaded http://localhost:34858/tests/dromaeo/cssquery-yui.html (next: http://localhost:34858/tests/dromaeo/cssquery-dojo.html) 20:19:56 INFO - PROCESS | 12677 | RSS: Main: 277962752 20:19:56 INFO - PROCESS | 12677 | 20:19:56 INFO - PROCESS | 12677 | __start_tp_report 20:19:56 INFO - PROCESS | 12677 | _x_x_mozilla_page_load 20:19:56 INFO - PROCESS | 12677 | _x_x_mozilla_page_load_details 20:19:56 INFO - PROCESS | 12677 | |i|pagename|runs| 20:19:56 INFO - PROCESS | 12677 | |0;dojo.html;3049.923751906202;3056.938861222774;3078.5843911072006;3082.352705931753;3094.4275309067825;5294.391145018324;5307.283516725241;5311.362636483617;5405.405405405407;5427.592930530212;5402.459754024596;5454.236406903036;5470.9452905470935;5522.641028333155;5535.723213839302;4912.361393018902;4964.87587810304;4997.100521906055;5009.323741294933;5095.490450954893;5025.698458092508;5051.444341122474;5064.3922729272535;5086.618503612244;5091.465396133408;4693.788779504914;4905.632077594177;4966.453690094087;4987.177115775893;4996.7001979881325;4833.275008748681;4850.684705494132;4862.781174847105;4865.537773911473;4969.254611808222;4877.829275975359;4906.8773280668265;4917.164082106051;4933.062718083588;4954.182559877616;5255.395629502618;5409.675419474844;5447.237386765856;5497.725113744336;5505.559555235572;5924.555658325599;5957.463828255434;5969.164316995624;5972.581919265654;5974.701264936779;6010.819675409744;6020.4581587656885;6041.24484439445;6044.24446944132;6045.3652366501765;5327.174287985368;5371.113766228567;5371.435999220064;5445.292112025451;5447.809326673548;5824.359320474744;5922.585419020627;5976.193213916065;6012.30858451275;6087.817365478954;5783;5793.449622285876;5824.796132135355;5833;5840.123981402823;5850.2102216200265;5855.648661080349;5888.440598143169;5911.083782013795;5919.55603329752;5631.2397826293745;5745.454181852718;5790.971045144747;5800.622959507703;5883.499902508334;5785.652860828363;5812.738426770806;5883.558733095035;5892.823215303548;5892.882142357214;4374.059577190919;4405.207062728739;4406.162829062468;4411.073674528321;4444.6666500012625;3849.172427928009;3851.306764782366;3895.2014836958356;3937.822797974041;3965.6629186519467;5477.75350109246;5509.807156749494;5515.448455154533;5582.748776305076;5597.944020559824;5863.736131874076;5866;5879.882402351929;5888.205092312569;5921.023031199804;5520.944790552043;5528.115501519818;5529.060059789846;5545.195946587723;5553.222548843084;4817.253325734517;4960.751962401865;4998.6750861193905;5017.197248440305;5049.343585333957;5950.940490595125;6005.3394126646035;6022.3676513966275;6025.849353766103;6059.424354686298;5771.307443106854;5809.24479817621;5831.94168058331;5835.533157347487;5852;5763.193152958673;5970.701464926823;6028;6056.424639659225;6072.696365181635;5824.15549745293;5826.329972053268;5826.621269617631;5830.970845145747;5887.676177810262;5920.703964801829;5928.199693041384;5938;5943.762249510144;5945.851353716192;5474.042042642442;5687.459691329317;5689.829305120853;5734.541236701138;5774.942250577608;5702.971485142548;5768.105943578752;5768.653880767168;5784.82645520635;5824.883502330014;5741.0527263002805;5745.339285982165;5751.166080918159;5752.856178595402;5759.481646651654;5522.889542209053;5602.775888964396;5617.241672374341;5630.577706671934;5795.275590551181;5585.1901474286815;5586.804461843816;5589.916151257816;5595.664260144405;5635.0138725723655 20:19:56 INFO - PROCESS | 12677 | |1;ext.html;585.8711083561618;616.6454288783949;624.2166081567635;631.3024108360263;633.3697970519333;43216.135677286475;44515.33227001598;44609.330860037124;44735.105297894064;48122.278165827454;45623.77188114055;45912.54087459124;45989;46175;46329.7683511582;8930.776730581738;9161.496117713523;9163.496007719572;9164.221041211506;9170.174684278414;8997.775055623597;9133.223675987549;9135;9139.131782480654;9139.405938614002;9133.132352426508;9187.26501879848;9193.540322983825;9196.632134714604;9196.770080747969;9069.365144439891;9129.178373946377;9129.269658427309;9130.406523575979;9135.588898499584;8908.51003194824;8999.32505062123;9003.324750643662;9004.27965762744;9008.864867027;41326.58673413257;41485.79257103725;41572.584274157474;41589.58410415887;41602.167956641;3654.9817250913575;3711.9814400928094;3747.194353214072;4110;4252.851150209759;32082;32267.83866080678;32425.053186453842;33337.6666233337;33845.32309353799;4329.870103896888;4356.499002614708;4366.5851744084575;4377.6935614507;4396.560343965578;352370;352481;353336;353761;354223;56514.71742641261;56597;56637;56766.43233567694;56976.71511642415;56599.71700141473;57565.71217143888;57975.71012144996;58799.41200588025;58990.70504647535;16452.506424807274;16848.83151168497;16947.576310592332;16971.321147154224;16975.490735277963;12963.675908102374;12981.935090324489;13001.609951701465;13024.739505209844;13030.283334416508;14280.642983925485;15485.303161357766;15499.690006199813;15562.610934726496;15788;6180.536459765535;6270.341614130451;6320.968395157995;6358.1734374531015;6372.076048972873;959.1224030012461;980.2207245240052;1024.6208902705898;1050.4905121016302;1079.06121674144;32480.837595812343;32656;32670;33158.83420582882;33176;1110.4225802582612;1117.5641499815079;1167.2121318110242;1172.536847945057;1188.287027783323;16683.249253783608;17059.147042648066;17093.316267349415;17168.914155429142;17180.67793919196;533942;536886;537335;537391;538382;59037.40962590405;59193.11210331848;59218.40781592215;59366;59461.40538594559;1319.8020296955533;1369.8767011050843;1376.7521846067605;1385.5496963486705;1393.0944885824092;31126.221844453165;31301.8434907824;32134.67865321317;32174.356512869144;32260.677393226706;51245;51376.48623513866;51521.74239128931;51643;51739;379032;379299;380071;380295;382329;30496.237594060327;34985;35516.467252991744;42018.57981420147;42506;40194.39708404435;47331.526684732715;47703.76148119237;48502.757486212344;48590.5140948586;38758.80620596879;38858.4171237426;41363.379549307385;42092.08513006016;42385.5761442394;39925.2014959705;40296;40590;41430.378544321255;41814;39590.03074769461;40177.79911100426;40397.798011009756;40590.18819623532;41339.379909300784;35045.29909401849;36444;36615;37331.6266837339;37596;27817.721822781514;28104.07957617909;28214.560612573478;28242.911586886785;28357.291067722646 20:19:56 INFO - PROCESS | 12677 | |2;jquery.html;376.04859704946495;381.3859685905691;403.2278187271375;405.13301534715714;410.56358281186476;14834.184119873402;17753.20110595023;19201.327953521628;20181.495462613424;20182;18657.813421865776;18734;18734.344297949574;18921.243150274007;18982.71525927112;5466.098093814516;5686.2039314496005;5836.883262334772;5872.060470324749;5899.262592175978;5684.772609095631;5808.1287806829105;5810;5810.56420768442;5816.360200377955;5781.595288329819;5791.652500849941;5791.681457519835;6039.758409663608;6393.808185754435;6409.583377080475;6478.125453063823;6504.577202481823;6508.121403610499;6516.902246466283;5677.148427735831;5722.484976352107;5776.855578610526;5782.855428614318;5783.913241301384;13992.440302387893;14014.71970560583;14136.575902722934;20722.75663460182;22654.320370388916;2254.1997590855176;2439.621858611918;2532.354249666323;2574.768270855632;2720.0071973729646;10268.383896966207;10865.565377384895;10881.075108615778;12522;14114.858851411456;1941.796111408296;1980.8217260446486;2008.1866843928233;2024.6153230886232;2037.6128535578341;82700.58649706774;86572.99999999937;87357.56321218416;88988.55505722495;90440.54779726126;10695.197860160519;10968.616098436516;10968.616098436516;10999.691075009841;11210.887891120985;15444;15484.07095574269;15494.690106197813;15582.454614088452;15683.215839207995;7979.760607181794;7999.840003199904;8160.959195203986;8164.346852251806;8297.709580164787;7616.771496855104;7617.923820761722;7679.616019199129;7803.375729941591;7809.414293927978;4641.8375356863025;4706.082313948816;4779.139754844092;4791.592714619296;4874.780634871439;1983.4347211044783;2002.8497862660358;2005.8495612829097;2006.809353111452;2031.1266155553253;866.571047331575;931.6459745296744;993.4734590666958;1066.706655669697;1092.0553721031251;18716.812831871506;19387.515312117026;19452.902735486234;19714.309999149962;19845.62854430601;956.1394744729798;964.7781010367655;1007.9042490963346;1014.3305418423805;1070.2775626452114;10410.323328983592;10809.189310801721;10885.346879187275;10902.400367979679;10995.725106872393;363460;369348;371419;525869;526666.5000125006;65667.67166164139;105791.47104264583;107280.46359768308;109921.35117973237;110007.44996274967;997.4165113408592;1016.8525563793208;1019.5819713917407;1023.0945613132432;1023.902729240721;17263.56841078983;17607.647847042732;18162.45512634623;18190.272389104288;18449;42877.57122428861;43570;44202.33696494491;44491.555084448744;45232.54767452284;59521.702391487765;60445;61298.387016129265;63191.36808631855;63936;7433.92566074354;7568.621568921421;7734.8839767402405;7891.9740433743245;8028.59857007159;11038.889611103787;12107.455164517442;12286.692832679255;12707.491700331882;13008.869911300766;11397.259178153394;11445.198836081394;11502.48238829238;11563.537458501565;11689.707757306136;10909.618163364245;10968.451577421256;10987.670369888916;11014.889851101387;11225.326480411202;10795.838062428913;11599.246049006788;11639.301641901175;11750.823737643772;11814.82277765817;7452.143003554661;7498.62506874665;7522.887156692764;7620.8475830481975;7644.044494438195;9579.952100239454;10750.301230419998;10918.836217456586;10924.50839712199;11211.327320360804 20:19:56 INFO - PROCESS | 12677 | |3;mootools.html;1388.8333399992002;1470.2501724120698;1530.8928375013747;1642.5811418088385;1775.9911200443996;8666.826663466727;9248.815023699522;9263.722088337343;9564.952175239114;9613.086756758114;9432.481213533252;9540.713778586654;9541.236701063915;9543.856842147356;9547.713568592953;2043.9795602044012;2280.9657855132186;2328.5575740609274;2366.349253955159;2368.265837590344;2357.1514254868234;2377.4888398994212;2382.7140743110763;2383.594788885885;2387.4270175157926;2322.7328857181474;2347.225415612844;2366.656834758959;2367.147826782357;2368.7157541095094;1957.2268953763185;2213.2143089203264;2321.663358812971;2322.8606283622953;2330.8018818400456;2271.568402003614;2296.5062511560095;2303.2169062518824;2308.2959697292317;2311.514581937795;9325.067493250688;9343.205827504671;9377.578008989612;9395.154436100784;9397.671081512182;5162.690238585659;5284.81503147388;5290.338707661543;5290.92063619046;5293.920591191135;3211.656113526485;3330.350581636582;3410.1304167437142;3422.469517224834;3436.415809312398;990.3760630802549;1055.4247934875434;1068.7969285835666;1072.13157342552;1075.0324707763075;13391.464341426332;13407.731845363136;13424;13441.596752097452;13445;4313.245182093121;4581.381513495636;4702.247640377592;4714.151452738472;4719.646026547954;11179.83230251547;11247;11256.15578831591;11276.1542884284;11278.323300601991;5436.103042997861;5436.10304299794;5441.564674826004;5443.618946673774;5444.8366549003595;4310.6335961443;4792.880177995508;4816.253480710496;4993.500649934977;4997.475265097112;3900.1614652849776;3981.303271927401;4005.719599627998;4073.429719839225;4209.34755112958;1194.164085140388;1213.1871645997203;1216.793145165324;1243.322389297835;1295.3458503455695;886.9157430044136;926.972190834276;1010.9292349535461;1025.8358662614096;1027.5889644142403;6241.375862413723;6473.77341793035;6497.545171837926;6501.512386571026;6709.832254193587;412.5297161236193;419.17841031577836;426.100927043937;431.49299573002224;432.70576008314646;4366.257736184856;4504.819807207675;4546.704464209815;4566.566176213254;4609.700369475973;11613.941930290295;11883.16817822744;11967.281963082243;11979.221350612357;11991.820122698167;11287.661370158909;11589.072874170215;11636.3600001999;12066.457009434596;12071.275723456445;8657.52383618907;8846.336524760669;8854;8858.867116993122;8912.688055918012;6454.19322584677;6598.10925525048;6658.600483970978;6668.099806526251;6743.966280168568;10817.945910270399;10847.891521084688;10988.395638239654;11087.55649774;11119.11047116245;10570.101541368762;10858.402787846744;11226.607068752555;11307.886921130683;11323.943380283046;7631.923680763121;8619.784505387415;8621;8804.691835785718;8877.02352741185;9058.230050445785;9300.20948219409;9301.953490232505;9305;9309.813803723751;7875.212478752079;7999.32005779492;8159;8162.5510596915365;8336.083030866721;8015.639296231567;8021.8395632088195;8065.516069035877;8197.79505512367;8268.710595129141;7499.625018748931;7513.436492262993;7584.12782529994;7596.202398748052;7653.464257501922;5296.841094767163;5332.866678333073;5445.700486473282;5447.673139611636;5516.779328826802;5470.234167216672;5495.340559132929;5616.0452723038;5617.354004289559;5647.576431767716 20:19:56 INFO - PROCESS | 12677 | |4;prototype.html;315.11452817582597;332.5693227270684;334.9313390754896;336.78445794691385;340.34993163058556;5272.710000949946;5286.259923610698;5287.550558202553;5350.796070884049;5357.678539287645;5292.867678308045;5300.284461597693;5306.363236411636;5320.76056577454;5320.813771517997;4210.242156411852;4220.388043733657;4220.409142720022;4252.2345977724;4266.104118135196;3735.7585448730815;4029.415734718464;4111.917761644765;4187.073945984851;4230.894227644319;4122.835086596532;4126.195391898581;4136.193442278757;4137.048478849866;4159.897627128798;4159.147374788161;4183.163367326531;4188.6649068074485;4188.874333769962;4195.895102622429;3556.324298383299;3571.160777217352;3680.4847321375028;3710.499082623838;3722.3113723961155;5120.180771076647;5132.692038477703;5137.691738495702;5138.30632864566;5188.974055129738;4777.641676874248;4783.521647835223;4783.928241076386;4788.545088216614;4802.927956080662;5327.547158491533;5468.890622187574;5511.58663100269;5543.695096769676;5545.972270138624;4058.3912413138264;4059.228746538178;4068.4304197412384;4068.4914385701786;4074.5110586729784;5540.722963851791;5664.376918538998;5798.188253644493;5821.446962538552;5821.7380217890295;3778.301014312361;4108.322126849096;4132.297509423405;4138.089620283533;4151.232022075926;4221.261279276176;4225.133847561243;4232.386303985966;4243.8090285937205;4247.787610619456;4782.473927868002;4788.760561971888;4790.520947905251;4799.760011999386;4803.95196048042;5455.263539422207;5534.529564987021;5581.609287349846;5610.635308704921;5616.5787565932715;1428.3715165327224;1446.1250943179436;1446.4142022480912;1447.7973083768281;1452.0271418149864;919.2416256588341;940.2524992630875;965.7252426796562;971.5239532629103;979.9657012004689;4766.6901651392545;4777.948851252719;4778.450478194983;4789.449213340441;4798.49615790344;5006.249062640633;5048.697078175322;5049.596032317406;5087.389513258358;5092.592592592584;4568.657350698711;4704.647151463654;4711.481737008926;4764.523547645208;4782.904341913142;2665.2803742989286;2665.454036658772;2680.560689772337;2683.396235846919;2685.9059932902255;5095.853432977625;5130.615203859726;5155.922661160085;5163.631637616035;5183.300254465675;4820.469748327681;5080.7298175456135;5097.929434818656;5099.776053747149;5100.373307746513;5589.351141413293;5701.344345400249;5786.88426231473;5847.8538036547725;5856.472917437366;5636.632614301921;5652.660840349592;5664.546836253172;5666.716664166693;5704.5453409382;5508.6037573655585;5590.273264475592;5614.9719251403485;5617.382087970238;5651.632643878134;5748.885022299614;5798.797042103506;5869.266341707286;5876.882462350643;5893.410658934073;4123.299039163289;4131.772752498641;4178.456800615901;4186.853460128881;4190.895227619334;5530.640508366943;5687.289088863892;5719.57103217252;5724.169995350734;5740.732410697835;5478.534324582455;5487.75305111263;5503.834884953458;5519.862003449946;5580.330360356783;5398.406175320633;5480.287562616834;5487.533559647315;5488.533474654699;5489.423610520837;5432.674039557639;5477.67133971963;5502.504774570308;5515.889682206253;5529.806456774155;5551.833444996657;5563.972180139235;5572.3034620672415;5577.776888924398;5579.860503487445;5304.310439642822;5478.972605136949;5479.917801233064;5494.230807687007;5538.335399752055 20:19:56 INFO - PROCESS | 12677 | |5;yui.html;705.8694141583808;727.9927200727991;778.8559116563436;793.5833687314156;822.6997146041692;15011.174385408798;16059.75910361344;16075;16137.435189768361;16154.596135096614;13942.860571394283;14035.649108772286;14068.296585170727;14070.085444446104;14077;665.7636539028648;810.785141937387;811.9837603247917;826.0706704956923;832.5379414424991;830.5265998380926;831.6216121664659;832.24681663095;832.2634468495395;832.50881979632;866.263675875507;866.9653213871437;867.4014929698519;867.7310033889514;868.2619773192768;721.084223036743;854.811941372897;860.6729442811754;868.444195714743;869.0917990699702;631.3844002097964;632.4497687012329;632.968351582419;633.5343522510951;634;15047.623809404742;15083.924580377137;15111.924440377838;15115.546533604009;15126;14422.639434014129;14652.70694586113;14718.632034199123;14725.337359818726;14824.184669843155;12520.937395313056;12701.301428421433;12849.164804287691;13036.217826930415;13537.187768733907;4398.7140835845885;4496.3705081288645;4499.797509112098;4556.589906908394;4664.067186562701;73878;74567;75883;76094;76652.23347766507;11385.544578216779;11445.771084578262;11717.414129293502;11844.348560829065;11847.348395838138;21456.570868582854;21484;21590.244341448288;21592.784072159393;21598.892005540187;7631.771046868603;7997.520148791091;8361.958190209132;8368.37237207212;8639.352048596254;4673.929891051637;4751.572358487683;4761.40482439695;4864.635152363587;4947.752612369439;3218.211538173116;3284.0476261884264;3317.0546394278;3317.4360358739073;3334.1164591383304;623.1649589550025;637.0985056145595;643.9517036222209;651.9967400163064;665.5707068940507;502.80893260560754;585.648610833505;625.3027873920546;651.5243871973486;652.55952232244;9009.54952252398;9093.590788414538;9158.81682366349;9206.677766278148;9356.864853027537;620.5221979076086;621.9315875253718;661.1207094564218;662.8011596521025;667.5327270910382;4081.959180408158;4185.1497563949715;4429.800658970354;4522.3442600823355;4531.161735079021;108193.45903270433;114939;116493;120747;120921.39539302247;96593.5170324158;96640;97102.51448742852;98670.6395828442;98953;82428;84569.57715211385;84925.57537212397;85140.57429712811;87622;8064.435489515678;8310.085890551914;8344.666213351398;8418.158184181533;8438.451500652438;52209.47790522046;53155;53196.46803531915;53548.16831732519;53953.46046539484;68697;70743;77454;79487.20512794798;80185.19814801778;10956.56173753041;10986;11260.549578017115;11341.886581134084;11547.191696581158;12538.310392928477;12750.171238869443;12765.361731913554;13460.259685716988;13488.460461581426;11011.229213954946;11062.17033722458;11132.499037543168;11597.942010289895;11691.415429228675;11425.828612570653;11982.161248712506;12060.396980151132;12081.456334464794;12453.25280483174;11262.549498019987;11391.487383067617;11406.087512998774;11672.649820505398;11768.52925882955;9457.905420945703;9483.66807161746;9777.657781977596;9822.508874556386;10075.798484030425;8673.566321684017;8877.37858349935;8915.955420222857;9873.210143188671;9951 20:19:56 INFO - PROCESS | 12677 | __end_tp_report 20:19:56 INFO - PROCESS | 12677 | __start_cc_report 20:19:56 INFO - PROCESS | 12677 | _x_x_mozilla_cycle_collect,1183 20:19:56 INFO - PROCESS | 12677 | __end_cc_report 20:19:56 INFO - PROCESS | 12677 | __startTimestamp1469071196055__endTimestamp 20:19:56 INFO - PROCESS | 12677 | 20:19:56 INFO - PROCESS | 12677 | ------- Summary: start ------- 20:19:56 INFO - PROCESS | 12677 | Number of tests: 6 20:19:56 INFO - PROCESS | 12677 | 20:19:56 INFO - PROCESS | 12677 | [#0] dojo.html Cycles:165 Average:5424.28 Median:5617.24 stddev:648.63 (11.5%) stddev-sans-first:623.30 20:19:56 INFO - PROCESS | 12677 | Values: 3049.9 3056.9 3078.6 3082.4 3094.4 5294.4 5307.3 5311.4 5405.4 5427.6 5402.5 5454.2 5470.9 5522.6 5535.7 4912.4 4964.9 4997.1 5009.3 5095.5 5025.7 5051.4 5064.4 5086.6 5091.5 4693.8 4905.6 4966.5 4987.2 4996.7 4833.3 4850.7 4862.8 4865.5 4969.3 4877.8 4906.9 4917.2 4933.1 4954.2 5255.4 5409.7 5447.2 5497.7 5505.6 5924.6 5957.5 5969.2 5972.6 5974.7 6010.8 6020.5 6041.2 6044.2 6045.4 5327.2 5371.1 5371.4 5445.3 5447.8 5824.4 5922.6 5976.2 6012.3 6087.8 5783.0 5793.4 5824.8 5833.0 5840.1 5850.2 5855.6 5888.4 5911.1 5919.6 5631.2 5745.5 5791.0 5800.6 5883.5 5785.7 5812.7 5883.6 5892.8 5892.9 4374.1 4405.2 4406.2 4411.1 4444.7 3849.2 3851.3 3895.2 3937.8 3965.7 5477.8 5509.8 5515.4 5582.7 5597.9 5863.7 5866.0 5879.9 5888.2 5921.0 5520.9 5528.1 5529.1 5545.2 5553.2 4817.3 4960.8 4998.7 5017.2 5049.3 5950.9 6005.3 6022.4 6025.8 6059.4 5771.3 5809.2 5831.9 5835.5 5852.0 5763.2 5970.7 6028.0 6056.4 6072.7 5824.2 5826.3 5826.6 5831.0 5887.7 5920.7 5928.2 5938.0 5943.8 5945.9 5474.0 5687.5 5689.8 5734.5 5774.9 5703.0 5768.1 5768.7 5784.8 5824.9 5741.1 5745.3 5751.2 5752.9 5759.5 5522.9 5602.8 5617.2 5630.6 5795.3 5585.2 5586.8 5589.9 5595.7 5635.0 20:19:56 INFO - PROCESS | 12677 | 20:19:56 INFO - PROCESS | 12677 | [#1] ext.html Cycles:180 Average:58953.53 Median:41581.08 stddev:114123.28 (274.5%) stddev-sans-first:114359.28 20:19:56 INFO - PROCESS | 12677 | Values: 585.9 616.6 624.2 631.3 633.4 43216.1 44515.3 44609.3 44735.1 48122.3 45623.8 45912.5 45989.0 46175.0 46329.8 8930.8 9161.5 9163.5 9164.2 9170.2 8997.8 9133.2 9135.0 9139.1 9139.4 9133.1 9187.3 9193.5 9196.6 9196.8 9069.4 9129.2 9129.3 9130.4 9135.6 8908.5 8999.3 9003.3 9004.3 9008.9 41326.6 41485.8 41572.6 41589.6 41602.2 3655.0 3712.0 3747.2 4110.0 4252.9 32082.0 32267.8 32425.1 33337.7 33845.3 4329.9 4356.5 4366.6 4377.7 4396.6 352370.0 352481.0 353336.0 353761.0 354223.0 56514.7 56597.0 56637.0 56766.4 56976.7 56599.7 57565.7 57975.7 58799.4 58990.7 16452.5 16848.8 16947.6 16971.3 16975.5 12963.7 12981.9 13001.6 13024.7 13030.3 14280.6 15485.3 15499.7 15562.6 15788.0 6180.5 6270.3 6321.0 6358.2 6372.1 959.1 980.2 1024.6 1050.5 1079.1 32480.8 32656.0 32670.0 33158.8 33176.0 1110.4 1117.6 1167.2 1172.5 1188.3 16683.2 17059.1 17093.3 17168.9 17180.7 533942.0 536886.0 537335.0 537391.0 538382.0 59037.4 59193.1 59218.4 59366.0 59461.4 1319.8 1369.9 1376.8 1385.5 1393.1 31126.2 31301.8 32134.7 32174.4 32260.7 51245.0 51376.5 51521.7 51643.0 51739.0 379032.0 379299.0 380071.0 380295.0 382329.0 30496.2 34985.0 35516.5 42018.6 42506.0 40194.4 47331.5 47703.8 48502.8 48590.5 38758.8 38858.4 41363.4 42092.1 42385.6 39925.2 40296.0 40590.0 41430.4 41814.0 39590.0 40177.8 40397.8 40590.2 41339.4 35045.3 36444.0 36615.0 37331.6 37596.0 27817.7 28104.1 28214.6 28242.9 28357.3 20:19:56 INFO - PROCESS | 12677 | 20:19:56 INFO - PROCESS | 12677 | [#2] jquery.html Cycles:180 Average:27701.72 Median:11488.48 stddev:73135.48 (636.6%) stddev-sans-first:73311.87 20:19:56 INFO - PROCESS | 12677 | Values: 376.0 381.4 403.2 405.1 410.6 14834.2 17753.2 19201.3 20181.5 20182.0 18657.8 18734.0 18734.3 18921.2 18982.7 5466.1 5686.2 5836.9 5872.1 5899.3 5684.8 5808.1 5810.0 5810.6 5816.4 5781.6 5791.7 5791.7 6039.8 6393.8 6409.6 6478.1 6504.6 6508.1 6516.9 5677.1 5722.5 5776.9 5782.9 5783.9 13992.4 14014.7 14136.6 20722.8 22654.3 2254.2 2439.6 2532.4 2574.8 2720.0 10268.4 10865.6 10881.1 12522.0 14114.9 1941.8 1980.8 2008.2 2024.6 2037.6 82700.6 86573.0 87357.6 88988.6 90440.5 10695.2 10968.6 10968.6 10999.7 11210.9 15444.0 15484.1 15494.7 15582.5 15683.2 7979.8 7999.8 8161.0 8164.3 8297.7 7616.8 7617.9 7679.6 7803.4 7809.4 4641.8 4706.1 4779.1 4791.6 4874.8 1983.4 2002.8 2005.8 2006.8 2031.1 866.6 931.6 993.5 1066.7 1092.1 18716.8 19387.5 19452.9 19714.3 19845.6 956.1 964.8 1007.9 1014.3 1070.3 10410.3 10809.2 10885.3 10902.4 10995.7 363460.0 369348.0 371419.0 525869.0 526666.5 65667.7 105791.5 107280.5 109921.4 110007.4 997.4 1016.9 1019.6 1023.1 1023.9 17263.6 17607.6 18162.5 18190.3 18449.0 42877.6 43570.0 44202.3 44491.6 45232.5 59521.7 60445.0 61298.4 63191.4 63936.0 7433.9 7568.6 7734.9 7892.0 8028.6 11038.9 12107.5 12286.7 12707.5 13008.9 11397.3 11445.2 11502.5 11563.5 11689.7 10909.6 10968.5 10987.7 11014.9 11225.3 10795.8 11599.2 11639.3 11750.8 11814.8 7452.1 7498.6 7522.9 7620.8 7644.0 9580.0 10750.3 10918.8 10924.5 11211.3 20:19:56 INFO - PROCESS | 12677 | 20:19:56 INFO - PROCESS | 12677 | [#3] mootools.html Cycles:180 Average:6159.36 Median:4525.76 stddev:3665.06 (81.0%) stddev-sans-first:3657.81 20:19:56 INFO - PROCESS | 12677 | Values: 1388.8 1470.3 1530.9 1642.6 1776.0 8666.8 9248.8 9263.7 9565.0 9613.1 9432.5 9540.7 9541.2 9543.9 9547.7 2044.0 2281.0 2328.6 2366.3 2368.3 2357.2 2377.5 2382.7 2383.6 2387.4 2322.7 2347.2 2366.7 2367.1 2368.7 1957.2 2213.2 2321.7 2322.9 2330.8 2271.6 2296.5 2303.2 2308.3 2311.5 9325.1 9343.2 9377.6 9395.2 9397.7 5162.7 5284.8 5290.3 5290.9 5293.9 3211.7 3330.4 3410.1 3422.5 3436.4 990.4 1055.4 1068.8 1072.1 1075.0 13391.5 13407.7 13424.0 13441.6 13445.0 4313.2 4581.4 4702.2 4714.2 4719.6 11179.8 11247.0 11256.2 11276.2 11278.3 5436.1 5436.1 5441.6 5443.6 5444.8 4310.6 4792.9 4816.3 4993.5 4997.5 3900.2 3981.3 4005.7 4073.4 4209.3 1194.2 1213.2 1216.8 1243.3 1295.3 886.9 927.0 1010.9 1025.8 1027.6 6241.4 6473.8 6497.5 6501.5 6709.8 412.5 419.2 426.1 431.5 432.7 4366.3 4504.8 4546.7 4566.6 4609.7 11613.9 11883.2 11967.3 11979.2 11991.8 11287.7 11589.1 11636.4 12066.5 12071.3 8657.5 8846.3 8854.0 8858.9 8912.7 6454.2 6598.1 6658.6 6668.1 6744.0 10817.9 10847.9 10988.4 11087.6 11119.1 10570.1 10858.4 11226.6 11307.9 11323.9 7631.9 8619.8 8621.0 8804.7 8877.0 9058.2 9300.2 9302.0 9305.0 9309.8 7875.2 7999.3 8159.0 8162.6 8336.1 8015.6 8021.8 8065.5 8197.8 8268.7 7499.6 7513.4 7584.1 7596.2 7653.5 5296.8 5332.9 5445.7 5447.7 5516.8 5470.2 5495.3 5616.0 5617.4 5647.6 20:19:56 INFO - PROCESS | 12677 | 20:19:56 INFO - PROCESS | 12677 | [#4] prototype.html Cycles:180 Average:4585.38 Median:5110.28 stddev:1328.94 (26.0%) stddev-sans-first:1293.44 20:19:56 INFO - PROCESS | 12677 | Values: 315.1 332.6 334.9 336.8 340.3 5272.7 5286.3 5287.6 5350.8 5357.7 5292.9 5300.3 5306.4 5320.8 5320.8 4210.2 4220.4 4220.4 4252.2 4266.1 3735.8 4029.4 4111.9 4187.1 4230.9 4122.8 4126.2 4136.2 4137.0 4159.9 4159.1 4183.2 4188.7 4188.9 4195.9 3556.3 3571.2 3680.5 3710.5 3722.3 5120.2 5132.7 5137.7 5138.3 5189.0 4777.6 4783.5 4783.9 4788.5 4802.9 5327.5 5468.9 5511.6 5543.7 5546.0 4058.4 4059.2 4068.4 4068.5 4074.5 5540.7 5664.4 5798.2 5821.4 5821.7 3778.3 4108.3 4132.3 4138.1 4151.2 4221.3 4225.1 4232.4 4243.8 4247.8 4782.5 4788.8 4790.5 4799.8 4804.0 5455.3 5534.5 5581.6 5610.6 5616.6 1428.4 1446.1 1446.4 1447.8 1452.0 919.2 940.3 965.7 971.5 980.0 4766.7 4777.9 4778.5 4789.4 4798.5 5006.2 5048.7 5049.6 5087.4 5092.6 4568.7 4704.6 4711.5 4764.5 4782.9 2665.3 2665.5 2680.6 2683.4 2685.9 5095.9 5130.6 5155.9 5163.6 5183.3 4820.5 5080.7 5097.9 5099.8 5100.4 5589.4 5701.3 5786.9 5847.9 5856.5 5636.6 5652.7 5664.5 5666.7 5704.5 5508.6 5590.3 5615.0 5617.4 5651.6 5748.9 5798.8 5869.3 5876.9 5893.4 4123.3 4131.8 4178.5 4186.9 4190.9 5530.6 5687.3 5719.6 5724.2 5740.7 5478.5 5487.8 5503.8 5519.9 5580.3 5398.4 5480.3 5487.5 5488.5 5489.4 5432.7 5477.7 5502.5 5515.9 5529.8 5551.8 5564.0 5572.3 5577.8 5579.9 5304.3 5479.0 5479.9 5494.2 5538.3 20:19:56 INFO - PROCESS | 12677 | 20:19:56 INFO - PROCESS | 12677 | [#5] yui.html Cycles:180 Average:20439.08 Median:53750.81 stddev:30034.47 (55.9%) stddev-sans-first:30082.17 20:19:56 INFO - PROCESS | 12677 | Values: 705.9 728.0 778.9 793.6 822.7 15011.2 16059.8 16075.0 16137.4 16154.6 13942.9 14035.6 14068.3 14070.1 14077.0 665.8 810.8 812.0 826.1 832.5 830.5 831.6 832.2 832.3 832.5 866.3 867.0 867.4 867.7 868.3 721.1 854.8 860.7 868.4 869.1 631.4 632.4 633.0 633.5 634.0 15047.6 15083.9 15111.9 15115.5 15126.0 14422.6 14652.7 14718.6 14725.3 14824.2 12520.9 12701.3 12849.2 13036.2 13537.2 4398.7 4496.4 4499.8 4556.6 4664.1 73878.0 74567.0 75883.0 76094.0 76652.2 11385.5 11445.8 11717.4 11844.3 11847.3 21456.6 21484.0 21590.2 21592.8 21598.9 7631.8 7997.5 8362.0 8368.4 8639.4 4673.9 4751.6 4761.4 4864.6 4947.8 3218.2 3284.0 3317.1 3317.4 3334.1 623.2 637.1 644.0 652.0 665.6 502.8 585.6 625.3 651.5 652.6 9009.5 9093.6 9158.8 9206.7 9356.9 620.5 621.9 661.1 662.8 667.5 4082.0 4185.1 4429.8 4522.3 4531.2 108193.5 114939.0 116493.0 120747.0 120921.4 96593.5 96640.0 97102.5 98670.6 98953.0 82428.0 84569.6 84925.6 85140.6 87622.0 8064.4 8310.1 8344.7 8418.2 8438.5 52209.5 53155.0 53196.5 53548.2 53953.5 68697.0 70743.0 77454.0 79487.2 80185.2 10956.6 10986.0 11260.5 11341.9 11547.2 12538.3 12750.2 12765.4 13460.3 13488.5 11011.2 11062.2 11132.5 11597.9 11691.4 11425.8 11982.2 12060.4 12081.5 12453.3 11262.5 11391.5 11406.1 11672.6 11768.5 9457.9 9483.7 9777.7 9822.5 10075.8 8673.6 8877.4 8916.0 9873.2 9951.0 20:19:56 INFO - PROCESS | 12677 | -------- Summary: end -------- 20:19:56 INFO - PROCESS | 12677 | 20:19:56 INFO - TEST-INFO | 12677: exit 0 20:19:56 INFO - TEST-OK | dromaeo_css | took 1112983ms 20:19:56 INFO - TEST-START | kraken 20:19:56 INFO - operating with platform_type : linux_ 20:19:56 INFO - Initialising browser for kraken test... 20:19:56 INFO - TEST-INFO | started process 12868 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxMaCUL/profile http://localhost:34858/getInfo.html) 20:20:00 INFO - PROCESS | 12868 | __metrics Screen width/height:1600/1200 20:20:00 INFO - PROCESS | 12868 | colorDepth:24 20:20:00 INFO - PROCESS | 12868 | Browser inner width/height: 1024/697 20:20:00 INFO - PROCESS | 12868 | __metrics 20:20:01 INFO - PROCESS | 12868 | 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" 20:20:01 INFO - PROCESS | 12868 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:20:02 INFO - TEST-INFO | 12868: exit 0 20:20:02 INFO - Browser initialized. 20:20:02 INFO - Running cycle 1/1 for kraken test... 20:20:02 INFO - TEST-INFO | started process 12951 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxMaCUL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:20:13 INFO - PROCESS | 12951 | RSS: Main: 157728768 20:20:13 INFO - PROCESS | 12951 | 20:20:16 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34858/tests/kraken/driver.html?testName=audio-beat-detection) 20:20:17 INFO - PROCESS | 12951 | RSS: Main: 214360064 20:20:17 INFO - PROCESS | 12951 | 20:20:19 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34858/tests/kraken/driver.html?testName=audio-dft) 20:20:20 INFO - PROCESS | 12951 | RSS: Main: 193028096 20:20:20 INFO - PROCESS | 12951 | 20:20:23 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34858/tests/kraken/driver.html?testName=audio-fft) 20:20:24 INFO - PROCESS | 12951 | RSS: Main: 270725120 20:20:24 INFO - PROCESS | 12951 | 20:20:26 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34858/tests/kraken/driver.html?testName=audio-oscillator) 20:20:26 INFO - PROCESS | 12951 | RSS: Main: 180699136 20:20:26 INFO - PROCESS | 12951 | 20:20:29 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34858/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:20:29 INFO - PROCESS | 12951 | RSS: Main: 185266176 20:20:29 INFO - PROCESS | 12951 | 20:20:32 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34858/tests/kraken/driver.html?testName=imaging-darkroom) 20:20:33 INFO - PROCESS | 12951 | RSS: Main: 220237824 20:20:33 INFO - PROCESS | 12951 | 20:20:36 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34858/tests/kraken/driver.html?testName=imaging-desaturate) 20:20:37 INFO - PROCESS | 12951 | RSS: Main: 220409856 20:20:37 INFO - PROCESS | 12951 | 20:20:40 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34858/tests/kraken/driver.html?testName=json-parse-financial) 20:20:40 INFO - PROCESS | 12951 | RSS: Main: 221343744 20:20:40 INFO - PROCESS | 12951 | 20:20:42 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34858/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:20:42 INFO - PROCESS | 12951 | RSS: Main: 185106432 20:20:42 INFO - PROCESS | 12951 | 20:20:44 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34858/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:20:45 INFO - PROCESS | 12951 | RSS: Main: 190005248 20:20:45 INFO - PROCESS | 12951 | 20:20:47 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34858/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:20:47 INFO - PROCESS | 12951 | RSS: Main: 195579904 20:20:47 INFO - PROCESS | 12951 | 20:20:50 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34858/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:20:51 INFO - PROCESS | 12951 | RSS: Main: 189235200 20:20:51 INFO - PROCESS | 12951 | 20:20:53 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34858/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:20:54 INFO - PROCESS | 12951 | RSS: Main: 193486848 20:20:54 INFO - PROCESS | 12951 | 20:20:55 INFO - PROCESS | 12951 | Cycle 1(1): loaded http://localhost:34858/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34858/tests/kraken/driver.html?testName=ai-astar) 20:20:56 INFO - PROCESS | 12951 | RSS: Main: 245010432 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | __start_tp_report 20:20:56 INFO - PROCESS | 12951 | _x_x_mozilla_page_load 20:20:56 INFO - PROCESS | 12951 | _x_x_mozilla_page_load_details 20:20:56 INFO - PROCESS | 12951 | |i|pagename|runs| 20:20:56 INFO - PROCESS | 12951 | |0;ai-astar;100;110;110;109;111;111;112;111;112;111 20:20:56 INFO - PROCESS | 12951 | |1;audio-beat-detection;147;120;122;178;122;120;148;117;161;122 20:20:56 INFO - PROCESS | 12951 | |2;audio-dft;161;161;161;160;161;159;162;161;163;162 20:20:56 INFO - PROCESS | 12951 | |3;audio-fft;76;74;75;109;76;75;74;74;100;75 20:20:56 INFO - PROCESS | 12951 | |4;audio-oscillator;83;84;124;85;84;84;129;84;85;137 20:20:56 INFO - PROCESS | 12951 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;116;117;117 20:20:56 INFO - PROCESS | 12951 | |6;imaging-darkroom;145;146;146;146;145;145;146;145;146;146 20:20:56 INFO - PROCESS | 12951 | |7;imaging-desaturate;95;95;95;95;95;95;94;95;95;94 20:20:56 INFO - PROCESS | 12951 | |8;json-parse-financial;52;49;51;51;50;51;50;50;51;51 20:20:56 INFO - PROCESS | 12951 | |9;json-stringify-tinderbox;61;62;62;62;64;62;62;63;62;62 20:20:56 INFO - PROCESS | 12951 | |10;stanford-crypto-aes;76;76;75;76;75;76;79;75;75;76 20:20:56 INFO - PROCESS | 12951 | |11;stanford-crypto-ccm;127;117;128;119;120;119;128;138;200;128 20:20:56 INFO - PROCESS | 12951 | |12;stanford-crypto-pbkdf2;163;159;160;157;154;157;157;156;156;154 20:20:56 INFO - PROCESS | 12951 | |13;stanford-crypto-sha256-iterative;66;64;62;63;63;61;61;62;62;62 20:20:56 INFO - PROCESS | 12951 | __end_tp_report 20:20:56 INFO - PROCESS | 12951 | __start_cc_report 20:20:56 INFO - PROCESS | 12951 | _x_x_mozilla_cycle_collect,1675 20:20:56 INFO - PROCESS | 12951 | __end_cc_report 20:20:56 INFO - PROCESS | 12951 | __startTimestamp1469071256219__endTimestamp 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | ------- Summary: start ------- 20:20:56 INFO - PROCESS | 12951 | Number of tests: 14 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.53 (3.2%) stddev-sans-first:0.97 20:20:56 INFO - PROCESS | 12951 | Values: 100.0 110.0 110.0 109.0 111.0 111.0 112.0 111.0 112.0 111.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:134.50 stddev:21.38 (15.9%) stddev-sans-first:22.28 20:20:56 INFO - PROCESS | 12951 | Values: 147.0 120.0 122.0 178.0 122.0 120.0 148.0 117.0 161.0 122.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 20:20:56 INFO - PROCESS | 12951 | Values: 161.0 161.0 161.0 160.0 161.0 159.0 162.0 161.0 163.0 162.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#3] audio-fft Cycles:10 Average:80.80 Median:75.00 stddev:12.69 (16.9%) stddev-sans-first:13.34 20:20:56 INFO - PROCESS | 12951 | Values: 76.0 74.0 75.0 109.0 76.0 75.0 74.0 74.0 100.0 75.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:84.00 stddev:22.37 (26.6%) stddev-sans-first:23.07 20:20:56 INFO - PROCESS | 12951 | Values: 83.0 84.0 124.0 85.0 84.0 84.0 129.0 84.0 85.0 137.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 20:20:56 INFO - PROCESS | 12951 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 20:20:56 INFO - PROCESS | 12951 | Values: 145.0 146.0 146.0 146.0 145.0 145.0 146.0 145.0 146.0 146.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 20:20:56 INFO - PROCESS | 12951 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:0.84 (1.7%) stddev-sans-first:0.73 20:20:56 INFO - PROCESS | 12951 | Values: 52.0 49.0 51.0 51.0 50.0 51.0 50.0 50.0 51.0 51.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:62.00 stddev:0.79 (1.3%) stddev-sans-first:0.71 20:20:56 INFO - PROCESS | 12951 | Values: 61.0 62.0 62.0 62.0 64.0 62.0 62.0 63.0 62.0 62.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#10] stanford-crypto-aes Cycles:10 Average:75.90 Median:76.00 stddev:1.20 (1.6%) stddev-sans-first:1.27 20:20:56 INFO - PROCESS | 12951 | Values: 76.0 76.0 75.0 76.0 75.0 76.0 79.0 75.0 75.0 76.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#11] stanford-crypto-ccm Cycles:10 Average:132.40 Median:128.00 stddev:24.58 (19.2%) stddev-sans-first:26.00 20:20:56 INFO - PROCESS | 12951 | Values: 127.0 117.0 128.0 119.0 120.0 119.0 128.0 138.0 200.0 128.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.30 Median:157.00 stddev:2.75 (1.8%) stddev-sans-first:2.00 20:20:56 INFO - PROCESS | 12951 | Values: 163.0 159.0 160.0 157.0 154.0 157.0 157.0 156.0 156.0 154.0 20:20:56 INFO - PROCESS | 12951 | 20:20:56 INFO - PROCESS | 12951 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.50 stddev:1.51 (2.4%) stddev-sans-first:0.97 20:20:56 INFO - PROCESS | 12951 | Values: 66.0 64.0 62.0 63.0 63.0 61.0 61.0 62.0 62.0 62.0 20:20:56 INFO - PROCESS | 12951 | -------- Summary: end -------- 20:20:56 INFO - PROCESS | 12951 | 20:20:57 INFO - TEST-INFO | 12951: exit 0 20:20:57 INFO - TEST-OK | kraken | took 60405ms 20:20:57 INFO - SUITE-END | took 1173s 20:20:57 INFO - Completed test suite (00:19:33) 20:20:57 INFO - javascript benchmark 20:20:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3049.923751906202, 3056.938861222774, 3078.5843911072006, 3082.352705931753, 3094.4275309067825, 5294.391145018324, 5307.283516725241, 5311.362636483617, 5405.405405405407, 5427.592930530212, 5402.459754024596, 5454.236406903036, 5470.9452905470935, 5522.641028333155, 5535.723213839302, 4912.361393018902, 4964.87587810304, 4997.100521906055, 5009.323741294933, 5095.490450954893, 5025.698458092508, 5051.444341122474, 5064.3922729272535, 5086.618503612244, 5091.465396133408, 4693.788779504914, 4905.632077594177, 4966.453690094087, 4987.177115775893, 4996.7001979881325, 4833.275008748681, 4850.684705494132, 4862.781174847105, 4865.537773911473, 4969.254611808222, 4877.829275975359, 4906.8773280668265, 4917.164082106051, 4933.062718083588, 4954.182559877616, 5255.395629502618, 5409.675419474844, 5447.237386765856, 5497.725113744336, 5505.559555235572, 5924.555658325599, 5957.463828255434, 5969.164316995624, 5972.581919265654, 5974.701264936779, 6010.819675409744, 6020.4581587656885, 6041.24484439445, 6044.24446944132, 6045.3652366501765, 5327.174287985368, 5371.113766228567, 5371.435999220064, 5445.292112025451, 5447.809326673548, 5824.359320474744, 5922.585419020627, 5976.193213916065, 6012.30858451275, 6087.817365478954, 5783.0, 5793.449622285876, 5824.796132135355, 5833.0, 5840.123981402823, 5850.2102216200265, 5855.648661080349, 5888.440598143169, 5911.083782013795, 5919.55603329752, 5631.2397826293745, 5745.454181852718, 5790.971045144747, 5800.622959507703, 5883.499902508334, 5785.652860828363, 5812.738426770806, 5883.558733095035, 5892.823215303548, 5892.882142357214, 4374.059577190919, 4405.207062728739, 4406.162829062468, 4411.073674528321, 4444.6666500012625, 3849.172427928009, 3851.306764782366, 3895.2014836958356, 3937.822797974041, 3965.6629186519467, 5477.75350109246, 5509.807156749494, 5515.448455154533, 5582.748776305076, 5597.944020559824, 5863.736131874076, 5866.0, 5879.882402351929, 5888.205092312569, 5921.023031199804, 5520.944790552043, 5528.115501519818, 5529.060059789846, 5545.195946587723, 5553.222548843084, 4817.253325734517, 4960.751962401865, 4998.6750861193905, 5017.197248440305, 5049.343585333957, 5950.940490595125, 6005.3394126646035, 6022.3676513966275, 6025.849353766103, 6059.424354686298, 5771.307443106854, 5809.24479817621, 5831.94168058331, 5835.533157347487, 5852.0, 5763.193152958673, 5970.701464926823, 6028.0, 6056.424639659225, 6072.696365181635, 5824.15549745293, 5826.329972053268, 5826.621269617631, 5830.970845145747, 5887.676177810262, 5920.703964801829, 5928.199693041384, 5938.0, 5943.762249510144, 5945.851353716192, 5474.042042642442, 5687.459691329317, 5689.829305120853, 5734.541236701138, 5774.942250577608, 5702.971485142548, 5768.105943578752, 5768.653880767168, 5784.82645520635, 5824.883502330014, 5741.0527263002805, 5745.339285982165, 5751.166080918159, 5752.856178595402, 5759.481646651654, 5522.889542209053, 5602.775888964396, 5617.241672374341, 5630.577706671934, 5795.275590551181, 5585.1901474286815, 5586.804461843816, 5589.916151257816, 5595.664260144405, 5635.0138725723655], "value": 5377.841746109946, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.8711083561618, 616.6454288783949, 624.2166081567635, 631.3024108360263, 633.3697970519333, 43216.135677286475, 44515.33227001598, 44609.330860037124, 44735.105297894064, 48122.278165827454, 45623.77188114055, 45912.54087459124, 45989.0, 46175.0, 46329.7683511582, 8930.776730581738, 9161.496117713523, 9163.496007719572, 9164.221041211506, 9170.174684278414, 8997.775055623597, 9133.223675987549, 9135.0, 9139.131782480654, 9139.405938614002, 9133.132352426508, 9187.26501879848, 9193.540322983825, 9196.632134714604, 9196.770080747969, 9069.365144439891, 9129.178373946377, 9129.269658427309, 9130.406523575979, 9135.588898499584, 8908.51003194824, 8999.32505062123, 9003.324750643662, 9004.27965762744, 9008.864867027, 41326.58673413257, 41485.79257103725, 41572.584274157474, 41589.58410415887, 41602.167956641, 3654.9817250913575, 3711.9814400928094, 3747.194353214072, 4110.0, 4252.851150209759, 32082.0, 32267.83866080678, 32425.053186453842, 33337.6666233337, 33845.32309353799, 4329.870103896888, 4356.499002614708, 4366.5851744084575, 4377.6935614507, 4396.560343965578, 352370.0, 352481.0, 353336.0, 353761.0, 354223.0, 56514.71742641261, 56597.0, 56637.0, 56766.43233567694, 56976.71511642415, 56599.71700141473, 57565.71217143888, 57975.71012144996, 58799.41200588025, 58990.70504647535, 16452.506424807274, 16848.83151168497, 16947.576310592332, 16971.321147154224, 16975.490735277963, 12963.675908102374, 12981.935090324489, 13001.609951701465, 13024.739505209844, 13030.283334416508, 14280.642983925485, 15485.303161357766, 15499.690006199813, 15562.610934726496, 15788.0, 6180.536459765535, 6270.341614130451, 6320.968395157995, 6358.1734374531015, 6372.076048972873, 959.1224030012461, 980.2207245240052, 1024.6208902705898, 1050.4905121016302, 1079.06121674144, 32480.837595812343, 32656.0, 32670.0, 33158.83420582882, 33176.0, 1110.4225802582612, 1117.5641499815079, 1167.2121318110242, 1172.536847945057, 1188.287027783323, 16683.249253783608, 17059.147042648066, 17093.316267349415, 17168.914155429142, 17180.67793919196, 533942.0, 536886.0, 537335.0, 537391.0, 538382.0, 59037.40962590405, 59193.11210331848, 59218.40781592215, 59366.0, 59461.40538594559, 1319.8020296955533, 1369.8767011050843, 1376.7521846067605, 1385.5496963486705, 1393.0944885824092, 31126.221844453165, 31301.8434907824, 32134.67865321317, 32174.356512869144, 32260.677393226706, 51245.0, 51376.48623513866, 51521.74239128931, 51643.0, 51739.0, 379032.0, 379299.0, 380071.0, 380295.0, 382329.0, 30496.237594060327, 34985.0, 35516.467252991744, 42018.57981420147, 42506.0, 40194.39708404435, 47331.526684732715, 47703.76148119237, 48502.757486212344, 48590.5140948586, 38758.80620596879, 38858.4171237426, 41363.379549307385, 42092.08513006016, 42385.5761442394, 39925.2014959705, 40296.0, 40590.0, 41430.378544321255, 41814.0, 39590.03074769461, 40177.79911100426, 40397.798011009756, 40590.18819623532, 41339.379909300784, 35045.29909401849, 36444.0, 36615.0, 37331.6266837339, 37596.0, 27817.721822781514, 28104.07957617909, 28214.560612573478, 28242.911586886785, 28357.291067722646], "value": 20191.637978216695, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.04859704946495, 381.3859685905691, 403.2278187271375, 405.13301534715714, 410.56358281186476, 14834.184119873402, 17753.20110595023, 19201.327953521628, 20181.495462613424, 20182.0, 18657.813421865776, 18734.0, 18734.344297949574, 18921.243150274007, 18982.71525927112, 5466.098093814516, 5686.2039314496005, 5836.883262334772, 5872.060470324749, 5899.262592175978, 5684.772609095631, 5808.1287806829105, 5810.0, 5810.56420768442, 5816.360200377955, 5781.595288329819, 5791.652500849941, 5791.681457519835, 6039.758409663608, 6393.808185754435, 6409.583377080475, 6478.125453063823, 6504.577202481823, 6508.121403610499, 6516.902246466283, 5677.148427735831, 5722.484976352107, 5776.855578610526, 5782.855428614318, 5783.913241301384, 13992.440302387893, 14014.71970560583, 14136.575902722934, 20722.75663460182, 22654.320370388916, 2254.1997590855176, 2439.621858611918, 2532.354249666323, 2574.768270855632, 2720.0071973729646, 10268.383896966207, 10865.565377384895, 10881.075108615778, 12522.0, 14114.858851411456, 1941.796111408296, 1980.8217260446486, 2008.1866843928233, 2024.6153230886232, 2037.6128535578341, 82700.58649706774, 86572.99999999937, 87357.56321218416, 88988.55505722495, 90440.54779726126, 10695.197860160519, 10968.616098436516, 10968.616098436516, 10999.691075009841, 11210.887891120985, 15444.0, 15484.07095574269, 15494.690106197813, 15582.454614088452, 15683.215839207995, 7979.760607181794, 7999.840003199904, 8160.959195203986, 8164.346852251806, 8297.709580164787, 7616.771496855104, 7617.923820761722, 7679.616019199129, 7803.375729941591, 7809.414293927978, 4641.8375356863025, 4706.082313948816, 4779.139754844092, 4791.592714619296, 4874.780634871439, 1983.4347211044783, 2002.8497862660358, 2005.8495612829097, 2006.809353111452, 2031.1266155553253, 866.571047331575, 931.6459745296744, 993.4734590666958, 1066.706655669697, 1092.0553721031251, 18716.812831871506, 19387.515312117026, 19452.902735486234, 19714.309999149962, 19845.62854430601, 956.1394744729798, 964.7781010367655, 1007.9042490963346, 1014.3305418423805, 1070.2775626452114, 10410.323328983592, 10809.189310801721, 10885.346879187275, 10902.400367979679, 10995.725106872393, 363460.0, 369348.0, 371419.0, 525869.0, 526666.5000125006, 65667.67166164139, 105791.47104264583, 107280.46359768308, 109921.35117973237, 110007.44996274967, 997.4165113408592, 1016.8525563793208, 1019.5819713917407, 1023.0945613132432, 1023.902729240721, 17263.56841078983, 17607.647847042732, 18162.45512634623, 18190.272389104288, 18449.0, 42877.57122428861, 43570.0, 44202.33696494491, 44491.555084448744, 45232.54767452284, 59521.702391487765, 60445.0, 61298.387016129265, 63191.36808631855, 63936.0, 7433.92566074354, 7568.621568921421, 7734.8839767402405, 7891.9740433743245, 8028.59857007159, 11038.889611103787, 12107.455164517442, 12286.692832679255, 12707.491700331882, 13008.869911300766, 11397.259178153394, 11445.198836081394, 11502.48238829238, 11563.537458501565, 11689.707757306136, 10909.618163364245, 10968.451577421256, 10987.670369888916, 11014.889851101387, 11225.326480411202, 10795.838062428913, 11599.246049006788, 11639.301641901175, 11750.823737643772, 11814.82277765817, 7452.143003554661, 7498.62506874665, 7522.887156692764, 7620.8475830481975, 7644.044494438195, 9579.952100239454, 10750.301230419998, 10918.836217456586, 10924.50839712199, 11211.327320360804], "value": 9174.201751167715, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.8333399992002, 1470.2501724120698, 1530.8928375013747, 1642.5811418088385, 1775.9911200443996, 8666.826663466727, 9248.815023699522, 9263.722088337343, 9564.952175239114, 9613.086756758114, 9432.481213533252, 9540.713778586654, 9541.236701063915, 9543.856842147356, 9547.713568592953, 2043.9795602044012, 2280.9657855132186, 2328.5575740609274, 2366.349253955159, 2368.265837590344, 2357.1514254868234, 2377.4888398994212, 2382.7140743110763, 2383.594788885885, 2387.4270175157926, 2322.7328857181474, 2347.225415612844, 2366.656834758959, 2367.147826782357, 2368.7157541095094, 1957.2268953763185, 2213.2143089203264, 2321.663358812971, 2322.8606283622953, 2330.8018818400456, 2271.568402003614, 2296.5062511560095, 2303.2169062518824, 2308.2959697292317, 2311.514581937795, 9325.067493250688, 9343.205827504671, 9377.578008989612, 9395.154436100784, 9397.671081512182, 5162.690238585659, 5284.81503147388, 5290.338707661543, 5290.92063619046, 5293.920591191135, 3211.656113526485, 3330.350581636582, 3410.1304167437142, 3422.469517224834, 3436.415809312398, 990.3760630802549, 1055.4247934875434, 1068.7969285835666, 1072.13157342552, 1075.0324707763075, 13391.464341426332, 13407.731845363136, 13424.0, 13441.596752097452, 13445.0, 4313.245182093121, 4581.381513495636, 4702.247640377592, 4714.151452738472, 4719.646026547954, 11179.83230251547, 11247.0, 11256.15578831591, 11276.1542884284, 11278.323300601991, 5436.103042997861, 5436.10304299794, 5441.564674826004, 5443.618946673774, 5444.8366549003595, 4310.6335961443, 4792.880177995508, 4816.253480710496, 4993.500649934977, 4997.475265097112, 3900.1614652849776, 3981.303271927401, 4005.719599627998, 4073.429719839225, 4209.34755112958, 1194.164085140388, 1213.1871645997203, 1216.793145165324, 1243.322389297835, 1295.3458503455695, 886.9157430044136, 926.972190834276, 1010.9292349535461, 1025.8358662614096, 1027.5889644142403, 6241.375862413723, 6473.77341793035, 6497.545171837926, 6501.512386571026, 6709.832254193587, 412.5297161236193, 419.17841031577836, 426.100927043937, 431.49299573002224, 432.70576008314646, 4366.257736184856, 4504.819807207675, 4546.704464209815, 4566.566176213254, 4609.700369475973, 11613.941930290295, 11883.16817822744, 11967.281963082243, 11979.221350612357, 11991.820122698167, 11287.661370158909, 11589.072874170215, 11636.3600001999, 12066.457009434596, 12071.275723456445, 8657.52383618907, 8846.336524760669, 8854.0, 8858.867116993122, 8912.688055918012, 6454.19322584677, 6598.10925525048, 6658.600483970978, 6668.099806526251, 6743.966280168568, 10817.945910270399, 10847.891521084688, 10988.395638239654, 11087.55649774, 11119.11047116245, 10570.101541368762, 10858.402787846744, 11226.607068752555, 11307.886921130683, 11323.943380283046, 7631.923680763121, 8619.784505387415, 8621.0, 8804.691835785718, 8877.02352741185, 9058.230050445785, 9300.20948219409, 9301.953490232505, 9305.0, 9309.813803723751, 7875.212478752079, 7999.32005779492, 8159.0, 8162.5510596915365, 8336.083030866721, 8015.639296231567, 8021.8395632088195, 8065.516069035877, 8197.79505512367, 8268.710595129141, 7499.625018748931, 7513.436492262993, 7584.12782529994, 7596.202398748052, 7653.464257501922, 5296.841094767163, 5332.866678333073, 5445.700486473282, 5447.673139611636, 5516.779328826802, 5470.234167216672, 5495.340559132929, 5616.0452723038, 5617.354004289559, 5647.576431767716], "value": 4732.611236582386, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.11452817582597, 332.5693227270684, 334.9313390754896, 336.78445794691385, 340.34993163058556, 5272.710000949946, 5286.259923610698, 5287.550558202553, 5350.796070884049, 5357.678539287645, 5292.867678308045, 5300.284461597693, 5306.363236411636, 5320.76056577454, 5320.813771517997, 4210.242156411852, 4220.388043733657, 4220.409142720022, 4252.2345977724, 4266.104118135196, 3735.7585448730815, 4029.415734718464, 4111.917761644765, 4187.073945984851, 4230.894227644319, 4122.835086596532, 4126.195391898581, 4136.193442278757, 4137.048478849866, 4159.897627128798, 4159.147374788161, 4183.163367326531, 4188.6649068074485, 4188.874333769962, 4195.895102622429, 3556.324298383299, 3571.160777217352, 3680.4847321375028, 3710.499082623838, 3722.3113723961155, 5120.180771076647, 5132.692038477703, 5137.691738495702, 5138.30632864566, 5188.974055129738, 4777.641676874248, 4783.521647835223, 4783.928241076386, 4788.545088216614, 4802.927956080662, 5327.547158491533, 5468.890622187574, 5511.58663100269, 5543.695096769676, 5545.972270138624, 4058.3912413138264, 4059.228746538178, 4068.4304197412384, 4068.4914385701786, 4074.5110586729784, 5540.722963851791, 5664.376918538998, 5798.188253644493, 5821.446962538552, 5821.7380217890295, 3778.301014312361, 4108.322126849096, 4132.297509423405, 4138.089620283533, 4151.232022075926, 4221.261279276176, 4225.133847561243, 4232.386303985966, 4243.8090285937205, 4247.787610619456, 4782.473927868002, 4788.760561971888, 4790.520947905251, 4799.760011999386, 4803.95196048042, 5455.263539422207, 5534.529564987021, 5581.609287349846, 5610.635308704921, 5616.5787565932715, 1428.3715165327224, 1446.1250943179436, 1446.4142022480912, 1447.7973083768281, 1452.0271418149864, 919.2416256588341, 940.2524992630875, 965.7252426796562, 971.5239532629103, 979.9657012004689, 4766.6901651392545, 4777.948851252719, 4778.450478194983, 4789.449213340441, 4798.49615790344, 5006.249062640633, 5048.697078175322, 5049.596032317406, 5087.389513258358, 5092.592592592584, 4568.657350698711, 4704.647151463654, 4711.481737008926, 4764.523547645208, 4782.904341913142, 2665.2803742989286, 2665.454036658772, 2680.560689772337, 2683.396235846919, 2685.9059932902255, 5095.853432977625, 5130.615203859726, 5155.922661160085, 5163.631637616035, 5183.300254465675, 4820.469748327681, 5080.7298175456135, 5097.929434818656, 5099.776053747149, 5100.373307746513, 5589.351141413293, 5701.344345400249, 5786.88426231473, 5847.8538036547725, 5856.472917437366, 5636.632614301921, 5652.660840349592, 5664.546836253172, 5666.716664166693, 5704.5453409382, 5508.6037573655585, 5590.273264475592, 5614.9719251403485, 5617.382087970238, 5651.632643878134, 5748.885022299614, 5798.797042103506, 5869.266341707286, 5876.882462350643, 5893.410658934073, 4123.299039163289, 4131.772752498641, 4178.456800615901, 4186.853460128881, 4190.895227619334, 5530.640508366943, 5687.289088863892, 5719.57103217252, 5724.169995350734, 5740.732410697835, 5478.534324582455, 5487.75305111263, 5503.834884953458, 5519.862003449946, 5580.330360356783, 5398.406175320633, 5480.287562616834, 5487.533559647315, 5488.533474654699, 5489.423610520837, 5432.674039557639, 5477.67133971963, 5502.504774570308, 5515.889682206253, 5529.806456774155, 5551.833444996657, 5563.972180139235, 5572.3034620672415, 5577.776888924398, 5579.860503487445, 5304.310439642822, 5478.972605136949, 5479.917801233064, 5494.230807687007, 5538.335399752055], "value": 4165.202309475282, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.8694141583808, 727.9927200727991, 778.8559116563436, 793.5833687314156, 822.6997146041692, 15011.174385408798, 16059.75910361344, 16075.0, 16137.435189768361, 16154.596135096614, 13942.860571394283, 14035.649108772286, 14068.296585170727, 14070.085444446104, 14077.0, 665.7636539028648, 810.785141937387, 811.9837603247917, 826.0706704956923, 832.5379414424991, 830.5265998380926, 831.6216121664659, 832.24681663095, 832.2634468495395, 832.50881979632, 866.263675875507, 866.9653213871437, 867.4014929698519, 867.7310033889514, 868.2619773192768, 721.084223036743, 854.811941372897, 860.6729442811754, 868.444195714743, 869.0917990699702, 631.3844002097964, 632.4497687012329, 632.968351582419, 633.5343522510951, 634.0, 15047.623809404742, 15083.924580377137, 15111.924440377838, 15115.546533604009, 15126.0, 14422.639434014129, 14652.70694586113, 14718.632034199123, 14725.337359818726, 14824.184669843155, 12520.937395313056, 12701.301428421433, 12849.164804287691, 13036.217826930415, 13537.187768733907, 4398.7140835845885, 4496.3705081288645, 4499.797509112098, 4556.589906908394, 4664.067186562701, 73878.0, 74567.0, 75883.0, 76094.0, 76652.23347766507, 11385.544578216779, 11445.771084578262, 11717.414129293502, 11844.348560829065, 11847.348395838138, 21456.570868582854, 21484.0, 21590.244341448288, 21592.784072159393, 21598.892005540187, 7631.771046868603, 7997.520148791091, 8361.958190209132, 8368.37237207212, 8639.352048596254, 4673.929891051637, 4751.572358487683, 4761.40482439695, 4864.635152363587, 4947.752612369439, 3218.211538173116, 3284.0476261884264, 3317.0546394278, 3317.4360358739073, 3334.1164591383304, 623.1649589550025, 637.0985056145595, 643.9517036222209, 651.9967400163064, 665.5707068940507, 502.80893260560754, 585.648610833505, 625.3027873920546, 651.5243871973486, 652.55952232244, 9009.54952252398, 9093.590788414538, 9158.81682366349, 9206.677766278148, 9356.864853027537, 620.5221979076086, 621.9315875253718, 661.1207094564218, 662.8011596521025, 667.5327270910382, 4081.959180408158, 4185.1497563949715, 4429.800658970354, 4522.3442600823355, 4531.161735079021, 108193.45903270433, 114939.0, 116493.0, 120747.0, 120921.39539302247, 96593.5170324158, 96640.0, 97102.51448742852, 98670.6395828442, 98953.0, 82428.0, 84569.57715211385, 84925.57537212397, 85140.57429712811, 87622.0, 8064.435489515678, 8310.085890551914, 8344.666213351398, 8418.158184181533, 8438.451500652438, 52209.47790522046, 53155.0, 53196.46803531915, 53548.16831732519, 53953.46046539484, 68697.0, 70743.0, 77454.0, 79487.20512794798, 80185.19814801778, 10956.56173753041, 10986.0, 11260.549578017115, 11341.886581134084, 11547.191696581158, 12538.310392928477, 12750.171238869443, 12765.361731913554, 13460.259685716988, 13488.460461581426, 11011.229213954946, 11062.17033722458, 11132.499037543168, 11597.942010289895, 11691.415429228675, 11425.828612570653, 11982.161248712506, 12060.396980151132, 12081.456334464794, 12453.25280483174, 11262.549498019987, 11391.487383067617, 11406.087512998774, 11672.649820505398, 11768.52925882955, 9457.905420945703, 9483.66807161746, 9777.657781977596, 9822.508874556386, 10075.798484030425, 8673.566321684017, 8877.37858349935, 8915.955420222857, 9873.210143188671, 9951.0], "value": 7279.9077688084535, "unit": "score"}], "name": "dromaeo_css", "value": 7231.181556618805, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 110.0, 109.0, 111.0, 111.0, 112.0, 111.0, 112.0, 111.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 120.0, 122.0, 178.0, 122.0, 120.0, 148.0, 117.0, 161.0, 122.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 161.0, 159.0, 162.0, 161.0, 163.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 109.0, 76.0, 75.0, 74.0, 74.0, 100.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 124.0, 85.0, 84.0, 84.0, 129.0, 84.0, 85.0, 137.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 51.0, 51.0, 50.0, 51.0, 50.0, 50.0, 51.0, 51.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 64.0, 62.0, 62.0, 63.0, 62.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 76.0, 75.0, 76.0, 79.0, 75.0, 75.0, 76.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 117.0, 128.0, 119.0, 120.0, 119.0, 128.0, 138.0, 200.0, 128.0], "value": 132.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 159.0, 160.0, 157.0, 154.0, 157.0, 157.0, 156.0, 156.0, 154.0], "value": 157.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 62.0, 63.0, 63.0, 61.0, 61.0, 62.0, 62.0, 62.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1483.4, "alertThreshold": 2.0}]} 20:20:57 INFO - Return code: 0 20:20:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:20:57 INFO - # TBPL SUCCESS # 20:20:57 INFO - Running post-action listener: _resource_record_post_action 20:20:57 INFO - [mozharness: 2016-07-21 03:20:57.531660Z] Finished run-tests step (success) 20:20:57 INFO - Running post-run listener: _resource_record_post_run 20:20:57 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 555679744; Read time: 12; Write time: 465052 20:20:57 INFO - TinderboxPrint: CPU usage
13.1% 20:20:57 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 20:20:57 INFO - TinderboxPrint: I/O write bytes / time
555,679,744 / 465,052 20:20:57 INFO - TinderboxPrint: CPU idle
8,264.2 (86.9%) 20:20:57 INFO - TinderboxPrint: CPU user
1,197.6 (12.6%) 20:20:57 INFO - TinderboxPrint: Swap in / out
0 / 0 20:20:57 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 257175552; Read time: 0; Write time: 258168 20:20:57 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 298504192; Read time: 0; Write time: 206884 20:20:58 INFO - Running post-run listener: _upload_blobber_files 20:20:58 INFO - Blob upload gear active. 20:20:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:20:58 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 20:20:58 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:20:58 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:20:58 INFO - (blobuploader) - INFO - Open directory for files ... 20:20:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:20:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:20:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:20:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:20:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:20:59 INFO - (blobuploader) - INFO - Done attempting. 20:20:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:20:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:20:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:21:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:21:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:21:08 INFO - (blobuploader) - INFO - Done attempting. 20:21:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:21:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:21:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:21:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:21:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:21:09 INFO - (blobuploader) - INFO - Done attempting. 20:21:09 INFO - (blobuploader) - INFO - Iteration through files over. 20:21:09 INFO - Return code: 0 20:21:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:21:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:21:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f1f3dc369ebdddef3a164ca06a59c847cda3946db7dd0827be9c8869b93e269366c1ee15ceceff88e11aefb8672576283f7673e9790c990459e402150bd60731", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aa55c9716fb31daaefc321e0f6ae5279ddf4c50daa94f05d1be7feea6597a842bea8ac7e219cec97b98afd4dfe0efe9e92143cbe7007409f41cf3d4ab7fe02e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/63d55622b29a52cc8013c47fd83797d2f5b6036c892e2571c353b8149ae7c4cd245f9bc009716dededf72a0a984b8455e9c5bf30f6013717969856002d3c6a55"} 20:21:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:21:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:21:09 INFO - Contents: 20:21:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f1f3dc369ebdddef3a164ca06a59c847cda3946db7dd0827be9c8869b93e269366c1ee15ceceff88e11aefb8672576283f7673e9790c990459e402150bd60731", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aa55c9716fb31daaefc321e0f6ae5279ddf4c50daa94f05d1be7feea6597a842bea8ac7e219cec97b98afd4dfe0efe9e92143cbe7007409f41cf3d4ab7fe02e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/63d55622b29a52cc8013c47fd83797d2f5b6036c892e2571c353b8149ae7c4cd245f9bc009716dededf72a0a984b8455e9c5bf30f6013717969856002d3c6a55"} 20:21:09 INFO - Running post-run listener: copy_logs_to_upload_dir 20:21:09 INFO - Copying logs to upload dir... 20:21:09 INFO - mkdir: /builds/slave/test/build/upload/logs 20:21:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.685160 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-07-20 20:21:09.535419) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 20:21:09.538735) ========= 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=923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f1f3dc369ebdddef3a164ca06a59c847cda3946db7dd0827be9c8869b93e269366c1ee15ceceff88e11aefb8672576283f7673e9790c990459e402150bd60731", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aa55c9716fb31daaefc321e0f6ae5279ddf4c50daa94f05d1be7feea6597a842bea8ac7e219cec97b98afd4dfe0efe9e92143cbe7007409f41cf3d4ab7fe02e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/63d55622b29a52cc8013c47fd83797d2f5b6036c892e2571c353b8149ae7c4cd245f9bc009716dededf72a0a984b8455e9c5bf30f6013717969856002d3c6a55"} build_url:https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011391 build_url: 'https://queue.taskcluster.net/v1/task/BwzVcW3FRqeCv6-oBuhcOA/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/Autoland/sha512/f1f3dc369ebdddef3a164ca06a59c847cda3946db7dd0827be9c8869b93e269366c1ee15ceceff88e11aefb8672576283f7673e9790c990459e402150bd60731", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aa55c9716fb31daaefc321e0f6ae5279ddf4c50daa94f05d1be7feea6597a842bea8ac7e219cec97b98afd4dfe0efe9e92143cbe7007409f41cf3d4ab7fe02e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/63d55622b29a52cc8013c47fd83797d2f5b6036c892e2571c353b8149ae7c4cd245f9bc009716dededf72a0a984b8455e9c5bf30f6013717969856002d3c6a55"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 20:21:09.564076) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 20:21:09.564411) ========= 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=923d835db2ba3686ebe126b700000192-1469070030.672490-1182892823 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004490 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 20:21:09.648251) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 20:21:09.648567) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 20:21:09.648883) ========= ========= Total master_lag: 0.16 =========