builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-034 starttime: 1471904514.29 results: success (0) buildid: 20160822145414 builduid: e1ad265425bf40b0b6d6b74795a4d2bd revision: 31cde7bb6a9e20cfffe822a9b531144234db6f7b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:54.288083) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:54.288612) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:54.288915) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:54.303504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:54.303791) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-22 15:21:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 122M=0s 2016-08-22 15:21:54 (122 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086556 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:54.404386) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:54.404698) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018830 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:54.453954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 15:21:54.454341) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 31cde7bb6a9e20cfffe822a9b531144234db6f7b --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 31cde7bb6a9e20cfffe822a9b531144234db6f7b --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=d6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-22 15:21:54,729 truncating revision to first 12 chars 2016-08-22 15:21:54,729 Setting DEBUG logging. 2016-08-22 15:21:54,729 attempt 1/10 2016-08-22 15:21:54,729 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/31cde7bb6a9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 15:21:56,141 unpacking tar archive at: autoland-31cde7bb6a9e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.796145 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 15:21:56.266128) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:56.266624) ========= script_repo_revision: 31cde7bb6a9e20cfffe822a9b531144234db6f7b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:56.267290) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:56.267671) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 15:21:56.278362) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-22 15:21:56.278673) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:21:56 INFO - MultiFileLogger online at 20160822 15:21:56 in /builds/slave/test 15:21:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 15:21:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:21:56 INFO - {'append_to_log': False, 15:21:56 INFO - 'base_work_dir': '/builds/slave/test', 15:21:56 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 15:21:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:21:56 INFO - 'branch': 'Autoland-Non-PGO', 15:21:56 INFO - 'buildbot_json_path': 'buildprops.json', 15:21:56 INFO - 'config_files': ('talos/linux_config.py',), 15:21:56 INFO - 'default_actions': ('clobber', 15:21:56 INFO - 'read-buildbot-config', 15:21:56 INFO - 'download-and-extract', 15:21:56 INFO - 'populate-webroot', 15:21:56 INFO - 'create-virtualenv', 15:21:56 INFO - 'install', 15:21:56 INFO - 'run-tests'), 15:21:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:21:56 INFO - 'download_minidump_stackwalk': True, 15:21:56 INFO - 'download_symbols': 'ondemand', 15:21:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:21:56 INFO - 'tooltool.py': '/tools/tooltool.py', 15:21:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:21:56 INFO - '/tools/misc-python/virtualenv.py')}, 15:21:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:21:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:21:56 INFO - 'installer_path': 'installer.exe', 15:21:56 INFO - 'log_level': 'info', 15:21:56 INFO - 'log_name': 'talos', 15:21:56 INFO - 'log_to_console': True, 15:21:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:21:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:21:56 INFO - 'opt_config_files': (), 15:21:56 INFO - 'pip_index': False, 15:21:56 INFO - 'sps_profile': False, 15:21:56 INFO - 'sps_profile_interval': 0, 15:21:56 INFO - 'suite': 'dromaeojs', 15:21:56 INFO - 'system_bits': '32', 15:21:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:21:56 INFO - 'title': 'talos-linux64-ix-034', 15:21:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:21:56 INFO - 'use_talos_json': True, 15:21:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:21:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:21:56 INFO - 'work_dir': 'build'} 15:21:56 INFO - [mozharness: 2016-08-22 22:21:56.642782Z] Running clobber step. 15:21:56 INFO - Running pre-action listener: _resource_record_pre_action 15:21:56 INFO - Running main action method: clobber 15:21:56 INFO - rmtree: /builds/slave/test/build 15:21:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:21:57 INFO - Running post-action listener: _resource_record_post_action 15:21:57 INFO - [mozharness: 2016-08-22 22:21:57.098559Z] Finished clobber step (success) 15:21:57 INFO - [mozharness: 2016-08-22 22:21:57.098660Z] Running read-buildbot-config step. 15:21:57 INFO - Running pre-action listener: _resource_record_pre_action 15:21:57 INFO - Running main action method: read_buildbot_config 15:21:57 INFO - Using buildbot properties: 15:21:57 INFO - { 15:21:57 INFO - "project": "", 15:21:57 INFO - "product": "firefox", 15:21:57 INFO - "script_repo_revision": "production", 15:21:57 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 15:21:57 INFO - "repository": "", 15:21:57 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 15:21:57 INFO - "buildid": "20160822145414", 15:21:57 INFO - "pgo_build": "False", 15:21:57 INFO - "basedir": "/builds/slave/test", 15:21:57 INFO - "buildnumber": 460, 15:21:57 INFO - "slavename": "talos-linux64-ix-034", 15:21:57 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:21:57 INFO - "platform": "ubuntu64_hw", 15:21:57 INFO - "scheduler": "tests-autoland-linux64-talos", 15:21:57 INFO - "branch": "autoland", 15:21:57 INFO - "repo_path": "integration/autoland", 15:21:57 INFO - "revision": "31cde7bb6a9e20cfffe822a9b531144234db6f7b", 15:21:57 INFO - "stage_platform": "linux64", 15:21:57 INFO - "builduid": "e1ad265425bf40b0b6d6b74795a4d2bd", 15:21:57 INFO - "slavebuilddir": "test" 15:21:57 INFO - } 15:21:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 15:21:57 INFO - Running post-action listener: _resource_record_post_action 15:21:57 INFO - [mozharness: 2016-08-22 22:21:57.101070Z] Finished read-buildbot-config step (success) 15:21:57 INFO - [mozharness: 2016-08-22 22:21:57.101159Z] Running download-and-extract step. 15:21:57 INFO - Running pre-action listener: _resource_record_pre_action 15:21:57 INFO - Running main action method: download_and_extract 15:21:57 INFO - mkdir: /builds/slave/test/build/tests 15:21:57 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:57 INFO - trying https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:21:57 INFO - Downloading https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:21:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:21:57 INFO - Downloaded 1361 bytes. 15:21:57 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:21:57 INFO - Using the following test package requirements: 15:21:57 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:21:57 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:21:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:21:57 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:21:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:21:57 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:21:57 INFO - u'jsshell-linux-x86_64.zip'], 15:21:57 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:21:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:21:57 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:21:57 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:21:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:21:57 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:21:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:21:57 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:21:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:21:57 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:21:57 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:21:57 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:21:57 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:57 INFO - trying https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:21:57 INFO - Downloading https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:21:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:21:58 INFO - Downloaded 20332625 bytes. 15:21:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 15:22:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:22:02 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:02 INFO - trying https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:22:02 INFO - Downloading https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:22:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:22:03 INFO - Downloaded 20332625 bytes. 15:22:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 15:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:08 INFO - trying https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:22:08 INFO - Downloading https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:22:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:22:09 INFO - Downloaded 14413981 bytes. 15:22:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 15:22:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:10 INFO - trying https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:22:10 INFO - Downloading https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:22:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:22:11 INFO - Downloaded 55785964 bytes. 15:22:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:22:11 INFO - mkdir: /builds/slave/test/properties 15:22:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:22:11 INFO - Writing to file /builds/slave/test/properties/build_url 15:22:11 INFO - Contents: 15:22:11 INFO - build_url:https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:22:11 INFO - Running post-action listener: _resource_record_post_action 15:22:11 INFO - Running post-action listener: set_extra_try_arguments 15:22:11 INFO - [mozharness: 2016-08-22 22:22:11.927489Z] Finished download-and-extract step (success) 15:22:11 INFO - [mozharness: 2016-08-22 22:22:11.927713Z] Running populate-webroot step. 15:22:11 INFO - Running pre-action listener: _resource_record_pre_action 15:22:11 INFO - Running main action method: populate_webroot 15:22:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:22:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:22:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:22:11 INFO - '--tppagecycles', 15:22:11 INFO - '7'], 15:22:11 INFO - 'tests': ['tsvgm']}}, 15:22:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:22:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:22:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:22:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:22:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:22:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:22:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:11 INFO - 'talos_options': ['--disable-e10s'], 15:22:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:22:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:22:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:11 INFO - 'talos_options': ['--disable-e10s'], 15:22:11 INFO - 'tests': ['damp', 'tps']}, 15:22:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:11 INFO - 'tests': ['damp', 'tps']}, 15:22:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:22:11 INFO - 'tests': ['dromaeo_dom']}, 15:22:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:22:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:22:11 INFO - 'tests': ['basic_compositor_video']}, 15:22:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:22:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:22:11 INFO - 'tests': ['a11yr', 15:22:11 INFO - 'ts_paint', 15:22:11 INFO - 'tpaint', 15:22:11 INFO - 'sessionrestore', 15:22:11 INFO - 'sessionrestore_no_auto_restore', 15:22:11 INFO - 'tabpaint']}, 15:22:11 INFO - 'other-e10s': {'tests': ['a11yr', 15:22:11 INFO - 'ts_paint', 15:22:11 INFO - 'tpaint', 15:22:11 INFO - 'sessionrestore', 15:22:11 INFO - 'sessionrestore_no_auto_restore', 15:22:11 INFO - 'tabpaint']}, 15:22:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:22:11 INFO - 'ts_paint', 15:22:11 INFO - 'tpaint', 15:22:11 INFO - 'sessionrestore', 15:22:11 INFO - 'sessionrestore_no_auto_restore', 15:22:11 INFO - 'tabpaint']}, 15:22:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:22:11 INFO - 'ts_paint', 15:22:11 INFO - 'tpaint', 15:22:11 INFO - 'sessionrestore', 15:22:11 INFO - 'sessionrestore_no_auto_restore', 15:22:11 INFO - 'tabpaint']}, 15:22:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:22:11 INFO - 'tests': ['a11yr', 15:22:11 INFO - 'ts_paint', 15:22:11 INFO - 'tpaint', 15:22:11 INFO - 'sessionrestore', 15:22:11 INFO - 'sessionrestore_no_auto_restore', 15:22:11 INFO - 'tabpaint']}, 15:22:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:22:11 INFO - 'tests': ['a11yr', 15:22:11 INFO - 'ts_paint', 15:22:11 INFO - 'tpaint', 15:22:11 INFO - 'sessionrestore', 15:22:11 INFO - 'sessionrestore_no_auto_restore', 15:22:11 INFO - 'tabpaint']}, 15:22:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:22:11 INFO - 'tests': ['tsvgx', 15:22:11 INFO - 'tsvgr_opacity', 15:22:11 INFO - 'tart', 15:22:11 INFO - 'tscrollx', 15:22:11 INFO - 'cart']}, 15:22:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:22:11 INFO - 'tsvgr_opacity', 15:22:11 INFO - 'tart', 15:22:11 INFO - 'tscrollx', 15:22:11 INFO - 'cart']}, 15:22:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:11 INFO - 'talos_options': ['--disable-e10s'], 15:22:11 INFO - 'tests': ['tp5o']}, 15:22:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:11 INFO - 'tests': ['tp5o']}, 15:22:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:11 INFO - 'talos_options': ['--disable-e10s', 15:22:11 INFO - '--xperf_path', 15:22:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:22:11 INFO - 'tests': ['tp5n']}, 15:22:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:22:11 INFO - 'talos_options': ['--xperf_path', 15:22:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:22:11 INFO - 'tests': ['tp5n']}}, 15:22:11 INFO - 'talos.zip': {'path': '', 15:22:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:22:11 INFO - Running post-action listener: _resource_record_post_action 15:22:11 INFO - [mozharness: 2016-08-22 22:22:11.949578Z] Finished populate-webroot step (success) 15:22:11 INFO - [mozharness: 2016-08-22 22:22:11.949666Z] Running create-virtualenv step. 15:22:11 INFO - Running pre-action listener: _resource_record_pre_action 15:22:11 INFO - Running main action method: create_virtualenv 15:22:11 INFO - Creating virtualenv /builds/slave/test/build/venv 15:22:11 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 15:22:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:22:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:22:12 INFO - Using real prefix '/usr' 15:22:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:22:12 INFO - Installing distribute.............................................................................................................................................................................................done. 15:22:14 INFO - Installing pip.................done. 15:22:14 INFO - Return code: 0 15:22:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:14 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 0x7f879fa92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111e230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1118090>, '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 0x111b9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111be40>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', '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 15:22:14 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 15:22:14 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 15:22:14 INFO - Using env: {'DISPLAY': ':0', 15:22:14 INFO - 'HOME': '/home/cltbld', 15:22:14 INFO - 'LANG': 'en_US.UTF-8', 15:22:14 INFO - 'LANGUAGE': 'en_US:en', 15:22:14 INFO - 'LOGNAME': 'cltbld', 15:22:14 INFO - 'MAIL': '/var/mail/cltbld', 15:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:14 INFO - 'MOZ_NO_REMOTE': '1', 15:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:22:14 INFO - 'NO_EM_RESTART': '1', 15:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:14 INFO - 'PWD': '/builds/slave/test', 15:22:14 INFO - 'SHELL': '/bin/bash', 15:22:14 INFO - 'SHLVL': '1', 15:22:14 INFO - 'TERM': 'linux', 15:22:14 INFO - 'TMOUT': '86400', 15:22:14 INFO - 'USER': 'cltbld', 15:22:14 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', 15:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:14 INFO - '_': '/tools/buildbot/bin/python'} 15:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:14 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 15:22:14 INFO - Cleaning up... 15:22:14 INFO - Return code: 0 15:22:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:14 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 0x7f879fa92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111e230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1118090>, '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 0x111b9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111be40>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', '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 15:22:14 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 15:22:14 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 15:22:14 INFO - Using env: {'DISPLAY': ':0', 15:22:14 INFO - 'HOME': '/home/cltbld', 15:22:14 INFO - 'LANG': 'en_US.UTF-8', 15:22:14 INFO - 'LANGUAGE': 'en_US:en', 15:22:14 INFO - 'LOGNAME': 'cltbld', 15:22:14 INFO - 'MAIL': '/var/mail/cltbld', 15:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:14 INFO - 'MOZ_NO_REMOTE': '1', 15:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:22:14 INFO - 'NO_EM_RESTART': '1', 15:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:14 INFO - 'PWD': '/builds/slave/test', 15:22:14 INFO - 'SHELL': '/bin/bash', 15:22:14 INFO - 'SHLVL': '1', 15:22:14 INFO - 'TERM': 'linux', 15:22:14 INFO - 'TMOUT': '86400', 15:22:14 INFO - 'USER': 'cltbld', 15:22:14 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', 15:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:14 INFO - '_': '/tools/buildbot/bin/python'} 15:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:14 INFO - Downloading/unpacking psutil>=3.1.1 15:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:22:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:22:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:22:16 INFO - Installing collected packages: psutil 15:22:16 INFO - Running setup.py install for psutil 15:22:17 INFO - building 'psutil._psutil_linux' extension 15:22:17 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 15:22:17 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 15:22:17 INFO - building 'psutil._psutil_posix' extension 15:22:17 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 15:22:17 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 15:22:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:22:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:22:17 INFO - Successfully installed psutil 15:22:17 INFO - Cleaning up... 15:22:17 INFO - Return code: 0 15:22:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:17 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 0x7f879fa92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111e230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1118090>, '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 0x111b9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111be40>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', '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 15:22:17 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 15:22:17 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 15:22:17 INFO - Using env: {'DISPLAY': ':0', 15:22:17 INFO - 'HOME': '/home/cltbld', 15:22:17 INFO - 'LANG': 'en_US.UTF-8', 15:22:17 INFO - 'LANGUAGE': 'en_US:en', 15:22:17 INFO - 'LOGNAME': 'cltbld', 15:22:17 INFO - 'MAIL': '/var/mail/cltbld', 15:22:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:17 INFO - 'MOZ_NO_REMOTE': '1', 15:22:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:22:17 INFO - 'NO_EM_RESTART': '1', 15:22:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:22:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:17 INFO - 'PWD': '/builds/slave/test', 15:22:17 INFO - 'SHELL': '/bin/bash', 15:22:17 INFO - 'SHLVL': '1', 15:22:17 INFO - 'TERM': 'linux', 15:22:17 INFO - 'TMOUT': '86400', 15:22:17 INFO - 'USER': 'cltbld', 15:22:17 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', 15:22:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:17 INFO - '_': '/tools/buildbot/bin/python'} 15:22:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:22:17 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 15:22:17 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 15:22:17 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 15:22:17 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 15:22:17 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 15:22:17 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 15:22:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:22:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:22:19 INFO - Installing collected packages: mozsystemmonitor 15:22:19 INFO - Running setup.py install for mozsystemmonitor 15:22:19 INFO - Successfully installed mozsystemmonitor 15:22:19 INFO - Cleaning up... 15:22:19 INFO - Return code: 0 15:22:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:19 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 0x7f879fa92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111e230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1118090>, '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 0x111b9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111be40>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', '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 15:22:19 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 15:22:19 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 15:22:19 INFO - Using env: {'DISPLAY': ':0', 15:22:19 INFO - 'HOME': '/home/cltbld', 15:22:19 INFO - 'LANG': 'en_US.UTF-8', 15:22:19 INFO - 'LANGUAGE': 'en_US:en', 15:22:19 INFO - 'LOGNAME': 'cltbld', 15:22:19 INFO - 'MAIL': '/var/mail/cltbld', 15:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:19 INFO - 'MOZ_NO_REMOTE': '1', 15:22:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:22:19 INFO - 'NO_EM_RESTART': '1', 15:22:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:19 INFO - 'PWD': '/builds/slave/test', 15:22:19 INFO - 'SHELL': '/bin/bash', 15:22:19 INFO - 'SHLVL': '1', 15:22:19 INFO - 'TERM': 'linux', 15:22:19 INFO - 'TMOUT': '86400', 15:22:19 INFO - 'USER': 'cltbld', 15:22:19 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', 15:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:19 INFO - '_': '/tools/buildbot/bin/python'} 15:22:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:19 INFO - Downloading/unpacking jsonschema==2.5.1 15:22:19 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 15:22:19 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 15:22:19 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 15:22:19 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 15:22:19 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 15:22:19 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 15:22:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:22:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:22 INFO - Downloading functools32-3.2.3-2.tar.gz 15:22:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:22:22 INFO - warning: no files found matching '*.txt' 15:22:22 INFO - no previously-included directories found matching 'build' 15:22:22 INFO - no previously-included directories found matching 'dist' 15:22:22 INFO - no previously-included directories found matching '.git*' 15:22:22 INFO - Installing collected packages: jsonschema, functools32 15:22:22 INFO - Running setup.py install for functools32 15:22:22 INFO - warning: no files found matching '*.txt' 15:22:22 INFO - no previously-included directories found matching 'build' 15:22:22 INFO - no previously-included directories found matching 'dist' 15:22:22 INFO - no previously-included directories found matching '.git*' 15:22:22 INFO - Successfully installed jsonschema functools32 15:22:22 INFO - Cleaning up... 15:22:22 INFO - Return code: 0 15:22:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:22 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f879fa92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111e230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1118090>, '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 0x111b9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111be40>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', '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 15:22:22 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:22:22 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 functools32==3.2.3-2 15:22:22 INFO - Using env: {'DISPLAY': ':0', 15:22:22 INFO - 'HOME': '/home/cltbld', 15:22:22 INFO - 'LANG': 'en_US.UTF-8', 15:22:22 INFO - 'LANGUAGE': 'en_US:en', 15:22:22 INFO - 'LOGNAME': 'cltbld', 15:22:22 INFO - 'MAIL': '/var/mail/cltbld', 15:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:22 INFO - 'MOZ_NO_REMOTE': '1', 15:22:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:22:22 INFO - 'NO_EM_RESTART': '1', 15:22:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:22 INFO - 'PWD': '/builds/slave/test', 15:22:22 INFO - 'SHELL': '/bin/bash', 15:22:22 INFO - 'SHLVL': '1', 15:22:22 INFO - 'TERM': 'linux', 15:22:22 INFO - 'TMOUT': '86400', 15:22:22 INFO - 'USER': 'cltbld', 15:22:22 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', 15:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:22 INFO - '_': '/tools/buildbot/bin/python'} 15:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:22:22 INFO - Cleaning up... 15:22:22 INFO - Return code: 0 15:22:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:22 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 0x7f879fa92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111e230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1118090>, '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 0x111b9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111be40>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', '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 15:22:22 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 15:22:22 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 15:22:22 INFO - Using env: {'DISPLAY': ':0', 15:22:22 INFO - 'HOME': '/home/cltbld', 15:22:22 INFO - 'LANG': 'en_US.UTF-8', 15:22:22 INFO - 'LANGUAGE': 'en_US:en', 15:22:22 INFO - 'LOGNAME': 'cltbld', 15:22:22 INFO - 'MAIL': '/var/mail/cltbld', 15:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:22 INFO - 'MOZ_NO_REMOTE': '1', 15:22:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:22:22 INFO - 'NO_EM_RESTART': '1', 15:22:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:22 INFO - 'PWD': '/builds/slave/test', 15:22:22 INFO - 'SHELL': '/bin/bash', 15:22:22 INFO - 'SHLVL': '1', 15:22:22 INFO - 'TERM': 'linux', 15:22:22 INFO - 'TMOUT': '86400', 15:22:22 INFO - 'USER': 'cltbld', 15:22:22 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', 15:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:22 INFO - '_': '/tools/buildbot/bin/python'} 15:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:22 INFO - Downloading/unpacking blobuploader==1.2.4 15:22:22 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 15:22:22 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 15:22:22 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 15:22:22 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 15:22:22 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 15:22:22 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 15:22:24 INFO - Downloading blobuploader-1.2.4.tar.gz 15:22:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:22:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:22:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:25 INFO - Downloading docopt-0.6.1.tar.gz 15:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:22:25 INFO - Installing collected packages: blobuploader, requests, docopt 15:22:25 INFO - Running setup.py install for blobuploader 15:22:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:22:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:22:25 INFO - Running setup.py install for requests 15:22:25 INFO - Running setup.py install for docopt 15:22:25 INFO - Successfully installed blobuploader requests docopt 15:22:25 INFO - Cleaning up... 15:22:26 INFO - Return code: 0 15:22:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:26 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 0x7f879fa92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111e230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1118090>, '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 0x111b9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111be40>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', '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 15:22:26 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 15:22:26 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 15:22:26 INFO - Using env: {'DISPLAY': ':0', 15:22:26 INFO - 'HOME': '/home/cltbld', 15:22:26 INFO - 'LANG': 'en_US.UTF-8', 15:22:26 INFO - 'LANGUAGE': 'en_US:en', 15:22:26 INFO - 'LOGNAME': 'cltbld', 15:22:26 INFO - 'MAIL': '/var/mail/cltbld', 15:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:26 INFO - 'MOZ_NO_REMOTE': '1', 15:22:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:22:26 INFO - 'NO_EM_RESTART': '1', 15:22:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:26 INFO - 'PWD': '/builds/slave/test', 15:22:26 INFO - 'SHELL': '/bin/bash', 15:22:26 INFO - 'SHLVL': '1', 15:22:26 INFO - 'TERM': 'linux', 15:22:26 INFO - 'TMOUT': '86400', 15:22:26 INFO - 'USER': 'cltbld', 15:22:26 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', 15:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:26 INFO - '_': '/tools/buildbot/bin/python'} 15:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:22:26 INFO - Running setup.py (path:/tmp/pip-l67QJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:22:26 INFO - Running setup.py (path:/tmp/pip-_ObuAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:22:26 INFO - Running setup.py (path:/tmp/pip-dK2ulx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:22:26 INFO - Running setup.py (path:/tmp/pip-Ry3Isd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:22:26 INFO - Running setup.py (path:/tmp/pip-3_ZBDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:22:26 INFO - Running setup.py (path:/tmp/pip-xLCKMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:22:26 INFO - Running setup.py (path:/tmp/pip-vPbe63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:22:26 INFO - Running setup.py (path:/tmp/pip-ReM5e4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:22:26 INFO - Running setup.py (path:/tmp/pip-A_JlIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:22:26 INFO - Running setup.py (path:/tmp/pip-nSDdz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:22:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:22:26 INFO - Running setup.py (path:/tmp/pip-sKkeRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:22:27 INFO - Running setup.py (path:/tmp/pip-MJzUct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:22:27 INFO - Running setup.py (path:/tmp/pip-EOmEkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:22:27 INFO - Running setup.py (path:/tmp/pip-IR3nuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:27 INFO - Running setup.py (path:/tmp/pip-fPEi7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:22:27 INFO - Running setup.py (path:/tmp/pip-Z0vqxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:22:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:22:27 INFO - Running setup.py (path:/tmp/pip-nymKui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:22:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:22:27 INFO - Running setup.py install for manifestparser 15:22:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:22:27 INFO - Running setup.py install for mozcrash 15:22:27 INFO - Running setup.py install for mozdebug 15:22:27 INFO - Running setup.py install for mozdevice 15:22:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:22:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:22:27 INFO - Running setup.py install for mozfile 15:22:27 INFO - Running setup.py install for mozhttpd 15:22:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Running setup.py install for mozinfo 15:22:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Running setup.py install for mozInstall 15:22:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Running setup.py install for mozleak 15:22:28 INFO - Running setup.py install for mozlog 15:22:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Running setup.py install for moznetwork 15:22:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Running setup.py install for mozprocess 15:22:28 INFO - Running setup.py install for mozprofile 15:22:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Running setup.py install for mozrunner 15:22:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:22:28 INFO - Running setup.py install for mozscreenshot 15:22:29 INFO - Running setup.py install for moztest 15:22:29 INFO - Running setup.py install for mozversion 15:22:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:22:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:22:29 INFO - Cleaning up... 15:22:29 INFO - Return code: 0 15:22:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:29 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 0x7f879fa92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111e230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1118090>, '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 0x111b9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111be40>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', '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 15:22:29 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 15:22:29 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 15:22:29 INFO - Using env: {'DISPLAY': ':0', 15:22:29 INFO - 'HOME': '/home/cltbld', 15:22:29 INFO - 'LANG': 'en_US.UTF-8', 15:22:29 INFO - 'LANGUAGE': 'en_US:en', 15:22:29 INFO - 'LOGNAME': 'cltbld', 15:22:29 INFO - 'MAIL': '/var/mail/cltbld', 15:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:29 INFO - 'MOZ_NO_REMOTE': '1', 15:22:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:22:29 INFO - 'NO_EM_RESTART': '1', 15:22:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:29 INFO - 'PWD': '/builds/slave/test', 15:22:29 INFO - 'SHELL': '/bin/bash', 15:22:29 INFO - 'SHLVL': '1', 15:22:29 INFO - 'TERM': 'linux', 15:22:29 INFO - 'TMOUT': '86400', 15:22:29 INFO - 'USER': 'cltbld', 15:22:29 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', 15:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:29 INFO - '_': '/tools/buildbot/bin/python'} 15:22:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:22:29 INFO - Running setup.py (path:/tmp/pip-hWXAbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:22:29 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)) 15:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:22:29 INFO - Running setup.py (path:/tmp/pip-DRpwEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:22:29 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)) 15:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:22:29 INFO - Running setup.py (path:/tmp/pip-aY0SqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:22:29 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)) 15:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:22:29 INFO - Running setup.py (path:/tmp/pip-sNN3hH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:22:29 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)) 15:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:22:29 INFO - Running setup.py (path:/tmp/pip-N6ClYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:22:29 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)) 15:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:22:29 INFO - Running setup.py (path:/tmp/pip-6nLx3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:22:29 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)) 15:22:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:22:29 INFO - Running setup.py (path:/tmp/pip-F3xSzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:22:30 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)) 15:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:22:30 INFO - Running setup.py (path:/tmp/pip-O9rPZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:22:30 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)) 15:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:22:30 INFO - Running setup.py (path:/tmp/pip-PfhUIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:22:30 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)) 15:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:22:30 INFO - Running setup.py (path:/tmp/pip-HJeCqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:22:30 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)) 15:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:22:30 INFO - Running setup.py (path:/tmp/pip-wqNFxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:22:30 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)) 15:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:22:30 INFO - Running setup.py (path:/tmp/pip-9CtcTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:22:30 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)) 15:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:22:30 INFO - Running setup.py (path:/tmp/pip-suBKMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:22:30 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)) 15:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:22:30 INFO - Running setup.py (path:/tmp/pip-byYsW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:22:30 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)) 15:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:30 INFO - Running setup.py (path:/tmp/pip-243zCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:22:30 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)) 15:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:22:30 INFO - Running setup.py (path:/tmp/pip-Pq_PI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:22:30 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)) 15:22:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:22:30 INFO - Running setup.py (path:/tmp/pip-IJ6uuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:22:30 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)) 15:22:30 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)) 15:22:30 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)) 15:22:30 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)) 15:22:30 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)) 15:22:30 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)) 15:22:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:22:32 INFO - Downloading blessings-1.6.tar.gz 15:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:22:32 INFO - Installing collected packages: blessings 15:22:32 INFO - Running setup.py install for blessings 15:22:32 INFO - Successfully installed blessings 15:22:32 INFO - Cleaning up... 15:22:32 INFO - Return code: 0 15:22:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:22:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:22:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:22:33 INFO - Reading from file tmpfile_stdout 15:22:33 INFO - Current package versions: 15:22:33 INFO - argparse == 1.2.1 15:22:33 INFO - blessings == 1.6 15:22:33 INFO - blobuploader == 1.2.4 15:22:33 INFO - docopt == 0.6.1 15:22:33 INFO - functools32 == 3.2.3-2 15:22:33 INFO - jsonschema == 2.5.1 15:22:33 INFO - manifestparser == 1.1 15:22:33 INFO - mozInstall == 1.12 15:22:33 INFO - mozcrash == 0.17 15:22:33 INFO - mozdebug == 0.1 15:22:33 INFO - mozdevice == 0.48 15:22:33 INFO - mozfile == 1.2 15:22:33 INFO - mozhttpd == 0.7 15:22:33 INFO - mozinfo == 0.9 15:22:33 INFO - mozleak == 0.1 15:22:33 INFO - mozlog == 3.3 15:22:33 INFO - moznetwork == 0.27 15:22:33 INFO - mozprocess == 0.23 15:22:33 INFO - mozprofile == 0.28 15:22:33 INFO - mozrunner == 6.12 15:22:33 INFO - mozscreenshot == 0.1 15:22:33 INFO - mozsystemmonitor == 0.3 15:22:33 INFO - moztest == 0.7 15:22:33 INFO - mozversion == 1.4 15:22:33 INFO - psutil == 3.1.1 15:22:33 INFO - requests == 1.2.3 15:22:33 INFO - wsgiref == 0.1.2 15:22:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:33 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 0x7f879fa92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111e230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1118090>, '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 0x111b9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111be40>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', '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 15:22:33 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 15:22:33 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 15:22:33 INFO - Using env: {'DISPLAY': ':0', 15:22:33 INFO - 'HOME': '/home/cltbld', 15:22:33 INFO - 'LANG': 'en_US.UTF-8', 15:22:33 INFO - 'LANGUAGE': 'en_US:en', 15:22:33 INFO - 'LOGNAME': 'cltbld', 15:22:33 INFO - 'MAIL': '/var/mail/cltbld', 15:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:33 INFO - 'MOZ_NO_REMOTE': '1', 15:22:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:22:33 INFO - 'NO_EM_RESTART': '1', 15:22:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:33 INFO - 'PWD': '/builds/slave/test', 15:22:33 INFO - 'SHELL': '/bin/bash', 15:22:33 INFO - 'SHLVL': '1', 15:22:33 INFO - 'TERM': 'linux', 15:22:33 INFO - 'TMOUT': '86400', 15:22:33 INFO - 'USER': 'cltbld', 15:22:33 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', 15:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:33 INFO - '_': '/tools/buildbot/bin/python'} 15:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:33 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)) 15:22:33 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)) 15:22:33 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)) 15:22:33 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)) 15:22:33 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)) 15:22:33 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)) 15:22:33 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)) 15:22:33 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)) 15:22:33 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)) 15:22:33 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)) 15:22:33 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)) 15:22:33 INFO - Cleaning up... 15:22:33 INFO - Return code: 0 15:22:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:22:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f879fa92f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x111e230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1118090>, '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 0x111b9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111be40>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', '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 15:22:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:22:33 INFO - Using env: {'DISPLAY': ':0', 15:22:33 INFO - 'HOME': '/home/cltbld', 15:22:33 INFO - 'LANG': 'en_US.UTF-8', 15:22:33 INFO - 'LANGUAGE': 'en_US:en', 15:22:33 INFO - 'LOGNAME': 'cltbld', 15:22:33 INFO - 'MAIL': '/var/mail/cltbld', 15:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:33 INFO - 'MOZ_NO_REMOTE': '1', 15:22:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:22:33 INFO - 'NO_EM_RESTART': '1', 15:22:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:33 INFO - 'PWD': '/builds/slave/test', 15:22:33 INFO - 'SHELL': '/bin/bash', 15:22:33 INFO - 'SHLVL': '1', 15:22:33 INFO - 'TERM': 'linux', 15:22:33 INFO - 'TMOUT': '86400', 15:22:33 INFO - 'USER': 'cltbld', 15:22:33 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', 15:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:33 INFO - '_': '/tools/buildbot/bin/python'} 15:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:22:33 INFO - Cleaning up... 15:22:33 INFO - Return code: 0 15:22:33 INFO - Running post-action listener: _resource_record_post_action 15:22:33 INFO - Running post-action listener: _start_resource_monitoring 15:22:33 INFO - Starting resource monitoring. 15:22:33 INFO - [mozharness: 2016-08-22 22:22:33.570845Z] Finished create-virtualenv step (success) 15:22:33 INFO - [mozharness: 2016-08-22 22:22:33.571309Z] Running install step. 15:22:33 INFO - Running pre-action listener: _resource_record_pre_action 15:22:33 INFO - Running main action method: install 15:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:22:33 INFO - Reading from file tmpfile_stdout 15:22:33 INFO - Detecting whether we're running mozinstall >=1.0... 15:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:22:33 INFO - Reading from file tmpfile_stdout 15:22:33 INFO - Output received: 15:22:33 INFO - Usage: mozinstall [options] installer 15:22:33 INFO - Options: 15:22:33 INFO - -h, --help show this help message and exit 15:22:33 INFO - -d DEST, --destination=DEST 15:22:33 INFO - Directory to install application into. [default: 15:22:33 INFO - "/builds/slave/test"] 15:22:33 INFO - --app=APP Application being installed. [default: firefox] 15:22:33 INFO - mkdir: /builds/slave/test/build/application 15:22:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:22:49 INFO - Reading from file tmpfile_stdout 15:22:49 INFO - Output received: 15:22:49 INFO - /builds/slave/test/build/application/firefox/firefox 15:22:49 INFO - Running post-action listener: _resource_record_post_action 15:22:49 INFO - [mozharness: 2016-08-22 22:22:49.972351Z] Finished install step (success) 15:22:49 INFO - [mozharness: 2016-08-22 22:22:49.972598Z] Running run-tests step. 15:22:49 INFO - Running pre-action listener: _resource_record_pre_action 15:22:49 INFO - Running main action method: run_tests 15:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:22:49 INFO - Python 2.7.3 15:22:49 INFO - Return code: 0 15:22:49 INFO - grabbing minidump binary from tooltool 15:22:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:22:49 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 0x1118090>, '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 0x111b9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x111be40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:22:49 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 15:22:49 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 15:22:49 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 15:22:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:22:50 INFO - Return code: 0 15:22:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:22:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:22:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:22:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:22:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:22:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 15:22:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 15:22:50 INFO - Using env: {'DISPLAY': ':0', 15:22:50 INFO - 'HOME': '/home/cltbld', 15:22:50 INFO - 'LANG': 'en_US.UTF-8', 15:22:50 INFO - 'LANGUAGE': 'en_US:en', 15:22:50 INFO - 'LOGNAME': 'cltbld', 15:22:50 INFO - 'MAIL': '/var/mail/cltbld', 15:22:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:22:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:22:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:22:50 INFO - 'MOZ_NO_REMOTE': '1', 15:22:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:22:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:22:50 INFO - 'NO_EM_RESTART': '1', 15:22:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:22:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:22:50 INFO - 'PWD': '/builds/slave/test', 15:22:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:22:50 INFO - 'SHELL': '/bin/bash', 15:22:50 INFO - 'SHLVL': '1', 15:22:50 INFO - 'TERM': 'linux', 15:22:50 INFO - 'TMOUT': '86400', 15:22:50 INFO - 'USER': 'cltbld', 15:22:50 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945', 15:22:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:22:50 INFO - '_': '/tools/buildbot/bin/python'} 15:22:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 15:22:50 INFO - mozversion application_buildid: 20160822145414 15:22:50 INFO - mozversion application_changeset: 31cde7bb6a9e20cfffe822a9b531144234db6f7b 15:22:50 INFO - mozversion application_display_name: Nightly 15:22:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:22:50 INFO - mozversion application_name: Firefox 15:22:50 INFO - mozversion application_remotingname: firefox 15:22:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:22:50 INFO - mozversion application_vendor: Mozilla 15:22:50 INFO - mozversion application_version: 51.0a1 15:22:50 INFO - mozversion platform_buildid: 20160822145414 15:22:50 INFO - mozversion platform_changeset: 31cde7bb6a9e20cfffe822a9b531144234db6f7b 15:22:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:22:50 INFO - mozversion platform_version: 51.0a1 15:22:50 INFO - using testdate: 1471904570 15:22:50 INFO - actual date: 1471904570 15:22:50 INFO - starting webserver on 'localhost:53159' 15:22:50 INFO - SUITE-START | Running 2 tests 15:22:50 INFO - TEST-START | dromaeo_css 15:22:50 INFO - operating with platform_type : linux_ 15:22:50 INFO - Initialising browser for dromaeo_css test... 15:22:50 INFO - TEST-INFO | started process 5713 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZfFjn2/profile http://localhost:53159/getInfo.html) 15:22:55 INFO - PROCESS | 5713 | __metrics Screen width/height:1600/1200 15:22:55 INFO - PROCESS | 5713 | colorDepth:24 15:22:55 INFO - PROCESS | 5713 | Browser inner width/height: 1024/697 15:22:55 INFO - PROCESS | 5713 | __metrics 15:22:55 INFO - PROCESS | 5713 | 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" 15:22:55 INFO - PROCESS | 5713 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:22:56 INFO - PROCESS | 5713 | console.error: PushService: 15:22:56 INFO - PROCESS | 5713 | stateChangeProcessEnqueue: Error transitioning state 15:22:56 INFO - PROCESS | 5713 | UnknownError 15:22:56 INFO - TEST-INFO | 5713: exit 0 15:22:56 INFO - Browser initialized. 15:22:56 INFO - Running cycle 1/1 for dromaeo_css test... 15:22:56 INFO - TEST-INFO | started process 5795 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZfFjn2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:23:09 INFO - PROCESS | 5795 | RSS: Main: 176230400 15:23:09 INFO - PROCESS | 5795 | 15:24:01 INFO - PROCESS | 5795 | 1471904641027 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] 15:26:12 INFO - PROCESS | 5795 | Cycle 1(1): loaded http://localhost:53159/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53159/tests/dromaeo/cssquery-ext.html) 15:26:13 INFO - PROCESS | 5795 | RSS: Main: 268279808 15:26:13 INFO - PROCESS | 5795 | 15:29:16 INFO - PROCESS | 5795 | Cycle 1(1): loaded http://localhost:53159/tests/dromaeo/cssquery-ext.html (next: http://localhost:53159/tests/dromaeo/cssquery-jquery.html) 15:29:17 INFO - PROCESS | 5795 | RSS: Main: 277716992 15:29:17 INFO - PROCESS | 5795 | 15:32:19 INFO - PROCESS | 5795 | Cycle 1(1): loaded http://localhost:53159/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53159/tests/dromaeo/cssquery-mootools.html) 15:32:19 INFO - PROCESS | 5795 | RSS: Main: 218836992 15:32:19 INFO - PROCESS | 5795 | 15:35:22 INFO - PROCESS | 5795 | Cycle 1(1): loaded http://localhost:53159/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53159/tests/dromaeo/cssquery-prototype.html) 15:35:23 INFO - PROCESS | 5795 | RSS: Main: 230166528 15:35:23 INFO - PROCESS | 5795 | 15:38:26 INFO - PROCESS | 5795 | Cycle 1(1): loaded http://localhost:53159/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53159/tests/dromaeo/cssquery-yui.html) 15:38:26 INFO - PROCESS | 5795 | RSS: Main: 219770880 15:38:26 INFO - PROCESS | 5795 | 15:41:30 INFO - PROCESS | 5795 | Cycle 1(1): loaded http://localhost:53159/tests/dromaeo/cssquery-yui.html (next: http://localhost:53159/tests/dromaeo/cssquery-dojo.html) 15:41:30 INFO - PROCESS | 5795 | RSS: Main: 305618944 15:41:30 INFO - PROCESS | 5795 | 15:41:30 INFO - PROCESS | 5795 | __start_tp_report 15:41:30 INFO - PROCESS | 5795 | _x_x_mozilla_page_load 15:41:30 INFO - PROCESS | 5795 | _x_x_mozilla_page_load_details 15:41:30 INFO - PROCESS | 5795 | |i|pagename|runs| 15:41:30 INFO - PROCESS | 5795 | |0;dojo.html;2567.666203393559;2614.4901744159883;2619.764221220092;2624.6850377954647;2630.539655560278;4547.795349209286;4615.076984603084;4642.651801114917;4728.692634978728;4733.431988161421;4631.143238500879;4679.204535229019;4680.461746899107;4696.835610753625;4778;4096.180763847227;4314.978425107885;4333.739975601474;4346.478422589294;4382.802773875167;4330.458692663401;4346.565343465644;4362.149380870732;4376.059147283333;4384.62730667892;4188.68584856137;4211.831526738927;4246.447961764981;4252.468441444819;4324.654027677778;4288.549702281278;4294.957050429487;4297.05464797744;4300.784960751981;4302.247106756305;4262.232798096373;4266.957330426687;4276.059266961263;4288.635465985395;4295.591918767744;4558.931616025762;4627.5603817637275;4670.6497012724185;4708.623310135181;4723.102610503994;5070.72111033893;5075.314832497639;5082;5082.186850104003;5082.491750824925;5094.77073531692;5101.107306221434;5104.617153713486;5111.207762796773;5112.923306150411;4542.682012259176;4547.3406356078185;4587.243104887723;4590.311453282035;4753.952460475421;5074.695518268915;5089.923651145235;5089.949100509021;5096.006278775603;5097.541221290102;4880.145974454527;4883.145449546315;4883.8290659826735;4885.5358740919555;4888.2178851383605;4840.273958906122;4909.067277217388;4949.084419382426;4968.8757781055765;4981.427135879348;4946.703197808143;4953.72754498506;4954.727489988085;4974.626902982291;4985.626078044161;4973.801047958041;4983.526564976321;4985.90028199434;4986.675866068694;4991.401031876198;3781.489498917611;3826.7895265760094;3829.1384438501113;3831.501904752364;3904.316744569746;3447.9827600861836;3475.600305964796;3477.1480987158307;3488.651134886542;3499.720022398202;4397.890052748707;4416.757078360657;4417.602415782595;4430.025394413224;4433.844815431445;4862.124817532878;4918.8770280743265;5034.874128146826;5041.697498150123;5055.191169412949;4321.978390108029;4361.542038086019;4372.103718737651;4388.539203383662;4398.670099742532;4507.729536227836;4532.546745325441;4534.342520334533;4544.386507821402;4549.909001819945;4647.349371088118;4757.048590281888;4784.306275590089;4823.252395878644;4825.059113472839;5034.546890779848;5101.158308879142;5119.616028797781;5120.667156634807;5125.666831655931;4869.95130048695;4872.829450969199;4877.219644856806;4880.78036488366;4889.315495830658;4811;5034.471380505141;5035.320231768665;5036.395632523974;5038.395392552917;4967.205247160437;4981.277714731271;4998.2502624606595;5066.721330326867;5067.949320506896;4931.087748766489;4932.506749325183;4934.111859865258;4937.284093806306;4940.7776650051555;4770;4939.259111133215;4958.950410495849;4962.751862406793;4965.28003439492;4755.262934245197;4770.427548694179;4795.184818580919;4864.562189402971;4877.073356062337;4959.60323174138;4967.130752118437;4972.8508144755715;4974.626902982219;4989.326440930428;4703.176944034849;4766.380370551945;4857.149998750134;4868.829590964299;4875.024995001085 15:41:30 INFO - PROCESS | 5795 | |1;ext.html;550.3533348315729;556.7745063249383;571.6198727845982;573.2347316332696;574.055678409017;38155.80922095389;39666.206675866466;39706;43495.56504434955;43541.34687979675;40255.19489610206;41063.79468102656;41087.38368924461;41116.58883411165;41162.7941860291;7570.886436703454;7781.2996830285265;7787.3770098392;7788.415868809833;7790.104138024115;7685.269899359552;7790.026246719161;7791.883121753178;7794.610269486531;7796.532208067534;7724.536527808322;7731.652075656581;7762.107357653857;7836.333911617519;7840.686372545091;7645.273698998614;7691.230876912292;7700.152983171846;7711.652975616111;7732.381409487228;7409.296116868889;7497.925020749777;7498.512596681198;7504.512206706547;7506.09926808781;37697;38353;38465.42301865474;38503.8074809627;38509.229815403814;2997.190758495194;3018.1850900257027;3076.9846150769104;3189.1708155879405;3230.6607806180245;29126.41747165066;29186.854065729534;29280.267993300124;30198;30355.69644303573;3101.805804765159;3117.9399004338406;3155.5266709993457;3169.0968074098773;3191.2341038150907;243900;244205.99999999822;244236;244761;244847;31108.533371999438;50925.745371272904;51272.74363628158;51304.23043654348;51337.229941550904;31664.36671266562;33573.16067098342;33739;33760.66239337575;33795.15512112217;13706.314684265946;13817.585472435641;13833;13849.792253116211;13854.930725346308;11396.487158077907;11580.47887845049;11589.130815188895;11589.710257243636;11596.362200079077;7697.268759467842;7798.103218129874;7828.373730101692;7860.5676687782725;7932.444728868923;3090.8763649454218;3113.486274764684;3114.1436105070834;3138.3095718941795;3140.795848269855;792.3067316098413;858.7209157023992;884.964601415949;959.3764053365369;960.4813400763649;26169.476610467686;27642.585361219197;28141.57787633187;28155.43689126247;28272.71727282701;960.3709570231509;976.472704739447;988.1699372527112;1014.5890914179769;1086.9130469562417;14124;14754.336054877342;14890.702185956437;15130.697386052218;15196.620084497976;387229;391384;391611;392071;392384;49499;50051.74974125179;50083.499165008616;50123;50202.49797502051;1090.7709381030072;1098.4343063322394;1142.6743378137357;1193.5404869125275;1200.975980480403;25104.62343064819;29421.411571768873;29510.55734164032;29519.409611808074;30137.69862301349;45977.77011114923;47334.52665473439;47336.526634733214;51639;51655.48344516507;362528;365499;366432;366595;367179;32170.35659286754;36331.63668363282;36554.8172259137;38385.616143838204;38792.41811372888;41220.38169427401;41897.79051104847;42246.14169370562;42720.572794272906;43140.78429607832;35565.644343557266;35724;35847.64152358443;35874.46188307125;36588.451173232956;35900.28199436049;36149.27701445904;36385.818070909474;36411.81794091013;36520.82709020057;31236.843815780776;31412.68587314189;31880;35430.92544357432;36264.81867590645;22982;33693.66306336905;33737.493937590465;36285.45571816372;36819.81590092032;25197.748022520274;25300.11449599255;25792.226233213034;25906.611400828624;26195 15:41:30 INFO - PROCESS | 5795 | |2;jquery.html;339.6603396603397;356.2785359646717;358.7811435024635;361.9457081437784;374.3561074951083;12660.43028063737;16032.91983540082;16450.095244761535;16543.751843722323;17560.560985975357;14265;14285.714285714279;14375.993680442372;14412.99109062366;14446.5666030019;4514.864554063367;4933.45731969483;5124.103281925667;5126.692398456086;5202.557782588485;5165.81919632813;5220.791168353261;5249.1601343785005;5255.316808814849;5258.106121959276;4931.753412329387;5045.596352291826;5223.686578805284;5229.790808367679;5234.790608375661;5033.748312584356;5070.6957582545165;5071.239314102877;5072.568831649332;5076.74616269185;4397.846075387378;4518.073794872043;4523.050159466516;4523.117991991563;4613.769311534444;20643.69034464484;20753.27363542284;20796.18422299946;21019.474513137142;21043.5791284175;1936.331965471924;1948.0649288341533;2067.824234940017;2208.712867327253;2481.528509583173;13132.474701011948;13148.934255328662;13235.669108272274;13262.933685331607;13893.652658683513;1765.4174122539532;1773.7871455425302;1783.545195975031;1797.7573027641363;1799.7570328005813;68906.31093689048;69133;69388;70290.29709702889;70722.64638676825;9182.586783594754;9287.164155225926;9401.529923503798;9588.280878934107;9859.161971232526;12976.416061277265;12977.091603587658;12992.415341309665;12994.415251313525;13008.739825203444;7091.60996145217;7375.483716139926;7424.777256682308;7644.541327520366;7650;7063.1524217094275;7184.1019872515935;7289.599072051088;7353.889691654629;7369.7052117915755;5825.592208545361;5975.7609695612555;5977.774556215965;6024.668643224577;6225.564210505221;1669.7495375693743;1728.1272957156555;1766.2140347545476;1767.7878654561534;1773.911304434773;821.252660079325;850.5577099908013;861.96983105591;889.1908363389284;910.1808372464834;16656.50030499087;16762.413315533897;17293.221805018806;17727.05535408279;17734;840.3403328387309;851.7104184577149;916.9908300917039;936.7611259129014;968.4334664221368;8768.342374321952;10019.949900250598;10137.783465983981;10160.33957792741;10363.740906477398;375498;377688;377697;378024;391709;40171.59828401737;40621.18757624831;51148.48851511512;51316.97366052658;71095.6445217781;900.9054049324726;928.8560273157672;953.2374100719396;953.4184147669915;953.8664586957832;15329.6167595811;15451.922740386226;15653.37386504527;16196.433124840574;16282.918585406996;35563.28873422569;36278.27443451169;36911.63088369082;37122.25755484929;37349;45725;47027.52972470231;47041.29438058363;47310;47584.286235707186;7337.229590893093;7403;7555.320021198118;7674.769756907302;7756;11722.76554468923;11857.525698971944;11873.584424545099;12331.260124392566;12434.316112613893;10387.68836934893;10471.68584942453;10609.522571484147;10643.627473038407;10685.839712404164;10194.133498652402;10337.74155646115;10487.056164945192;10593.252113401202;10685;9798;10105.545250463894;10328.896711033094;10770.353778773298;10990.725231869268;7401.296876796696;7628.23717628255;7645.770626881203;7654.540727556365;7691.1155217150745;9357.157855793012;9858.162056225014;9882.654107106504;10063.094321511098;10418.895811041793 15:41:30 INFO - PROCESS | 5795 | |3;mootools.html;1247.9064070194738;1329.9867001329983;1363.475062101091;1406.662401023754;1692.534552997926;6647.50143739219;7504.212057733935;7775.727849525268;7779.533228006324;7982.521048737065;7814.843703125948;7862.174471680462;7886.921130788691;7892.486988345753;7892.684292628288;2181.814545763608;2198.8021078102965;2201.5486825200796;2203.7575866654656;2212.867227966319;2141.4860433496005;2168.2086038595958;2176.064292354287;2188.73735151782;2193.1665966932624;2123.3099242746093;2149.322963266574;2157.1371451419477;2161.7189765330486;2171.3703026122407;2077.740282464692;2105.87364758115;2109.272301056133;2112.4296439961277;2112.7887211278903;1996.46095554201;2070;2071.533904871407;2075.1180748181964;2083.645780217367;7729.188435214278;7787.8442431151625;7867.6459559319965;7870.05559332878;7887.605619718991;4298.527162012176;4400.273954797454;4404.207242696313;4424.778761061934;4428.977855110704;2822.2803185187645;2950.129711735043;2967.1840243932875;2978.3149875528534;2981.791274610778;960.078324808185;963.2582911158369;963.9807203855884;981.3474039763548;983.0857302708479;10598;10684.626038088629;10724.142068634568;10755.731106722316;10757.892421075767;3965.464662270614;4010.6189911958318;4067.247559201557;4158.2307273154565;4192.748435093904;8233.259006689426;8360.247577718035;8375.58122093905;8384.03583587883;8385.413021088587;4312.849050283225;4313;4315.007548263917;4317.56824317572;4318.071614602875;3632.9091772705365;3902.980485097613;3948.723589348718;3971.146203566247;4043.7775922324554;3298.0765385692043;3333.1667083229195;3443.2941247044296;3450.6721861423125;3463.0476618929492;1019.8776146862424;1047.8899715529913;1052.4527245832123;1056.2183983851894;1056.3239526702916;816.5223344343507;822.1819289806681;841.2512863551444;851.051078047972;861.2722249698994;5154.871128221825;5180.171172612364;5280.20796880471;5434.402215756264;5446.455354464601;838.5263472755396;876.9649214031494;881.4358810361374;903.701778413122;906.6917248135534;3511.3855075361707;3522.894313170609;3575.2313252650806;3645.9817700911326;3792.3363411402893;9342.859857102148;9660.95169524148;9743.269254805777;9877.703668889944;9890.109890109925;9534.427934323963;9645;9654.179394751385;9768.95115524432;9830.754231144136;7338.96330518344;7479.813004674817;7494.850102998019;7499.362554182955;7500.737474188377;5094;5143.691378517151;5153.639245252946;5182.948170518246;5251.684898906078;8620.956895215735;8795.296376290009;8832.205101540892;8964.775880603038;9089.272858171462;8838.911610883808;8986.101389861224;9257.120573545502;9331.860022099538;9337.859932100888;6221.47117495018;6722.462203023649;6902.378785909293;7118.572885626671;7126.857462850818;7418.146913105062;7601.961990190013;7614.885776713243;7640;7664.386849051951;6574.342565743579;6678.766243181465;6709.993500974892;6818.079559259635;6929.376356127973;6689.531732778853;6715.764948226789;6737.090492783412;6742.359475849787;6755.851505244022;6252.09344645033;6343.809685709436;6361.554691171574;6362.968185159045;6366.299707032315;4591.6556258281435;4601.240795268744;4688.156131896155;4708.482066972699;4712.764361781966;4583.702059366131;4597.35637010812;4613.215753322011;4617.861464156081;4908.803647854046 15:41:30 INFO - PROCESS | 5795 | |4;prototype.html;288.76321416438515;291.58303625815086;298.9103269019294;301.36562535862026;302.57488229038194;4455.175792478394;4575.787416334674;4619.884502887426;4657.743824089674;4700.506446823081;4454.599086082252;4566.49768525462;4572.885677858055;4592.933180068966;4597.839075632353;3678.4850120983083;3683.4843121963013;3684.299983003221;3691.483192353059;3702.8518859245596;3492.0920560654286;3566.5006899034024;3683.7605555638934;3691.427828686558;3692.7045836333164;3649.94525082124;3656.5063716398345;3660.8718694845684;3668.229671768932;3671.7613355131966;3636.1455058061597;3649.379605467064;3662.3773958427;3665.651763082503;3691.1141326081547;3257.5439438478634;3265.297960938386;3266.248762784573;3270.9509357359657;3297.9340413191603;4314.503832059322;4538.341940418653;4569.908601827978;4588.334691469751;4596.54034596541;4135.1729654069;4143.254214241435;4158.480189976253;4165.854195103157;4177.561356057631;4848.684835485682;4865.781039853215;4873.390826146731;4877;4885.853424397238;3320.1035720355612;3507.456344266617;3517.507548943124;3533.8586456541707;3541.4156664150382;4994.950050499521;5060.266261392115;5064.417591976896;5065.645404821664;5070.2141168119;3581.7492775505984;3583.1937813992163;3595.838187281526;3596.6403359663823;3606.585242697071;3619.588360539392;3632.473291372736;3643.3806252937065;3645;3646.58064322601;3916.921661566753;4105.281575724236;4125.257453658371;4152.941000044969;4156.314208155621;4809.326694262806;4822.758862056883;4826.7827947742435;4850.369451971221;4851.902961940812;1186.5728337798387;1239.535174309634;1249.956251531192;1287.7038281195191;1296.235221219485;900.9414388064755;901.3195037746459;922.4649703172144;933.290699068699;937.2970272295778;3879.342650284564;3903.336432806373;3912.491376121086;3923.5684074751753;3927.4501569780255;4399.1641588098155;4406.4357153210785;4426.587509807098;4451.577100175478;4472.418585583919;3861.3242682530445;3869.7484663497344;3873.767573945572;3879;3882.6216693074116;2340.005497663486;2368.874784477394;2458.1027924807318;2649.165512863442;2661.9600705989424;4820.927686084711;4823.083614113307;4824.155772739757;4842.709437433765;4851.805927762921;5130.410002849645;5137.640365174402;5144.3506594104065;5174.43081261061;5185.559227465707;4700.660311811117;4816.807327706852;4817.410254615968;4825.927611085766;4866.343043689128;4986.6509344347;4987.376577927759;5001.549860512571;5010.774515146754;5016.749162541932;4931.580815630711;4939.728314942569;4951.207806750903;4953.133201689618;4960.627952903619;4602.838900638461;4800.4479484859685;4830.396200474941;4852.636052296021;4852.927206091982;3541.9114522136124;3565.322588708137;3585.085803120292;3585.3725598020765;3591;4796.904061918812;4958.206686930074;4967.3045773591;4986.7257300848805;5000.974995125001;4674.859754207379;4811.109944660249;4846.079244943449;4875.219964805614;4879.170541007966;4849.393825771778;4859.125357435554;4861.611071114373;4867.318575399376;4915.5330243626795;4563;4811.092685460881;4828.348172996741;4835.6131509479865;4841.419029716457;4888.535589119028;4915.361003069578;4918.360613120271;4923.5814955729165;4933.925991110064;4805.639577031807;4807.495213002724;4837.089349706916;4857.149998750276;4880.92678609814 15:41:30 INFO - PROCESS | 5795 | |5;yui.html;635.63451015666;667.392672667872;696.3558708194919;725.9854802903942;735.4667865797296;11930.940345298282;13189.340532973361;13194.07641465098;13197.208167509956;13307.80038299425;13250;13269.469221231138;13307.201567905933;13377.331133443313;13381.665458363546;622.1725105609551;743.71738739279;749.3068911257093;767.5471471831618;776.6932061835572;762.6872982077349;772.1005029141044;774.1213722425032;774.8489044636298;774.9031371078615;817.9141190175035;819.2503858969036;821.4455242711176;822.7202751064636;822.9218224268685;657.5002997721718;776.6621519638975;800.9679612815538;817.0195765081896;817.6320655704927;581.0702875399327;581.7905554000599;581.8603535151575;582.1035605168037;582.1762206477829;11068.778624427547;11075.778484430346;11077.667669969915;11085.778284434347;11186.944065279622;12195.756084878252;12343.19769214998;12537.937310313482;13089.149205301625;13509.392077356542;11447.542098316057;11731.413429328499;11809.94095029528;11948.522059117711;12216.389180541026;4022.235775202702;4071.246819338432;4096.938545921814;4233.322668373076;4241.618254357092;63535;64373.67813160951;64499;65307;66961.33038669649;9560.665376711782;9817.263705222136;9933.205343572497;10236.078752912274;10917.39954302521;18229.54426139357;18610.16254268561;18623.90688046551;18626.06869656512;18659.626807464047;6376.266729326187;6504.349565043458;6648.335166483313;6700.095487109178;6756.155480564929;3622.4385220290897;3647.908802279911;3688.1517251032415;3701.685356744653;3708.072981754561;2897.5219088850104;3000.069978306732;3006.804557703786;3008.8044277121917;3015.20097174249;483.8185680369861;486.9147899117711;489.70372924380234;502.87930896584555;503.189864318447;519.2548692626094;531.5030446532427;534.7700488789858;570.0195663458844;577.2293987526615;7236.746713864989;7325.340719335285;8621.61202745878;9058.592363343665;9078.636854525877;535.8392482255308;562.3224015061841;615.9568830181844;621.5369549685423;636.4208570201188;3706.314331848617;4008.2584721826556;4067.613576710208;4120.526139493936;4191.7275377101005;75041;78526.60736696394;78668.60665696635;90196;95343;75799;77820;77969;77977.61011194908;78429.60785196038;64484.35515644783;66159;67192.32807671958;68803.65598171977;69333.65333173302;8064.596770161351;8441.915580844114;8611.569421529024;8658.826823463622;8701.695440659547;41956.3706544396;42147;42231;42504.36243456412;43300;35688.464673029404;39252.803735981135;39320.80339598284;39970;40149.397759033054;10402.947985260025;10427.374357538572;10459.110975567162;10497.895021049997;10655.094316982833;11907.345096019802;12012.099092568267;12235.816462753244;12269;12947;11108.389038602954;11328.546858125583;11356.148288878532;11608.767824643292;11648.359340236368;11011.119090661196;11722.423552666896;11723.41382930867;11758.647240582795;11855.17013809025;11168.441577921234;11295.435228238719;11685.707857303636;11985.760284794429;12017.63947081589;9480.668176614061;9540.95229523848;9776.657816976372;9819.85270220933;9906.356086854332;9313.767155821159;9738.220942324739;9775.511224438891;9827.26295527824;9836 15:41:30 INFO - PROCESS | 5795 | __end_tp_report 15:41:30 INFO - PROCESS | 5795 | __start_cc_report 15:41:30 INFO - PROCESS | 5795 | _x_x_mozilla_cycle_collect,1039 15:41:30 INFO - PROCESS | 5795 | __end_cc_report 15:41:30 INFO - PROCESS | 5795 | __startTimestamp1471905690322__endTimestamp 15:41:30 INFO - PROCESS | 5795 | 15:41:30 INFO - PROCESS | 5795 | ------- Summary: start ------- 15:41:30 INFO - PROCESS | 5795 | Number of tests: 6 15:41:30 INFO - PROCESS | 5795 | 15:41:30 INFO - PROCESS | 5795 | [#0] dojo.html Cycles:165 Average:4622.79 Median:4784.31 stddev:520.79 (10.9%) stddev-sans-first:496.80 15:41:30 INFO - PROCESS | 5795 | Values: 2567.7 2614.5 2619.8 2624.7 2630.5 4547.8 4615.1 4642.7 4728.7 4733.4 4631.1 4679.2 4680.5 4696.8 4778.0 4096.2 4315.0 4333.7 4346.5 4382.8 4330.5 4346.6 4362.1 4376.1 4384.6 4188.7 4211.8 4246.4 4252.5 4324.7 4288.5 4295.0 4297.1 4300.8 4302.2 4262.2 4267.0 4276.1 4288.6 4295.6 4558.9 4627.6 4670.6 4708.6 4723.1 5070.7 5075.3 5082.0 5082.2 5082.5 5094.8 5101.1 5104.6 5111.2 5112.9 4542.7 4547.3 4587.2 4590.3 4754.0 5074.7 5089.9 5089.9 5096.0 5097.5 4880.1 4883.1 4883.8 4885.5 4888.2 4840.3 4909.1 4949.1 4968.9 4981.4 4946.7 4953.7 4954.7 4974.6 4985.6 4973.8 4983.5 4985.9 4986.7 4991.4 3781.5 3826.8 3829.1 3831.5 3904.3 3448.0 3475.6 3477.1 3488.7 3499.7 4397.9 4416.8 4417.6 4430.0 4433.8 4862.1 4918.9 5034.9 5041.7 5055.2 4322.0 4361.5 4372.1 4388.5 4398.7 4507.7 4532.5 4534.3 4544.4 4549.9 4647.3 4757.0 4784.3 4823.3 4825.1 5034.5 5101.2 5119.6 5120.7 5125.7 4870.0 4872.8 4877.2 4880.8 4889.3 4811.0 5034.5 5035.3 5036.4 5038.4 4967.2 4981.3 4998.3 5066.7 5067.9 4931.1 4932.5 4934.1 4937.3 4940.8 4770.0 4939.3 4959.0 4962.8 4965.3 4755.3 4770.4 4795.2 4864.6 4877.1 4959.6 4967.1 4972.9 4974.6 4989.3 4703.2 4766.4 4857.1 4868.8 4875.0 15:41:30 INFO - PROCESS | 5795 | 15:41:30 INFO - PROCESS | 5795 | [#1] ext.html Cycles:180 Average:48011.60 Median:36571.63 stddev:89751.89 (245.4%) stddev-sans-first:89932.93 15:41:30 INFO - PROCESS | 5795 | Values: 550.4 556.8 571.6 573.2 574.1 38155.8 39666.2 39706.0 43495.6 43541.3 40255.2 41063.8 41087.4 41116.6 41162.8 7570.9 7781.3 7787.4 7788.4 7790.1 7685.3 7790.0 7791.9 7794.6 7796.5 7724.5 7731.7 7762.1 7836.3 7840.7 7645.3 7691.2 7700.2 7711.7 7732.4 7409.3 7497.9 7498.5 7504.5 7506.1 37697.0 38353.0 38465.4 38503.8 38509.2 2997.2 3018.2 3077.0 3189.2 3230.7 29126.4 29186.9 29280.3 30198.0 30355.7 3101.8 3117.9 3155.5 3169.1 3191.2 243900.0 244206.0 244236.0 244761.0 244847.0 31108.5 50925.7 51272.7 51304.2 51337.2 31664.4 33573.2 33739.0 33760.7 33795.2 13706.3 13817.6 13833.0 13849.8 13854.9 11396.5 11580.5 11589.1 11589.7 11596.4 7697.3 7798.1 7828.4 7860.6 7932.4 3090.9 3113.5 3114.1 3138.3 3140.8 792.3 858.7 885.0 959.4 960.5 26169.5 27642.6 28141.6 28155.4 28272.7 960.4 976.5 988.2 1014.6 1086.9 14124.0 14754.3 14890.7 15130.7 15196.6 387229.0 391384.0 391611.0 392071.0 392384.0 49499.0 50051.7 50083.5 50123.0 50202.5 1090.8 1098.4 1142.7 1193.5 1201.0 25104.6 29421.4 29510.6 29519.4 30137.7 45977.8 47334.5 47336.5 51639.0 51655.5 362528.0 365499.0 366432.0 366595.0 367179.0 32170.4 36331.6 36554.8 38385.6 38792.4 41220.4 41897.8 42246.1 42720.6 43140.8 35565.6 35724.0 35847.6 35874.5 36588.5 35900.3 36149.3 36385.8 36411.8 36520.8 31236.8 31412.7 31880.0 35430.9 36264.8 22982.0 33693.7 33737.5 36285.5 36819.8 25197.7 25300.1 25792.2 25906.6 26195.0 15:41:30 INFO - PROCESS | 5795 | 15:41:30 INFO - PROCESS | 5795 | [#2] jquery.html Cycles:180 Average:23246.77 Median:40396.39 stddev:62341.65 (154.3%) stddev-sans-first:62492.80 15:41:30 INFO - PROCESS | 5795 | Values: 339.7 356.3 358.8 361.9 374.4 12660.4 16032.9 16450.1 16543.8 17560.6 14265.0 14285.7 14376.0 14413.0 14446.6 4514.9 4933.5 5124.1 5126.7 5202.6 5165.8 5220.8 5249.2 5255.3 5258.1 4931.8 5045.6 5223.7 5229.8 5234.8 5033.7 5070.7 5071.2 5072.6 5076.7 4397.8 4518.1 4523.1 4523.1 4613.8 20643.7 20753.3 20796.2 21019.5 21043.6 1936.3 1948.1 2067.8 2208.7 2481.5 13132.5 13148.9 13235.7 13262.9 13893.7 1765.4 1773.8 1783.5 1797.8 1799.8 68906.3 69133.0 69388.0 70290.3 70722.6 9182.6 9287.2 9401.5 9588.3 9859.2 12976.4 12977.1 12992.4 12994.4 13008.7 7091.6 7375.5 7424.8 7644.5 7650.0 7063.2 7184.1 7289.6 7353.9 7369.7 5825.6 5975.8 5977.8 6024.7 6225.6 1669.7 1728.1 1766.2 1767.8 1773.9 821.3 850.6 862.0 889.2 910.2 16656.5 16762.4 17293.2 17727.1 17734.0 840.3 851.7 917.0 936.8 968.4 8768.3 10019.9 10137.8 10160.3 10363.7 375498.0 377688.0 377697.0 378024.0 391709.0 40171.6 40621.2 51148.5 51317.0 71095.6 900.9 928.9 953.2 953.4 953.9 15329.6 15451.9 15653.4 16196.4 16282.9 35563.3 36278.3 36911.6 37122.3 37349.0 45725.0 47027.5 47041.3 47310.0 47584.3 7337.2 7403.0 7555.3 7674.8 7756.0 11722.8 11857.5 11873.6 12331.3 12434.3 10387.7 10471.7 10609.5 10643.6 10685.8 10194.1 10337.7 10487.1 10593.3 10685.0 9798.0 10105.5 10328.9 10770.4 10990.7 7401.3 7628.2 7645.8 7654.5 7691.1 9357.2 9858.2 9882.7 10063.1 10418.9 15:41:30 INFO - PROCESS | 5795 | 15:41:30 INFO - PROCESS | 5795 | [#3] mootools.html Cycles:180 Average:5100.57 Median:5217.32 stddev:2888.81 (55.4%) stddev-sans-first:2882.40 15:41:30 INFO - PROCESS | 5795 | Values: 1247.9 1330.0 1363.5 1406.7 1692.5 6647.5 7504.2 7775.7 7779.5 7982.5 7814.8 7862.2 7886.9 7892.5 7892.7 2181.8 2198.8 2201.5 2203.8 2212.9 2141.5 2168.2 2176.1 2188.7 2193.2 2123.3 2149.3 2157.1 2161.7 2171.4 2077.7 2105.9 2109.3 2112.4 2112.8 1996.5 2070.0 2071.5 2075.1 2083.6 7729.2 7787.8 7867.6 7870.1 7887.6 4298.5 4400.3 4404.2 4424.8 4429.0 2822.3 2950.1 2967.2 2978.3 2981.8 960.1 963.3 964.0 981.3 983.1 10598.0 10684.6 10724.1 10755.7 10757.9 3965.5 4010.6 4067.2 4158.2 4192.7 8233.3 8360.2 8375.6 8384.0 8385.4 4312.8 4313.0 4315.0 4317.6 4318.1 3632.9 3903.0 3948.7 3971.1 4043.8 3298.1 3333.2 3443.3 3450.7 3463.0 1019.9 1047.9 1052.5 1056.2 1056.3 816.5 822.2 841.3 851.1 861.3 5154.9 5180.2 5280.2 5434.4 5446.5 838.5 877.0 881.4 903.7 906.7 3511.4 3522.9 3575.2 3646.0 3792.3 9342.9 9661.0 9743.3 9877.7 9890.1 9534.4 9645.0 9654.2 9769.0 9830.8 7339.0 7479.8 7494.9 7499.4 7500.7 5094.0 5143.7 5153.6 5182.9 5251.7 8621.0 8795.3 8832.2 8964.8 9089.3 8838.9 8986.1 9257.1 9331.9 9337.9 6221.5 6722.5 6902.4 7118.6 7126.9 7418.1 7602.0 7614.9 7640.0 7664.4 6574.3 6678.8 6710.0 6818.1 6929.4 6689.5 6715.8 6737.1 6742.4 6755.9 6252.1 6343.8 6361.6 6363.0 6366.3 4591.7 4601.2 4688.2 4708.5 4712.8 4583.7 4597.4 4613.2 4617.9 4908.8 15:41:30 INFO - PROCESS | 5795 | 15:41:30 INFO - PROCESS | 5795 | [#4] prototype.html Cycles:180 Average:4020.59 Median:4568.20 stddev:1160.87 (25.4%) stddev-sans-first:1129.83 15:41:30 INFO - PROCESS | 5795 | Values: 288.8 291.6 298.9 301.4 302.6 4455.2 4575.8 4619.9 4657.7 4700.5 4454.6 4566.5 4572.9 4592.9 4597.8 3678.5 3683.5 3684.3 3691.5 3702.9 3492.1 3566.5 3683.8 3691.4 3692.7 3649.9 3656.5 3660.9 3668.2 3671.8 3636.1 3649.4 3662.4 3665.7 3691.1 3257.5 3265.3 3266.2 3271.0 3297.9 4314.5 4538.3 4569.9 4588.3 4596.5 4135.2 4143.3 4158.5 4165.9 4177.6 4848.7 4865.8 4873.4 4877.0 4885.9 3320.1 3507.5 3517.5 3533.9 3541.4 4995.0 5060.3 5064.4 5065.6 5070.2 3581.7 3583.2 3595.8 3596.6 3606.6 3619.6 3632.5 3643.4 3645.0 3646.6 3916.9 4105.3 4125.3 4152.9 4156.3 4809.3 4822.8 4826.8 4850.4 4851.9 1186.6 1239.5 1250.0 1287.7 1296.2 900.9 901.3 922.5 933.3 937.3 3879.3 3903.3 3912.5 3923.6 3927.5 4399.2 4406.4 4426.6 4451.6 4472.4 3861.3 3869.7 3873.8 3879.0 3882.6 2340.0 2368.9 2458.1 2649.2 2662.0 4820.9 4823.1 4824.2 4842.7 4851.8 5130.4 5137.6 5144.4 5174.4 5185.6 4700.7 4816.8 4817.4 4825.9 4866.3 4986.7 4987.4 5001.5 5010.8 5016.7 4931.6 4939.7 4951.2 4953.1 4960.6 4602.8 4800.4 4830.4 4852.6 4852.9 3541.9 3565.3 3585.1 3585.4 3591.0 4796.9 4958.2 4967.3 4986.7 5001.0 4674.9 4811.1 4846.1 4875.2 4879.2 4849.4 4859.1 4861.6 4867.3 4915.5 4563.0 4811.1 4828.3 4835.6 4841.4 4888.5 4915.4 4918.4 4923.6 4933.9 4805.6 4807.5 4837.1 4857.1 4880.9 15:41:30 INFO - PROCESS | 5795 | 15:41:30 INFO - PROCESS | 5795 | [#5] yui.html Cycles:180 Average:16282.56 Median:503.03 stddev:22349.92 (4443.0%) stddev-sans-first:22381.74 15:41:30 INFO - PROCESS | 5795 | Values: 635.6 667.4 696.4 726.0 735.5 11930.9 13189.3 13194.1 13197.2 13307.8 13250.0 13269.5 13307.2 13377.3 13381.7 622.2 743.7 749.3 767.5 776.7 762.7 772.1 774.1 774.8 774.9 817.9 819.3 821.4 822.7 822.9 657.5 776.7 801.0 817.0 817.6 581.1 581.8 581.9 582.1 582.2 11068.8 11075.8 11077.7 11085.8 11186.9 12195.8 12343.2 12537.9 13089.1 13509.4 11447.5 11731.4 11809.9 11948.5 12216.4 4022.2 4071.2 4096.9 4233.3 4241.6 63535.0 64373.7 64499.0 65307.0 66961.3 9560.7 9817.3 9933.2 10236.1 10917.4 18229.5 18610.2 18623.9 18626.1 18659.6 6376.3 6504.3 6648.3 6700.1 6756.2 3622.4 3647.9 3688.2 3701.7 3708.1 2897.5 3000.1 3006.8 3008.8 3015.2 483.8 486.9 489.7 502.9 503.2 519.3 531.5 534.8 570.0 577.2 7236.7 7325.3 8621.6 9058.6 9078.6 535.8 562.3 616.0 621.5 636.4 3706.3 4008.3 4067.6 4120.5 4191.7 75041.0 78526.6 78668.6 90196.0 95343.0 75799.0 77820.0 77969.0 77977.6 78429.6 64484.4 66159.0 67192.3 68803.7 69333.7 8064.6 8441.9 8611.6 8658.8 8701.7 41956.4 42147.0 42231.0 42504.4 43300.0 35688.5 39252.8 39320.8 39970.0 40149.4 10402.9 10427.4 10459.1 10497.9 10655.1 11907.3 12012.1 12235.8 12269.0 12947.0 11108.4 11328.5 11356.1 11608.8 11648.4 11011.1 11722.4 11723.4 11758.6 11855.2 11168.4 11295.4 11685.7 11985.8 12017.6 9480.7 9541.0 9776.7 9819.9 9906.4 9313.8 9738.2 9775.5 9827.3 9836.0 15:41:30 INFO - PROCESS | 5795 | -------- Summary: end -------- 15:41:30 INFO - PROCESS | 5795 | 15:41:31 INFO - TEST-INFO | 5795: exit 0 15:41:31 INFO - TEST-OK | dromaeo_css | took 1121061ms 15:41:31 INFO - TEST-START | kraken 15:41:31 INFO - operating with platform_type : linux_ 15:41:31 INFO - Initialising browser for kraken test... 15:41:31 INFO - TEST-INFO | started process 6096 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9KI_CW/profile http://localhost:53159/getInfo.html) 15:41:35 INFO - PROCESS | 6096 | __metrics Screen width/height:1600/1200 15:41:35 INFO - PROCESS | 6096 | colorDepth:24 15:41:35 INFO - PROCESS | 6096 | Browser inner width/height: 1024/697 15:41:35 INFO - PROCESS | 6096 | __metrics 15:41:36 INFO - PROCESS | 6096 | 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" 15:41:36 INFO - PROCESS | 6096 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:41:37 INFO - PROCESS | 6096 | console.error: PushService: 15:41:37 INFO - PROCESS | 6096 | stateChangeProcessEnqueue: Error transitioning state 15:41:37 INFO - PROCESS | 6096 | UnknownError 15:41:37 INFO - TEST-INFO | 6096: exit 0 15:41:37 INFO - Browser initialized. 15:41:37 INFO - Running cycle 1/1 for kraken test... 15:41:37 INFO - TEST-INFO | started process 6176 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9KI_CW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:41:50 INFO - PROCESS | 6176 | RSS: Main: 175923200 15:41:50 INFO - PROCESS | 6176 | 15:41:52 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53159/tests/kraken/driver.html?testName=audio-beat-detection) 15:41:53 INFO - PROCESS | 6176 | RSS: Main: 236634112 15:41:53 INFO - PROCESS | 6176 | 15:41:56 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53159/tests/kraken/driver.html?testName=audio-dft) 15:41:57 INFO - PROCESS | 6176 | RSS: Main: 227004416 15:41:57 INFO - PROCESS | 6176 | 15:42:00 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53159/tests/kraken/driver.html?testName=audio-fft) 15:42:01 INFO - PROCESS | 6176 | RSS: Main: 301592576 15:42:01 INFO - PROCESS | 6176 | 15:42:03 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53159/tests/kraken/driver.html?testName=audio-oscillator) 15:42:03 INFO - PROCESS | 6176 | RSS: Main: 210190336 15:42:03 INFO - PROCESS | 6176 | 15:42:06 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53159/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:42:06 INFO - PROCESS | 6176 | RSS: Main: 215396352 15:42:06 INFO - PROCESS | 6176 | 15:42:10 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53159/tests/kraken/driver.html?testName=imaging-darkroom) 15:42:10 INFO - PROCESS | 6176 | RSS: Main: 250929152 15:42:10 INFO - PROCESS | 6176 | 15:42:14 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53159/tests/kraken/driver.html?testName=imaging-desaturate) 15:42:14 INFO - PROCESS | 6176 | RSS: Main: 244232192 15:42:14 INFO - PROCESS | 6176 | 15:42:17 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53159/tests/kraken/driver.html?testName=json-parse-financial) 15:42:18 INFO - PROCESS | 6176 | RSS: Main: 247283712 15:42:18 INFO - PROCESS | 6176 | 15:42:20 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53159/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:42:20 INFO - PROCESS | 6176 | RSS: Main: 211406848 15:42:20 INFO - PROCESS | 6176 | 15:42:22 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53159/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:42:23 INFO - PROCESS | 6176 | RSS: Main: 219947008 15:42:23 INFO - PROCESS | 6176 | 15:42:25 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53159/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:42:26 INFO - PROCESS | 6176 | RSS: Main: 222900224 15:42:26 INFO - PROCESS | 6176 | 15:42:29 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53159/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:42:29 INFO - PROCESS | 6176 | RSS: Main: 218619904 15:42:29 INFO - PROCESS | 6176 | 15:42:32 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53159/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:42:33 INFO - PROCESS | 6176 | RSS: Main: 221802496 15:42:33 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | Cycle 1(1): loaded http://localhost:53159/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53159/tests/kraken/driver.html?testName=ai-astar) 15:42:35 INFO - PROCESS | 6176 | RSS: Main: 273010688 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | __start_tp_report 15:42:35 INFO - PROCESS | 6176 | _x_x_mozilla_page_load 15:42:35 INFO - PROCESS | 6176 | _x_x_mozilla_page_load_details 15:42:35 INFO - PROCESS | 6176 | |i|pagename|runs| 15:42:35 INFO - PROCESS | 6176 | |0;ai-astar;101;114;111;116;114;114;120;113;113;112 15:42:35 INFO - PROCESS | 6176 | |1;audio-beat-detection;147;124;157;191;123;159;152;123;202;135 15:42:35 INFO - PROCESS | 6176 | |2;audio-dft;162;162;164;160;161;162;161;161;163;161 15:42:35 INFO - PROCESS | 6176 | |3;audio-fft;76;75;126;116;75;76;76;121;113;77 15:42:35 INFO - PROCESS | 6176 | |4;audio-oscillator;87;86;160;86;86;87;150;86;87;150 15:42:35 INFO - PROCESS | 6176 | |5;imaging-gaussian-blur;117;116;117;117;116;117;117;117;116;116 15:42:35 INFO - PROCESS | 6176 | |6;imaging-darkroom;147;147;146;146;146;147;147;147;146;147 15:42:35 INFO - PROCESS | 6176 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;94 15:42:35 INFO - PROCESS | 6176 | |8;json-parse-financial;65;67;67;67;67;66;66;65;66;66 15:42:35 INFO - PROCESS | 6176 | |9;json-stringify-tinderbox;66;67;66;66;66;66;66;67;66;65 15:42:35 INFO - PROCESS | 6176 | |10;stanford-crypto-aes;82;82;84;81;85;81;81;83;82;90 15:42:35 INFO - PROCESS | 6176 | |11;stanford-crypto-ccm;143;133;134;139;134;141;155;132;156;134 15:42:35 INFO - PROCESS | 6176 | |12;stanford-crypto-pbkdf2;174;181;173;173;173;174;171;169;173;168 15:42:35 INFO - PROCESS | 6176 | |13;stanford-crypto-sha256-iterative;69;71;72;69;67;69;67;67;67;67 15:42:35 INFO - PROCESS | 6176 | __end_tp_report 15:42:35 INFO - PROCESS | 6176 | __start_cc_report 15:42:35 INFO - PROCESS | 6176 | _x_x_mozilla_cycle_collect,1970 15:42:35 INFO - PROCESS | 6176 | __end_cc_report 15:42:35 INFO - PROCESS | 6176 | __startTimestamp1471905755278__endTimestamp 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | ------- Summary: start ------- 15:42:35 INFO - PROCESS | 6176 | Number of tests: 14 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:4.83 (4.2%) stddev-sans-first:2.62 15:42:35 INFO - PROCESS | 6176 | Values: 101.0 114.0 111.0 116.0 114.0 114.0 120.0 113.0 113.0 112.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#1] audio-beat-detection Cycles:10 Average:151.30 Median:154.50 stddev:27.63 (17.9%) stddev-sans-first:29.26 15:42:35 INFO - PROCESS | 6176 | Values: 147.0 124.0 157.0 191.0 123.0 159.0 152.0 123.0 202.0 135.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.16 (0.7%) stddev-sans-first:1.22 15:42:35 INFO - PROCESS | 6176 | Values: 162.0 162.0 164.0 160.0 161.0 162.0 161.0 161.0 163.0 161.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#3] audio-fft Cycles:10 Average:93.10 Median:75.50 stddev:22.54 (29.9%) stddev-sans-first:23.04 15:42:35 INFO - PROCESS | 6176 | Values: 76.0 75.0 126.0 116.0 75.0 76.0 76.0 121.0 113.0 77.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#4] audio-oscillator Cycles:10 Average:106.50 Median:86.00 stddev:32.44 (37.7%) stddev-sans-first:33.63 15:42:35 INFO - PROCESS | 6176 | Values: 87.0 86.0 160.0 86.0 86.0 87.0 150.0 86.0 87.0 150.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 15:42:35 INFO - PROCESS | 6176 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 15:42:35 INFO - PROCESS | 6176 | Values: 147.0 147.0 146.0 146.0 146.0 147.0 147.0 147.0 146.0 147.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:42:35 INFO - PROCESS | 6176 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:66.50 stddev:0.79 (1.2%) stddev-sans-first:0.71 15:42:35 INFO - PROCESS | 6176 | Values: 65.0 67.0 67.0 67.0 67.0 66.0 66.0 65.0 66.0 66.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 15:42:35 INFO - PROCESS | 6176 | Values: 66.0 67.0 66.0 66.0 66.0 66.0 66.0 67.0 66.0 65.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:82.50 stddev:2.77 (3.4%) stddev-sans-first:2.91 15:42:35 INFO - PROCESS | 6176 | Values: 82.0 82.0 84.0 81.0 85.0 81.0 81.0 83.0 82.0 90.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#11] stanford-crypto-ccm Cycles:10 Average:140.10 Median:140.00 stddev:8.90 (6.4%) stddev-sans-first:9.38 15:42:35 INFO - PROCESS | 6176 | Values: 143.0 133.0 134.0 139.0 134.0 141.0 155.0 132.0 156.0 134.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:173.00 stddev:3.51 (2.0%) stddev-sans-first:3.70 15:42:35 INFO - PROCESS | 6176 | Values: 174.0 181.0 173.0 173.0 173.0 174.0 171.0 169.0 173.0 168.0 15:42:35 INFO - PROCESS | 6176 | 15:42:35 INFO - PROCESS | 6176 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:1.84 (2.7%) stddev-sans-first:1.94 15:42:35 INFO - PROCESS | 6176 | Values: 69.0 71.0 72.0 69.0 67.0 69.0 67.0 67.0 67.0 67.0 15:42:35 INFO - PROCESS | 6176 | -------- Summary: end -------- 15:42:35 INFO - PROCESS | 6176 | 15:42:36 INFO - TEST-INFO | 6176: exit 0 15:42:36 INFO - TEST-OK | kraken | took 65255ms 15:42:36 INFO - SUITE-END | took 1186s 15:42:36 INFO - Completed test suite (00:19:46) 15:42:36 INFO - javascript benchmark 15:42:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2567.666203393559, 2614.4901744159883, 2619.764221220092, 2624.6850377954647, 2630.539655560278, 4547.795349209286, 4615.076984603084, 4642.651801114917, 4728.692634978728, 4733.431988161421, 4631.143238500879, 4679.204535229019, 4680.461746899107, 4696.835610753625, 4778.0, 4096.180763847227, 4314.978425107885, 4333.739975601474, 4346.478422589294, 4382.802773875167, 4330.458692663401, 4346.565343465644, 4362.149380870732, 4376.059147283333, 4384.62730667892, 4188.68584856137, 4211.831526738927, 4246.447961764981, 4252.468441444819, 4324.654027677778, 4288.549702281278, 4294.957050429487, 4297.05464797744, 4300.784960751981, 4302.247106756305, 4262.232798096373, 4266.957330426687, 4276.059266961263, 4288.635465985395, 4295.591918767744, 4558.931616025762, 4627.5603817637275, 4670.6497012724185, 4708.623310135181, 4723.102610503994, 5070.72111033893, 5075.314832497639, 5082.0, 5082.186850104003, 5082.491750824925, 5094.77073531692, 5101.107306221434, 5104.617153713486, 5111.207762796773, 5112.923306150411, 4542.682012259176, 4547.3406356078185, 4587.243104887723, 4590.311453282035, 4753.952460475421, 5074.695518268915, 5089.923651145235, 5089.949100509021, 5096.006278775603, 5097.541221290102, 4880.145974454527, 4883.145449546315, 4883.8290659826735, 4885.5358740919555, 4888.2178851383605, 4840.273958906122, 4909.067277217388, 4949.084419382426, 4968.8757781055765, 4981.427135879348, 4946.703197808143, 4953.72754498506, 4954.727489988085, 4974.626902982291, 4985.626078044161, 4973.801047958041, 4983.526564976321, 4985.90028199434, 4986.675866068694, 4991.401031876198, 3781.489498917611, 3826.7895265760094, 3829.1384438501113, 3831.501904752364, 3904.316744569746, 3447.9827600861836, 3475.600305964796, 3477.1480987158307, 3488.651134886542, 3499.720022398202, 4397.890052748707, 4416.757078360657, 4417.602415782595, 4430.025394413224, 4433.844815431445, 4862.124817532878, 4918.8770280743265, 5034.874128146826, 5041.697498150123, 5055.191169412949, 4321.978390108029, 4361.542038086019, 4372.103718737651, 4388.539203383662, 4398.670099742532, 4507.729536227836, 4532.546745325441, 4534.342520334533, 4544.386507821402, 4549.909001819945, 4647.349371088118, 4757.048590281888, 4784.306275590089, 4823.252395878644, 4825.059113472839, 5034.546890779848, 5101.158308879142, 5119.616028797781, 5120.667156634807, 5125.666831655931, 4869.95130048695, 4872.829450969199, 4877.219644856806, 4880.78036488366, 4889.315495830658, 4811.0, 5034.471380505141, 5035.320231768665, 5036.395632523974, 5038.395392552917, 4967.205247160437, 4981.277714731271, 4998.2502624606595, 5066.721330326867, 5067.949320506896, 4931.087748766489, 4932.506749325183, 4934.111859865258, 4937.284093806306, 4940.7776650051555, 4770.0, 4939.259111133215, 4958.950410495849, 4962.751862406793, 4965.28003439492, 4755.262934245197, 4770.427548694179, 4795.184818580919, 4864.562189402971, 4877.073356062337, 4959.60323174138, 4967.130752118437, 4972.8508144755715, 4974.626902982219, 4989.326440930428, 4703.176944034849, 4766.380370551945, 4857.149998750134, 4868.829590964299, 4875.024995001085], "value": 4587.351856698565, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.3533348315729, 556.7745063249383, 571.6198727845982, 573.2347316332696, 574.055678409017, 38155.80922095389, 39666.206675866466, 39706.0, 43495.56504434955, 43541.34687979675, 40255.19489610206, 41063.79468102656, 41087.38368924461, 41116.58883411165, 41162.7941860291, 7570.886436703454, 7781.2996830285265, 7787.3770098392, 7788.415868809833, 7790.104138024115, 7685.269899359552, 7790.026246719161, 7791.883121753178, 7794.610269486531, 7796.532208067534, 7724.536527808322, 7731.652075656581, 7762.107357653857, 7836.333911617519, 7840.686372545091, 7645.273698998614, 7691.230876912292, 7700.152983171846, 7711.652975616111, 7732.381409487228, 7409.296116868889, 7497.925020749777, 7498.512596681198, 7504.512206706547, 7506.09926808781, 37697.0, 38353.0, 38465.42301865474, 38503.8074809627, 38509.229815403814, 2997.190758495194, 3018.1850900257027, 3076.9846150769104, 3189.1708155879405, 3230.6607806180245, 29126.41747165066, 29186.854065729534, 29280.267993300124, 30198.0, 30355.69644303573, 3101.805804765159, 3117.9399004338406, 3155.5266709993457, 3169.0968074098773, 3191.2341038150907, 243900.0, 244205.99999999822, 244236.0, 244761.0, 244847.0, 31108.533371999438, 50925.745371272904, 51272.74363628158, 51304.23043654348, 51337.229941550904, 31664.36671266562, 33573.16067098342, 33739.0, 33760.66239337575, 33795.15512112217, 13706.314684265946, 13817.585472435641, 13833.0, 13849.792253116211, 13854.930725346308, 11396.487158077907, 11580.47887845049, 11589.130815188895, 11589.710257243636, 11596.362200079077, 7697.268759467842, 7798.103218129874, 7828.373730101692, 7860.5676687782725, 7932.444728868923, 3090.8763649454218, 3113.486274764684, 3114.1436105070834, 3138.3095718941795, 3140.795848269855, 792.3067316098413, 858.7209157023992, 884.964601415949, 959.3764053365369, 960.4813400763649, 26169.476610467686, 27642.585361219197, 28141.57787633187, 28155.43689126247, 28272.71727282701, 960.3709570231509, 976.472704739447, 988.1699372527112, 1014.5890914179769, 1086.9130469562417, 14124.0, 14754.336054877342, 14890.702185956437, 15130.697386052218, 15196.620084497976, 387229.0, 391384.0, 391611.0, 392071.0, 392384.0, 49499.0, 50051.74974125179, 50083.499165008616, 50123.0, 50202.49797502051, 1090.7709381030072, 1098.4343063322394, 1142.6743378137357, 1193.5404869125275, 1200.975980480403, 25104.62343064819, 29421.411571768873, 29510.55734164032, 29519.409611808074, 30137.69862301349, 45977.77011114923, 47334.52665473439, 47336.526634733214, 51639.0, 51655.48344516507, 362528.0, 365499.0, 366432.0, 366595.0, 367179.0, 32170.35659286754, 36331.63668363282, 36554.8172259137, 38385.616143838204, 38792.41811372888, 41220.38169427401, 41897.79051104847, 42246.14169370562, 42720.572794272906, 43140.78429607832, 35565.644343557266, 35724.0, 35847.64152358443, 35874.46188307125, 36588.451173232956, 35900.28199436049, 36149.27701445904, 36385.818070909474, 36411.81794091013, 36520.82709020057, 31236.843815780776, 31412.68587314189, 31880.0, 35430.92544357432, 36264.81867590645, 22982.0, 33693.66306336905, 33737.493937590465, 36285.45571816372, 36819.81590092032, 25197.748022520274, 25300.11449599255, 25792.226233213034, 25906.611400828624, 26195.0], "value": 16682.875564748378, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.6603396603397, 356.2785359646717, 358.7811435024635, 361.9457081437784, 374.3561074951083, 12660.43028063737, 16032.91983540082, 16450.095244761535, 16543.751843722323, 17560.560985975357, 14265.0, 14285.714285714279, 14375.993680442372, 14412.99109062366, 14446.5666030019, 4514.864554063367, 4933.45731969483, 5124.103281925667, 5126.692398456086, 5202.557782588485, 5165.81919632813, 5220.791168353261, 5249.1601343785005, 5255.316808814849, 5258.106121959276, 4931.753412329387, 5045.596352291826, 5223.686578805284, 5229.790808367679, 5234.790608375661, 5033.748312584356, 5070.6957582545165, 5071.239314102877, 5072.568831649332, 5076.74616269185, 4397.846075387378, 4518.073794872043, 4523.050159466516, 4523.117991991563, 4613.769311534444, 20643.69034464484, 20753.27363542284, 20796.18422299946, 21019.474513137142, 21043.5791284175, 1936.331965471924, 1948.0649288341533, 2067.824234940017, 2208.712867327253, 2481.528509583173, 13132.474701011948, 13148.934255328662, 13235.669108272274, 13262.933685331607, 13893.652658683513, 1765.4174122539532, 1773.7871455425302, 1783.545195975031, 1797.7573027641363, 1799.7570328005813, 68906.31093689048, 69133.0, 69388.0, 70290.29709702889, 70722.64638676825, 9182.586783594754, 9287.164155225926, 9401.529923503798, 9588.280878934107, 9859.161971232526, 12976.416061277265, 12977.091603587658, 12992.415341309665, 12994.415251313525, 13008.739825203444, 7091.60996145217, 7375.483716139926, 7424.777256682308, 7644.541327520366, 7650.0, 7063.1524217094275, 7184.1019872515935, 7289.599072051088, 7353.889691654629, 7369.7052117915755, 5825.592208545361, 5975.7609695612555, 5977.774556215965, 6024.668643224577, 6225.564210505221, 1669.7495375693743, 1728.1272957156555, 1766.2140347545476, 1767.7878654561534, 1773.911304434773, 821.252660079325, 850.5577099908013, 861.96983105591, 889.1908363389284, 910.1808372464834, 16656.50030499087, 16762.413315533897, 17293.221805018806, 17727.05535408279, 17734.0, 840.3403328387309, 851.7104184577149, 916.9908300917039, 936.7611259129014, 968.4334664221368, 8768.342374321952, 10019.949900250598, 10137.783465983981, 10160.33957792741, 10363.740906477398, 375498.0, 377688.0, 377697.0, 378024.0, 391709.0, 40171.59828401737, 40621.18757624831, 51148.48851511512, 51316.97366052658, 71095.6445217781, 900.9054049324726, 928.8560273157672, 953.2374100719396, 953.4184147669915, 953.8664586957832, 15329.6167595811, 15451.922740386226, 15653.37386504527, 16196.433124840574, 16282.918585406996, 35563.28873422569, 36278.27443451169, 36911.63088369082, 37122.25755484929, 37349.0, 45725.0, 47027.52972470231, 47041.29438058363, 47310.0, 47584.286235707186, 7337.229590893093, 7403.0, 7555.320021198118, 7674.769756907302, 7756.0, 11722.76554468923, 11857.525698971944, 11873.584424545099, 12331.260124392566, 12434.316112613893, 10387.68836934893, 10471.68584942453, 10609.522571484147, 10643.627473038407, 10685.839712404164, 10194.133498652402, 10337.74155646115, 10487.056164945192, 10593.252113401202, 10685.0, 9798.0, 10105.545250463894, 10328.896711033094, 10770.353778773298, 10990.725231869268, 7401.296876796696, 7628.23717628255, 7645.770626881203, 7654.540727556365, 7691.1155217150745, 9357.157855793012, 9858.162056225014, 9882.654107106504, 10063.094321511098, 10418.895811041793], "value": 8159.755893925525, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1247.9064070194738, 1329.9867001329983, 1363.475062101091, 1406.662401023754, 1692.534552997926, 6647.50143739219, 7504.212057733935, 7775.727849525268, 7779.533228006324, 7982.521048737065, 7814.843703125948, 7862.174471680462, 7886.921130788691, 7892.486988345753, 7892.684292628288, 2181.814545763608, 2198.8021078102965, 2201.5486825200796, 2203.7575866654656, 2212.867227966319, 2141.4860433496005, 2168.2086038595958, 2176.064292354287, 2188.73735151782, 2193.1665966932624, 2123.3099242746093, 2149.322963266574, 2157.1371451419477, 2161.7189765330486, 2171.3703026122407, 2077.740282464692, 2105.87364758115, 2109.272301056133, 2112.4296439961277, 2112.7887211278903, 1996.46095554201, 2070.0, 2071.533904871407, 2075.1180748181964, 2083.645780217367, 7729.188435214278, 7787.8442431151625, 7867.6459559319965, 7870.05559332878, 7887.605619718991, 4298.527162012176, 4400.273954797454, 4404.207242696313, 4424.778761061934, 4428.977855110704, 2822.2803185187645, 2950.129711735043, 2967.1840243932875, 2978.3149875528534, 2981.791274610778, 960.078324808185, 963.2582911158369, 963.9807203855884, 981.3474039763548, 983.0857302708479, 10598.0, 10684.626038088629, 10724.142068634568, 10755.731106722316, 10757.892421075767, 3965.464662270614, 4010.6189911958318, 4067.247559201557, 4158.2307273154565, 4192.748435093904, 8233.259006689426, 8360.247577718035, 8375.58122093905, 8384.03583587883, 8385.413021088587, 4312.849050283225, 4313.0, 4315.007548263917, 4317.56824317572, 4318.071614602875, 3632.9091772705365, 3902.980485097613, 3948.723589348718, 3971.146203566247, 4043.7775922324554, 3298.0765385692043, 3333.1667083229195, 3443.2941247044296, 3450.6721861423125, 3463.0476618929492, 1019.8776146862424, 1047.8899715529913, 1052.4527245832123, 1056.2183983851894, 1056.3239526702916, 816.5223344343507, 822.1819289806681, 841.2512863551444, 851.051078047972, 861.2722249698994, 5154.871128221825, 5180.171172612364, 5280.20796880471, 5434.402215756264, 5446.455354464601, 838.5263472755396, 876.9649214031494, 881.4358810361374, 903.701778413122, 906.6917248135534, 3511.3855075361707, 3522.894313170609, 3575.2313252650806, 3645.9817700911326, 3792.3363411402893, 9342.859857102148, 9660.95169524148, 9743.269254805777, 9877.703668889944, 9890.109890109925, 9534.427934323963, 9645.0, 9654.179394751385, 9768.95115524432, 9830.754231144136, 7338.96330518344, 7479.813004674817, 7494.850102998019, 7499.362554182955, 7500.737474188377, 5094.0, 5143.691378517151, 5153.639245252946, 5182.948170518246, 5251.684898906078, 8620.956895215735, 8795.296376290009, 8832.205101540892, 8964.775880603038, 9089.272858171462, 8838.911610883808, 8986.101389861224, 9257.120573545502, 9331.860022099538, 9337.859932100888, 6221.47117495018, 6722.462203023649, 6902.378785909293, 7118.572885626671, 7126.857462850818, 7418.146913105062, 7601.961990190013, 7614.885776713243, 7640.0, 7664.386849051951, 6574.342565743579, 6678.766243181465, 6709.993500974892, 6818.079559259635, 6929.376356127973, 6689.531732778853, 6715.764948226789, 6737.090492783412, 6742.359475849787, 6755.851505244022, 6252.09344645033, 6343.809685709436, 6361.554691171574, 6362.968185159045, 6366.299707032315, 4591.6556258281435, 4601.240795268744, 4688.156131896155, 4708.482066972699, 4712.764361781966, 4583.702059366131, 4597.35637010812, 4613.215753322011, 4617.861464156081, 4908.803647854046], "value": 4092.2223582692995, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.76321416438515, 291.58303625815086, 298.9103269019294, 301.36562535862026, 302.57488229038194, 4455.175792478394, 4575.787416334674, 4619.884502887426, 4657.743824089674, 4700.506446823081, 4454.599086082252, 4566.49768525462, 4572.885677858055, 4592.933180068966, 4597.839075632353, 3678.4850120983083, 3683.4843121963013, 3684.299983003221, 3691.483192353059, 3702.8518859245596, 3492.0920560654286, 3566.5006899034024, 3683.7605555638934, 3691.427828686558, 3692.7045836333164, 3649.94525082124, 3656.5063716398345, 3660.8718694845684, 3668.229671768932, 3671.7613355131966, 3636.1455058061597, 3649.379605467064, 3662.3773958427, 3665.651763082503, 3691.1141326081547, 3257.5439438478634, 3265.297960938386, 3266.248762784573, 3270.9509357359657, 3297.9340413191603, 4314.503832059322, 4538.341940418653, 4569.908601827978, 4588.334691469751, 4596.54034596541, 4135.1729654069, 4143.254214241435, 4158.480189976253, 4165.854195103157, 4177.561356057631, 4848.684835485682, 4865.781039853215, 4873.390826146731, 4877.0, 4885.853424397238, 3320.1035720355612, 3507.456344266617, 3517.507548943124, 3533.8586456541707, 3541.4156664150382, 4994.950050499521, 5060.266261392115, 5064.417591976896, 5065.645404821664, 5070.2141168119, 3581.7492775505984, 3583.1937813992163, 3595.838187281526, 3596.6403359663823, 3606.585242697071, 3619.588360539392, 3632.473291372736, 3643.3806252937065, 3645.0, 3646.58064322601, 3916.921661566753, 4105.281575724236, 4125.257453658371, 4152.941000044969, 4156.314208155621, 4809.326694262806, 4822.758862056883, 4826.7827947742435, 4850.369451971221, 4851.902961940812, 1186.5728337798387, 1239.535174309634, 1249.956251531192, 1287.7038281195191, 1296.235221219485, 900.9414388064755, 901.3195037746459, 922.4649703172144, 933.290699068699, 937.2970272295778, 3879.342650284564, 3903.336432806373, 3912.491376121086, 3923.5684074751753, 3927.4501569780255, 4399.1641588098155, 4406.4357153210785, 4426.587509807098, 4451.577100175478, 4472.418585583919, 3861.3242682530445, 3869.7484663497344, 3873.767573945572, 3879.0, 3882.6216693074116, 2340.005497663486, 2368.874784477394, 2458.1027924807318, 2649.165512863442, 2661.9600705989424, 4820.927686084711, 4823.083614113307, 4824.155772739757, 4842.709437433765, 4851.805927762921, 5130.410002849645, 5137.640365174402, 5144.3506594104065, 5174.43081261061, 5185.559227465707, 4700.660311811117, 4816.807327706852, 4817.410254615968, 4825.927611085766, 4866.343043689128, 4986.6509344347, 4987.376577927759, 5001.549860512571, 5010.774515146754, 5016.749162541932, 4931.580815630711, 4939.728314942569, 4951.207806750903, 4953.133201689618, 4960.627952903619, 4602.838900638461, 4800.4479484859685, 4830.396200474941, 4852.636052296021, 4852.927206091982, 3541.9114522136124, 3565.322588708137, 3585.085803120292, 3585.3725598020765, 3591.0, 4796.904061918812, 4958.206686930074, 4967.3045773591, 4986.7257300848805, 5000.974995125001, 4674.859754207379, 4811.109944660249, 4846.079244943449, 4875.219964805614, 4879.170541007966, 4849.393825771778, 4859.125357435554, 4861.611071114373, 4867.318575399376, 4915.5330243626795, 4563.0, 4811.092685460881, 4828.348172996741, 4835.6131509479865, 4841.419029716457, 4888.535589119028, 4915.361003069578, 4918.360613120271, 4923.5814955729165, 4933.925991110064, 4805.639577031807, 4807.495213002724, 4837.089349706916, 4857.149998750276, 4880.92678609814], "value": 3661.5154620410563, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.63451015666, 667.392672667872, 696.3558708194919, 725.9854802903942, 735.4667865797296, 11930.940345298282, 13189.340532973361, 13194.07641465098, 13197.208167509956, 13307.80038299425, 13250.0, 13269.469221231138, 13307.201567905933, 13377.331133443313, 13381.665458363546, 622.1725105609551, 743.71738739279, 749.3068911257093, 767.5471471831618, 776.6932061835572, 762.6872982077349, 772.1005029141044, 774.1213722425032, 774.8489044636298, 774.9031371078615, 817.9141190175035, 819.2503858969036, 821.4455242711176, 822.7202751064636, 822.9218224268685, 657.5002997721718, 776.6621519638975, 800.9679612815538, 817.0195765081896, 817.6320655704927, 581.0702875399327, 581.7905554000599, 581.8603535151575, 582.1035605168037, 582.1762206477829, 11068.778624427547, 11075.778484430346, 11077.667669969915, 11085.778284434347, 11186.944065279622, 12195.756084878252, 12343.19769214998, 12537.937310313482, 13089.149205301625, 13509.392077356542, 11447.542098316057, 11731.413429328499, 11809.94095029528, 11948.522059117711, 12216.389180541026, 4022.235775202702, 4071.246819338432, 4096.938545921814, 4233.322668373076, 4241.618254357092, 63535.0, 64373.67813160951, 64499.0, 65307.0, 66961.33038669649, 9560.665376711782, 9817.263705222136, 9933.205343572497, 10236.078752912274, 10917.39954302521, 18229.54426139357, 18610.16254268561, 18623.90688046551, 18626.06869656512, 18659.626807464047, 6376.266729326187, 6504.349565043458, 6648.335166483313, 6700.095487109178, 6756.155480564929, 3622.4385220290897, 3647.908802279911, 3688.1517251032415, 3701.685356744653, 3708.072981754561, 2897.5219088850104, 3000.069978306732, 3006.804557703786, 3008.8044277121917, 3015.20097174249, 483.8185680369861, 486.9147899117711, 489.70372924380234, 502.87930896584555, 503.189864318447, 519.2548692626094, 531.5030446532427, 534.7700488789858, 570.0195663458844, 577.2293987526615, 7236.746713864989, 7325.340719335285, 8621.61202745878, 9058.592363343665, 9078.636854525877, 535.8392482255308, 562.3224015061841, 615.9568830181844, 621.5369549685423, 636.4208570201188, 3706.314331848617, 4008.2584721826556, 4067.613576710208, 4120.526139493936, 4191.7275377101005, 75041.0, 78526.60736696394, 78668.60665696635, 90196.0, 95343.0, 75799.0, 77820.0, 77969.0, 77977.61011194908, 78429.60785196038, 64484.35515644783, 66159.0, 67192.32807671958, 68803.65598171977, 69333.65333173302, 8064.596770161351, 8441.915580844114, 8611.569421529024, 8658.826823463622, 8701.695440659547, 41956.3706544396, 42147.0, 42231.0, 42504.36243456412, 43300.0, 35688.464673029404, 39252.803735981135, 39320.80339598284, 39970.0, 40149.397759033054, 10402.947985260025, 10427.374357538572, 10459.110975567162, 10497.895021049997, 10655.094316982833, 11907.345096019802, 12012.099092568267, 12235.816462753244, 12269.0, 12947.0, 11108.389038602954, 11328.546858125583, 11356.148288878532, 11608.767824643292, 11648.359340236368, 11011.119090661196, 11722.423552666896, 11723.41382930867, 11758.647240582795, 11855.17013809025, 11168.441577921234, 11295.435228238719, 11685.707857303636, 11985.760284794429, 12017.63947081589, 9480.668176614061, 9540.95229523848, 9776.657816976372, 9819.85270220933, 9906.356086854332, 9313.767155821159, 9738.220942324739, 9775.511224438891, 9827.26295527824, 9836.0], "value": 6378.068157626636, "unit": "score"}], "name": "dromaeo_css", "value": 6251.413350856496, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 111.0, 116.0, 114.0, 114.0, 120.0, 113.0, 113.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 124.0, 157.0, 191.0, 123.0, 159.0, 152.0, 123.0, 202.0, 135.0], "value": 151.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 164.0, 160.0, 161.0, 162.0, 161.0, 161.0, 163.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 126.0, 116.0, 75.0, 76.0, 76.0, 121.0, 113.0, 77.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 160.0, 86.0, 86.0, 87.0, 150.0, 86.0, 87.0, 150.0], "value": 106.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 65.0, 66.0, 66.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 65.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 84.0, 81.0, 85.0, 81.0, 81.0, 83.0, 82.0, 90.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 133.0, 134.0, 139.0, 134.0, 141.0, 155.0, 132.0, 156.0, 134.0], "value": 140.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 181.0, 173.0, 173.0, 173.0, 174.0, 171.0, 169.0, 173.0, 168.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 72.0, 69.0, 67.0, 69.0, 67.0, 67.0, 67.0, 67.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1580.3999999999999, "alertThreshold": 2.0}]} 15:42:37 INFO - Return code: 0 15:42:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:42:37 INFO - # TBPL SUCCESS # 15:42:37 INFO - Running post-action listener: _resource_record_post_action 15:42:37 INFO - [mozharness: 2016-08-22 22:42:37.062853Z] Finished run-tests step (success) 15:42:37 INFO - Running post-run listener: _resource_record_post_run 15:42:37 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 404193280; Read time: 16; Write time: 415308 15:42:37 INFO - TinderboxPrint: CPU usage
13.3% 15:42:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 15:42:37 INFO - TinderboxPrint: I/O write bytes / time
404,193,280 / 415,308 15:42:37 INFO - TinderboxPrint: CPU idle
8,324.7 (86.7%) 15:42:37 INFO - TinderboxPrint: CPU user
1,207.3 (12.6%) 15:42:37 INFO - TinderboxPrint: Swap in / out
0 / 0 15:42:37 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 114233344; Read time: 0; Write time: 161504 15:42:37 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 289959936; Read time: 0; Write time: 253804 15:42:37 INFO - Running post-run listener: _upload_blobber_files 15:42:37 INFO - Blob upload gear active. 15:42:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:42:37 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 15:42:37 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:42:37 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:42:38 INFO - (blobuploader) - INFO - Open directory for files ... 15:42:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:42:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:42:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:42:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:42:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:42:38 INFO - (blobuploader) - INFO - Done attempting. 15:42:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:42:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:42:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:42:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:42:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:42:39 INFO - (blobuploader) - INFO - Done attempting. 15:42:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:42:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:42:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:42:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:42:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:42:44 INFO - (blobuploader) - INFO - Done attempting. 15:42:44 INFO - (blobuploader) - INFO - Iteration through files over. 15:42:44 INFO - Return code: 0 15:42:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:42:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:42:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e808b57be6e161b7b9e4790aad7acae4fd453a6f8303d378406e3fda7a808ab966a21889e660dfad5c24c62800951a8cca8f9df57c26fdd5ffeb412aa3b34e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cbbeb37482362f00a82b76226a55a13c742df0834605c0db9825ea67d612b7b4c25f358ecf4591ac79c6bb085317b3d3b4faf04348d31557f21b6b974682d196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ccc280d382c969bb3f7dd15520764ee315e48cd70a00b39c9b13774ca6a86e537042dfef71151341223a2b1c33283597e4d8ed7633b7be98bbc4da68fb2871df"} 15:42:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:42:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:42:44 INFO - Contents: 15:42:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e808b57be6e161b7b9e4790aad7acae4fd453a6f8303d378406e3fda7a808ab966a21889e660dfad5c24c62800951a8cca8f9df57c26fdd5ffeb412aa3b34e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cbbeb37482362f00a82b76226a55a13c742df0834605c0db9825ea67d612b7b4c25f358ecf4591ac79c6bb085317b3d3b4faf04348d31557f21b6b974682d196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ccc280d382c969bb3f7dd15520764ee315e48cd70a00b39c9b13774ca6a86e537042dfef71151341223a2b1c33283597e4d8ed7633b7be98bbc4da68fb2871df"} 15:42:44 INFO - Running post-run listener: copy_logs_to_upload_dir 15:42:44 INFO - Copying logs to upload dir... 15:42:44 INFO - mkdir: /builds/slave/test/build/upload/logs 15:42:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.882449 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-22 15:42:44.170803) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 15:42:44.174581) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945 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-Non-PGO/sha512/e808b57be6e161b7b9e4790aad7acae4fd453a6f8303d378406e3fda7a808ab966a21889e660dfad5c24c62800951a8cca8f9df57c26fdd5ffeb412aa3b34e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cbbeb37482362f00a82b76226a55a13c742df0834605c0db9825ea67d612b7b4c25f358ecf4591ac79c6bb085317b3d3b4faf04348d31557f21b6b974682d196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ccc280d382c969bb3f7dd15520764ee315e48cd70a00b39c9b13774ca6a86e537042dfef71151341223a2b1c33283597e4d8ed7633b7be98bbc4da68fb2871df"} build_url:https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://queue.taskcluster.net/v1/task/PA0ZyqQQT1CzV_YoKagFqQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e808b57be6e161b7b9e4790aad7acae4fd453a6f8303d378406e3fda7a808ab966a21889e660dfad5c24c62800951a8cca8f9df57c26fdd5ffeb412aa3b34e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/cbbeb37482362f00a82b76226a55a13c742df0834605c0db9825ea67d612b7b4c25f358ecf4591ac79c6bb085317b3d3b4faf04348d31557f21b6b974682d196", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ccc280d382c969bb3f7dd15520764ee315e48cd70a00b39c9b13774ca6a86e537042dfef71151341223a2b1c33283597e4d8ed7633b7be98bbc4da68fb2871df"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 15:42:44.201599) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:42:44.202051) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1471904513.737047-1709377945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004511 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 15:42:44.285658) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 15:42:44.286080) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 15:42:44.286473) ========= ========= Total master_lag: 0.16 =========