builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-040 starttime: 1472706684.32 results: success (0) buildid: 20160831203921 builduid: 4f7dccb7a4d343acb5b911cc94b82161 revision: 0dfbee74cdbbfa8ad688860554bee62add4479c6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:24.320496) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:24.321525) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:24.321919) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:24.337963) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:24.338299) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-31 22:11:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 103M=0s 2016-08-31 22:11:24 (103 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092435 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:24.450590) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:24.450988) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018026 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:24.501354) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:24.501752) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 0dfbee74cdbbfa8ad688860554bee62add4479c6 --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 0dfbee74cdbbfa8ad688860554bee62add4479c6 --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=9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-31 22:11:24,757 truncating revision to first 12 chars 2016-08-31 22:11:24,757 Setting DEBUG logging. 2016-08-31 22:11:24,757 attempt 1/10 2016-08-31 22:11:24,757 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/0dfbee74cdbb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 22:11:25,040 unpacking tar archive at: autoland-0dfbee74cdbb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.604267 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:25.121177) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:25.121605) ========= script_repo_revision: 0dfbee74cdbbfa8ad688860554bee62add4479c6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:25.122088) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:25.122472) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 22:11:25.132586) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-31 22:11:25.132893) ========= /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=9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:11:25 INFO - MultiFileLogger online at 20160831 22:11:25 in /builds/slave/test 22:11:25 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 22:11:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:11:25 INFO - {'append_to_log': False, 22:11:25 INFO - 'base_work_dir': '/builds/slave/test', 22:11:25 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 22:11:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:11:25 INFO - 'branch': 'Autoland-Non-PGO', 22:11:25 INFO - 'buildbot_json_path': 'buildprops.json', 22:11:25 INFO - 'config_files': ('talos/linux_config.py',), 22:11:25 INFO - 'default_actions': ('clobber', 22:11:25 INFO - 'read-buildbot-config', 22:11:25 INFO - 'download-and-extract', 22:11:25 INFO - 'populate-webroot', 22:11:25 INFO - 'create-virtualenv', 22:11:25 INFO - 'install', 22:11:25 INFO - 'run-tests'), 22:11:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:11:25 INFO - 'download_minidump_stackwalk': True, 22:11:25 INFO - 'download_symbols': 'ondemand', 22:11:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:11:25 INFO - 'tooltool.py': '/tools/tooltool.py', 22:11:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:11:25 INFO - '/tools/misc-python/virtualenv.py')}, 22:11:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:11:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:11:25 INFO - 'installer_path': 'installer.exe', 22:11:25 INFO - 'log_level': 'info', 22:11:25 INFO - 'log_name': 'talos', 22:11:25 INFO - 'log_to_console': True, 22:11:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:11:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:11:25 INFO - 'opt_config_files': (), 22:11:25 INFO - 'pip_index': False, 22:11:25 INFO - 'sps_profile': False, 22:11:25 INFO - 'sps_profile_interval': 0, 22:11:25 INFO - 'suite': 'dromaeojs', 22:11:25 INFO - 'system_bits': '32', 22:11:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:11:25 INFO - 'title': 'talos-linux64-ix-040', 22:11:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:11:25 INFO - 'use_talos_json': True, 22:11:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:11:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:11:25 INFO - 'work_dir': 'build'} 22:11:25 INFO - [mozharness: 2016-09-01 05:11:25.489465Z] Running clobber step. 22:11:25 INFO - Running pre-action listener: _resource_record_pre_action 22:11:25 INFO - Running main action method: clobber 22:11:25 INFO - rmtree: /builds/slave/test/build 22:11:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:11:25 INFO - Running post-action listener: _resource_record_post_action 22:11:25 INFO - [mozharness: 2016-09-01 05:11:25.942903Z] Finished clobber step (success) 22:11:25 INFO - [mozharness: 2016-09-01 05:11:25.943007Z] Running read-buildbot-config step. 22:11:25 INFO - Running pre-action listener: _resource_record_pre_action 22:11:25 INFO - Running main action method: read_buildbot_config 22:11:25 INFO - Using buildbot properties: 22:11:25 INFO - { 22:11:25 INFO - "project": "", 22:11:25 INFO - "product": "firefox", 22:11:25 INFO - "script_repo_revision": "production", 22:11:25 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 22:11:25 INFO - "repository": "", 22:11:25 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 22:11:25 INFO - "buildid": "20160831203921", 22:11:25 INFO - "pgo_build": "False", 22:11:25 INFO - "basedir": "/builds/slave/test", 22:11:25 INFO - "buildnumber": 381, 22:11:25 INFO - "slavename": "talos-linux64-ix-040", 22:11:25 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:11:25 INFO - "platform": "ubuntu64_hw", 22:11:25 INFO - "scheduler": "tests-autoland-linux64-talos", 22:11:25 INFO - "branch": "autoland", 22:11:25 INFO - "repo_path": "integration/autoland", 22:11:25 INFO - "revision": "0dfbee74cdbbfa8ad688860554bee62add4479c6", 22:11:25 INFO - "stage_platform": "linux64", 22:11:25 INFO - "builduid": "4f7dccb7a4d343acb5b911cc94b82161", 22:11:25 INFO - "slavebuilddir": "test" 22:11:25 INFO - } 22:11:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 22:11:25 INFO - Running post-action listener: _resource_record_post_action 22:11:25 INFO - [mozharness: 2016-09-01 05:11:25.945751Z] Finished read-buildbot-config step (success) 22:11:25 INFO - [mozharness: 2016-09-01 05:11:25.945840Z] Running download-and-extract step. 22:11:25 INFO - Running pre-action listener: _resource_record_pre_action 22:11:25 INFO - Running main action method: download_and_extract 22:11:25 INFO - mkdir: /builds/slave/test/build/tests 22:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:25 INFO - trying https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:11:25 INFO - Downloading https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/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 22:11:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/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 22:11:26 INFO - Downloaded 1361 bytes. 22:11:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 22:11:26 INFO - Using the following test package requirements: 22:11:26 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:11:26 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:11:26 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:11:26 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:26 INFO - u'jsshell-linux-x86_64.zip'], 22:11:26 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:11:26 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 22:11:26 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:11:26 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:11:26 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:11:26 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 22:11:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:11:26 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:11: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')]} 22:11:26 INFO - trying https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:11:26 INFO - Downloading https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/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 22:11:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/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 22:11:27 INFO - Downloaded 20333697 bytes. 22:11:27 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 22:11:31 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 22:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:31 INFO - trying https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 22:11:31 INFO - Downloading https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/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 22:11:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/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 22:11:32 INFO - Downloaded 20333697 bytes. 22:11:32 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 22:11:37 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')]} 22:11:37 INFO - trying https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 22:11:37 INFO - Downloading https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/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 22:11:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/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 22:11:37 INFO - Downloaded 14413998 bytes. 22:11:37 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 22:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:38 INFO - trying https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:11:38 INFO - Downloading https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/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 22:11:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/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 22:11:39 INFO - Downloaded 55871731 bytes. 22:11:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:11:39 INFO - mkdir: /builds/slave/test/properties 22:11:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:11:39 INFO - Writing to file /builds/slave/test/properties/build_url 22:11:39 INFO - Contents: 22:11:39 INFO - build_url:https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 22:11:39 INFO - Running post-action listener: _resource_record_post_action 22:11:39 INFO - Running post-action listener: set_extra_try_arguments 22:11:39 INFO - [mozharness: 2016-09-01 05:11:39.867556Z] Finished download-and-extract step (success) 22:11:39 INFO - [mozharness: 2016-09-01 05:11:39.867856Z] Running populate-webroot step. 22:11:39 INFO - Running pre-action listener: _resource_record_pre_action 22:11:39 INFO - Running main action method: populate_webroot 22:11:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:11:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:11:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:11:39 INFO - '--tppagecycles', 22:11:39 INFO - '7'], 22:11:39 INFO - 'tests': ['tsvgm']}}, 22:11:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:11:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:11:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:11:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:11:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:11:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:11:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:39 INFO - 'talos_options': ['--disable-e10s'], 22:11:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:11:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:11:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:39 INFO - 'talos_options': ['--disable-e10s'], 22:11:39 INFO - 'tests': ['damp', 'tps']}, 22:11:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:39 INFO - 'tests': ['damp', 'tps']}, 22:11:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:11:39 INFO - 'tests': ['dromaeo_dom']}, 22:11:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:11:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:11:39 INFO - 'tests': ['basic_compositor_video']}, 22:11:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:11:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:11:39 INFO - 'tests': ['a11yr', 22:11:39 INFO - 'ts_paint', 22:11:39 INFO - 'tpaint', 22:11:39 INFO - 'sessionrestore', 22:11:39 INFO - 'sessionrestore_no_auto_restore', 22:11:39 INFO - 'tabpaint']}, 22:11:39 INFO - 'other-e10s': {'tests': ['a11yr', 22:11:39 INFO - 'ts_paint', 22:11:39 INFO - 'tpaint', 22:11:39 INFO - 'sessionrestore', 22:11:39 INFO - 'sessionrestore_no_auto_restore', 22:11:39 INFO - 'tabpaint']}, 22:11:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:11:39 INFO - 'ts_paint', 22:11:39 INFO - 'tpaint', 22:11:39 INFO - 'sessionrestore', 22:11:39 INFO - 'sessionrestore_no_auto_restore', 22:11:39 INFO - 'tabpaint']}, 22:11:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:11:39 INFO - 'ts_paint', 22:11:39 INFO - 'tpaint', 22:11:39 INFO - 'sessionrestore', 22:11:39 INFO - 'sessionrestore_no_auto_restore', 22:11:39 INFO - 'tabpaint']}, 22:11:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:11:39 INFO - 'tests': ['a11yr', 22:11:39 INFO - 'ts_paint', 22:11:39 INFO - 'tpaint', 22:11:39 INFO - 'sessionrestore', 22:11:39 INFO - 'sessionrestore_no_auto_restore', 22:11:39 INFO - 'tabpaint']}, 22:11:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:11:39 INFO - 'tests': ['a11yr', 22:11:39 INFO - 'ts_paint', 22:11:39 INFO - 'tpaint', 22:11:39 INFO - 'sessionrestore', 22:11:39 INFO - 'sessionrestore_no_auto_restore', 22:11:39 INFO - 'tabpaint']}, 22:11:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:11:39 INFO - 'tests': ['tsvgx', 22:11:39 INFO - 'tsvgr_opacity', 22:11:39 INFO - 'tart', 22:11:39 INFO - 'tscrollx', 22:11:39 INFO - 'cart']}, 22:11:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:11:39 INFO - 'tsvgr_opacity', 22:11:39 INFO - 'tart', 22:11:39 INFO - 'tscrollx', 22:11:39 INFO - 'cart']}, 22:11:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:39 INFO - 'talos_options': ['--disable-e10s'], 22:11:39 INFO - 'tests': ['tp5o']}, 22:11:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:39 INFO - 'tests': ['tp5o']}, 22:11:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:39 INFO - 'talos_options': ['--disable-e10s', 22:11:39 INFO - '--xperf_path', 22:11:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:11:39 INFO - 'tests': ['tp5n']}, 22:11:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:39 INFO - 'talos_options': ['--xperf_path', 22:11:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:11:39 INFO - 'tests': ['tp5n']}}, 22:11:39 INFO - 'talos.zip': {'path': '', 22:11:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:11:39 INFO - Running post-action listener: _resource_record_post_action 22:11:39 INFO - [mozharness: 2016-09-01 05:11:39.888597Z] Finished populate-webroot step (success) 22:11:39 INFO - [mozharness: 2016-09-01 05:11:39.888694Z] Running create-virtualenv step. 22:11:39 INFO - Running pre-action listener: _resource_record_pre_action 22:11:39 INFO - Running main action method: create_virtualenv 22:11:39 INFO - Creating virtualenv /builds/slave/test/build/venv 22:11:39 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 22:11:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:11:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:11:39 INFO - Using real prefix '/usr' 22:11:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:11:40 INFO - Installing distribute.............................................................................................................................................................................................done. 22:11:42 INFO - Installing pip.................done. 22:11:42 INFO - Return code: 0 22:11:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1b6b32f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb6090>, '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 0x1bb99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb9e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', '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 22:11:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:11:42 INFO - Using env: {'DISPLAY': ':0', 22:11:42 INFO - 'HOME': '/home/cltbld', 22:11:42 INFO - 'LANG': 'en_US.UTF-8', 22:11:42 INFO - 'LANGUAGE': 'en_US:en', 22:11:42 INFO - 'LOGNAME': 'cltbld', 22:11:42 INFO - 'MAIL': '/var/mail/cltbld', 22:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:42 INFO - 'MOZ_NO_REMOTE': '1', 22:11:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:42 INFO - 'NO_EM_RESTART': '1', 22:11:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:42 INFO - 'PWD': '/builds/slave/test', 22:11:42 INFO - 'SHELL': '/bin/bash', 22:11:42 INFO - 'SHLVL': '1', 22:11:42 INFO - 'TERM': 'linux', 22:11:42 INFO - 'TMOUT': '86400', 22:11:42 INFO - 'USER': 'cltbld', 22:11:42 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', 22:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:42 INFO - '_': '/tools/buildbot/bin/python'} 22:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:42 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 22:11:42 INFO - Cleaning up... 22:11:42 INFO - Return code: 0 22:11:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc1b6b32f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb6090>, '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 0x1bb99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb9e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', '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 22:11:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:11:42 INFO - Using env: {'DISPLAY': ':0', 22:11:42 INFO - 'HOME': '/home/cltbld', 22:11:42 INFO - 'LANG': 'en_US.UTF-8', 22:11:42 INFO - 'LANGUAGE': 'en_US:en', 22:11:42 INFO - 'LOGNAME': 'cltbld', 22:11:42 INFO - 'MAIL': '/var/mail/cltbld', 22:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:42 INFO - 'MOZ_NO_REMOTE': '1', 22:11:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:42 INFO - 'NO_EM_RESTART': '1', 22:11:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:42 INFO - 'PWD': '/builds/slave/test', 22:11:42 INFO - 'SHELL': '/bin/bash', 22:11:42 INFO - 'SHLVL': '1', 22:11:42 INFO - 'TERM': 'linux', 22:11:42 INFO - 'TMOUT': '86400', 22:11:42 INFO - 'USER': 'cltbld', 22:11:42 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', 22:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:42 INFO - '_': '/tools/buildbot/bin/python'} 22:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:42 INFO - Downloading/unpacking psutil>=3.1.1 22:11:42 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 22:11:42 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 22:11:42 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 22:11:42 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 22:11:42 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 22:11:42 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 22:11:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:11:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:11:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:11:44 INFO - Installing collected packages: psutil 22:11:44 INFO - Running setup.py install for psutil 22:11:44 INFO - building 'psutil._psutil_linux' extension 22:11:44 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 22:11:45 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 22:11:45 INFO - building 'psutil._psutil_posix' extension 22:11:45 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 22:11:45 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 22:11:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:11:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:11:45 INFO - Successfully installed psutil 22:11:45 INFO - Cleaning up... 22:11:45 INFO - Return code: 0 22:11:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:11:45 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')]} 22:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:45 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')]} 22:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:45 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 0x7fc1b6b32f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb6090>, '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 0x1bb99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb9e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', '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 22:11:45 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 22:11:45 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 22:11:45 INFO - Using env: {'DISPLAY': ':0', 22:11:45 INFO - 'HOME': '/home/cltbld', 22:11:45 INFO - 'LANG': 'en_US.UTF-8', 22:11:45 INFO - 'LANGUAGE': 'en_US:en', 22:11:45 INFO - 'LOGNAME': 'cltbld', 22:11:45 INFO - 'MAIL': '/var/mail/cltbld', 22:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:45 INFO - 'MOZ_NO_REMOTE': '1', 22:11:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:45 INFO - 'NO_EM_RESTART': '1', 22:11:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:45 INFO - 'PWD': '/builds/slave/test', 22:11:45 INFO - 'SHELL': '/bin/bash', 22:11:45 INFO - 'SHLVL': '1', 22:11:45 INFO - 'TERM': 'linux', 22:11:45 INFO - 'TMOUT': '86400', 22:11:45 INFO - 'USER': 'cltbld', 22:11:45 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', 22:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:45 INFO - '_': '/tools/buildbot/bin/python'} 22:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:11:45 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 22:11:45 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 22:11:45 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 22:11:45 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 22:11:45 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 22:11:45 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 22:11:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:11:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:11:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:11:47 INFO - Installing collected packages: mozsystemmonitor 22:11:47 INFO - Running setup.py install for mozsystemmonitor 22:11:47 INFO - Successfully installed mozsystemmonitor 22:11:47 INFO - Cleaning up... 22:11:47 INFO - Return code: 0 22:11:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1b6b32f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb6090>, '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 0x1bb99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb9e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', '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 22:11:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:11:47 INFO - Using env: {'DISPLAY': ':0', 22:11:47 INFO - 'HOME': '/home/cltbld', 22:11:47 INFO - 'LANG': 'en_US.UTF-8', 22:11:47 INFO - 'LANGUAGE': 'en_US:en', 22:11:47 INFO - 'LOGNAME': 'cltbld', 22:11:47 INFO - 'MAIL': '/var/mail/cltbld', 22:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:47 INFO - 'MOZ_NO_REMOTE': '1', 22:11:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:47 INFO - 'NO_EM_RESTART': '1', 22:11:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:47 INFO - 'PWD': '/builds/slave/test', 22:11:47 INFO - 'SHELL': '/bin/bash', 22:11:47 INFO - 'SHLVL': '1', 22:11:47 INFO - 'TERM': 'linux', 22:11:47 INFO - 'TMOUT': '86400', 22:11:47 INFO - 'USER': 'cltbld', 22:11:47 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', 22:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:47 INFO - '_': '/tools/buildbot/bin/python'} 22:11:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:47 INFO - Downloading/unpacking jsonschema==2.5.1 22:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:11:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:11:49 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 22:11:49 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 22:11:49 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 22:11:49 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 22:11:49 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 22:11:49 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 22:11:50 INFO - Downloading functools32-3.2.3-2.tar.gz 22:11:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:11:50 INFO - warning: no files found matching '*.txt' 22:11:50 INFO - no previously-included directories found matching 'build' 22:11:50 INFO - no previously-included directories found matching 'dist' 22:11:50 INFO - no previously-included directories found matching '.git*' 22:11:50 INFO - Installing collected packages: jsonschema, functools32 22:11:50 INFO - Running setup.py install for functools32 22:11:50 INFO - warning: no files found matching '*.txt' 22:11:50 INFO - no previously-included directories found matching 'build' 22:11:50 INFO - no previously-included directories found matching 'dist' 22:11:50 INFO - no previously-included directories found matching '.git*' 22:11:50 INFO - Successfully installed jsonschema functools32 22:11:50 INFO - Cleaning up... 22:11:50 INFO - Return code: 0 22:11:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc1b6b32f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb6090>, '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 0x1bb99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb9e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', '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 22:11:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:11:50 INFO - Using env: {'DISPLAY': ':0', 22:11:50 INFO - 'HOME': '/home/cltbld', 22:11:50 INFO - 'LANG': 'en_US.UTF-8', 22:11:50 INFO - 'LANGUAGE': 'en_US:en', 22:11:50 INFO - 'LOGNAME': 'cltbld', 22:11:50 INFO - 'MAIL': '/var/mail/cltbld', 22:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:50 INFO - 'MOZ_NO_REMOTE': '1', 22:11:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:50 INFO - 'NO_EM_RESTART': '1', 22:11:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:50 INFO - 'PWD': '/builds/slave/test', 22:11:50 INFO - 'SHELL': '/bin/bash', 22:11:50 INFO - 'SHLVL': '1', 22:11:50 INFO - 'TERM': 'linux', 22:11:50 INFO - 'TMOUT': '86400', 22:11:50 INFO - 'USER': 'cltbld', 22:11:50 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', 22:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:50 INFO - '_': '/tools/buildbot/bin/python'} 22:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:11:50 INFO - Cleaning up... 22:11:50 INFO - Return code: 0 22:11:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc1b6b32f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb6090>, '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 0x1bb99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb9e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', '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 22:11:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:11:50 INFO - Using env: {'DISPLAY': ':0', 22:11:50 INFO - 'HOME': '/home/cltbld', 22:11:50 INFO - 'LANG': 'en_US.UTF-8', 22:11:50 INFO - 'LANGUAGE': 'en_US:en', 22:11:50 INFO - 'LOGNAME': 'cltbld', 22:11:50 INFO - 'MAIL': '/var/mail/cltbld', 22:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:50 INFO - 'MOZ_NO_REMOTE': '1', 22:11:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:50 INFO - 'NO_EM_RESTART': '1', 22:11:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:50 INFO - 'PWD': '/builds/slave/test', 22:11:50 INFO - 'SHELL': '/bin/bash', 22:11:50 INFO - 'SHLVL': '1', 22:11:50 INFO - 'TERM': 'linux', 22:11:50 INFO - 'TMOUT': '86400', 22:11:50 INFO - 'USER': 'cltbld', 22:11:50 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', 22:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:50 INFO - '_': '/tools/buildbot/bin/python'} 22:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:50 INFO - Downloading/unpacking blobuploader==1.2.4 22:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:52 INFO - Downloading blobuploader-1.2.4.tar.gz 22:11:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:11:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:11:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:11:53 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 22:11:53 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 22:11:53 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 22:11:53 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 22:11:53 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 22:11:53 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 22:11:53 INFO - Downloading docopt-0.6.1.tar.gz 22:11:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:11:53 INFO - Installing collected packages: blobuploader, requests, docopt 22:11:53 INFO - Running setup.py install for blobuploader 22:11:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:11:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:11:53 INFO - Running setup.py install for requests 22:11:53 INFO - Running setup.py install for docopt 22:11:53 INFO - Successfully installed blobuploader requests docopt 22:11:53 INFO - Cleaning up... 22:11:54 INFO - Return code: 0 22:11:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:54 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 0x7fc1b6b32f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb6090>, '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 0x1bb99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb9e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', '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 22:11:54 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 22:11:54 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 22:11:54 INFO - Using env: {'DISPLAY': ':0', 22:11:54 INFO - 'HOME': '/home/cltbld', 22:11:54 INFO - 'LANG': 'en_US.UTF-8', 22:11:54 INFO - 'LANGUAGE': 'en_US:en', 22:11:54 INFO - 'LOGNAME': 'cltbld', 22:11:54 INFO - 'MAIL': '/var/mail/cltbld', 22:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:54 INFO - 'MOZ_NO_REMOTE': '1', 22:11:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:54 INFO - 'NO_EM_RESTART': '1', 22:11:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:54 INFO - 'PWD': '/builds/slave/test', 22:11:54 INFO - 'SHELL': '/bin/bash', 22:11:54 INFO - 'SHLVL': '1', 22:11:54 INFO - 'TERM': 'linux', 22:11:54 INFO - 'TMOUT': '86400', 22:11:54 INFO - 'USER': 'cltbld', 22:11:54 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', 22:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:54 INFO - '_': '/tools/buildbot/bin/python'} 22:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:11:54 INFO - Running setup.py (path:/tmp/pip-Z7WsS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:11:54 INFO - Running setup.py (path:/tmp/pip-Aw4K7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:11:54 INFO - Running setup.py (path:/tmp/pip-7UkXRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:11:54 INFO - Running setup.py (path:/tmp/pip-5vmj_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:11:54 INFO - Running setup.py (path:/tmp/pip-IKBinK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:11:54 INFO - Running setup.py (path:/tmp/pip-bTM2d3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:11:54 INFO - Running setup.py (path:/tmp/pip-9cSM23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:11:54 INFO - Running setup.py (path:/tmp/pip-2UlJZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:11:54 INFO - Running setup.py (path:/tmp/pip-VXjb9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:11:54 INFO - Running setup.py (path:/tmp/pip-oCCT_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:11:54 INFO - Running setup.py (path:/tmp/pip-5HYBX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:11:55 INFO - Running setup.py (path:/tmp/pip-xxB38f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:11:55 INFO - Running setup.py (path:/tmp/pip-FsJFcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:11:55 INFO - Running setup.py (path:/tmp/pip-bHa_5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:55 INFO - Running setup.py (path:/tmp/pip-ZA_FQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:11:55 INFO - Running setup.py (path:/tmp/pip-Vi9zBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:11:55 INFO - Running setup.py (path:/tmp/pip-As4NPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:11:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:11:55 INFO - Running setup.py install for manifestparser 22:11:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:11:55 INFO - Running setup.py install for mozcrash 22:11:55 INFO - Running setup.py install for mozdebug 22:11:55 INFO - Running setup.py install for mozdevice 22:11:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:11:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:11:55 INFO - Running setup.py install for mozfile 22:11:55 INFO - Running setup.py install for mozhttpd 22:11:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Running setup.py install for mozinfo 22:11:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Running setup.py install for mozInstall 22:11:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Running setup.py install for mozleak 22:11:56 INFO - Running setup.py install for mozlog 22:11:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Running setup.py install for moznetwork 22:11:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Running setup.py install for mozprocess 22:11:56 INFO - Running setup.py install for mozprofile 22:11:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Running setup.py install for mozrunner 22:11:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:11:56 INFO - Running setup.py install for mozscreenshot 22:11:57 INFO - Running setup.py install for moztest 22:11:57 INFO - Running setup.py install for mozversion 22:11:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:11:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:11:57 INFO - Cleaning up... 22:11:57 INFO - Return code: 0 22:11:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11: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')]} 22:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11: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')]} 22:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:57 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 0x7fc1b6b32f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb6090>, '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 0x1bb99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb9e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', '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 22:11:57 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 22:11:57 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 22:11:57 INFO - Using env: {'DISPLAY': ':0', 22:11:57 INFO - 'HOME': '/home/cltbld', 22:11:57 INFO - 'LANG': 'en_US.UTF-8', 22:11:57 INFO - 'LANGUAGE': 'en_US:en', 22:11:57 INFO - 'LOGNAME': 'cltbld', 22:11:57 INFO - 'MAIL': '/var/mail/cltbld', 22:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:57 INFO - 'MOZ_NO_REMOTE': '1', 22:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:57 INFO - 'NO_EM_RESTART': '1', 22:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:57 INFO - 'PWD': '/builds/slave/test', 22:11:57 INFO - 'SHELL': '/bin/bash', 22:11:57 INFO - 'SHLVL': '1', 22:11:57 INFO - 'TERM': 'linux', 22:11:57 INFO - 'TMOUT': '86400', 22:11:57 INFO - 'USER': 'cltbld', 22:11:57 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', 22:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:57 INFO - '_': '/tools/buildbot/bin/python'} 22:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:11:57 INFO - Running setup.py (path:/tmp/pip-pqjc_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:11:57 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)) 22:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:11:57 INFO - Running setup.py (path:/tmp/pip-JPZ8OP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:11:57 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)) 22:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:11:57 INFO - Running setup.py (path:/tmp/pip-DlJipf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:11:57 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)) 22:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:11:57 INFO - Running setup.py (path:/tmp/pip-MpgRrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:11:57 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)) 22:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:11:57 INFO - Running setup.py (path:/tmp/pip-AITtoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:11:57 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)) 22:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:11:57 INFO - Running setup.py (path:/tmp/pip-U4ujHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:11:57 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)) 22:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:11:57 INFO - Running setup.py (path:/tmp/pip-tAd5c2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:11:57 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)) 22:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:11:57 INFO - Running setup.py (path:/tmp/pip-LOFrMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:11:58 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)) 22:11:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:11:58 INFO - Running setup.py (path:/tmp/pip-OTr2XG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:11:58 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)) 22:11:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:11:58 INFO - Running setup.py (path:/tmp/pip-sCfAui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:11:58 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)) 22:11:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:11:58 INFO - Running setup.py (path:/tmp/pip-AZJbb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:11:58 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)) 22:11:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:11:58 INFO - Running setup.py (path:/tmp/pip-ecR5GU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:11:58 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)) 22:11:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:11:58 INFO - Running setup.py (path:/tmp/pip-1bWk55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:11:58 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)) 22:11:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:11:58 INFO - Running setup.py (path:/tmp/pip-RpJCJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:11:58 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)) 22:11:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:58 INFO - Running setup.py (path:/tmp/pip-8e_x83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:58 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)) 22:11:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:11:58 INFO - Running setup.py (path:/tmp/pip-Ilc3HR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:11:58 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)) 22:11:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:11:58 INFO - Running setup.py (path:/tmp/pip-E3_6u3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:11:58 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)) 22:11:58 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)) 22:11:58 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)) 22:11:58 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)) 22:11:58 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)) 22:11:58 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)) 22:11:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:11:58 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 22:11:58 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 22:11:58 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 22:11:58 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 22:11:58 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 22:11:58 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 22:12:00 INFO - Downloading blessings-1.6.tar.gz 22:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:12:00 INFO - Installing collected packages: blessings 22:12:00 INFO - Running setup.py install for blessings 22:12:00 INFO - Successfully installed blessings 22:12:00 INFO - Cleaning up... 22:12:00 INFO - Return code: 0 22:12:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:12:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:12:01 INFO - Reading from file tmpfile_stdout 22:12:01 INFO - Current package versions: 22:12:01 INFO - argparse == 1.2.1 22:12:01 INFO - blessings == 1.6 22:12:01 INFO - blobuploader == 1.2.4 22:12:01 INFO - docopt == 0.6.1 22:12:01 INFO - functools32 == 3.2.3-2 22:12:01 INFO - jsonschema == 2.5.1 22:12:01 INFO - manifestparser == 1.1 22:12:01 INFO - mozInstall == 1.12 22:12:01 INFO - mozcrash == 0.17 22:12:01 INFO - mozdebug == 0.1 22:12:01 INFO - mozdevice == 0.48 22:12:01 INFO - mozfile == 1.2 22:12:01 INFO - mozhttpd == 0.7 22:12:01 INFO - mozinfo == 0.9 22:12:01 INFO - mozleak == 0.1 22:12:01 INFO - mozlog == 3.3 22:12:01 INFO - moznetwork == 0.27 22:12:01 INFO - mozprocess == 0.23 22:12:01 INFO - mozprofile == 0.28 22:12:01 INFO - mozrunner == 6.12 22:12:01 INFO - mozscreenshot == 0.1 22:12:01 INFO - mozsystemmonitor == 0.3 22:12:01 INFO - moztest == 0.7 22:12:01 INFO - mozversion == 1.4 22:12:01 INFO - psutil == 3.1.1 22:12:01 INFO - requests == 1.2.3 22:12:01 INFO - wsgiref == 0.1.2 22:12:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:01 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 0x7fc1b6b32f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb6090>, '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 0x1bb99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb9e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', '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 22:12:01 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 22:12:01 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 22:12:01 INFO - Using env: {'DISPLAY': ':0', 22:12:01 INFO - 'HOME': '/home/cltbld', 22:12:01 INFO - 'LANG': 'en_US.UTF-8', 22:12:01 INFO - 'LANGUAGE': 'en_US:en', 22:12:01 INFO - 'LOGNAME': 'cltbld', 22:12:01 INFO - 'MAIL': '/var/mail/cltbld', 22:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:01 INFO - 'MOZ_NO_REMOTE': '1', 22:12:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:01 INFO - 'NO_EM_RESTART': '1', 22:12:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:01 INFO - 'PWD': '/builds/slave/test', 22:12:01 INFO - 'SHELL': '/bin/bash', 22:12:01 INFO - 'SHLVL': '1', 22:12:01 INFO - 'TERM': 'linux', 22:12:01 INFO - 'TMOUT': '86400', 22:12:01 INFO - 'USER': 'cltbld', 22:12:01 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', 22:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:01 INFO - '_': '/tools/buildbot/bin/python'} 22:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:01 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)) 22:12:01 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)) 22:12:01 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)) 22:12:01 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)) 22:12:01 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)) 22:12:01 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)) 22:12:01 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)) 22:12:01 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)) 22:12:01 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)) 22:12:01 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)) 22:12:01 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)) 22:12:01 INFO - Cleaning up... 22:12:01 INFO - Return code: 0 22:12:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:12:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:12:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:12:01 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 0x7fc1b6b32f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1aed8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bbc230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bb6090>, '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 0x1bb99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb9e40>, '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': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', '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 22:12:01 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 22:12:01 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 22:12:01 INFO - Using env: {'DISPLAY': ':0', 22:12:01 INFO - 'HOME': '/home/cltbld', 22:12:01 INFO - 'LANG': 'en_US.UTF-8', 22:12:01 INFO - 'LANGUAGE': 'en_US:en', 22:12:01 INFO - 'LOGNAME': 'cltbld', 22:12:01 INFO - 'MAIL': '/var/mail/cltbld', 22:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:01 INFO - 'MOZ_NO_REMOTE': '1', 22:12:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:01 INFO - 'NO_EM_RESTART': '1', 22:12:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:01 INFO - 'PWD': '/builds/slave/test', 22:12:01 INFO - 'SHELL': '/bin/bash', 22:12:01 INFO - 'SHLVL': '1', 22:12:01 INFO - 'TERM': 'linux', 22:12:01 INFO - 'TMOUT': '86400', 22:12:01 INFO - 'USER': 'cltbld', 22:12:01 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', 22:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:01 INFO - '_': '/tools/buildbot/bin/python'} 22:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:12:01 INFO - Cleaning up... 22:12:01 INFO - Return code: 0 22:12:01 INFO - Running post-action listener: _resource_record_post_action 22:12:01 INFO - Running post-action listener: _start_resource_monitoring 22:12:01 INFO - Starting resource monitoring. 22:12:01 INFO - [mozharness: 2016-09-01 05:12:01.529410Z] Finished create-virtualenv step (success) 22:12:01 INFO - [mozharness: 2016-09-01 05:12:01.529920Z] Running install step. 22:12:01 INFO - Running pre-action listener: _resource_record_pre_action 22:12:01 INFO - Running main action method: install 22:12:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:12:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:12:01 INFO - Reading from file tmpfile_stdout 22:12:01 INFO - Detecting whether we're running mozinstall >=1.0... 22:12:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:12:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:12:01 INFO - Reading from file tmpfile_stdout 22:12:01 INFO - Output received: 22:12:01 INFO - Usage: mozinstall [options] installer 22:12:01 INFO - Options: 22:12:01 INFO - -h, --help show this help message and exit 22:12:01 INFO - -d DEST, --destination=DEST 22:12:01 INFO - Directory to install application into. [default: 22:12:01 INFO - "/builds/slave/test"] 22:12:01 INFO - --app=APP Application being installed. [default: firefox] 22:12:01 INFO - mkdir: /builds/slave/test/build/application 22:12:01 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'] 22:12:01 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 22:12:17 INFO - Reading from file tmpfile_stdout 22:12:17 INFO - Output received: 22:12:17 INFO - /builds/slave/test/build/application/firefox/firefox 22:12:17 INFO - Running post-action listener: _resource_record_post_action 22:12:17 INFO - [mozharness: 2016-09-01 05:12:17.959522Z] Finished install step (success) 22:12:17 INFO - [mozharness: 2016-09-01 05:12:17.959805Z] Running run-tests step. 22:12:17 INFO - Running pre-action listener: _resource_record_pre_action 22:12:17 INFO - Running main action method: run_tests 22:12:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:12:17 INFO - Python 2.7.3 22:12:17 INFO - Return code: 0 22:12:17 INFO - grabbing minidump binary from tooltool 22:12: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')]} 22:12:17 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 0x1bb6090>, '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 0x1bb99b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bb9e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:12:17 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 22:12:17 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 22:12:17 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 22:12:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:12:18 INFO - Return code: 0 22:12:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:12:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:12:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:12:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:12:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:12:18 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/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 22:12:18 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/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 22:12:18 INFO - Using env: {'DISPLAY': ':0', 22:12:18 INFO - 'HOME': '/home/cltbld', 22:12:18 INFO - 'LANG': 'en_US.UTF-8', 22:12:18 INFO - 'LANGUAGE': 'en_US:en', 22:12:18 INFO - 'LOGNAME': 'cltbld', 22:12:18 INFO - 'MAIL': '/var/mail/cltbld', 22:12:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:12:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:18 INFO - 'MOZ_NO_REMOTE': '1', 22:12:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:12:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:12:18 INFO - 'NO_EM_RESTART': '1', 22:12:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:18 INFO - 'PWD': '/builds/slave/test', 22:12:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:12:18 INFO - 'SHELL': '/bin/bash', 22:12:18 INFO - 'SHLVL': '1', 22:12:18 INFO - 'TERM': 'linux', 22:12:18 INFO - 'TMOUT': '86400', 22:12:18 INFO - 'USER': 'cltbld', 22:12:18 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629', 22:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:18 INFO - '_': '/tools/buildbot/bin/python'} 22:12:18 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/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 22:12:18 INFO - mozversion application_buildid: 20160831203921 22:12:18 INFO - mozversion application_changeset: 0dfbee74cdbbfa8ad688860554bee62add4479c6 22:12:18 INFO - mozversion application_display_name: Nightly 22:12:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:12:18 INFO - mozversion application_name: Firefox 22:12:18 INFO - mozversion application_remotingname: firefox 22:12:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:12:18 INFO - mozversion application_vendor: Mozilla 22:12:18 INFO - mozversion application_version: 51.0a1 22:12:18 INFO - mozversion platform_buildid: 20160831203921 22:12:18 INFO - mozversion platform_changeset: 0dfbee74cdbbfa8ad688860554bee62add4479c6 22:12:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:12:18 INFO - mozversion platform_version: 51.0a1 22:12:18 INFO - using testdate: 1472706738 22:12:18 INFO - actual date: 1472706738 22:12:18 INFO - starting webserver on 'localhost:57992' 22:12:18 INFO - SUITE-START | Running 2 tests 22:12:18 INFO - TEST-START | dromaeo_css 22:12:18 INFO - operating with platform_type : linux_ 22:12:18 INFO - Initialising browser for dromaeo_css test... 22:12:18 INFO - TEST-INFO | started process 29821 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptBQHME/profile http://localhost:57992/getInfo.html) 22:12:22 INFO - PROCESS | 29821 | __metrics Screen width/height:1600/1200 22:12:22 INFO - PROCESS | 29821 | colorDepth:24 22:12:22 INFO - PROCESS | 29821 | Browser inner width/height: 1024/697 22:12:22 INFO - PROCESS | 29821 | __metrics 22:12:23 INFO - PROCESS | 29821 | 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" 22:12:23 INFO - PROCESS | 29821 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:12:24 INFO - TEST-INFO | 29821: exit 0 22:12:24 INFO - Browser initialized. 22:12:24 INFO - Running cycle 1/1 for dromaeo_css test... 22:12:24 INFO - TEST-INFO | started process 29903 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptBQHME/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:12:36 INFO - PROCESS | 29903 | RSS: Main: 181272576 22:12:36 INFO - PROCESS | 29903 | 22:13:28 INFO - PROCESS | 29903 | 1472706808451 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] 22:15:41 INFO - PROCESS | 29903 | Cycle 1(1): loaded http://localhost:57992/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57992/tests/dromaeo/cssquery-ext.html) 22:15:42 INFO - PROCESS | 29903 | RSS: Main: 305287168 22:15:42 INFO - PROCESS | 29903 | 22:18:45 INFO - PROCESS | 29903 | Cycle 1(1): loaded http://localhost:57992/tests/dromaeo/cssquery-ext.html (next: http://localhost:57992/tests/dromaeo/cssquery-jquery.html) 22:18:45 INFO - PROCESS | 29903 | RSS: Main: 273227776 22:18:45 INFO - PROCESS | 29903 | 22:21:47 INFO - PROCESS | 29903 | Cycle 1(1): loaded http://localhost:57992/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57992/tests/dromaeo/cssquery-mootools.html) 22:21:48 INFO - PROCESS | 29903 | RSS: Main: 247263232 22:21:48 INFO - PROCESS | 29903 | 22:24:50 INFO - PROCESS | 29903 | Cycle 1(1): loaded http://localhost:57992/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57992/tests/dromaeo/cssquery-prototype.html) 22:24:51 INFO - PROCESS | 29903 | RSS: Main: 220110848 22:24:51 INFO - PROCESS | 29903 | 22:27:54 INFO - PROCESS | 29903 | Cycle 1(1): loaded http://localhost:57992/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57992/tests/dromaeo/cssquery-yui.html) 22:27:55 INFO - PROCESS | 29903 | RSS: Main: 219041792 22:27:55 INFO - PROCESS | 29903 | 22:30:58 INFO - PROCESS | 29903 | Cycle 1(1): loaded http://localhost:57992/tests/dromaeo/cssquery-yui.html (next: http://localhost:57992/tests/dromaeo/cssquery-dojo.html) 22:30:58 INFO - PROCESS | 29903 | RSS: Main: 309325824 22:30:58 INFO - PROCESS | 29903 | 22:30:58 INFO - PROCESS | 29903 | __start_tp_report 22:30:58 INFO - PROCESS | 29903 | _x_x_mozilla_page_load 22:30:58 INFO - PROCESS | 29903 | _x_x_mozilla_page_load_details 22:30:58 INFO - PROCESS | 29903 | |i|pagename|runs| 22:30:58 INFO - PROCESS | 29903 | |0;dojo.html;2617.2279177642595;2618.6988496322924;2628.067036202147;2667.906623268186;2680.396910695094;4587.220172570663;4643.860684179472;4665.230237010894;4757.524247575241;4824.204006338954;4671.5562021607975;4696.577308042276;4723.811047558093;4731.219348807451;4791.568758811706;4312.762798046107;4333.523312435629;4338;4346.891327716817;4469.262571675669;4402.3836662867225;4402.449693788277;4409.250427427329;4410.558944105596;4440.467143942716;4235.428217190702;4273.487181538204;4274.401583778274;4286.61420472159;4346.543612920661;4306.956930430687;4309.547497512779;4313.15893400787;4314.245007123741;4321.524632290446;4257.084726783756;4287.185434767415;4299.269124248885;4311.073119279338;4346.15250026245;4622.699524530894;4661.09108723799;4661.114388266218;4699.882502937419;4765.26138448541;5023.874403139914;5028.7988480460735;5043.117454445494;5049.267856160836;5051.924221136685;4892.6575139740235;5033.974830125862;5047.621428392848;5067.189249720027;5076.796928122871;4685.0395668887795;4692.366530518337;4698.976505117521;4703.2004559225015;4706.270528068155;5159.793608255628;5160;5170.1469257572835;5172.948270517322;5195.714235716996;4727.763611819395;4742.644301677388;4826.493218212037;4872.366592342973;4945.23348880924;4938.9012219755405;4971.6271279654175;4974.228994505857;4974.626902982291;5020.899582008339;4908.803647854117;4922;4928.852134435972;4931.4821943696115;4982.7259500727805;4795.448523419782;4944.802207911644;4986.576141028053;5024.547790698854;5060.873478163076;4271.829126834954;4272.230998420252;4273.87178384649;4317.460317460317;4325.7620830853975;3716.554013518341;3737.6822970047306;3740.4202348636004;3779.3575092233837;3791.3175628387157;4223.936640950388;4268.423762792046;4289.506706728766;4351.673624478177;4372.497162826316;5043.646944713835;5065.240213967934;5065.848024559269;5101.285819985205;5119.0529751996;4336.869893903188;4339.500957389878;4353.651707863363;4396.780160991938;4404.867853964386;4379.080393117417;4389.056352884145;4406.757628330409;4546.227141385973;4546.249868771682;4549.11292298012;4553;4590.288505281686;4595.425571803525;4604.171249175047;4817.253325734517;4821.975890120527;4826.638002149923;4856.975715121402;4930.087933732271;4488.730676159441;4512.593866552026;4538.88652783683;4551.36280920678;4597.931031034604;4896.730679812645;4933.6299777516115;4956.058348913695;5013.799448022039;5016.498350164954;4994.700317980787;5015.398152221756;5015.498450154955;5023.598112150946;5077.238414237894;4747.026859493864;4778.737169455713;4878.731669758055;4896.216605343128;4956.975215123901;4837.395325584302;4854;4920.089783390084;4957.776900039579;4986.252062190701;4854.75726213695;4864.270359446112;4866.635002374906;4866.8053277868485;4910.459849416633;4957.975210123926;4958.752062396794;4962.627802914723;4964;5003.924941125813;4667.696599721007;4798;4848.200046992348;4851.29656199842;4898.069366820293 22:30:58 INFO - PROCESS | 29903 | |1;ext.html;560.6636018388966;570.8287513745876;571.2516603249743;574.253470488365;576.1616847486904;27593.724062759367;30600;37002.629973700256;38341.80829095851;38425.23149537014;38929.41605875914;39376.40935385964;39388.803055984754;39388.803055984754;39393.21213575727;7611.200823913492;7722.459427840054;7746.8450630987345;7749.263819937097;7759.185285544993;7092.929070709304;9671.226301895831;9899.406035637849;9920.156786673142;10253.84619230712;9980.30137890348;10061.899381006206;10071.194304455626;10075.596976120947;10082.092611664948;9824.459654718987;9857;9908.35595686278;9909.851352229722;9918.752031199205;9767.365121267096;9802.90197098027;9809.558569864374;9833.655822046265;9836.672049273328;30979.0706278812;31152.688473115206;31161.532577011363;31213.37573248545;31308.53037204444;2673.2915777319026;2775.2090654163667;2807.368342123026;2829.773618110546;2850.743433091011;27059;27260.727392726018;27413.31466713348;27432.588511172347;27548.17355479339;3192.201949512622;3264.4287249731437;3267.1015470745733;3281.949776071658;3299.554560134375;248611;249284.75357623276;249425;249463;249948;41604.79197603992;41651.79174104171;41678.37482437766;41698.1660366797;41735.16529669389;32250.35499290001;32489.675103249137;32592;34469.31061378758;53425.1986220207;13237;13284.269365185008;13297.135686180562;13303.201807891364;13321.267330296927;9840;10315.690529284133;10762.408067556204;10772.353658780341;10777.407242601732;17202.397916072878;17291;17322.393716220125;17326.480205593853;17353;5463.644863083887;5482.150266708666;5492.368377636543;5665.433456654301;5677.517411020027;759.1838773318594;893.660409044559;903.1510380242551;913.6893456224915;986.6053578568486;26889.193324200307;27684.584731229046;28191.15426537207;28242.576361354597;28363;881.6164968238836;903.4804987132425;920.8848893888264;924.2790623313826;957.0764212534798;13313.33433328349;14021.719565608631;14101;14315.355808988412;14370.353334099991;385002;390879;390971;391198;391392;47392.763036185286;47883.521164787904;47942.76028619835;47990;48045.279320810216;1032.1020711980625;1134.7276653603076;1157.8436911016904;1165.9125565582615;1198.3409124981226;25674.871625641754;27969.160925172277;28733.99431019988;28772.856135719187;28844.134675959755;40735.592644074364;41185.1762964745;41250.1749964993;41352;41419.1716165681;319170;322364;322460;323034;323378;24199.87900060559;24226.152084676953;25088.37279067965;25156.59759542036;26153.73846261513;40112.39831402473;40152;40423.3936490947;40734.59265407308;41390.793046034574;35259.29481410409;35338.82330588331;35715.28569428649;35819.64180358163;36022.63977360193;34280.48579271263;34363;34506.30987380188;34683.65316346804;34693.82653086718;28941.421171576872;29036.27409314784;29121.41757164887;31928.5210721844;35393;29578.408431831675;32484.187895302806;32509.837450812596;32529.837350813094;32614.086309642993;24617.507649846546;24962.750372496044;24990.500189995735;25184.24447266585;25366.49267014686 22:30:58 INFO - PROCESS | 29903 | |2;jquery.html;339.8148009334912;354.3762977160199;358.61448942386926;360.1841828258993;379.0618219905734;15708;16287.59281017975;16567.420140295093;17288.394906178288;17422.82577174228;16166.515004549883;16220.188990550485;16285.185740712976;16666.66666666666;16780.160991950415;4876.4148302203785;4913.80344786209;5035.320231768719;5057.013882292954;5059.519345662156;3558.6975107115927;4930;5008;5543.223948647193;5875;5946.43508866657;5954.55340849438;5968.492678122365;5973.910391344133;5976.4919981801595;4996.375453068366;5268.525832675077;5286.709230992294;5311.893762124774;5925.377835327272;5143.228515722634;5144.279800827887;5147.4080480744815;5154.226865970097;5155.200943853709;11425.885741142649;11498.655040348804;11640.010599099087;11723.76552468946;11772.529098836036;1807.1957978699484;1871.7285993530998;2012.1951219512166;2063.494443861249;2105.0211651581935;8335;8566.18621230982;9004.279657627374;9145.725628231165;11114.444277786079;1657.3867668962562;1674.9330026798914;1698.3376483171573;1699.8045224799182;1715.4682048564985;69580.6520967392;69617;69896;71277.93083103758;72055.27944720538;9592.232621390272;9690.224782017422;9887.45618990948;9924;10013.699589012342;13463.192208467523;13476.663083422993;13480.460781568823;13499.662508437172;13501.987350948719;7026.92973070273;7055.964720176366;7129.821754456077;7196.172440169343;7398.593077380796;6725.831854203684;6871.896921546181;6983.301669832976;7168;7179.317964793337;4106.7125301229225;4116.567760385176;4120.2583534964;4135.276326642826;4275.444192254987;1694.5848267174622;1711.24705129745;1722.457425910834;1737.9725640809047;1751.8073011968672;621.7295476467751;840.1976112812249;849.5964416902045;869.1482347299682;901;15448.536543903701;15579.454719085004;16118.677626447405;16138.75791863123;17319.2206350712;818.9682984816782;831.2061980808313;835.423557745166;862.8878245828002;911.6717981526646;9305.209057230077;9513.14381705636;9537.856932146024;9569.521523923915;9758.316917815684;394647;413361;415624;419337;420988;74143.62928185324;84149;86382.56808715916;86613.5669321662;87396;911.8632205169278;927.7541451515219;933.780561568031;941.915227629503;943.4858002388714;14632.780508292597;14829.40682372693;14999.7000059996;15390.230488475756;15558.144302063496;30084.097477075724;34245;37855.05362365963;38395.27221274994;39422.211555769296;47901;48980.51019489759;51062;52210.73894630503;52510.73744631252;4760.761961901821;5523.834284971457;6397.776077837253;6739.326067393222;6918.8616227676175;10502.52738626779;11123.944380278046;11337.319760814378;11638.476268567765;11951.342676152895;10053.849192262269;10104.191664666661;10169.271223891808;10495;10584.206184536037;9683.906615560642;9800.411975281506;9817.460039697886;9957.850632240667;10084.193264538966;9980.950095249478;10205.724284464442;10361.681329423182;10575.15398768112;10671.35971841692;6649.933500664932;6778.561663656577;6793.252742198255;7072.080629518336;7083.539569927938;8807.911920880966;9882;10105.24210684187;10262.076413122855;10492.475376231005 22:30:58 INFO - PROCESS | 29903 | |3;mootools.html;1302.8306320178378;1348.2382453913538;1372.3549931532182;1605.075590182004;1611.7824093747347;6499.447546958502;7354.227806080358;7400.296971787671;7962.681492740291;7992.920070799283;7484.139323977731;7707.22927707229;7713.614319284027;7719.4210434217375;7725.575093369863;2182.2580322690324;2215.3907675389237;2216.656418255173;2217.4456385903522;2219.1678120704737;1832.3128826689992;2074.471009892483;2161.135545781684;2173.3588591365506;2202.7907348801837;2188.3106821351303;2209.3482422685265;2209.602271591113;2209.624363858148;2211.535577528713;2116.6401711709045;2127.563849410867;2162.664786958024;2163.3185546552786;2163.350994701583;2120.978790212109;2122.1511395441794;2137.7755335689685;2142.828573714099;2153.6231159547015;7745;7829.843403131906;7833.412494062911;7834.804129896798;7851.057873055212;4520.141173177086;4650.651201159926;4650.813967441299;4656.348111264426;4660.207764680013;2806.256342069353;2890.8699108539954;2962.614860068199;2985.2835319523374;2986.880524779006;844.9915500845036;892.9553522323857;893.0176805513947;896.7802888292345;908.309684639672;10302.587896484207;10317.226208034352;10327.58689652413;10335.483225838752;10364.948175259151;3789.2989796887114;4053.9797301013896;4140.8136633851545;4212.7682977435925;4228.725132866415;8809.691660791841;8822.25010874083;8830.161134692195;8834.381593288408;8834.823303533893;4677.298405239173;4684.859454216379;4686.5782079613;4700.929486057712;4707.5057119002695;4225.654332692907;4257.680673949466;4284.614384705392;4287.2711639021445;4309.00892794659;3540.150363912694;3634.2186429917697;3685.0971511979737;3699.5005674233635;3704.1664376133986;1040.6773900090996;1132.660201939415;1176.7822952753768;1184.2420850655587;1189.2329447506484;728.7340120855955;741.5476559298824;777.6578305545542;798.7923139983646;818.2880893622468;4978.5270399312;5083.389993200765;5117.488251174927;5156.793728250828;5356.437574054746;812.959352032396;842.5113434208267;846.9990119999748;856.1737922904427;882.1090698394668;3496.615372309044;3587.4798154267487;3889.53325600924;3908.081600823804;3962.782046987386;9419.387739796892;9529.237660987104;9689.515524223902;9720.416774993522;9758.658446954323;9620.470874101991;9622.037796220322;9660.613575457044;9672.419654820593;9680.75798105053;7115.89326160108;7196.244394338623;7332.23011583776;7385.889211661829;7398.482106252511;4818.903621927612;4939.555440010273;4950.232713929347;4970.701757894538;5094.770735316845;8737.344699147463;8799.956000219958;8812.779680508038;8998.100189980949;9002.459852408876;8959.1936725695;9045.728628141167;9059.22996545301;9140.177384035469;9225.031371705874;6074.665893375906;6217.378262173746;6218.533609979179;6232.407921247474;6235.095911092957;6851.554648947803;6874.621895795779;6934.514583979275;6942.687579058854;7040.542364746275;5500.147477141049;5958.731857066356;6370.26741924666;6381.425671689571;6414.61512309263;6151.907721384094;6203.286622038523;6219.875602488016;6414.583052101599;6631.403173714389;6125.663088530174;6126.448619624255;6163.044728067157;6169.136320915165;6178.104174894705;4152.2525945328925;4190.497140343178;4271.252530807158;4303.407739136414;4391.824327026883;4266.338717498793;4316.244657184917;4377.934330984974;4436.933445998248;4508.887277818081 22:30:58 INFO - PROCESS | 29903 | |4;prototype.html;284.91167738001224;287.28609405627014;290.6468640601667;293.52595558173556;293.8677595082213;4710.05798840232;4778.37535238019;4789.281607758838;4806.83176088837;4839.927401088982;4751.572358487735;4771.90456190876;4778.6416018798645;4809.979808480438;4814.181589129847;3530.117470632342;3594.7124230061663;3708.3139619170406;3766.6610005099537;3775.8678446254385;3716.699155295652;3730.8694195703156;3732.4774531565604;3733.813309334536;3737.822585885451;3696.853975267662;3699.926001479969;3700.1859590889962;3706.314331848617;3710.610385909468;3518.894433167009;3578;3701.777893326409;3728.4966529518574;3741.7193710471824;3223.403670320998;3247.1328985833006;3248.4964830451318;3253.9511907321407;3254.3491301739746;4631.675782695213;4642.883927901796;4645.628349732014;4652.162610730067;4660.5339466053465;4076.2255171517318;4210.810513526899;4224.809883555247;4226.4716910386205;4231.196072746168;4944.381952255968;4980.501949805027;4981.551660350586;4988.725620090893;4996.2755400467095;3559.661832125944;3562.305350456636;3569.0542006368332;3577.481265216555;3579.284143171376;5206.323177986876;5218.530332270114;5228.686278823283;5229.424763276036;5232.555232805178;3367.4948757686543;3536.8938931832085;3569.286142771456;3573.982130089385;3634.436662317345;3607.8917632471066;3616.4575313703153;3618.149734812317;3618.7105031597407;3625.619309972468;4162.958370416257;4167.395727619539;4170.582941705866;4170.62179634497;4175.457190565207;4744.7390393528685;5010.2735103409805;5013.724245166478;5017.272495488134;5024.171011783015;1214.1622280626339;1241.7582417582419;1242.7949388351;1243.198137201518;1244.8630650628425;779.9727009554638;791.6437603078638;806.4354951533833;813.7151996801073;821.1829229916272;4002.3796311571755;4269.398975384231;4277.486701595828;4450.109978004412;4460.687751857339;5172.8965420691375;5215.504527069922;5226.5818734501145;5236.29310043147;5243.947560524422;4477.753723545236;4493.573110554492;4508.45898492183;4508.616767574793;4510.616597589176;2611.7779988701172;2711.240852561286;2724.809263351546;2733.521633714092;2744.903928362498;4794.448638406628;4797.280407938767;4797.904041919142;4799.6400269979895;4801.639877009238;5249.343832020997;5263.105272103675;5289.148797920697;5289.788408463618;5303.575713942875;4654.13898428785;4758.452777930583;4788.209945358977;4796;4798.4001999750035;5122.923156152586;5127.179651255781;5142.382914050338;5159.045576568347;5165.225216217447;5043.722595257149;5048.3941926969;5082.551472830226;5090.669106508065;5093.4023647939075;4579.564941330568;4726.409198850144;4760.523947605212;4763.7141771493825;4795.520447955177;3551.8224088795973;3551.893443196708;3552.87564935226;3561.6438356164176;3573.3210689968823;5085.898282034413;5120.182335271074;5137.843985103322;5152.330197074356;5165.61257905651;4718.41019872516;4954.801807927786;5008.524190201925;5042.39491261051;5058.924116138334;5000.099982003274;5012.774425150804;5024.397072351341;5035.949640503548;5045.772940217773;4983.9501604984935;4984.40187177541;4988.702937236272;4992.753261032477;5005.899882002267;4821.180399332192;4898.461169271449;4942.46783497117;5039.395272567315;5082.136036873814;4972.2790195422185;4972.552470277692;4978.4523702393435;4981.925271120864;4985.476524964826 22:30:58 INFO - PROCESS | 29903 | |5;yui.html;555.4251349852902;611.7850694599877;617.444300129883;673.5117040145892;708.592559278415;13294.734105317888;14458.927705361484;14467.421303147861;14508.129512229274;14566.344514496846;12656.746865062692;12690.746185076294;12702;12727.172733772299;12732.663070377615;587.9265091863517;714.2250658036037;718.1346477494619;737.4542838299674;739.7632757517597;734.0494060192065;735.2169939014959;736.2710916192957;736.5065406177847;740.1525253584642;763.9961800190991;764.6788348893479;764.7132325377984;764.8126209078756;765.7932358263265;622.8162692005847;744.6202436757266;765.7281665008898;767.1369709077288;775.1434664695543;545.2747845365654;545.5362941499733;546.7976848566017;547.2311402479482;550.3368441028551;13016.674583135402;13018.544350947672;13049.86950130496;13053.738925221538;13065.869341306561;12416.255024698547;12774.361281935866;13057.086003979724;13245.933770331183;13358.866411335859;11784.234024788448;12105.576304829377;12110.757784844342;12159.331236781973;12297.75404491914;4058.553559108496;4072.287349713818;4096.303628383182;4140.0891803803115;4219.894502637428;64647;64732.67633661849;66535;68997;68998.3100169002;10053.447060411601;10053.849192262122;10054.949725251327;10072.395656260647;10223.335483193569;18722.344717934808;18774.812251877578;18780.154893029845;18791.624167516573;18802.717959230624;6534.117894084237;6640.435562977201;6683.866322673616;6687.866242675216;6789.422899053636;3689.409694448875;3725.6274372562525;3790.166163444038;3803.0112170835782;3880.049387899983;2629.9211023669322;2728.822626529269;2761.3648860762137;2768.6400767900045;2772.8613569321856;474.14890271961224;479.94000749906263;492.5025724018767;496.76651973572365;532.3079996005177;499.2436458764974;523.6910222968467;539.3824071438181;569.5386736743251;596.150485558078;6773.62745049027;7351.595662238521;7505.849883002309;8312.625931833081;8374.204450577185;457.2250036188695;473.80810771637545;524.1168630856941;577.566824881339;578.109711044986;3770.6417890300377;3872.206197729459;4042.4542686736913;4093.1813637272667;4132.8140233689555;101286;102321;104751.47624261983;110374;111547;89673;89845.55077224573;90241;93322.53338733263;95162;79926;82056.17943820605;82188;82755;82963.99999999879;7657.502262352929;7743.806404839924;8003.31971782405;8003.6398362075015;8043.6782528698195;46526.53473465357;46553.495377065155;48003.75998119987;48646;48677.75661121814;38944;65545.67227163834;66492;66551.66724166348;66879.3312066873;9668.75828104303;10812.783744325227;10934.835977460185;11298.152638551977;11313.71715707114;11998.880011199777;12280.49288648131;12848.293343866177;13064.738705225653;13473.258970756702;11530.423478826193;11807.173497855068;12002.159848810848;12158;12676.302803345905;12295.75408491843;12462.127651064338;13259.469621215429;13277.136986095873;13303.201807891557;11545.422728863356;11744.060475161796;12173.452194651085;12378.504859805507;12481.56314528987;9791.951040244754;10044.648437304659;10192.694219173436;10664.07180872195;10691.25161238737;10012.749681258027;10529.45592673433;10591.735206619896;10602.628907988183;10615.31000484966 22:30:58 INFO - PROCESS | 29903 | __end_tp_report 22:30:58 INFO - PROCESS | 29903 | __start_cc_report 22:30:58 INFO - PROCESS | 29903 | _x_x_mozilla_cycle_collect,1109 22:30:58 INFO - PROCESS | 29903 | __end_cc_report 22:30:58 INFO - PROCESS | 29903 | __startTimestamp1472707858672__endTimestamp 22:30:58 INFO - PROCESS | 29903 | 22:30:58 INFO - PROCESS | 29903 | ------- Summary: start ------- 22:30:58 INFO - PROCESS | 29903 | Number of tests: 6 22:30:58 INFO - PROCESS | 29903 | 22:30:58 INFO - PROCESS | 29903 | [#0] dojo.html Cycles:165 Average:4626.03 Median:4731.22 stddev:475.79 (10.1%) stddev-sans-first:450.39 22:30:58 INFO - PROCESS | 29903 | Values: 2617.2 2618.7 2628.1 2667.9 2680.4 4587.2 4643.9 4665.2 4757.5 4824.2 4671.6 4696.6 4723.8 4731.2 4791.6 4312.8 4333.5 4338.0 4346.9 4469.3 4402.4 4402.4 4409.3 4410.6 4440.5 4235.4 4273.5 4274.4 4286.6 4346.5 4307.0 4309.5 4313.2 4314.2 4321.5 4257.1 4287.2 4299.3 4311.1 4346.2 4622.7 4661.1 4661.1 4699.9 4765.3 5023.9 5028.8 5043.1 5049.3 5051.9 4892.7 5034.0 5047.6 5067.2 5076.8 4685.0 4692.4 4699.0 4703.2 4706.3 5159.8 5160.0 5170.1 5172.9 5195.7 4727.8 4742.6 4826.5 4872.4 4945.2 4938.9 4971.6 4974.2 4974.6 5020.9 4908.8 4922.0 4928.9 4931.5 4982.7 4795.4 4944.8 4986.6 5024.5 5060.9 4271.8 4272.2 4273.9 4317.5 4325.8 3716.6 3737.7 3740.4 3779.4 3791.3 4223.9 4268.4 4289.5 4351.7 4372.5 5043.6 5065.2 5065.8 5101.3 5119.1 4336.9 4339.5 4353.7 4396.8 4404.9 4379.1 4389.1 4406.8 4546.2 4546.2 4549.1 4553.0 4590.3 4595.4 4604.2 4817.3 4822.0 4826.6 4857.0 4930.1 4488.7 4512.6 4538.9 4551.4 4597.9 4896.7 4933.6 4956.1 5013.8 5016.5 4994.7 5015.4 5015.5 5023.6 5077.2 4747.0 4778.7 4878.7 4896.2 4957.0 4837.4 4854.0 4920.1 4957.8 4986.3 4854.8 4864.3 4866.6 4866.8 4910.5 4958.0 4958.8 4962.6 4964.0 5003.9 4667.7 4798.0 4848.2 4851.3 4898.1 22:30:58 INFO - PROCESS | 29903 | 22:30:58 INFO - PROCESS | 29903 | [#1] ext.html Cycles:180 Average:46047.71 Median:3274.53 stddev:85777.03 (2619.5%) stddev-sans-first:85949.66 22:30:58 INFO - PROCESS | 29903 | Values: 560.7 570.8 571.3 574.3 576.2 27593.7 30600.0 37002.6 38341.8 38425.2 38929.4 39376.4 39388.8 39388.8 39393.2 7611.2 7722.5 7746.8 7749.3 7759.2 7092.9 9671.2 9899.4 9920.2 10253.8 9980.3 10061.9 10071.2 10075.6 10082.1 9824.5 9857.0 9908.4 9909.9 9918.8 9767.4 9802.9 9809.6 9833.7 9836.7 30979.1 31152.7 31161.5 31213.4 31308.5 2673.3 2775.2 2807.4 2829.8 2850.7 27059.0 27260.7 27413.3 27432.6 27548.2 3192.2 3264.4 3267.1 3281.9 3299.6 248611.0 249284.8 249425.0 249463.0 249948.0 41604.8 41651.8 41678.4 41698.2 41735.2 32250.4 32489.7 32592.0 34469.3 53425.2 13237.0 13284.3 13297.1 13303.2 13321.3 9840.0 10315.7 10762.4 10772.4 10777.4 17202.4 17291.0 17322.4 17326.5 17353.0 5463.6 5482.2 5492.4 5665.4 5677.5 759.2 893.7 903.2 913.7 986.6 26889.2 27684.6 28191.2 28242.6 28363.0 881.6 903.5 920.9 924.3 957.1 13313.3 14021.7 14101.0 14315.4 14370.4 385002.0 390879.0 390971.0 391198.0 391392.0 47392.8 47883.5 47942.8 47990.0 48045.3 1032.1 1134.7 1157.8 1165.9 1198.3 25674.9 27969.2 28734.0 28772.9 28844.1 40735.6 41185.2 41250.2 41352.0 41419.2 319170.0 322364.0 322460.0 323034.0 323378.0 24199.9 24226.2 25088.4 25156.6 26153.7 40112.4 40152.0 40423.4 40734.6 41390.8 35259.3 35338.8 35715.3 35819.6 36022.6 34280.5 34363.0 34506.3 34683.7 34693.8 28941.4 29036.3 29121.4 31928.5 35393.0 29578.4 32484.2 32509.8 32529.8 32614.1 24617.5 24962.8 24990.5 25184.2 25366.5 22:30:58 INFO - PROCESS | 29903 | 22:30:58 INFO - PROCESS | 29903 | [#2] jquery.html Cycles:180 Average:24702.03 Median:4921.90 stddev:68280.55 (1387.3%) stddev-sans-first:68447.59 22:30:58 INFO - PROCESS | 29903 | Values: 339.8 354.4 358.6 360.2 379.1 15708.0 16287.6 16567.4 17288.4 17422.8 16166.5 16220.2 16285.2 16666.7 16780.2 4876.4 4913.8 5035.3 5057.0 5059.5 3558.7 4930.0 5008.0 5543.2 5875.0 5946.4 5954.6 5968.5 5973.9 5976.5 4996.4 5268.5 5286.7 5311.9 5925.4 5143.2 5144.3 5147.4 5154.2 5155.2 11425.9 11498.7 11640.0 11723.8 11772.5 1807.2 1871.7 2012.2 2063.5 2105.0 8335.0 8566.2 9004.3 9145.7 11114.4 1657.4 1674.9 1698.3 1699.8 1715.5 69580.7 69617.0 69896.0 71277.9 72055.3 9592.2 9690.2 9887.5 9924.0 10013.7 13463.2 13476.7 13480.5 13499.7 13502.0 7026.9 7056.0 7129.8 7196.2 7398.6 6725.8 6871.9 6983.3 7168.0 7179.3 4106.7 4116.6 4120.3 4135.3 4275.4 1694.6 1711.2 1722.5 1738.0 1751.8 621.7 840.2 849.6 869.1 901.0 15448.5 15579.5 16118.7 16138.8 17319.2 819.0 831.2 835.4 862.9 911.7 9305.2 9513.1 9537.9 9569.5 9758.3 394647.0 413361.0 415624.0 419337.0 420988.0 74143.6 84149.0 86382.6 86613.6 87396.0 911.9 927.8 933.8 941.9 943.5 14632.8 14829.4 14999.7 15390.2 15558.1 30084.1 34245.0 37855.1 38395.3 39422.2 47901.0 48980.5 51062.0 52210.7 52510.7 4760.8 5523.8 6397.8 6739.3 6918.9 10502.5 11123.9 11337.3 11638.5 11951.3 10053.8 10104.2 10169.3 10495.0 10584.2 9683.9 9800.4 9817.5 9957.9 10084.2 9981.0 10205.7 10361.7 10575.2 10671.4 6649.9 6778.6 6793.3 7072.1 7083.5 8807.9 9882.0 10105.2 10262.1 10492.5 22:30:58 INFO - PROCESS | 29903 | 22:30:58 INFO - PROCESS | 29903 | [#3] mootools.html Cycles:180 Average:5027.18 Median:5089.08 stddev:2817.67 (55.4%) stddev-sans-first:2811.68 22:30:58 INFO - PROCESS | 29903 | Values: 1302.8 1348.2 1372.4 1605.1 1611.8 6499.4 7354.2 7400.3 7962.7 7992.9 7484.1 7707.2 7713.6 7719.4 7725.6 2182.3 2215.4 2216.7 2217.4 2219.2 1832.3 2074.5 2161.1 2173.4 2202.8 2188.3 2209.3 2209.6 2209.6 2211.5 2116.6 2127.6 2162.7 2163.3 2163.4 2121.0 2122.2 2137.8 2142.8 2153.6 7745.0 7829.8 7833.4 7834.8 7851.1 4520.1 4650.7 4650.8 4656.3 4660.2 2806.3 2890.9 2962.6 2985.3 2986.9 845.0 893.0 893.0 896.8 908.3 10302.6 10317.2 10327.6 10335.5 10364.9 3789.3 4054.0 4140.8 4212.8 4228.7 8809.7 8822.3 8830.2 8834.4 8834.8 4677.3 4684.9 4686.6 4700.9 4707.5 4225.7 4257.7 4284.6 4287.3 4309.0 3540.2 3634.2 3685.1 3699.5 3704.2 1040.7 1132.7 1176.8 1184.2 1189.2 728.7 741.5 777.7 798.8 818.3 4978.5 5083.4 5117.5 5156.8 5356.4 813.0 842.5 847.0 856.2 882.1 3496.6 3587.5 3889.5 3908.1 3962.8 9419.4 9529.2 9689.5 9720.4 9758.7 9620.5 9622.0 9660.6 9672.4 9680.8 7115.9 7196.2 7332.2 7385.9 7398.5 4818.9 4939.6 4950.2 4970.7 5094.8 8737.3 8800.0 8812.8 8998.1 9002.5 8959.2 9045.7 9059.2 9140.2 9225.0 6074.7 6217.4 6218.5 6232.4 6235.1 6851.6 6874.6 6934.5 6942.7 7040.5 5500.1 5958.7 6370.3 6381.4 6414.6 6151.9 6203.3 6219.9 6414.6 6631.4 6125.7 6126.4 6163.0 6169.1 6178.1 4152.3 4190.5 4271.3 4303.4 4391.8 4266.3 4316.2 4377.9 4436.9 4508.9 22:30:58 INFO - PROCESS | 29903 | 22:30:58 INFO - PROCESS | 29903 | [#4] prototype.html Cycles:180 Average:4135.94 Median:4748.16 stddev:1220.01 (25.7%) stddev-sans-first:1188.70 22:30:58 INFO - PROCESS | 29903 | Values: 284.9 287.3 290.6 293.5 293.9 4710.1 4778.4 4789.3 4806.8 4839.9 4751.6 4771.9 4778.6 4810.0 4814.2 3530.1 3594.7 3708.3 3766.7 3775.9 3716.7 3730.9 3732.5 3733.8 3737.8 3696.9 3699.9 3700.2 3706.3 3710.6 3518.9 3578.0 3701.8 3728.5 3741.7 3223.4 3247.1 3248.5 3254.0 3254.3 4631.7 4642.9 4645.6 4652.2 4660.5 4076.2 4210.8 4224.8 4226.5 4231.2 4944.4 4980.5 4981.6 4988.7 4996.3 3559.7 3562.3 3569.1 3577.5 3579.3 5206.3 5218.5 5228.7 5229.4 5232.6 3367.5 3536.9 3569.3 3574.0 3634.4 3607.9 3616.5 3618.1 3618.7 3625.6 4163.0 4167.4 4170.6 4170.6 4175.5 4744.7 5010.3 5013.7 5017.3 5024.2 1214.2 1241.8 1242.8 1243.2 1244.9 780.0 791.6 806.4 813.7 821.2 4002.4 4269.4 4277.5 4450.1 4460.7 5172.9 5215.5 5226.6 5236.3 5243.9 4477.8 4493.6 4508.5 4508.6 4510.6 2611.8 2711.2 2724.8 2733.5 2744.9 4794.4 4797.3 4797.9 4799.6 4801.6 5249.3 5263.1 5289.1 5289.8 5303.6 4654.1 4758.5 4788.2 4796.0 4798.4 5122.9 5127.2 5142.4 5159.0 5165.2 5043.7 5048.4 5082.6 5090.7 5093.4 4579.6 4726.4 4760.5 4763.7 4795.5 3551.8 3551.9 3552.9 3561.6 3573.3 5085.9 5120.2 5137.8 5152.3 5165.6 4718.4 4954.8 5008.5 5042.4 5058.9 5000.1 5012.8 5024.4 5035.9 5045.8 4984.0 4984.4 4988.7 4992.8 5005.9 4821.2 4898.5 4942.5 5039.4 5082.1 4972.3 4972.6 4978.5 4981.9 4985.5 22:30:58 INFO - PROCESS | 29903 | 22:30:58 INFO - PROCESS | 29903 | [#5] yui.html Cycles:180 Average:18688.15 Median:4114.56 stddev:27393.38 (665.8%) stddev-sans-first:27436.39 22:30:58 INFO - PROCESS | 29903 | Values: 555.4 611.8 617.4 673.5 708.6 13294.7 14458.9 14467.4 14508.1 14566.3 12656.7 12690.7 12702.0 12727.2 12732.7 587.9 714.2 718.1 737.5 739.8 734.0 735.2 736.3 736.5 740.2 764.0 764.7 764.7 764.8 765.8 622.8 744.6 765.7 767.1 775.1 545.3 545.5 546.8 547.2 550.3 13016.7 13018.5 13049.9 13053.7 13065.9 12416.3 12774.4 13057.1 13245.9 13358.9 11784.2 12105.6 12110.8 12159.3 12297.8 4058.6 4072.3 4096.3 4140.1 4219.9 64647.0 64732.7 66535.0 68997.0 68998.3 10053.4 10053.8 10054.9 10072.4 10223.3 18722.3 18774.8 18780.2 18791.6 18802.7 6534.1 6640.4 6683.9 6687.9 6789.4 3689.4 3725.6 3790.2 3803.0 3880.0 2629.9 2728.8 2761.4 2768.6 2772.9 474.1 479.9 492.5 496.8 532.3 499.2 523.7 539.4 569.5 596.2 6773.6 7351.6 7505.8 8312.6 8374.2 457.2 473.8 524.1 577.6 578.1 3770.6 3872.2 4042.5 4093.2 4132.8 101286.0 102321.0 104751.5 110374.0 111547.0 89673.0 89845.6 90241.0 93322.5 95162.0 79926.0 82056.2 82188.0 82755.0 82964.0 7657.5 7743.8 8003.3 8003.6 8043.7 46526.5 46553.5 48003.8 48646.0 48677.8 38944.0 65545.7 66492.0 66551.7 66879.3 9668.8 10812.8 10934.8 11298.2 11313.7 11998.9 12280.5 12848.3 13064.7 13473.3 11530.4 11807.2 12002.2 12158.0 12676.3 12295.8 12462.1 13259.5 13277.1 13303.2 11545.4 11744.1 12173.5 12378.5 12481.6 9792.0 10044.6 10192.7 10664.1 10691.3 10012.7 10529.5 10591.7 10602.6 10615.3 22:30:58 INFO - PROCESS | 29903 | -------- Summary: end -------- 22:30:58 INFO - PROCESS | 29903 | 22:30:59 INFO - TEST-INFO | 29903: exit 0 22:30:59 INFO - TEST-OK | dromaeo_css | took 1121402ms 22:30:59 INFO - TEST-START | kraken 22:30:59 INFO - operating with platform_type : linux_ 22:30:59 INFO - Initialising browser for kraken test... 22:30:59 INFO - TEST-INFO | started process 30213 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsrHvws/profile http://localhost:57992/getInfo.html) 22:31:04 INFO - PROCESS | 30213 | __metrics Screen width/height:1600/1200 22:31:04 INFO - PROCESS | 30213 | colorDepth:24 22:31:04 INFO - PROCESS | 30213 | Browser inner width/height: 1024/697 22:31:04 INFO - PROCESS | 30213 | __metrics 22:31:04 INFO - PROCESS | 30213 | 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" 22:31:04 INFO - PROCESS | 30213 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:31:05 INFO - PROCESS | 30213 | console.error: PushService: 22:31:05 INFO - PROCESS | 30213 | stateChangeProcessEnqueue: Error transitioning state 22:31:05 INFO - PROCESS | 30213 | UnknownError 22:31:06 INFO - TEST-INFO | 30213: exit 0 22:31:06 INFO - Browser initialized. 22:31:06 INFO - Running cycle 1/1 for kraken test... 22:31:06 INFO - TEST-INFO | started process 30294 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsrHvws/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:31:18 INFO - PROCESS | 30294 | RSS: Main: 182341632 22:31:18 INFO - PROCESS | 30294 | 22:31:21 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57992/tests/kraken/driver.html?testName=audio-beat-detection) 22:31:21 INFO - PROCESS | 30294 | RSS: Main: 235634688 22:31:21 INFO - PROCESS | 30294 | 22:31:24 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57992/tests/kraken/driver.html?testName=audio-dft) 22:31:25 INFO - PROCESS | 30294 | RSS: Main: 225349632 22:31:25 INFO - PROCESS | 30294 | 22:31:28 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57992/tests/kraken/driver.html?testName=audio-fft) 22:31:29 INFO - PROCESS | 30294 | RSS: Main: 301854720 22:31:29 INFO - PROCESS | 30294 | 22:31:31 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57992/tests/kraken/driver.html?testName=audio-oscillator) 22:31:31 INFO - PROCESS | 30294 | RSS: Main: 212525056 22:31:31 INFO - PROCESS | 30294 | 22:31:34 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57992/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:31:34 INFO - PROCESS | 30294 | RSS: Main: 214581248 22:31:34 INFO - PROCESS | 30294 | 22:31:37 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57992/tests/kraken/driver.html?testName=imaging-darkroom) 22:31:38 INFO - PROCESS | 30294 | RSS: Main: 253685760 22:31:38 INFO - PROCESS | 30294 | 22:31:42 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57992/tests/kraken/driver.html?testName=imaging-desaturate) 22:31:42 INFO - PROCESS | 30294 | RSS: Main: 252219392 22:31:42 INFO - PROCESS | 30294 | 22:31:45 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57992/tests/kraken/driver.html?testName=json-parse-financial) 22:31:46 INFO - PROCESS | 30294 | RSS: Main: 252215296 22:31:46 INFO - PROCESS | 30294 | 22:31:47 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57992/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:31:48 INFO - PROCESS | 30294 | RSS: Main: 215384064 22:31:48 INFO - PROCESS | 30294 | 22:31:50 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57992/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:31:51 INFO - PROCESS | 30294 | RSS: Main: 223358976 22:31:51 INFO - PROCESS | 30294 | 22:31:53 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57992/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:31:53 INFO - PROCESS | 30294 | RSS: Main: 223645696 22:31:53 INFO - PROCESS | 30294 | 22:31:56 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57992/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:31:57 INFO - PROCESS | 30294 | RSS: Main: 225021952 22:31:57 INFO - PROCESS | 30294 | 22:32:00 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57992/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:32:00 INFO - PROCESS | 30294 | RSS: Main: 222650368 22:32:00 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | Cycle 1(1): loaded http://localhost:57992/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57992/tests/kraken/driver.html?testName=ai-astar) 22:32:02 INFO - PROCESS | 30294 | RSS: Main: 274042880 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | __start_tp_report 22:32:02 INFO - PROCESS | 30294 | _x_x_mozilla_page_load 22:32:02 INFO - PROCESS | 30294 | _x_x_mozilla_page_load_details 22:32:02 INFO - PROCESS | 30294 | |i|pagename|runs| 22:32:02 INFO - PROCESS | 30294 | |0;ai-astar;115;115;132;114;115;115;115;116;111;113 22:32:02 INFO - PROCESS | 30294 | |1;audio-beat-detection;146;120;156;195;124;157;146;128;178;122 22:32:02 INFO - PROCESS | 30294 | |2;audio-dft;162;163;164;164;161;162;162;162;162;162 22:32:02 INFO - PROCESS | 30294 | |3;audio-fft;76;75;76;167;78;75;76;77;156;77 22:32:02 INFO - PROCESS | 30294 | |4;audio-oscillator;84;85;150;85;85;84;142;84;84;134 22:32:02 INFO - PROCESS | 30294 | |5;imaging-gaussian-blur;117;116;117;117;116;117;117;116;117;116 22:32:02 INFO - PROCESS | 30294 | |6;imaging-darkroom;146;147;146;146;147;146;146;146;147;146 22:32:02 INFO - PROCESS | 30294 | |7;imaging-desaturate;95;95;96;95;95;95;95;96;96;95 22:32:02 INFO - PROCESS | 30294 | |8;json-parse-financial;65;64;65;67;66;65;66;66;65;64 22:32:02 INFO - PROCESS | 30294 | |9;json-stringify-tinderbox;67;66;66;67;66;66;67;66;66;66 22:32:02 INFO - PROCESS | 30294 | |10;stanford-crypto-aes;82;82;90;88;83;83;82;82;91;81 22:32:02 INFO - PROCESS | 30294 | |11;stanford-crypto-ccm;137;134;131;133;130;130;134;132;173;141 22:32:02 INFO - PROCESS | 30294 | |12;stanford-crypto-pbkdf2;174;173;173;176;178;179;178;175;177;174 22:32:02 INFO - PROCESS | 30294 | |13;stanford-crypto-sha256-iterative;69;70;71;70;68;71;68;68;69;68 22:32:02 INFO - PROCESS | 30294 | __end_tp_report 22:32:02 INFO - PROCESS | 30294 | __start_cc_report 22:32:02 INFO - PROCESS | 30294 | _x_x_mozilla_cycle_collect,1950 22:32:02 INFO - PROCESS | 30294 | __end_cc_report 22:32:02 INFO - PROCESS | 30294 | __startTimestamp1472707922894__endTimestamp 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | ------- Summary: start ------- 22:32:02 INFO - PROCESS | 30294 | Number of tests: 14 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#0] ai-astar Cycles:10 Average:116.10 Median:115.00 stddev:5.76 (5.0%) stddev-sans-first:6.10 22:32:02 INFO - PROCESS | 30294 | Values: 115.0 115.0 132.0 114.0 115.0 115.0 115.0 116.0 111.0 113.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#1] audio-beat-detection Cycles:10 Average:147.20 Median:151.00 stddev:25.15 (16.7%) stddev-sans-first:26.67 22:32:02 INFO - PROCESS | 30294 | Values: 146.0 120.0 156.0 195.0 124.0 157.0 146.0 128.0 178.0 122.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 22:32:02 INFO - PROCESS | 30294 | Values: 162.0 163.0 164.0 164.0 161.0 162.0 162.0 162.0 162.0 162.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#3] audio-fft Cycles:10 Average:93.30 Median:76.00 stddev:36.05 (47.4%) stddev-sans-first:37.69 22:32:02 INFO - PROCESS | 30294 | Values: 76.0 75.0 76.0 167.0 78.0 75.0 76.0 77.0 156.0 77.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#4] audio-oscillator Cycles:10 Average:101.70 Median:84.00 stddev:28.07 (33.4%) stddev-sans-first:29.03 22:32:02 INFO - PROCESS | 30294 | Values: 84.0 85.0 150.0 85.0 85.0 84.0 142.0 84.0 84.0 134.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:32:02 INFO - PROCESS | 30294 | Values: 117.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 117.0 116.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 22:32:02 INFO - PROCESS | 30294 | Values: 146.0 147.0 146.0 146.0 147.0 146.0 146.0 146.0 147.0 146.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 22:32:02 INFO - PROCESS | 30294 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:65.50 stddev:0.95 (1.4%) stddev-sans-first:1.00 22:32:02 INFO - PROCESS | 30294 | Values: 65.0 64.0 65.0 67.0 66.0 65.0 66.0 66.0 65.0 64.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:0.48 (0.7%) stddev-sans-first:0.44 22:32:02 INFO - PROCESS | 30294 | Values: 67.0 66.0 66.0 67.0 66.0 66.0 67.0 66.0 66.0 66.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:83.00 stddev:3.75 (4.5%) stddev-sans-first:3.87 22:32:02 INFO - PROCESS | 30294 | Values: 82.0 82.0 90.0 88.0 83.0 83.0 82.0 82.0 91.0 81.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#11] stanford-crypto-ccm Cycles:10 Average:137.50 Median:134.00 stddev:12.92 (9.6%) stddev-sans-first:13.70 22:32:02 INFO - PROCESS | 30294 | Values: 137.0 134.0 131.0 133.0 130.0 130.0 134.0 132.0 173.0 141.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:176.50 stddev:2.21 (1.3%) stddev-sans-first:2.26 22:32:02 INFO - PROCESS | 30294 | Values: 174.0 173.0 173.0 176.0 178.0 179.0 178.0 175.0 177.0 174.0 22:32:02 INFO - PROCESS | 30294 | 22:32:02 INFO - PROCESS | 30294 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.50 stddev:1.23 (1.8%) stddev-sans-first:1.30 22:32:02 INFO - PROCESS | 30294 | Values: 69.0 70.0 71.0 70.0 68.0 71.0 68.0 68.0 69.0 68.0 22:32:02 INFO - PROCESS | 30294 | -------- Summary: end -------- 22:32:02 INFO - PROCESS | 30294 | 22:32:04 INFO - TEST-INFO | 30294: exit 0 22:32:04 INFO - TEST-OK | kraken | took 64525ms 22:32:04 INFO - SUITE-END | took 1185s 22:32:04 INFO - Completed test suite (00:19:46) 22:32:04 INFO - javascript benchmark 22:32:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2617.2279177642595, 2618.6988496322924, 2628.067036202147, 2667.906623268186, 2680.396910695094, 4587.220172570663, 4643.860684179472, 4665.230237010894, 4757.524247575241, 4824.204006338954, 4671.5562021607975, 4696.577308042276, 4723.811047558093, 4731.219348807451, 4791.568758811706, 4312.762798046107, 4333.523312435629, 4338.0, 4346.891327716817, 4469.262571675669, 4402.3836662867225, 4402.449693788277, 4409.250427427329, 4410.558944105596, 4440.467143942716, 4235.428217190702, 4273.487181538204, 4274.401583778274, 4286.61420472159, 4346.543612920661, 4306.956930430687, 4309.547497512779, 4313.15893400787, 4314.245007123741, 4321.524632290446, 4257.084726783756, 4287.185434767415, 4299.269124248885, 4311.073119279338, 4346.15250026245, 4622.699524530894, 4661.09108723799, 4661.114388266218, 4699.882502937419, 4765.26138448541, 5023.874403139914, 5028.7988480460735, 5043.117454445494, 5049.267856160836, 5051.924221136685, 4892.6575139740235, 5033.974830125862, 5047.621428392848, 5067.189249720027, 5076.796928122871, 4685.0395668887795, 4692.366530518337, 4698.976505117521, 4703.2004559225015, 4706.270528068155, 5159.793608255628, 5160.0, 5170.1469257572835, 5172.948270517322, 5195.714235716996, 4727.763611819395, 4742.644301677388, 4826.493218212037, 4872.366592342973, 4945.23348880924, 4938.9012219755405, 4971.6271279654175, 4974.228994505857, 4974.626902982291, 5020.899582008339, 4908.803647854117, 4922.0, 4928.852134435972, 4931.4821943696115, 4982.7259500727805, 4795.448523419782, 4944.802207911644, 4986.576141028053, 5024.547790698854, 5060.873478163076, 4271.829126834954, 4272.230998420252, 4273.87178384649, 4317.460317460317, 4325.7620830853975, 3716.554013518341, 3737.6822970047306, 3740.4202348636004, 3779.3575092233837, 3791.3175628387157, 4223.936640950388, 4268.423762792046, 4289.506706728766, 4351.673624478177, 4372.497162826316, 5043.646944713835, 5065.240213967934, 5065.848024559269, 5101.285819985205, 5119.0529751996, 4336.869893903188, 4339.500957389878, 4353.651707863363, 4396.780160991938, 4404.867853964386, 4379.080393117417, 4389.056352884145, 4406.757628330409, 4546.227141385973, 4546.249868771682, 4549.11292298012, 4553.0, 4590.288505281686, 4595.425571803525, 4604.171249175047, 4817.253325734517, 4821.975890120527, 4826.638002149923, 4856.975715121402, 4930.087933732271, 4488.730676159441, 4512.593866552026, 4538.88652783683, 4551.36280920678, 4597.931031034604, 4896.730679812645, 4933.6299777516115, 4956.058348913695, 5013.799448022039, 5016.498350164954, 4994.700317980787, 5015.398152221756, 5015.498450154955, 5023.598112150946, 5077.238414237894, 4747.026859493864, 4778.737169455713, 4878.731669758055, 4896.216605343128, 4956.975215123901, 4837.395325584302, 4854.0, 4920.089783390084, 4957.776900039579, 4986.252062190701, 4854.75726213695, 4864.270359446112, 4866.635002374906, 4866.8053277868485, 4910.459849416633, 4957.975210123926, 4958.752062396794, 4962.627802914723, 4964.0, 5003.924941125813, 4667.696599721007, 4798.0, 4848.200046992348, 4851.29656199842, 4898.069366820293], "value": 4596.385585585704, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.6636018388966, 570.8287513745876, 571.2516603249743, 574.253470488365, 576.1616847486904, 27593.724062759367, 30600.0, 37002.629973700256, 38341.80829095851, 38425.23149537014, 38929.41605875914, 39376.40935385964, 39388.803055984754, 39388.803055984754, 39393.21213575727, 7611.200823913492, 7722.459427840054, 7746.8450630987345, 7749.263819937097, 7759.185285544993, 7092.929070709304, 9671.226301895831, 9899.406035637849, 9920.156786673142, 10253.84619230712, 9980.30137890348, 10061.899381006206, 10071.194304455626, 10075.596976120947, 10082.092611664948, 9824.459654718987, 9857.0, 9908.35595686278, 9909.851352229722, 9918.752031199205, 9767.365121267096, 9802.90197098027, 9809.558569864374, 9833.655822046265, 9836.672049273328, 30979.0706278812, 31152.688473115206, 31161.532577011363, 31213.37573248545, 31308.53037204444, 2673.2915777319026, 2775.2090654163667, 2807.368342123026, 2829.773618110546, 2850.743433091011, 27059.0, 27260.727392726018, 27413.31466713348, 27432.588511172347, 27548.17355479339, 3192.201949512622, 3264.4287249731437, 3267.1015470745733, 3281.949776071658, 3299.554560134375, 248611.0, 249284.75357623276, 249425.0, 249463.0, 249948.0, 41604.79197603992, 41651.79174104171, 41678.37482437766, 41698.1660366797, 41735.16529669389, 32250.35499290001, 32489.675103249137, 32592.0, 34469.31061378758, 53425.1986220207, 13237.0, 13284.269365185008, 13297.135686180562, 13303.201807891364, 13321.267330296927, 9840.0, 10315.690529284133, 10762.408067556204, 10772.353658780341, 10777.407242601732, 17202.397916072878, 17291.0, 17322.393716220125, 17326.480205593853, 17353.0, 5463.644863083887, 5482.150266708666, 5492.368377636543, 5665.433456654301, 5677.517411020027, 759.1838773318594, 893.660409044559, 903.1510380242551, 913.6893456224915, 986.6053578568486, 26889.193324200307, 27684.584731229046, 28191.15426537207, 28242.576361354597, 28363.0, 881.6164968238836, 903.4804987132425, 920.8848893888264, 924.2790623313826, 957.0764212534798, 13313.33433328349, 14021.719565608631, 14101.0, 14315.355808988412, 14370.353334099991, 385002.0, 390879.0, 390971.0, 391198.0, 391392.0, 47392.763036185286, 47883.521164787904, 47942.76028619835, 47990.0, 48045.279320810216, 1032.1020711980625, 1134.7276653603076, 1157.8436911016904, 1165.9125565582615, 1198.3409124981226, 25674.871625641754, 27969.160925172277, 28733.99431019988, 28772.856135719187, 28844.134675959755, 40735.592644074364, 41185.1762964745, 41250.1749964993, 41352.0, 41419.1716165681, 319170.0, 322364.0, 322460.0, 323034.0, 323378.0, 24199.87900060559, 24226.152084676953, 25088.37279067965, 25156.59759542036, 26153.73846261513, 40112.39831402473, 40152.0, 40423.3936490947, 40734.59265407308, 41390.793046034574, 35259.29481410409, 35338.82330588331, 35715.28569428649, 35819.64180358163, 36022.63977360193, 34280.48579271263, 34363.0, 34506.30987380188, 34683.65316346804, 34693.82653086718, 28941.421171576872, 29036.27409314784, 29121.41757164887, 31928.5210721844, 35393.0, 29578.408431831675, 32484.187895302806, 32509.837450812596, 32529.837350813094, 32614.086309642993, 24617.507649846546, 24962.750372496044, 24990.500189995735, 25184.24447266585, 25366.49267014686], "value": 16926.133200374446, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.8148009334912, 354.3762977160199, 358.61448942386926, 360.1841828258993, 379.0618219905734, 15708.0, 16287.59281017975, 16567.420140295093, 17288.394906178288, 17422.82577174228, 16166.515004549883, 16220.188990550485, 16285.185740712976, 16666.66666666666, 16780.160991950415, 4876.4148302203785, 4913.80344786209, 5035.320231768719, 5057.013882292954, 5059.519345662156, 3558.6975107115927, 4930.0, 5008.0, 5543.223948647193, 5875.0, 5946.43508866657, 5954.55340849438, 5968.492678122365, 5973.910391344133, 5976.4919981801595, 4996.375453068366, 5268.525832675077, 5286.709230992294, 5311.893762124774, 5925.377835327272, 5143.228515722634, 5144.279800827887, 5147.4080480744815, 5154.226865970097, 5155.200943853709, 11425.885741142649, 11498.655040348804, 11640.010599099087, 11723.76552468946, 11772.529098836036, 1807.1957978699484, 1871.7285993530998, 2012.1951219512166, 2063.494443861249, 2105.0211651581935, 8335.0, 8566.18621230982, 9004.279657627374, 9145.725628231165, 11114.444277786079, 1657.3867668962562, 1674.9330026798914, 1698.3376483171573, 1699.8045224799182, 1715.4682048564985, 69580.6520967392, 69617.0, 69896.0, 71277.93083103758, 72055.27944720538, 9592.232621390272, 9690.224782017422, 9887.45618990948, 9924.0, 10013.699589012342, 13463.192208467523, 13476.663083422993, 13480.460781568823, 13499.662508437172, 13501.987350948719, 7026.92973070273, 7055.964720176366, 7129.821754456077, 7196.172440169343, 7398.593077380796, 6725.831854203684, 6871.896921546181, 6983.301669832976, 7168.0, 7179.317964793337, 4106.7125301229225, 4116.567760385176, 4120.2583534964, 4135.276326642826, 4275.444192254987, 1694.5848267174622, 1711.24705129745, 1722.457425910834, 1737.9725640809047, 1751.8073011968672, 621.7295476467751, 840.1976112812249, 849.5964416902045, 869.1482347299682, 901.0, 15448.536543903701, 15579.454719085004, 16118.677626447405, 16138.75791863123, 17319.2206350712, 818.9682984816782, 831.2061980808313, 835.423557745166, 862.8878245828002, 911.6717981526646, 9305.209057230077, 9513.14381705636, 9537.856932146024, 9569.521523923915, 9758.316917815684, 394647.0, 413361.0, 415624.0, 419337.0, 420988.0, 74143.62928185324, 84149.0, 86382.56808715916, 86613.5669321662, 87396.0, 911.8632205169278, 927.7541451515219, 933.780561568031, 941.915227629503, 943.4858002388714, 14632.780508292597, 14829.40682372693, 14999.7000059996, 15390.230488475756, 15558.144302063496, 30084.097477075724, 34245.0, 37855.05362365963, 38395.27221274994, 39422.211555769296, 47901.0, 48980.51019489759, 51062.0, 52210.73894630503, 52510.73744631252, 4760.761961901821, 5523.834284971457, 6397.776077837253, 6739.326067393222, 6918.8616227676175, 10502.52738626779, 11123.944380278046, 11337.319760814378, 11638.476268567765, 11951.342676152895, 10053.849192262269, 10104.191664666661, 10169.271223891808, 10495.0, 10584.206184536037, 9683.906615560642, 9800.411975281506, 9817.460039697886, 9957.850632240667, 10084.193264538966, 9980.950095249478, 10205.724284464442, 10361.681329423182, 10575.15398768112, 10671.35971841692, 6649.933500664932, 6778.561663656577, 6793.252742198255, 7072.080629518336, 7083.539569927938, 8807.911920880966, 9882.0, 10105.24210684187, 10262.076413122855, 10492.475376231005], "value": 7917.7005517869675, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1302.8306320178378, 1348.2382453913538, 1372.3549931532182, 1605.075590182004, 1611.7824093747347, 6499.447546958502, 7354.227806080358, 7400.296971787671, 7962.681492740291, 7992.920070799283, 7484.139323977731, 7707.22927707229, 7713.614319284027, 7719.4210434217375, 7725.575093369863, 2182.2580322690324, 2215.3907675389237, 2216.656418255173, 2217.4456385903522, 2219.1678120704737, 1832.3128826689992, 2074.471009892483, 2161.135545781684, 2173.3588591365506, 2202.7907348801837, 2188.3106821351303, 2209.3482422685265, 2209.602271591113, 2209.624363858148, 2211.535577528713, 2116.6401711709045, 2127.563849410867, 2162.664786958024, 2163.3185546552786, 2163.350994701583, 2120.978790212109, 2122.1511395441794, 2137.7755335689685, 2142.828573714099, 2153.6231159547015, 7745.0, 7829.843403131906, 7833.412494062911, 7834.804129896798, 7851.057873055212, 4520.141173177086, 4650.651201159926, 4650.813967441299, 4656.348111264426, 4660.207764680013, 2806.256342069353, 2890.8699108539954, 2962.614860068199, 2985.2835319523374, 2986.880524779006, 844.9915500845036, 892.9553522323857, 893.0176805513947, 896.7802888292345, 908.309684639672, 10302.587896484207, 10317.226208034352, 10327.58689652413, 10335.483225838752, 10364.948175259151, 3789.2989796887114, 4053.9797301013896, 4140.8136633851545, 4212.7682977435925, 4228.725132866415, 8809.691660791841, 8822.25010874083, 8830.161134692195, 8834.381593288408, 8834.823303533893, 4677.298405239173, 4684.859454216379, 4686.5782079613, 4700.929486057712, 4707.5057119002695, 4225.654332692907, 4257.680673949466, 4284.614384705392, 4287.2711639021445, 4309.00892794659, 3540.150363912694, 3634.2186429917697, 3685.0971511979737, 3699.5005674233635, 3704.1664376133986, 1040.6773900090996, 1132.660201939415, 1176.7822952753768, 1184.2420850655587, 1189.2329447506484, 728.7340120855955, 741.5476559298824, 777.6578305545542, 798.7923139983646, 818.2880893622468, 4978.5270399312, 5083.389993200765, 5117.488251174927, 5156.793728250828, 5356.437574054746, 812.959352032396, 842.5113434208267, 846.9990119999748, 856.1737922904427, 882.1090698394668, 3496.615372309044, 3587.4798154267487, 3889.53325600924, 3908.081600823804, 3962.782046987386, 9419.387739796892, 9529.237660987104, 9689.515524223902, 9720.416774993522, 9758.658446954323, 9620.470874101991, 9622.037796220322, 9660.613575457044, 9672.419654820593, 9680.75798105053, 7115.89326160108, 7196.244394338623, 7332.23011583776, 7385.889211661829, 7398.482106252511, 4818.903621927612, 4939.555440010273, 4950.232713929347, 4970.701757894538, 5094.770735316845, 8737.344699147463, 8799.956000219958, 8812.779680508038, 8998.100189980949, 9002.459852408876, 8959.1936725695, 9045.728628141167, 9059.22996545301, 9140.177384035469, 9225.031371705874, 6074.665893375906, 6217.378262173746, 6218.533609979179, 6232.407921247474, 6235.095911092957, 6851.554648947803, 6874.621895795779, 6934.514583979275, 6942.687579058854, 7040.542364746275, 5500.147477141049, 5958.731857066356, 6370.26741924666, 6381.425671689571, 6414.61512309263, 6151.907721384094, 6203.286622038523, 6219.875602488016, 6414.583052101599, 6631.403173714389, 6125.663088530174, 6126.448619624255, 6163.044728067157, 6169.136320915165, 6178.104174894705, 4152.2525945328925, 4190.497140343178, 4271.252530807158, 4303.407739136414, 4391.824327026883, 4266.338717498793, 4316.244657184917, 4377.934330984974, 4436.933445998248, 4508.887277818081], "value": 4050.825811836889, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.91167738001224, 287.28609405627014, 290.6468640601667, 293.52595558173556, 293.8677595082213, 4710.05798840232, 4778.37535238019, 4789.281607758838, 4806.83176088837, 4839.927401088982, 4751.572358487735, 4771.90456190876, 4778.6416018798645, 4809.979808480438, 4814.181589129847, 3530.117470632342, 3594.7124230061663, 3708.3139619170406, 3766.6610005099537, 3775.8678446254385, 3716.699155295652, 3730.8694195703156, 3732.4774531565604, 3733.813309334536, 3737.822585885451, 3696.853975267662, 3699.926001479969, 3700.1859590889962, 3706.314331848617, 3710.610385909468, 3518.894433167009, 3578.0, 3701.777893326409, 3728.4966529518574, 3741.7193710471824, 3223.403670320998, 3247.1328985833006, 3248.4964830451318, 3253.9511907321407, 3254.3491301739746, 4631.675782695213, 4642.883927901796, 4645.628349732014, 4652.162610730067, 4660.5339466053465, 4076.2255171517318, 4210.810513526899, 4224.809883555247, 4226.4716910386205, 4231.196072746168, 4944.381952255968, 4980.501949805027, 4981.551660350586, 4988.725620090893, 4996.2755400467095, 3559.661832125944, 3562.305350456636, 3569.0542006368332, 3577.481265216555, 3579.284143171376, 5206.323177986876, 5218.530332270114, 5228.686278823283, 5229.424763276036, 5232.555232805178, 3367.4948757686543, 3536.8938931832085, 3569.286142771456, 3573.982130089385, 3634.436662317345, 3607.8917632471066, 3616.4575313703153, 3618.149734812317, 3618.7105031597407, 3625.619309972468, 4162.958370416257, 4167.395727619539, 4170.582941705866, 4170.62179634497, 4175.457190565207, 4744.7390393528685, 5010.2735103409805, 5013.724245166478, 5017.272495488134, 5024.171011783015, 1214.1622280626339, 1241.7582417582419, 1242.7949388351, 1243.198137201518, 1244.8630650628425, 779.9727009554638, 791.6437603078638, 806.4354951533833, 813.7151996801073, 821.1829229916272, 4002.3796311571755, 4269.398975384231, 4277.486701595828, 4450.109978004412, 4460.687751857339, 5172.8965420691375, 5215.504527069922, 5226.5818734501145, 5236.29310043147, 5243.947560524422, 4477.753723545236, 4493.573110554492, 4508.45898492183, 4508.616767574793, 4510.616597589176, 2611.7779988701172, 2711.240852561286, 2724.809263351546, 2733.521633714092, 2744.903928362498, 4794.448638406628, 4797.280407938767, 4797.904041919142, 4799.6400269979895, 4801.639877009238, 5249.343832020997, 5263.105272103675, 5289.148797920697, 5289.788408463618, 5303.575713942875, 4654.13898428785, 4758.452777930583, 4788.209945358977, 4796.0, 4798.4001999750035, 5122.923156152586, 5127.179651255781, 5142.382914050338, 5159.045576568347, 5165.225216217447, 5043.722595257149, 5048.3941926969, 5082.551472830226, 5090.669106508065, 5093.4023647939075, 4579.564941330568, 4726.409198850144, 4760.523947605212, 4763.7141771493825, 4795.520447955177, 3551.8224088795973, 3551.893443196708, 3552.87564935226, 3561.6438356164176, 3573.3210689968823, 5085.898282034413, 5120.182335271074, 5137.843985103322, 5152.330197074356, 5165.61257905651, 4718.41019872516, 4954.801807927786, 5008.524190201925, 5042.39491261051, 5058.924116138334, 5000.099982003274, 5012.774425150804, 5024.397072351341, 5035.949640503548, 5045.772940217773, 4983.9501604984935, 4984.40187177541, 4988.702937236272, 4992.753261032477, 5005.899882002267, 4821.180399332192, 4898.461169271449, 4942.46783497117, 5039.395272567315, 5082.136036873814, 4972.2790195422185, 4972.552470277692, 4978.4523702393435, 4981.925271120864, 4985.476524964826], "value": 3743.0823314866575, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.4251349852902, 611.7850694599877, 617.444300129883, 673.5117040145892, 708.592559278415, 13294.734105317888, 14458.927705361484, 14467.421303147861, 14508.129512229274, 14566.344514496846, 12656.746865062692, 12690.746185076294, 12702.0, 12727.172733772299, 12732.663070377615, 587.9265091863517, 714.2250658036037, 718.1346477494619, 737.4542838299674, 739.7632757517597, 734.0494060192065, 735.2169939014959, 736.2710916192957, 736.5065406177847, 740.1525253584642, 763.9961800190991, 764.6788348893479, 764.7132325377984, 764.8126209078756, 765.7932358263265, 622.8162692005847, 744.6202436757266, 765.7281665008898, 767.1369709077288, 775.1434664695543, 545.2747845365654, 545.5362941499733, 546.7976848566017, 547.2311402479482, 550.3368441028551, 13016.674583135402, 13018.544350947672, 13049.86950130496, 13053.738925221538, 13065.869341306561, 12416.255024698547, 12774.361281935866, 13057.086003979724, 13245.933770331183, 13358.866411335859, 11784.234024788448, 12105.576304829377, 12110.757784844342, 12159.331236781973, 12297.75404491914, 4058.553559108496, 4072.287349713818, 4096.303628383182, 4140.0891803803115, 4219.894502637428, 64647.0, 64732.67633661849, 66535.0, 68997.0, 68998.3100169002, 10053.447060411601, 10053.849192262122, 10054.949725251327, 10072.395656260647, 10223.335483193569, 18722.344717934808, 18774.812251877578, 18780.154893029845, 18791.624167516573, 18802.717959230624, 6534.117894084237, 6640.435562977201, 6683.866322673616, 6687.866242675216, 6789.422899053636, 3689.409694448875, 3725.6274372562525, 3790.166163444038, 3803.0112170835782, 3880.049387899983, 2629.9211023669322, 2728.822626529269, 2761.3648860762137, 2768.6400767900045, 2772.8613569321856, 474.14890271961224, 479.94000749906263, 492.5025724018767, 496.76651973572365, 532.3079996005177, 499.2436458764974, 523.6910222968467, 539.3824071438181, 569.5386736743251, 596.150485558078, 6773.62745049027, 7351.595662238521, 7505.849883002309, 8312.625931833081, 8374.204450577185, 457.2250036188695, 473.80810771637545, 524.1168630856941, 577.566824881339, 578.109711044986, 3770.6417890300377, 3872.206197729459, 4042.4542686736913, 4093.1813637272667, 4132.8140233689555, 101286.0, 102321.0, 104751.47624261983, 110374.0, 111547.0, 89673.0, 89845.55077224573, 90241.0, 93322.53338733263, 95162.0, 79926.0, 82056.17943820605, 82188.0, 82755.0, 82963.99999999879, 7657.502262352929, 7743.806404839924, 8003.31971782405, 8003.6398362075015, 8043.6782528698195, 46526.53473465357, 46553.495377065155, 48003.75998119987, 48646.0, 48677.75661121814, 38944.0, 65545.67227163834, 66492.0, 66551.66724166348, 66879.3312066873, 9668.75828104303, 10812.783744325227, 10934.835977460185, 11298.152638551977, 11313.71715707114, 11998.880011199777, 12280.49288648131, 12848.293343866177, 13064.738705225653, 13473.258970756702, 11530.423478826193, 11807.173497855068, 12002.159848810848, 12158.0, 12676.302803345905, 12295.75408491843, 12462.127651064338, 13259.469621215429, 13277.136986095873, 13303.201807891557, 11545.422728863356, 11744.060475161796, 12173.452194651085, 12378.504859805507, 12481.56314528987, 9791.951040244754, 10044.648437304659, 10192.694219173436, 10664.07180872195, 10691.25161238737, 10012.749681258027, 10529.45592673433, 10591.735206619896, 10602.628907988183, 10615.31000484966], "value": 6585.305393743975, "unit": "score"}], "name": "dromaeo_css", "value": 6282.925783691149, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 132.0, 114.0, 115.0, 115.0, 115.0, 116.0, 111.0, 113.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 120.0, 156.0, 195.0, 124.0, 157.0, 146.0, 128.0, 178.0, 122.0], "value": 147.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 164.0, 164.0, 161.0, 162.0, 162.0, 162.0, 162.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 167.0, 78.0, 75.0, 76.0, 77.0, 156.0, 77.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 150.0, 85.0, 85.0, 84.0, 142.0, 84.0, 84.0, 134.0], "value": 101.7, "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, 116.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 67.0, 66.0, 65.0, 66.0, 66.0, 65.0, 64.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 67.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 90.0, 88.0, 83.0, 83.0, 82.0, 82.0, 91.0, 81.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 131.0, 133.0, 130.0, 130.0, 134.0, 132.0, 173.0, 141.0], "value": 137.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 173.0, 176.0, 178.0, 179.0, 178.0, 175.0, 177.0, 174.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 71.0, 70.0, 68.0, 71.0, 68.0, 68.0, 69.0, 68.0], "value": 69.2, "unit": "score"}], "name": "kraken", "value": 1577.3, "alertThreshold": 2.0}]} 22:32:04 INFO - Return code: 0 22:32:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:32:04 INFO - # TBPL SUCCESS # 22:32:04 INFO - Running post-action listener: _resource_record_post_action 22:32:04 INFO - [mozharness: 2016-09-01 05:32:04.710628Z] Finished run-tests step (success) 22:32:04 INFO - Running post-run listener: _resource_record_post_run 22:32:04 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 417300480; Read time: 16; Write time: 248172 22:32:04 INFO - TinderboxPrint: CPU usage
13.0% 22:32:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 22:32:04 INFO - TinderboxPrint: I/O write bytes / time
417,300,480 / 248,172 22:32:04 INFO - TinderboxPrint: CPU idle
8,348.1 (87.0%) 22:32:04 INFO - TinderboxPrint: CPU user
1,201.9 (12.5%) 22:32:04 INFO - TinderboxPrint: Swap in / out
0 / 0 22:32:04 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 125042688; Read time: 0; Write time: 80164 22:32:04 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 292257792; Read time: 0; Write time: 168008 22:32:05 INFO - Running post-run listener: _upload_blobber_files 22:32:05 INFO - Blob upload gear active. 22:32:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:32:05 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 22:32:05 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'] 22:32:05 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 22:32:05 INFO - (blobuploader) - INFO - Open directory for files ... 22:32:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:32:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:32:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:32:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:32:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:32:10 INFO - (blobuploader) - INFO - Done attempting. 22:32:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:32:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:32:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:32:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:32:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:32:11 INFO - (blobuploader) - INFO - Done attempting. 22:32:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:32:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:32:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:32:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:32:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:32:11 INFO - (blobuploader) - INFO - Done attempting. 22:32:11 INFO - (blobuploader) - INFO - Iteration through files over. 22:32:11 INFO - Return code: 0 22:32:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:32:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:32:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/92c23e42dd3760700eb19b8a7777b2c14aa70b271a4f2b840adf54c5af2a1147bc0786687efe57459cb85c1ceab052acc21ac62ffa9805905476e76aba0b4e56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/40754d312eb00dfcf0d9aa56373e43f91d9f1c287e327a2301d6505323f9c9a6ad80247c274a64466869e4576246c284b08925c0568ded9c55fc512a65aee02a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8e5682166d05cf322365e51ba2c643cfb91ff89f304a68b9c582e803d14e35911448157e0901ac38ab45cee0214dd7339e16303df5199483df6eece77511a5b2"} 22:32:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:32:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:32:11 INFO - Contents: 22:32:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/92c23e42dd3760700eb19b8a7777b2c14aa70b271a4f2b840adf54c5af2a1147bc0786687efe57459cb85c1ceab052acc21ac62ffa9805905476e76aba0b4e56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/40754d312eb00dfcf0d9aa56373e43f91d9f1c287e327a2301d6505323f9c9a6ad80247c274a64466869e4576246c284b08925c0568ded9c55fc512a65aee02a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8e5682166d05cf322365e51ba2c643cfb91ff89f304a68b9c582e803d14e35911448157e0901ac38ab45cee0214dd7339e16303df5199483df6eece77511a5b2"} 22:32:11 INFO - Running post-run listener: copy_logs_to_upload_dir 22:32:11 INFO - Copying logs to upload dir... 22:32:11 INFO - mkdir: /builds/slave/test/build/upload/logs 22:32:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.576544 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-08-31 22:32:11.721357) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 22:32:11.724473) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629 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/92c23e42dd3760700eb19b8a7777b2c14aa70b271a4f2b840adf54c5af2a1147bc0786687efe57459cb85c1ceab052acc21ac62ffa9805905476e76aba0b4e56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/40754d312eb00dfcf0d9aa56373e43f91d9f1c287e327a2301d6505323f9c9a6ad80247c274a64466869e4576246c284b08925c0568ded9c55fc512a65aee02a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8e5682166d05cf322365e51ba2c643cfb91ff89f304a68b9c582e803d14e35911448157e0901ac38ab45cee0214dd7339e16303df5199483df6eece77511a5b2"} build_url:https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010066 build_url: 'https://queue.taskcluster.net/v1/task/duWHY8HeQtSBuBMBAb_-hw/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/92c23e42dd3760700eb19b8a7777b2c14aa70b271a4f2b840adf54c5af2a1147bc0786687efe57459cb85c1ceab052acc21ac62ffa9805905476e76aba0b4e56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/40754d312eb00dfcf0d9aa56373e43f91d9f1c287e327a2301d6505323f9c9a6ad80247c274a64466869e4576246c284b08925c0568ded9c55fc512a65aee02a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/8e5682166d05cf322365e51ba2c643cfb91ff89f304a68b9c582e803d14e35911448157e0901ac38ab45cee0214dd7339e16303df5199483df6eece77511a5b2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 22:32:11.748250) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:32:11.748566) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1472705047.161300-869283629 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004445 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 22:32:11.830926) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 22:32:11.831283) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 22:32:11.831695) ========= ========= Total master_lag: 0.17 =========