builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1471558816.47 results: success (0) buildid: 20160818123002 builduid: 0f164c87ca714839bb814cc92cce8662 revision: b336ce57d8847ce54f9903eb7f2ee152d49b397a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:16.471914) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:16.472348) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:16.472620) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:16.488025) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:16.488288) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 15:20:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2016-08-18 15:20:16 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126814 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:16.628674) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:16.628983) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017159 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:16.678307) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:16.678663) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev b336ce57d8847ce54f9903eb7f2ee152d49b397a --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 b336ce57d8847ce54f9903eb7f2ee152d49b397a --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=9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 15:20:16,882 truncating revision to first 12 chars 2016-08-18 15:20:16,882 Setting DEBUG logging. 2016-08-18 15:20:16,882 attempt 1/10 2016-08-18 15:20:16,882 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/b336ce57d884?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 15:20:17,229 unpacking tar archive at: autoland-b336ce57d884/testing/mozharness/ program finished with exit code 0 elapsedTime=0.661094 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:17.353764) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:17.354078) ========= script_repo_revision: b336ce57d8847ce54f9903eb7f2ee152d49b397a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:17.354463) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:17.354736) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 15:20:17.364071) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-18 15:20:17.364341) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:20:17 INFO - MultiFileLogger online at 20160818 15:20:17 in /builds/slave/test 15:20:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 15:20:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:20:17 INFO - {'append_to_log': False, 15:20:17 INFO - 'base_work_dir': '/builds/slave/test', 15:20:17 INFO - 'blob_upload_branch': 'Autoland', 15:20:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:20:17 INFO - 'branch': 'Autoland', 15:20:17 INFO - 'buildbot_json_path': 'buildprops.json', 15:20:17 INFO - 'config_files': ('talos/linux_config.py',), 15:20:17 INFO - 'default_actions': ('clobber', 15:20:17 INFO - 'read-buildbot-config', 15:20:17 INFO - 'download-and-extract', 15:20:17 INFO - 'populate-webroot', 15:20:17 INFO - 'create-virtualenv', 15:20:17 INFO - 'install', 15:20:17 INFO - 'run-tests'), 15:20:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:20:17 INFO - 'download_minidump_stackwalk': True, 15:20:17 INFO - 'download_symbols': 'ondemand', 15:20:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:20:17 INFO - 'tooltool.py': '/tools/tooltool.py', 15:20:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:20:17 INFO - '/tools/misc-python/virtualenv.py')}, 15:20:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:20:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:20:17 INFO - 'installer_path': 'installer.exe', 15:20:17 INFO - 'log_level': 'info', 15:20:17 INFO - 'log_name': 'talos', 15:20:17 INFO - 'log_to_console': True, 15:20:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:20:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:20:17 INFO - 'opt_config_files': (), 15:20:17 INFO - 'pip_index': False, 15:20:17 INFO - 'sps_profile': False, 15:20:17 INFO - 'sps_profile_interval': 0, 15:20:17 INFO - 'suite': 'dromaeojs', 15:20:17 INFO - 'system_bits': '32', 15:20:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:20:17 INFO - 'title': 'talos-linux64-ix-013', 15:20:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:20:17 INFO - 'use_talos_json': True, 15:20:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:20:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:20:17 INFO - 'work_dir': 'build'} 15:20:17 INFO - [mozharness: 2016-08-18 22:20:17.663640Z] Running clobber step. 15:20:17 INFO - Running pre-action listener: _resource_record_pre_action 15:20:17 INFO - Running main action method: clobber 15:20:17 INFO - rmtree: /builds/slave/test/build 15:20:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:20:18 INFO - Running post-action listener: _resource_record_post_action 15:20:18 INFO - [mozharness: 2016-08-18 22:20:18.682350Z] Finished clobber step (success) 15:20:18 INFO - [mozharness: 2016-08-18 22:20:18.682450Z] Running read-buildbot-config step. 15:20:18 INFO - Running pre-action listener: _resource_record_pre_action 15:20:18 INFO - Running main action method: read_buildbot_config 15:20:18 INFO - Using buildbot properties: 15:20:18 INFO - { 15:20:18 INFO - "project": "", 15:20:18 INFO - "product": "firefox", 15:20:18 INFO - "script_repo_revision": "production", 15:20:18 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 15:20:18 INFO - "repository": "", 15:20:18 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 15:20:18 INFO - "buildid": "20160818123002", 15:20:18 INFO - "pgo_build": "True", 15:20:18 INFO - "basedir": "/builds/slave/test", 15:20:18 INFO - "buildnumber": 108, 15:20:18 INFO - "slavename": "talos-linux64-ix-013", 15:20:18 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:20:18 INFO - "platform": "ubuntu64_hw", 15:20:18 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 15:20:18 INFO - "branch": "autoland", 15:20:18 INFO - "repo_path": "integration/autoland", 15:20:18 INFO - "revision": "b336ce57d8847ce54f9903eb7f2ee152d49b397a", 15:20:18 INFO - "stage_platform": "linux64-pgo", 15:20:18 INFO - "builduid": "0f164c87ca714839bb814cc92cce8662", 15:20:18 INFO - "slavebuilddir": "test" 15:20:18 INFO - } 15:20:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 15:20:18 INFO - Running post-action listener: _resource_record_post_action 15:20:18 INFO - [mozharness: 2016-08-18 22:20:18.684865Z] Finished read-buildbot-config step (success) 15:20:18 INFO - [mozharness: 2016-08-18 22:20:18.684954Z] Running download-and-extract step. 15:20:18 INFO - Running pre-action listener: _resource_record_pre_action 15:20:18 INFO - Running main action method: download_and_extract 15:20:18 INFO - mkdir: /builds/slave/test/build/tests 15:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:18 INFO - trying https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:20:18 INFO - Downloading https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:20:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:20:19 INFO - Downloaded 1361 bytes. 15:20:19 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 15:20:19 INFO - Using the following test package requirements: 15:20:19 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:20:19 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:20:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:20:19 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:20:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:20:19 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:20:19 INFO - u'jsshell-linux-x86_64.zip'], 15:20:19 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:20:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:20:19 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 15:20:19 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:20:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:20:19 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:20:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:20:19 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:20:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:20:19 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 15:20:19 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:20:19 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:20:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:19 INFO - trying https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:20:19 INFO - Downloading https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:20:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:20:20 INFO - Downloaded 21907526 bytes. 15:20:20 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 15:20:24 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:24 INFO - trying https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:20:24 INFO - Downloading https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 15:20:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:20:25 INFO - Downloaded 21907526 bytes. 15:20:25 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 15:20:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:30 INFO - trying https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:20:30 INFO - Downloading https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 15:20:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:20:30 INFO - Downloaded 14413981 bytes. 15:20:30 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 15:20: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')]} 15:20:31 INFO - trying https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:20:31 INFO - Downloading https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:20:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:20:32 INFO - Downloaded 59637800 bytes. 15:20:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:20:32 INFO - mkdir: /builds/slave/test/properties 15:20:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:20:32 INFO - Writing to file /builds/slave/test/properties/build_url 15:20:32 INFO - Contents: 15:20:32 INFO - build_url:https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 15:20:33 INFO - Running post-action listener: _resource_record_post_action 15:20:33 INFO - Running post-action listener: set_extra_try_arguments 15:20:33 INFO - [mozharness: 2016-08-18 22:20:33.450799Z] Finished download-and-extract step (success) 15:20:33 INFO - [mozharness: 2016-08-18 22:20:33.451021Z] Running populate-webroot step. 15:20:33 INFO - Running pre-action listener: _resource_record_pre_action 15:20:33 INFO - Running main action method: populate_webroot 15:20:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:20:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:20:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:20:33 INFO - '--tppagecycles', 15:20:33 INFO - '7'], 15:20:33 INFO - 'tests': ['tsvgm']}}, 15:20:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:20:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:20:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:20:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:20:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:20:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:20:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:33 INFO - 'talos_options': ['--disable-e10s'], 15:20:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:20:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:20:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:33 INFO - 'talos_options': ['--disable-e10s'], 15:20:33 INFO - 'tests': ['damp', 'tps']}, 15:20:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:33 INFO - 'tests': ['damp', 'tps']}, 15:20:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:20:33 INFO - 'tests': ['dromaeo_dom']}, 15:20:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:20:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:20:33 INFO - 'tests': ['basic_compositor_video']}, 15:20:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:20:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:20:33 INFO - 'tests': ['a11yr', 15:20:33 INFO - 'ts_paint', 15:20:33 INFO - 'tpaint', 15:20:33 INFO - 'sessionrestore', 15:20:33 INFO - 'sessionrestore_no_auto_restore', 15:20:33 INFO - 'tabpaint']}, 15:20:33 INFO - 'other-e10s': {'tests': ['a11yr', 15:20:33 INFO - 'ts_paint', 15:20:33 INFO - 'tpaint', 15:20:33 INFO - 'sessionrestore', 15:20:33 INFO - 'sessionrestore_no_auto_restore', 15:20:33 INFO - 'tabpaint']}, 15:20:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:20:33 INFO - 'ts_paint', 15:20:33 INFO - 'tpaint', 15:20:33 INFO - 'sessionrestore', 15:20:33 INFO - 'sessionrestore_no_auto_restore', 15:20:33 INFO - 'tabpaint']}, 15:20:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:20:33 INFO - 'ts_paint', 15:20:33 INFO - 'tpaint', 15:20:33 INFO - 'sessionrestore', 15:20:33 INFO - 'sessionrestore_no_auto_restore', 15:20:33 INFO - 'tabpaint']}, 15:20:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:20:33 INFO - 'tests': ['a11yr', 15:20:33 INFO - 'ts_paint', 15:20:33 INFO - 'tpaint', 15:20:33 INFO - 'sessionrestore', 15:20:33 INFO - 'sessionrestore_no_auto_restore', 15:20:33 INFO - 'tabpaint']}, 15:20:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:20:33 INFO - 'tests': ['a11yr', 15:20:33 INFO - 'ts_paint', 15:20:33 INFO - 'tpaint', 15:20:33 INFO - 'sessionrestore', 15:20:33 INFO - 'sessionrestore_no_auto_restore', 15:20:33 INFO - 'tabpaint']}, 15:20:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:20:33 INFO - 'tests': ['tsvgx', 15:20:33 INFO - 'tsvgr_opacity', 15:20:33 INFO - 'tart', 15:20:33 INFO - 'tscrollx', 15:20:33 INFO - 'cart']}, 15:20:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:20:33 INFO - 'tsvgr_opacity', 15:20:33 INFO - 'tart', 15:20:33 INFO - 'tscrollx', 15:20:33 INFO - 'cart']}, 15:20:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:33 INFO - 'talos_options': ['--disable-e10s'], 15:20:33 INFO - 'tests': ['tp5o']}, 15:20:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:33 INFO - 'tests': ['tp5o']}, 15:20:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:33 INFO - 'talos_options': ['--disable-e10s', 15:20:33 INFO - '--xperf_path', 15:20:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:20:33 INFO - 'tests': ['tp5n']}, 15:20:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:20:33 INFO - 'talos_options': ['--xperf_path', 15:20:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:20:33 INFO - 'tests': ['tp5n']}}, 15:20:33 INFO - 'talos.zip': {'path': '', 15:20:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:20:33 INFO - Running post-action listener: _resource_record_post_action 15:20:33 INFO - [mozharness: 2016-08-18 22:20:33.473450Z] Finished populate-webroot step (success) 15:20:33 INFO - [mozharness: 2016-08-18 22:20:33.473538Z] Running create-virtualenv step. 15:20:33 INFO - Running pre-action listener: _resource_record_pre_action 15:20:33 INFO - Running main action method: create_virtualenv 15:20:33 INFO - Creating virtualenv /builds/slave/test/build/venv 15:20:33 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:20:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:20:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:20:33 INFO - Using real prefix '/usr' 15:20:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:20:34 INFO - Installing distribute.............................................................................................................................................................................................done. 15:20:36 INFO - Installing pip.................done. 15:20:36 INFO - Return code: 0 15:20:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa42963af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9d850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe98370>, '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 0xe9b8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bd80>, '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': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:20:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:20:36 INFO - Using env: {'DISPLAY': ':0', 15:20:36 INFO - 'HOME': '/home/cltbld', 15:20:36 INFO - 'LANG': 'en_US.UTF-8', 15:20:36 INFO - 'LANGUAGE': 'en_US:en', 15:20:36 INFO - 'LOGNAME': 'cltbld', 15:20:36 INFO - 'MAIL': '/var/mail/cltbld', 15:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:36 INFO - 'MOZ_NO_REMOTE': '1', 15:20:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:36 INFO - 'NO_EM_RESTART': '1', 15:20:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:36 INFO - 'PWD': '/builds/slave/test', 15:20:36 INFO - 'SHELL': '/bin/bash', 15:20:36 INFO - 'SHLVL': '1', 15:20:36 INFO - 'TERM': 'linux', 15:20:36 INFO - 'TMOUT': '86400', 15:20:36 INFO - 'USER': 'cltbld', 15:20:36 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 15:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:36 INFO - '_': '/tools/buildbot/bin/python'} 15:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:20:36 INFO - Cleaning up... 15:20:36 INFO - Return code: 0 15:20:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa42963af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9d850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe98370>, '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 0xe9b8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bd80>, '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': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:20:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:20:36 INFO - Using env: {'DISPLAY': ':0', 15:20:36 INFO - 'HOME': '/home/cltbld', 15:20:36 INFO - 'LANG': 'en_US.UTF-8', 15:20:36 INFO - 'LANGUAGE': 'en_US:en', 15:20:36 INFO - 'LOGNAME': 'cltbld', 15:20:36 INFO - 'MAIL': '/var/mail/cltbld', 15:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:36 INFO - 'MOZ_NO_REMOTE': '1', 15:20:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:36 INFO - 'NO_EM_RESTART': '1', 15:20:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:36 INFO - 'PWD': '/builds/slave/test', 15:20:36 INFO - 'SHELL': '/bin/bash', 15:20:36 INFO - 'SHLVL': '1', 15:20:36 INFO - 'TERM': 'linux', 15:20:36 INFO - 'TMOUT': '86400', 15:20:36 INFO - 'USER': 'cltbld', 15:20:36 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 15:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:36 INFO - '_': '/tools/buildbot/bin/python'} 15:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:36 INFO - Downloading/unpacking psutil>=3.1.1 15:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:20:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:20:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:20:38 INFO - Installing collected packages: psutil 15:20:38 INFO - Running setup.py install for psutil 15:20:38 INFO - building 'psutil._psutil_linux' extension 15:20:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:20:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:20:38 INFO - building 'psutil._psutil_posix' extension 15:20:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:20:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:20:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:20:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:20:39 INFO - Successfully installed psutil 15:20:39 INFO - Cleaning up... 15:20:39 INFO - Return code: 0 15:20:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:39 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 0x7fa42963af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9d850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe98370>, '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 0xe9b8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bd80>, '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': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:20:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:20:39 INFO - Using env: {'DISPLAY': ':0', 15:20:39 INFO - 'HOME': '/home/cltbld', 15:20:39 INFO - 'LANG': 'en_US.UTF-8', 15:20:39 INFO - 'LANGUAGE': 'en_US:en', 15:20:39 INFO - 'LOGNAME': 'cltbld', 15:20:39 INFO - 'MAIL': '/var/mail/cltbld', 15:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:39 INFO - 'MOZ_NO_REMOTE': '1', 15:20:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:39 INFO - 'NO_EM_RESTART': '1', 15:20:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:39 INFO - 'PWD': '/builds/slave/test', 15:20:39 INFO - 'SHELL': '/bin/bash', 15:20:39 INFO - 'SHLVL': '1', 15:20:39 INFO - 'TERM': 'linux', 15:20:39 INFO - 'TMOUT': '86400', 15:20:39 INFO - 'USER': 'cltbld', 15:20:39 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 15:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:39 INFO - '_': '/tools/buildbot/bin/python'} 15:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:20:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:20:41 INFO - Installing collected packages: mozsystemmonitor 15:20:41 INFO - Running setup.py install for mozsystemmonitor 15:20:41 INFO - Successfully installed mozsystemmonitor 15:20:41 INFO - Cleaning up... 15:20:41 INFO - Return code: 0 15:20:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:41 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 0x7fa42963af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9d850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe98370>, '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 0xe9b8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bd80>, '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': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:20:41 INFO - Using env: {'DISPLAY': ':0', 15:20:41 INFO - 'HOME': '/home/cltbld', 15:20:41 INFO - 'LANG': 'en_US.UTF-8', 15:20:41 INFO - 'LANGUAGE': 'en_US:en', 15:20:41 INFO - 'LOGNAME': 'cltbld', 15:20:41 INFO - 'MAIL': '/var/mail/cltbld', 15:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:41 INFO - 'MOZ_NO_REMOTE': '1', 15:20:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:41 INFO - 'NO_EM_RESTART': '1', 15:20:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:41 INFO - 'PWD': '/builds/slave/test', 15:20:41 INFO - 'SHELL': '/bin/bash', 15:20:41 INFO - 'SHLVL': '1', 15:20:41 INFO - 'TERM': 'linux', 15:20:41 INFO - 'TMOUT': '86400', 15:20:41 INFO - 'USER': 'cltbld', 15:20:41 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 15:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:41 INFO - '_': '/tools/buildbot/bin/python'} 15:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:41 INFO - Downloading/unpacking jsonschema==2.5.1 15:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:20:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:43 INFO - Downloading functools32-3.2.3-2.tar.gz 15:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:20:43 INFO - warning: no files found matching '*.txt' 15:20:43 INFO - no previously-included directories found matching 'build' 15:20:43 INFO - no previously-included directories found matching 'dist' 15:20:43 INFO - no previously-included directories found matching '.git*' 15:20:43 INFO - Installing collected packages: jsonschema, functools32 15:20:43 INFO - Running setup.py install for functools32 15:20:43 INFO - warning: no files found matching '*.txt' 15:20:43 INFO - no previously-included directories found matching 'build' 15:20:43 INFO - no previously-included directories found matching 'dist' 15:20:43 INFO - no previously-included directories found matching '.git*' 15:20:43 INFO - Successfully installed jsonschema functools32 15:20:43 INFO - Cleaning up... 15:20:43 INFO - Return code: 0 15:20:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:43 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 0x7fa42963af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9d850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe98370>, '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 0xe9b8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bd80>, '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': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:20:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:20:43 INFO - Using env: {'DISPLAY': ':0', 15:20:43 INFO - 'HOME': '/home/cltbld', 15:20:43 INFO - 'LANG': 'en_US.UTF-8', 15:20:43 INFO - 'LANGUAGE': 'en_US:en', 15:20:43 INFO - 'LOGNAME': 'cltbld', 15:20:43 INFO - 'MAIL': '/var/mail/cltbld', 15:20:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:43 INFO - 'MOZ_NO_REMOTE': '1', 15:20:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:43 INFO - 'NO_EM_RESTART': '1', 15:20:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:43 INFO - 'PWD': '/builds/slave/test', 15:20:43 INFO - 'SHELL': '/bin/bash', 15:20:43 INFO - 'SHLVL': '1', 15:20:43 INFO - 'TERM': 'linux', 15:20:43 INFO - 'TMOUT': '86400', 15:20:43 INFO - 'USER': 'cltbld', 15:20:43 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 15:20:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:43 INFO - '_': '/tools/buildbot/bin/python'} 15:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:20:44 INFO - Cleaning up... 15:20:44 INFO - Return code: 0 15:20:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:44 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 0x7fa42963af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9d850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe98370>, '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 0xe9b8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bd80>, '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': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:20:44 INFO - Using env: {'DISPLAY': ':0', 15:20:44 INFO - 'HOME': '/home/cltbld', 15:20:44 INFO - 'LANG': 'en_US.UTF-8', 15:20:44 INFO - 'LANGUAGE': 'en_US:en', 15:20:44 INFO - 'LOGNAME': 'cltbld', 15:20:44 INFO - 'MAIL': '/var/mail/cltbld', 15:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:44 INFO - 'MOZ_NO_REMOTE': '1', 15:20:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:44 INFO - 'NO_EM_RESTART': '1', 15:20:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:44 INFO - 'PWD': '/builds/slave/test', 15:20:44 INFO - 'SHELL': '/bin/bash', 15:20:44 INFO - 'SHLVL': '1', 15:20:44 INFO - 'TERM': 'linux', 15:20:44 INFO - 'TMOUT': '86400', 15:20:44 INFO - 'USER': 'cltbld', 15:20:44 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 15:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:44 INFO - '_': '/tools/buildbot/bin/python'} 15:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:44 INFO - Downloading/unpacking blobuploader==1.2.4 15:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:46 INFO - Downloading blobuploader-1.2.4.tar.gz 15:20:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:20:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:20:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:47 INFO - Downloading docopt-0.6.1.tar.gz 15:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:20:47 INFO - Installing collected packages: blobuploader, requests, docopt 15:20:47 INFO - Running setup.py install for blobuploader 15:20:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:20:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:20:47 INFO - Running setup.py install for requests 15:20:47 INFO - Running setup.py install for docopt 15:20:47 INFO - Successfully installed blobuploader requests docopt 15:20:47 INFO - Cleaning up... 15:20:47 INFO - Return code: 0 15:20:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20: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')]} 15:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20: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')]} 15:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:47 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 0x7fa42963af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9d850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe98370>, '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 0xe9b8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bd80>, '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': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:20:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:20:47 INFO - Using env: {'DISPLAY': ':0', 15:20:47 INFO - 'HOME': '/home/cltbld', 15:20:47 INFO - 'LANG': 'en_US.UTF-8', 15:20:47 INFO - 'LANGUAGE': 'en_US:en', 15:20:47 INFO - 'LOGNAME': 'cltbld', 15:20:47 INFO - 'MAIL': '/var/mail/cltbld', 15:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:47 INFO - 'MOZ_NO_REMOTE': '1', 15:20:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:47 INFO - 'NO_EM_RESTART': '1', 15:20:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:47 INFO - 'PWD': '/builds/slave/test', 15:20:47 INFO - 'SHELL': '/bin/bash', 15:20:47 INFO - 'SHLVL': '1', 15:20:47 INFO - 'TERM': 'linux', 15:20:47 INFO - 'TMOUT': '86400', 15:20:47 INFO - 'USER': 'cltbld', 15:20:47 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 15:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:47 INFO - '_': '/tools/buildbot/bin/python'} 15:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:48 INFO - Running setup.py (path:/tmp/pip-odpAlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:48 INFO - Running setup.py (path:/tmp/pip-vGj90x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:48 INFO - Running setup.py (path:/tmp/pip-qpL46K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:48 INFO - Running setup.py (path:/tmp/pip-9RR3Yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:48 INFO - Running setup.py (path:/tmp/pip-v9QvlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:48 INFO - Running setup.py (path:/tmp/pip-m2Q9Ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:48 INFO - Running setup.py (path:/tmp/pip-VwePW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:48 INFO - Running setup.py (path:/tmp/pip-pVqC_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:48 INFO - Running setup.py (path:/tmp/pip-CGUaNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:48 INFO - Running setup.py (path:/tmp/pip-QBCqri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:48 INFO - Running setup.py (path:/tmp/pip-uH9sBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:48 INFO - Running setup.py (path:/tmp/pip-OFntUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:48 INFO - Running setup.py (path:/tmp/pip-NTwPq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:48 INFO - Running setup.py (path:/tmp/pip-LzZEnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:49 INFO - Running setup.py (path:/tmp/pip-B_RXGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:49 INFO - Running setup.py (path:/tmp/pip-68gEmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:49 INFO - Running setup.py (path:/tmp/pip-W0KM2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:20:49 INFO - Running setup.py install for manifestparser 15:20:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:20:49 INFO - Running setup.py install for mozcrash 15:20:49 INFO - Running setup.py install for mozdebug 15:20:49 INFO - Running setup.py install for mozdevice 15:20:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:20:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:20:49 INFO - Running setup.py install for mozfile 15:20:49 INFO - Running setup.py install for mozhttpd 15:20:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:20:49 INFO - Running setup.py install for mozinfo 15:20:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:20:49 INFO - Running setup.py install for mozInstall 15:20:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:20:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:20:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:20:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:20:50 INFO - Running setup.py install for mozleak 15:20:50 INFO - Running setup.py install for mozlog 15:20:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:20:50 INFO - Running setup.py install for moznetwork 15:20:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:20:50 INFO - Running setup.py install for mozprocess 15:20:50 INFO - Running setup.py install for mozprofile 15:20:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:20:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:20:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:20:50 INFO - Running setup.py install for mozrunner 15:20:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:20:50 INFO - Running setup.py install for mozscreenshot 15:20:50 INFO - Running setup.py install for moztest 15:20:50 INFO - Running setup.py install for mozversion 15:20:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:20:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:20:51 INFO - Cleaning up... 15:20:51 INFO - Return code: 0 15:20:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:51 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 0x7fa42963af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9d850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe98370>, '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 0xe9b8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bd80>, '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': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:20:51 INFO - Using env: {'DISPLAY': ':0', 15:20:51 INFO - 'HOME': '/home/cltbld', 15:20:51 INFO - 'LANG': 'en_US.UTF-8', 15:20:51 INFO - 'LANGUAGE': 'en_US:en', 15:20:51 INFO - 'LOGNAME': 'cltbld', 15:20:51 INFO - 'MAIL': '/var/mail/cltbld', 15:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:51 INFO - 'MOZ_NO_REMOTE': '1', 15:20:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:51 INFO - 'NO_EM_RESTART': '1', 15:20:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:51 INFO - 'PWD': '/builds/slave/test', 15:20:51 INFO - 'SHELL': '/bin/bash', 15:20:51 INFO - 'SHLVL': '1', 15:20:51 INFO - 'TERM': 'linux', 15:20:51 INFO - 'TMOUT': '86400', 15:20:51 INFO - 'USER': 'cltbld', 15:20:51 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 15:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:51 INFO - '_': '/tools/buildbot/bin/python'} 15:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:51 INFO - Running setup.py (path:/tmp/pip-6MZPv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:51 INFO - Running setup.py (path:/tmp/pip-Ev0gvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:51 INFO - Running setup.py (path:/tmp/pip-cVPpsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:51 INFO - Running setup.py (path:/tmp/pip-JRbL6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:51 INFO - Running setup.py (path:/tmp/pip-Rf7ad8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:51 INFO - Running setup.py (path:/tmp/pip-HNXm0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:51 INFO - Running setup.py (path:/tmp/pip-MWtTkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:51 INFO - Running setup.py (path:/tmp/pip-HBJ4b0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:51 INFO - Running setup.py (path:/tmp/pip-mKvWmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:51 INFO - Running setup.py (path:/tmp/pip-rCGfIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:52 INFO - Running setup.py (path:/tmp/pip-kmbL8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:52 INFO - Running setup.py (path:/tmp/pip-0rRf_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:52 INFO - Running setup.py (path:/tmp/pip-6AORX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:52 INFO - Running setup.py (path:/tmp/pip-OpkbjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:52 INFO - Running setup.py (path:/tmp/pip-xnWcbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:52 INFO - Running setup.py (path:/tmp/pip-ZkIANZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:52 INFO - Running setup.py (path:/tmp/pip-WLUwym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:20:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:20:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20:54 INFO - Downloading blessings-1.6.tar.gz 15:20:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:20:54 INFO - Installing collected packages: blessings 15:20:54 INFO - Running setup.py install for blessings 15:20:54 INFO - Successfully installed blessings 15:20:54 INFO - Cleaning up... 15:20:54 INFO - Return code: 0 15:20:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:20:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:54 INFO - Reading from file tmpfile_stdout 15:20:54 INFO - Current package versions: 15:20:54 INFO - argparse == 1.2.1 15:20:54 INFO - blessings == 1.6 15:20:54 INFO - blobuploader == 1.2.4 15:20:54 INFO - docopt == 0.6.1 15:20:54 INFO - functools32 == 3.2.3-2 15:20:54 INFO - jsonschema == 2.5.1 15:20:54 INFO - manifestparser == 1.1 15:20:54 INFO - mozInstall == 1.12 15:20:54 INFO - mozcrash == 0.17 15:20:54 INFO - mozdebug == 0.1 15:20:54 INFO - mozdevice == 0.48 15:20:54 INFO - mozfile == 1.2 15:20:54 INFO - mozhttpd == 0.7 15:20:54 INFO - mozinfo == 0.9 15:20:54 INFO - mozleak == 0.1 15:20:54 INFO - mozlog == 3.3 15:20:54 INFO - moznetwork == 0.27 15:20:54 INFO - mozprocess == 0.23 15:20:54 INFO - mozprofile == 0.28 15:20:54 INFO - mozrunner == 6.12 15:20:54 INFO - mozscreenshot == 0.1 15:20:54 INFO - mozsystemmonitor == 0.3 15:20:54 INFO - moztest == 0.7 15:20:54 INFO - mozversion == 1.4 15:20:54 INFO - psutil == 3.1.1 15:20:54 INFO - requests == 1.2.3 15:20:54 INFO - wsgiref == 0.1.2 15:20:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20: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')]} 15:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20: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')]} 15:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:54 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 0x7fa42963af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9d850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe98370>, '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 0xe9b8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bd80>, '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': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:20:54 INFO - Using env: {'DISPLAY': ':0', 15:20:54 INFO - 'HOME': '/home/cltbld', 15:20:54 INFO - 'LANG': 'en_US.UTF-8', 15:20:54 INFO - 'LANGUAGE': 'en_US:en', 15:20:54 INFO - 'LOGNAME': 'cltbld', 15:20:54 INFO - 'MAIL': '/var/mail/cltbld', 15:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:54 INFO - 'MOZ_NO_REMOTE': '1', 15:20:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:54 INFO - 'NO_EM_RESTART': '1', 15:20:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:54 INFO - 'PWD': '/builds/slave/test', 15:20:54 INFO - 'SHELL': '/bin/bash', 15:20:54 INFO - 'SHLVL': '1', 15:20:54 INFO - 'TERM': 'linux', 15:20:54 INFO - 'TMOUT': '86400', 15:20:54 INFO - 'USER': 'cltbld', 15:20:54 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 15:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:54 INFO - '_': '/tools/buildbot/bin/python'} 15:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:20:55 INFO - Cleaning up... 15:20:55 INFO - Return code: 0 15:20:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa42963af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe9d850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe98370>, '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 0xe9b8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bd80>, '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': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:20:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:20:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:20:55 INFO - Using env: {'DISPLAY': ':0', 15:20:55 INFO - 'HOME': '/home/cltbld', 15:20:55 INFO - 'LANG': 'en_US.UTF-8', 15:20:55 INFO - 'LANGUAGE': 'en_US:en', 15:20:55 INFO - 'LOGNAME': 'cltbld', 15:20:55 INFO - 'MAIL': '/var/mail/cltbld', 15:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:55 INFO - 'MOZ_NO_REMOTE': '1', 15:20:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:20:55 INFO - 'NO_EM_RESTART': '1', 15:20:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:55 INFO - 'PWD': '/builds/slave/test', 15:20:55 INFO - 'SHELL': '/bin/bash', 15:20:55 INFO - 'SHLVL': '1', 15:20:55 INFO - 'TERM': 'linux', 15:20:55 INFO - 'TMOUT': '86400', 15:20:55 INFO - 'USER': 'cltbld', 15:20:55 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 15:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:55 INFO - '_': '/tools/buildbot/bin/python'} 15:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:20:55 INFO - Cleaning up... 15:20:55 INFO - Return code: 0 15:20:55 INFO - Running post-action listener: _resource_record_post_action 15:20:55 INFO - Running post-action listener: _start_resource_monitoring 15:20:55 INFO - Starting resource monitoring. 15:20:55 INFO - [mozharness: 2016-08-18 22:20:55.364348Z] Finished create-virtualenv step (success) 15:20:55 INFO - [mozharness: 2016-08-18 22:20:55.364851Z] Running install step. 15:20:55 INFO - Running pre-action listener: _resource_record_pre_action 15:20:55 INFO - Running main action method: install 15:20:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:55 INFO - Reading from file tmpfile_stdout 15:20:55 INFO - Detecting whether we're running mozinstall >=1.0... 15:20:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:20:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:20:55 INFO - Reading from file tmpfile_stdout 15:20:55 INFO - Output received: 15:20:55 INFO - Usage: mozinstall [options] installer 15:20:55 INFO - Options: 15:20:55 INFO - -h, --help show this help message and exit 15:20:55 INFO - -d DEST, --destination=DEST 15:20:55 INFO - Directory to install application into. [default: 15:20:55 INFO - "/builds/slave/test"] 15:20:55 INFO - --app=APP Application being installed. [default: firefox] 15:20:55 INFO - mkdir: /builds/slave/test/build/application 15:20:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:20:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:21:12 INFO - Reading from file tmpfile_stdout 15:21:12 INFO - Output received: 15:21:12 INFO - /builds/slave/test/build/application/firefox/firefox 15:21:12 INFO - Running post-action listener: _resource_record_post_action 15:21:12 INFO - [mozharness: 2016-08-18 22:21:12.638900Z] Finished install step (success) 15:21:12 INFO - [mozharness: 2016-08-18 22:21:12.639146Z] Running run-tests step. 15:21:12 INFO - Running pre-action listener: _resource_record_pre_action 15:21:12 INFO - Running main action method: run_tests 15:21:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:21:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:21:12 INFO - Python 2.7.3 15:21:12 INFO - Return code: 0 15:21:12 INFO - grabbing minidump binary from tooltool 15:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:12 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 0xe98370>, '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 0xe9b8f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe9bd80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:21:12 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:21:12 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:21:12 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:21:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:21:12 INFO - Return code: 0 15:21:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:21:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:21:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:21:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:21:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:21:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:21:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:21:12 INFO - Using env: {'DISPLAY': ':0', 15:21:12 INFO - 'HOME': '/home/cltbld', 15:21:12 INFO - 'LANG': 'en_US.UTF-8', 15:21:12 INFO - 'LANGUAGE': 'en_US:en', 15:21:12 INFO - 'LOGNAME': 'cltbld', 15:21:12 INFO - 'MAIL': '/var/mail/cltbld', 15:21:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:21:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:21:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:12 INFO - 'MOZ_NO_REMOTE': '1', 15:21:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:21:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:21:12 INFO - 'NO_EM_RESTART': '1', 15:21:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:21:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:12 INFO - 'PWD': '/builds/slave/test', 15:21:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:21:12 INFO - 'SHELL': '/bin/bash', 15:21:12 INFO - 'SHLVL': '1', 15:21:12 INFO - 'TERM': 'linux', 15:21:12 INFO - 'TMOUT': '86400', 15:21:12 INFO - 'USER': 'cltbld', 15:21:12 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532', 15:21:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:12 INFO - '_': '/tools/buildbot/bin/python'} 15:21:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:21:12 INFO - mozversion application_buildid: 20160818123002 15:21:12 INFO - mozversion application_changeset: b336ce57d8847ce54f9903eb7f2ee152d49b397a 15:21:12 INFO - mozversion application_display_name: Nightly 15:21:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:21:12 INFO - mozversion application_name: Firefox 15:21:12 INFO - mozversion application_remotingname: firefox 15:21:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:21:12 INFO - mozversion application_vendor: Mozilla 15:21:12 INFO - mozversion application_version: 51.0a1 15:21:12 INFO - mozversion platform_buildid: 20160818123002 15:21:12 INFO - mozversion platform_changeset: b336ce57d8847ce54f9903eb7f2ee152d49b397a 15:21:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:21:12 INFO - mozversion platform_version: 51.0a1 15:21:12 INFO - using testdate: 1471558872 15:21:12 INFO - actual date: 1471558872 15:21:12 INFO - starting webserver on 'localhost:55611' 15:21:12 INFO - SUITE-START | Running 2 tests 15:21:12 INFO - TEST-START | dromaeo_css 15:21:12 INFO - operating with platform_type : linux_ 15:21:12 INFO - Initialising browser for dromaeo_css test... 15:21:12 INFO - TEST-INFO | started process 7904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRVLRhB/profile http://localhost:55611/getInfo.html) 15:21:17 INFO - PROCESS | 7904 | __metrics Screen width/height:1600/1200 15:21:17 INFO - PROCESS | 7904 | colorDepth:24 15:21:17 INFO - PROCESS | 7904 | Browser inner width/height: 1024/697 15:21:17 INFO - PROCESS | 7904 | __metrics 15:21:17 INFO - PROCESS | 7904 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:21:17 INFO - PROCESS | 7904 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:21:18 INFO - PROCESS | 7904 | console.error: PushService: 15:21:18 INFO - PROCESS | 7904 | stateChangeProcessEnqueue: Error transitioning state 15:21:18 INFO - PROCESS | 7904 | UnknownError 15:21:18 INFO - TEST-INFO | 7904: exit 0 15:21:18 INFO - Browser initialized. 15:21:18 INFO - Running cycle 1/1 for dromaeo_css test... 15:21:18 INFO - TEST-INFO | started process 7986 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRVLRhB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:21:30 INFO - PROCESS | 7986 | RSS: Main: 155791360 15:21:30 INFO - PROCESS | 7986 | 15:22:22 INFO - PROCESS | 7986 | 1471558942271 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:24:31 INFO - PROCESS | 7986 | Cycle 1(1): loaded http://localhost:55611/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55611/tests/dromaeo/cssquery-ext.html) 15:24:32 INFO - PROCESS | 7986 | RSS: Main: 279347200 15:24:32 INFO - PROCESS | 7986 | 15:27:35 INFO - PROCESS | 7986 | Cycle 1(1): loaded http://localhost:55611/tests/dromaeo/cssquery-ext.html (next: http://localhost:55611/tests/dromaeo/cssquery-jquery.html) 15:27:36 INFO - PROCESS | 7986 | RSS: Main: 242556928 15:27:36 INFO - PROCESS | 7986 | 15:30:37 INFO - PROCESS | 7986 | Cycle 1(1): loaded http://localhost:55611/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55611/tests/dromaeo/cssquery-mootools.html) 15:30:38 INFO - PROCESS | 7986 | RSS: Main: 184967168 15:30:38 INFO - PROCESS | 7986 | 15:33:40 INFO - PROCESS | 7986 | Cycle 1(1): loaded http://localhost:55611/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55611/tests/dromaeo/cssquery-prototype.html) 15:33:41 INFO - PROCESS | 7986 | RSS: Main: 233615360 15:33:41 INFO - PROCESS | 7986 | 15:36:44 INFO - PROCESS | 7986 | Cycle 1(1): loaded http://localhost:55611/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55611/tests/dromaeo/cssquery-yui.html) 15:36:44 INFO - PROCESS | 7986 | RSS: Main: 185995264 15:36:44 INFO - PROCESS | 7986 | 15:39:47 INFO - PROCESS | 7986 | Cycle 1(1): loaded http://localhost:55611/tests/dromaeo/cssquery-yui.html (next: http://localhost:55611/tests/dromaeo/cssquery-dojo.html) 15:39:47 INFO - PROCESS | 7986 | RSS: Main: 268541952 15:39:47 INFO - PROCESS | 7986 | 15:39:47 INFO - PROCESS | 7986 | __start_tp_report 15:39:47 INFO - PROCESS | 7986 | _x_x_mozilla_page_load 15:39:47 INFO - PROCESS | 7986 | _x_x_mozilla_page_load_details 15:39:47 INFO - PROCESS | 7986 | |i|pagename|runs| 15:39:47 INFO - PROCESS | 7986 | |0;dojo.html;3001.2946957465006;3006.3686625808577;3035.681253468385;3037.544368344748;3045.101694999975;5246.475352464751;5276.445973172815;5341.359036915565;5404.567634589233;5413.83758487245;5403.594730395226;5420.13277875539;5427.0773968425365;5480.780768769255;5488.1493368527945;4872.269159626049;4915.213565829468;4964.429090654567;4979.153543897528;5085.440601533847;4995.35060442142;5025.3718285214345;5044.218146187347;5050.873728156807;5051.3180720602795;4835.685680430796;4857.684250523723;4898.485659005789;4916.926246106311;4996.950030499703;4869.732164730938;4871.610271178332;4888.120138375091;4934.975325123387;4937.580305674022;4897.51024897511;4920.876978075541;4923.753812309406;4950.87622809429;4972.602191824645;5285.154375299933;5336.599755018389;5400.270963419927;5413.648112872651;5424.376196737387;5744.22452968848;5793.942060579382;5802.477777000048;5840.620359676607;5846.064629659234;5845.06478963368;5881.2354393928945;5882.441168089024;5883.0881213411985;5889.823305300848;5270.736463176864;5320.52115309624;5386.622936394415;5390.54180394663;5452.645578037414;5943.613665111754;5955.13650520672;5956.821295361146;5958.136070269787;5960.016597261403;5667;5668.489835914787;5719.170720245541;5719.513841323451;5724.427557244242;5634.211210430461;5711.485966263056;5745.396733343023;5751.079827227623;5794.1308803679785;5732.541396688255;5754.280714910637;5755.971220143872;5764.596478246483;5795.217645617788;5733.31200255972;5767.077267637242;5772.94227057724;5807.680577568274;5810.6513609183585;4305.935410968838;4360.912781744348;4362.738235705804;4404.053128577371;4412.602865742098;3933.272344616255;3975.1453437511072;3975.403689446606;3975.4633124528395;3976.045749020272;5391.703456309862;5394.838154855361;5399.406065332811;5401.405845357008;5401.675899446046;5798.797042103506;5840.561957853178;5853.619514731528;5863.120531920246;5904.5571582131515;5417.702026388586;5440.7551660174595;5450.809221677301;5465.234867118606;5470.781168753285;4944.307796908436;4980.05378977993;5043.571296439771;5044.2685810558;5090.26191202281;5990.610610310316;6082.026875699867;6082.817515474543;6130.1724267224245;6141.048137538599;5802.6808525531505;5845.976954032943;5849.1226316052935;5852.912206316823;5855.180274761622;5863.413658634273;5942.61373010753;6078.057901025348;6086.239220097488;6100.145979562776;5659.236003139688;5708.885822283448;5709.914351284817;5758;5764.106563482667;5807.65154090739;5820.912686309624;5820.970895145497;5823.388544202798;5827.9708601458415;5492.615516913777;5598.300212473441;5679.261695979496;5694.914576281442;5697.974364614243;5614.69119198448;5687.658740475585;5695.345035320825;5729.9713501432225;5734;5579.944200558105;5621.5221706155435;5623.465770751772;5629.240052592848;5629.380768115422;5517.448255174611;5657.660540367591;5738.225339579103;5747.942520574741;5753.309602847685;5556.111022236423;5618.381977982337;5636.915446268227;5663.858403539944;5674.546036317167 15:39:47 INFO - PROCESS | 7986 | |1;ext.html;554.5725046708646;589.6697849204443;595.8391234366721;605.9030555111183;610.5237914426747;31706;41946.37080443792;42514.36228456576;42860.35709464353;42881;45296.77351613245;45632;45794;45823.770881145545;45835.77082114593;9144.634214631407;9253.537323133816;9288.58201380936;9305.11601397866;9305.767355816091;9009.459432434074;9143.588538515749;9167.495787731705;9172.724818255463;9202.217811486033;9185.265178785681;9230.584623691917;9247.768805779842;9248.953755231249;9255.676051338205;9155.176034156924;9197.21823644991;9197.678081267124;9202.539873006323;9203.309751768644;9023.233025192867;9106.362554621179;9106.81786364269;9109.81780364389;9112.179903808688;42723.14553708908;43118;43120.56879431228;43126;43191.56808431907;3507.2284097498764;3537.3102245062223;3709.7774133552075;3758.229562939618;4017.9215212408003;30482.06070838101;31425.37149257002;33489.54381580041;33715;33910.15224619379;4436.423264975565;4444.20004399208;4554.658400619934;4724.031573527453;4774.546418090275;380377;385778;388485.99999999715;388640;389114;55847;65581.6720916402;66490.66754666196;66595;66675.66662166755;65494.67252663706;66214.33785662179;66521.6673916637;73225.63387183136;73568;17464.65070698579;17731.29074837018;17753.556161096076;17764.644707105785;17766.733498997266;12153.756924861453;13147.276899770604;13205.867941320656;13224.669383265495;13225.603231903058;19846.90076549608;20394.69407958882;20518.794812051987;20915.8954205228;21063;7844.294013538694;7933.167017463199;7970.960145199237;8058.919410805935;8063.0245589830865;982.729749318943;1018.8420794776821;1063.2397835547465;1073.4257172412722;1116.6203490813164;33437.665623343455;33609;33759.66240337614;33950.32099357999;34601.61593536281;1096.7719441552858;1104.6741211342512;1148.4028305281374;1187.7683851648846;1244.3094082783962;16451.42420015318;17395.56511087233;17553;17748.29006839712;17799.911000445172;583062;586816;587591;588397;589456;59982.70008649929;60530.697346513865;60726;60862.391376086554;60932;1220.7924652809045;1367.6922692394132;1374.4021350712471;1379.4551152294787;1414.5485016559767;31247.218819529695;33679.66320336765;34811.825940870134;34915.4762678555;34958.82520587381;51706.48293517167;52452.213216801014;52463;52521.47478525319;52560.73719631377;393923;395459;396107;396412;396813;29443;29803.55294670625;29932.85033574905;31383.529247060855;31662;49268;49707.50292497173;49852.75073624609;49920;50051.499485004686;39737;40230.798846005586;40661.1867762649;42105.78947105245;42642.36036459517;39766;39861.60138398579;41435.7928210357;41440.96476953938;42419.7879010603;42465.787671061444;43076.35385469158;43268.13463730771;44964.55035449604;45823.541764581925;38570.925835055205;44230.77884610556;44259.55740442683;44498.77750611226;44730;33416.49875251922;35780.28439431249;35985.640143599274;36038;36082.27835443329 15:39:47 INFO - PROCESS | 7986 | |2;jquery.html;377.7733359984008;398.1102236501421;402.875108716298;403.84653831544;418.6734347209176;14171.574852754407;18434.815651843495;18718.251269949204;18923.337683181093;19630.312939047137;16807.495775126736;17232.913835430805;17297.913510432463;17656.735148972744;17911.910440447813;5594.216809646653;5595.076812325961;5824.941750582503;5826.883462330751;5829.533637309027;5640.605157638967;5716.114002329646;5763;5771.884562308751;5777.104548794943;5537.473939975695;5680.4319568043275;5743.253377060976;5754.424557544233;5756.453136951983;5620.213170156161;5706.743196556165;5711.885762284731;5715.514181294595;5717.599768016241;5003.624728145367;5010.749462526859;5104.3619547556555;5129.769160387791;5131.486851314876;23330.883345583163;23451.88274058636;23455.296341109795;23538.29385118449;23569;2325.2210509479346;2600.310917606817;2752.6421565196397;3116.9844150779327;3173.8571764270664;14432;14535.127892326494;14684;14689.70620587593;14718.190499522521;1871;1976.8912709800954;1979.1192919150983;2033.6542787725973;2060.9690854637192;80770.59614701947;81200.18799811986;81590.18409815885;82929;83823.5808820958;10437.791244175225;10470.319429237228;10610.20423468243;10618.840717389245;10966.177536684781;14885.627859303604;14900.552983410515;14905.850941490664;14909.850901491063;14910.552683419515;7947.284744373034;8164.265216130577;8194.09864914859;8336.374771892133;8684.60919258635;7947.880781788278;8075;8151.633176507072;8344.207300306462;8516.70191543293;6428.77499287532;6613.834654133685;6621.304762999912;6633.27239125804;6703.698333575001;1912.2828939147819;1920.4910698664864;1939.1758502636324;1942.3298961858136;1994.321930543622;927.6521304510809;1012.4431562640518;1033.4574348467115;1034.1158309645296;1073.4847273308856;19538.41384758431;20143.39569812908;20219.595608087755;20261.29085482001;20336.08487618061;1020.8417695257247;1040.5629635553087;1057.4554104636118;1066.4934156275863;1086;10156.746081348025;11714.23857449263;11828.40857957116;11848.585299514476;11864.169508134348;454768;462158;463106;474039;474751;80126.5993670028;80261.19738802654;80744;80797;86269.5686521576;972.7957129002989;974.0697633759884;977.4428575711918;982.9311948163703;989;16941.37408701653;17267.740983885502;18219.817801821813;18257.543561411072;18430.907845460686;31047.068587942398;42360;45263.321050183615;45371.77314113409;46433.76783116063;63672.363276368495;64107.67946160239;64576.67711661412;64597;66376.6681166591;8304.70933517324;8613.052564217805;8616.310695144248;8638.222559969632;9098.090190980849;12824.358782061046;13303.534376296782;13425.462981480632;13628.727425451634;13761.518346858213;11144.33134011962;11251.324920504796;11374.601888933848;11479.36863472517;11741.41292935367;11029.503672334924;11216.158788091088;11394.202405831513;11398.435352045723;11425.657230283105;10588;11315.321080735182;11531.827022594836;11854.822177667169;11876.346800926032;8567.61445734931;8587.95706021466;8603.698870539502;8625.654973801229;9028.232600228792;10651.680449586525;11266.324020558794;11359.886401136213;11449.713757156138;11543.884561154617 15:39:47 INFO - PROCESS | 7986 | |3;mootools.html;1378.3659516622354;1507.7889095526625;1533.7162624914392;1661.9916900415496;1780.3234770787108;8529.658813647455;9063.139001794836;9078.773030674229;9386.014468480813;9388.155066044072;9235.21500672442;9344.813103737939;9365.063493650632;9365.203957663607;9365.578548965295;2120.4804822818355;2338.0998315648426;2420.8305418620657;2445.938851528717;2448.045262347686;2443.5723748344058;2472.49313890652;2477.7770000699934;2478.157426474998;2481.0447977528693;2348.7181538215355;2355.5171189906114;2369.455025344172;2369.9052037918464;2420.4311986683115;2277.681124642535;2312.710911136108;2322.3381336319153;2331.6152834782242;2364.881755912215;2391.390195500154;2403;2405.927822165338;2407.337982054931;2407.735149133594;9245.583948722257;9309.813803723955;9323.300752443525;9331.90668093324;9347.252219822398;5252.737363131866;5299.072662284123;5302.814401495929;5443.455654434525;5453.727313634342;3323.6344002159744;3423.1271025888486;3508.8771892983623;3515.2442224921765;3558.1104723819044;1201.915865889388;1245.819356193356;1251.4431078170219;1260.2312589320507;1269.1433282534254;11872.050235981102;11888.465019074163;11909.464074116599;11991.22057066288;11997.400129993553;4304.849330273488;4387.605115539617;4570.908581828411;4575.7483338416705;4610.723356598548;9013.324000699973;9014.414063085878;9018.323625728097;9022.503762293005;9022.639094436281;5103.795848166106;5106.029854327666;5125.231215317657;5131.7434128293435;5135.948640513622;4371.775902747236;4805.063012712558;4821.927671084936;4833.492483289274;4947.777350019258;3841.2125514269505;3923.2349691809823;3974.4833171687496;3983.203359328146;3992.48097747291;1198.2151690642643;1219.4207751318231;1220.8473940757406;1229.9631011069682;1232.89520390766;935.3686261773275;999.5352161245056;1011.3881101933224;1018.3686114608842;1024.8565200871883;6085.482733967574;6163.599366041282;6176.5234226861485;6285.402886725845;6471.158749362553;470.90817290627996;475.139996606144;485.922252439608;496.8782648251129;497.0729589315972;4139.917201656011;4191.895202619959;4226.154769046141;4317.70468859341;4327.5239723630375;11304.604338848101;11510.30938143714;11534.250273732181;11561.479733412016;11596.246243994114;11505.827412588818;11509.25189862673;11515.53937842477;11518;11520.596779112691;8105.473144245605;8442.746717598482;8453.915460845314;8529.82940341202;8623.094575069777;6022.789202378071;6138.278584949473;6367.61794292344;6458.128152699325;6489.994050922114;10502.842457362993;10641.787164256826;10765.623203188165;10903.127749780157;10919.781604368027;10769;10928.344299342065;10975.50610222558;10982.725431863948;11033.944830276118;7431.739889103856;7663.923360766321;7765.72819951299;7873.921260787319;7978.0825205102155;9145.77135571616;9216.400933939272;9219.631214751334;9221.585028673591;9231.215346695337;7836.960815195887;7961.681532738858;8194.50832950025;8214.712485062993;8242.464239824392;7712.829260552375;7837.529748215125;7846.568438735924;8054.114047454658;8172.387070969582;7052.259512751292;7052.365287124184;7052.576845389293;7127.6457473079945;7135;5008.749562521786;5092.439831618593;5181.896362072813;5227.817026404057;5314.202869569595;5308.920366194588;5316.680999140064;5398.271233383443;5449.128139497661;5553.694546799965 15:39:47 INFO - PROCESS | 7986 | |4;prototype.html;328.0208918067995;339.123366098635;347.83130181861793;348.0532950374979;349.08713713638844;5336.866578335544;5375.6774593524415;5388.40727519973;5407.756650950707;5416.8916621667595;5037.697738135706;5085.008423357447;5205.245239440279;5211.843644690665;5301.561281810372;4121.505419349674;4142.295809712341;4148.315527937887;4149.481314835645;4162.105147393309;4155.376693495969;4183.602557757008;4184.267753143203;4186.225548273579;4197.4753155855515;3974.56915510416;3993.5407428145845;4144.626983571478;4149.5228048774325;4159.563245859186;4143.316352801814;4151.377293405983;4159.272127377697;4159.563245859172;4178.540360560336;3725.9068523286865;3732.7760334379755;3739.5325584301963;3742.7380083394173;3756.6619004289473;5057.9494205057845;5116.797552575142;5135.435102138762;5201.713905735183;5204.817831375922;4724.385829842098;4735.195016847144;4746.691465054795;4758.143534163849;4797.880052998668;5435.918461223085;5451.792024230193;5471.972640136773;5490.423505531901;5494.1758736189895;3883.1262965832743;4009.0979529605897;4071.5114186297833;4072.5316588592395;4090.386442033689;5641.661500309994;5643.661380317194;5657.773689052433;5673;5674.829755107312;4171.561985991488;4172.666186705056;4179.26862799009;4181.142865712522;4196.643285320782;3926.2147570486018;4132.628063474302;4270.829166833354;4271.594198551133;4274.529801721808;4685.8594242172785;4691.82704323919;4694.554017368345;4707.728913193487;4719.480857105716;5544.3346798384455;5550.639208451437;5560.777568897199;5624.91562626561;5629.3112066380745;1357.8234829472106;1415.2216281045385;1419.9077059991273;1427.928603569838;1437.73401920645;919.1543779722671;949.6296444386695;984.9753756156153;994.8159590475785;997.2022382094441;5256.816011439581;5299.178627312773;5306.92039619406;5334.359876814807;5354.491323324278;5583.078791999355;5614.803481878114;5619.291267763257;5638.379778224392;5661.1508273758445;5197.58419326453;5263.578913686896;5264.52619264268;5278.155495120763;5310.123829568155;2953.896613618513;2970.0792754246254;2971.925701857471;2975.5834183214365;2979.3147576057195;5522.2544956431175;5732.649613658464;5775.88448231033;5780.1907732917425;5807.912881306784;5673.461021202979;5688.544916406678;5696.686682232517;5703.600747947604;5707.71461426927;5770.596058275964;5779.971100144557;5796.971015145066;5805.477507024388;5822.777216784438;5378.704171270618;5525.696086715269;5586.581006424453;5597;5602.411746766531;5470.480304371078;5477.1510415885605;5510.228568000403;5522.972385138049;5534.806281780118;5492.505674489315;5744.741486633028;5746.13807928814;5789.363170051207;5820.912686309794;4002.219567184371;4032.2540330039037;4035.838566457426;4052.169305292467;4065.715399921977;5564.8330550083565;5583.134614134816;5587.189857470726;5606.6636001840025;5650.3219613646625;5278.2610434539965;5403.270558474713;5419.512243898068;5426.918596220981;5448.618596698193;5377.623566350474;5402.540784033401;5415.48552887475;5418.674879507243;5435.565154787687;5223.764930578057;5260.605454590997;5373.328333958255;5385.518982279905;5398.568114550905;5494.461550765792;5520.420355862577;5554.722263886709;5571.637843540157;5576.525995290446;5451;5456.181572764117;5456.53619442336;5464.28964234647;5464.863378415572 15:39:47 INFO - PROCESS | 7986 | |5;yui.html;761.9657115429806;767.9385649148072;805.7824387415397;818.333058557276;846.9153084691528;14273.857261427383;14866.628334291649;15324.233788310596;15332.92333538332;15657.765133523006;13886.375113119908;13918.095323803946;14194.787078193835;14203.78694319586;14208.431662733505;675.6047712088434;797.5732982854174;803.3091541274499;823.4112609484217;839.6935118681668;833.4041160570191;838.6226198210799;839.6389552492426;840.2815592668272;841.7306461932184;876.3471213945619;878.4992554244084;879.0769691823591;879.3888247667874;879.6393478673745;696.0186137546061;842.2588122452264;858.5406807358033;872.3631748823362;874.7681864305932;626.959247648906;627.2473032361185;627.595201095291;628.9842753931142;632.2255237334275;13923.58229253124;13978.930105349407;13990.510332138429;13993.440262389595;14010.369533371022;13478.258695771729;13952.790708139386;14070.22613756243;14091.71816563663;14106.294685265799;12282.263064216175;12440.75118497625;12516.937415312865;12762.298073605947;12899.613011609667;4238.279492486285;4279.315309550489;4314.460692413449;4318.848840290606;4487.147441986012;84457;87175;87778.56110719405;87922.12077879203;88925.11074889233;9776.560054797552;10865.293755905841;10913.945430272797;10953.17851161166;11281.548738050386;22665.4333641657;22701.09195632156;22953.7704622955;23009.769902301097;23056.53886922252;8248.876266855881;8344.582770861432;8432.620532076071;8551.828963420698;8564.486130832045;4555.385023021916;4751.881202969954;4777.331173635694;4884.0476107159475;4975.203967365204;3626.256617393428;3666.7799932004164;3731.141837377364;3737.9626203738158;3741.43878418234;575.3556017260695;576.6165499942575;576.7145263094795;578.2800413485154;594.8334466349429;703.7044441334651;703.8240439890028;721.1706537481938;755.6712829918893;761.5392687424139;9239.630414783467;9873.358231714916;10031.347962382568;10157.847632285522;10188.694339169837;670.7149461478851;733.2301083861923;758.635854789704;765.0551568812513;780.4302858913026;4669.229577119737;4877.487863774252;4897.363342765418;4916.631252656065;4947.431045429822;129329.35335323263;130852.3457382707;131135;132080.3395983014;136238.31880840732;111121;111551.44224278827;112116.43941780402;115409;116862;91589.71949067348;95023;97808.51095744476;99308;102783.4860825706;8892.73321800347;9366;9509.707140506789;9579.952100239454;9670.516474176404;44529.33206001978;50635.493645064555;52492.21261681154;53538.46461535335;53721;79492;79551;79813.60093199497;81428.18571814206;82322;13608;13653.79519307231;13792.310384480936;13945.233012184428;14048.227347495986;13374.398152082986;13704.314784260947;14059.718805624036;14060.08609440383;14447.711045779235;12264.32546209967;12683.175593586388;13007.414666340226;13134.868651313365;13771;12561.309127998047;12703.618891433272;12737.93631031839;12770.744585108432;13056.412461439068;12640.87359126397;12938;13018.869811302146;13019.739605208033;13320.600381988555;10073.798524029626;10649.574017039231;10734.785304294026;10917.617883374043;10947.993050473571;11013.559457621606;11056.093341858776;11205.495752690986;11293.830592541282;11329.660110196708 15:39:47 INFO - PROCESS | 7986 | __end_tp_report 15:39:47 INFO - PROCESS | 7986 | __start_cc_report 15:39:47 INFO - PROCESS | 7986 | _x_x_mozilla_cycle_collect,1107 15:39:47 INFO - PROCESS | 7986 | __end_cc_report 15:39:47 INFO - PROCESS | 7986 | __startTimestamp1471559987525__endTimestamp 15:39:47 INFO - PROCESS | 7986 | 15:39:47 INFO - PROCESS | 7986 | ------- Summary: start ------- 15:39:47 INFO - PROCESS | 7986 | Number of tests: 6 15:39:47 INFO - PROCESS | 7986 | 15:39:47 INFO - PROCESS | 7986 | [#0] dojo.html Cycles:165 Average:5383.84 Median:5621.52 stddev:630.12 (11.2%) stddev-sans-first:603.70 15:39:47 INFO - PROCESS | 7986 | Values: 3001.3 3006.4 3035.7 3037.5 3045.1 5246.5 5276.4 5341.4 5404.6 5413.8 5403.6 5420.1 5427.1 5480.8 5488.1 4872.3 4915.2 4964.4 4979.2 5085.4 4995.4 5025.4 5044.2 5050.9 5051.3 4835.7 4857.7 4898.5 4916.9 4997.0 4869.7 4871.6 4888.1 4935.0 4937.6 4897.5 4920.9 4923.8 4950.9 4972.6 5285.2 5336.6 5400.3 5413.6 5424.4 5744.2 5793.9 5802.5 5840.6 5846.1 5845.1 5881.2 5882.4 5883.1 5889.8 5270.7 5320.5 5386.6 5390.5 5452.6 5943.6 5955.1 5956.8 5958.1 5960.0 5667.0 5668.5 5719.2 5719.5 5724.4 5634.2 5711.5 5745.4 5751.1 5794.1 5732.5 5754.3 5756.0 5764.6 5795.2 5733.3 5767.1 5772.9 5807.7 5810.7 4305.9 4360.9 4362.7 4404.1 4412.6 3933.3 3975.1 3975.4 3975.5 3976.0 5391.7 5394.8 5399.4 5401.4 5401.7 5798.8 5840.6 5853.6 5863.1 5904.6 5417.7 5440.8 5450.8 5465.2 5470.8 4944.3 4980.1 5043.6 5044.3 5090.3 5990.6 6082.0 6082.8 6130.2 6141.0 5802.7 5846.0 5849.1 5852.9 5855.2 5863.4 5942.6 6078.1 6086.2 6100.1 5659.2 5708.9 5709.9 5758.0 5764.1 5807.7 5820.9 5821.0 5823.4 5828.0 5492.6 5598.3 5679.3 5694.9 5698.0 5614.7 5687.7 5695.3 5730.0 5734.0 5579.9 5621.5 5623.5 5629.2 5629.4 5517.4 5657.7 5738.2 5747.9 5753.3 5556.1 5618.4 5636.9 5663.9 5674.5 15:39:47 INFO - PROCESS | 7986 | 15:39:47 INFO - PROCESS | 7986 | [#1] ext.html Cycles:180 Average:62912.50 Median:42870.68 stddev:123497.65 (288.1%) stddev-sans-first:123755.34 15:39:47 INFO - PROCESS | 7986 | Values: 554.6 589.7 595.8 605.9 610.5 31706.0 41946.4 42514.4 42860.4 42881.0 45296.8 45632.0 45794.0 45823.8 45835.8 9144.6 9253.5 9288.6 9305.1 9305.8 9009.5 9143.6 9167.5 9172.7 9202.2 9185.3 9230.6 9247.8 9249.0 9255.7 9155.2 9197.2 9197.7 9202.5 9203.3 9023.2 9106.4 9106.8 9109.8 9112.2 42723.1 43118.0 43120.6 43126.0 43191.6 3507.2 3537.3 3709.8 3758.2 4017.9 30482.1 31425.4 33489.5 33715.0 33910.2 4436.4 4444.2 4554.7 4724.0 4774.5 380377.0 385778.0 388486.0 388640.0 389114.0 55847.0 65581.7 66490.7 66595.0 66675.7 65494.7 66214.3 66521.7 73225.6 73568.0 17464.7 17731.3 17753.6 17764.6 17766.7 12153.8 13147.3 13205.9 13224.7 13225.6 19846.9 20394.7 20518.8 20915.9 21063.0 7844.3 7933.2 7971.0 8058.9 8063.0 982.7 1018.8 1063.2 1073.4 1116.6 33437.7 33609.0 33759.7 33950.3 34601.6 1096.8 1104.7 1148.4 1187.8 1244.3 16451.4 17395.6 17553.0 17748.3 17799.9 583062.0 586816.0 587591.0 588397.0 589456.0 59982.7 60530.7 60726.0 60862.4 60932.0 1220.8 1367.7 1374.4 1379.5 1414.5 31247.2 33679.7 34811.8 34915.5 34958.8 51706.5 52452.2 52463.0 52521.5 52560.7 393923.0 395459.0 396107.0 396412.0 396813.0 29443.0 29803.6 29932.9 31383.5 31662.0 49268.0 49707.5 49852.8 49920.0 50051.5 39737.0 40230.8 40661.2 42105.8 42642.4 39766.0 39861.6 41435.8 41441.0 42419.8 42465.8 43076.4 43268.1 44964.6 45823.5 38570.9 44230.8 44259.6 44498.8 44730.0 33416.5 35780.3 35985.6 36038.0 36082.3 15:39:47 INFO - PROCESS | 7986 | 15:39:47 INFO - PROCESS | 7986 | [#2] jquery.html Cycles:180 Average:28385.39 Median:2676.48 stddev:76801.20 (2869.5%) stddev-sans-first:76987.85 15:39:47 INFO - PROCESS | 7986 | Values: 377.8 398.1 402.9 403.8 418.7 14171.6 18434.8 18718.3 18923.3 19630.3 16807.5 17232.9 17297.9 17656.7 17911.9 5594.2 5595.1 5824.9 5826.9 5829.5 5640.6 5716.1 5763.0 5771.9 5777.1 5537.5 5680.4 5743.3 5754.4 5756.5 5620.2 5706.7 5711.9 5715.5 5717.6 5003.6 5010.7 5104.4 5129.8 5131.5 23330.9 23451.9 23455.3 23538.3 23569.0 2325.2 2600.3 2752.6 3117.0 3173.9 14432.0 14535.1 14684.0 14689.7 14718.2 1871.0 1976.9 1979.1 2033.7 2061.0 80770.6 81200.2 81590.2 82929.0 83823.6 10437.8 10470.3 10610.2 10618.8 10966.2 14885.6 14900.6 14905.9 14909.9 14910.6 7947.3 8164.3 8194.1 8336.4 8684.6 7947.9 8075.0 8151.6 8344.2 8516.7 6428.8 6613.8 6621.3 6633.3 6703.7 1912.3 1920.5 1939.2 1942.3 1994.3 927.7 1012.4 1033.5 1034.1 1073.5 19538.4 20143.4 20219.6 20261.3 20336.1 1020.8 1040.6 1057.5 1066.5 1086.0 10156.7 11714.2 11828.4 11848.6 11864.2 454768.0 462158.0 463106.0 474039.0 474751.0 80126.6 80261.2 80744.0 80797.0 86269.6 972.8 974.1 977.4 982.9 989.0 16941.4 17267.7 18219.8 18257.5 18430.9 31047.1 42360.0 45263.3 45371.8 46433.8 63672.4 64107.7 64576.7 64597.0 66376.7 8304.7 8613.1 8616.3 8638.2 9098.1 12824.4 13303.5 13425.5 13628.7 13761.5 11144.3 11251.3 11374.6 11479.4 11741.4 11029.5 11216.2 11394.2 11398.4 11425.7 10588.0 11315.3 11531.8 11854.8 11876.3 8567.6 8588.0 8603.7 8625.7 9028.2 10651.7 11266.3 11359.9 11449.7 11543.9 15:39:47 INFO - PROCESS | 7986 | 15:39:47 INFO - PROCESS | 7986 | [#3] mootools.html Cycles:180 Average:5938.27 Median:2640.10 stddev:3417.53 (129.4%) stddev-sans-first:3409.94 15:39:47 INFO - PROCESS | 7986 | Values: 1378.4 1507.8 1533.7 1662.0 1780.3 8529.7 9063.1 9078.8 9386.0 9388.2 9235.2 9344.8 9365.1 9365.2 9365.6 2120.5 2338.1 2420.8 2445.9 2448.0 2443.6 2472.5 2477.8 2478.2 2481.0 2348.7 2355.5 2369.5 2369.9 2420.4 2277.7 2312.7 2322.3 2331.6 2364.9 2391.4 2403.0 2405.9 2407.3 2407.7 9245.6 9309.8 9323.3 9331.9 9347.3 5252.7 5299.1 5302.8 5443.5 5453.7 3323.6 3423.1 3508.9 3515.2 3558.1 1201.9 1245.8 1251.4 1260.2 1269.1 11872.1 11888.5 11909.5 11991.2 11997.4 4304.8 4387.6 4570.9 4575.7 4610.7 9013.3 9014.4 9018.3 9022.5 9022.6 5103.8 5106.0 5125.2 5131.7 5135.9 4371.8 4805.1 4821.9 4833.5 4947.8 3841.2 3923.2 3974.5 3983.2 3992.5 1198.2 1219.4 1220.8 1230.0 1232.9 935.4 999.5 1011.4 1018.4 1024.9 6085.5 6163.6 6176.5 6285.4 6471.2 470.9 475.1 485.9 496.9 497.1 4139.9 4191.9 4226.2 4317.7 4327.5 11304.6 11510.3 11534.3 11561.5 11596.2 11505.8 11509.3 11515.5 11518.0 11520.6 8105.5 8442.7 8453.9 8529.8 8623.1 6022.8 6138.3 6367.6 6458.1 6490.0 10502.8 10641.8 10765.6 10903.1 10919.8 10769.0 10928.3 10975.5 10982.7 11033.9 7431.7 7663.9 7765.7 7873.9 7978.1 9145.8 9216.4 9219.6 9221.6 9231.2 7837.0 7961.7 8194.5 8214.7 8242.5 7712.8 7837.5 7846.6 8054.1 8172.4 7052.3 7052.4 7052.6 7127.6 7135.0 5008.7 5092.4 5181.9 5227.8 5314.2 5308.9 5316.7 5398.3 5449.1 5553.7 15:39:47 INFO - PROCESS | 7986 | 15:39:47 INFO - PROCESS | 7986 | [#4] prototype.html Cycles:180 Average:4643.94 Median:5322.24 stddev:1338.33 (25.1%) stddev-sans-first:1302.29 15:39:47 INFO - PROCESS | 7986 | Values: 328.0 339.1 347.8 348.1 349.1 5336.9 5375.7 5388.4 5407.8 5416.9 5037.7 5085.0 5205.2 5211.8 5301.6 4121.5 4142.3 4148.3 4149.5 4162.1 4155.4 4183.6 4184.3 4186.2 4197.5 3974.6 3993.5 4144.6 4149.5 4159.6 4143.3 4151.4 4159.3 4159.6 4178.5 3725.9 3732.8 3739.5 3742.7 3756.7 5057.9 5116.8 5135.4 5201.7 5204.8 4724.4 4735.2 4746.7 4758.1 4797.9 5435.9 5451.8 5472.0 5490.4 5494.2 3883.1 4009.1 4071.5 4072.5 4090.4 5641.7 5643.7 5657.8 5673.0 5674.8 4171.6 4172.7 4179.3 4181.1 4196.6 3926.2 4132.6 4270.8 4271.6 4274.5 4685.9 4691.8 4694.6 4707.7 4719.5 5544.3 5550.6 5560.8 5624.9 5629.3 1357.8 1415.2 1419.9 1427.9 1437.7 919.2 949.6 985.0 994.8 997.2 5256.8 5299.2 5306.9 5334.4 5354.5 5583.1 5614.8 5619.3 5638.4 5661.2 5197.6 5263.6 5264.5 5278.2 5310.1 2953.9 2970.1 2971.9 2975.6 2979.3 5522.3 5732.6 5775.9 5780.2 5807.9 5673.5 5688.5 5696.7 5703.6 5707.7 5770.6 5780.0 5797.0 5805.5 5822.8 5378.7 5525.7 5586.6 5597.0 5602.4 5470.5 5477.2 5510.2 5523.0 5534.8 5492.5 5744.7 5746.1 5789.4 5820.9 4002.2 4032.3 4035.8 4052.2 4065.7 5564.8 5583.1 5587.2 5606.7 5650.3 5278.3 5403.3 5419.5 5426.9 5448.6 5377.6 5402.5 5415.5 5418.7 5435.6 5223.8 5260.6 5373.3 5385.5 5398.6 5494.5 5520.4 5554.7 5571.6 5576.5 5451.0 5456.2 5456.5 5464.3 5464.9 15:39:47 INFO - PROCESS | 7986 | 15:39:47 INFO - PROCESS | 7986 | [#5] yui.html Cycles:180 Average:22367.73 Median:4316.65 stddev:34063.41 (789.1%) stddev-sans-first:34120.34 15:39:47 INFO - PROCESS | 7986 | Values: 762.0 767.9 805.8 818.3 846.9 14273.9 14866.6 15324.2 15332.9 15657.8 13886.4 13918.1 14194.8 14203.8 14208.4 675.6 797.6 803.3 823.4 839.7 833.4 838.6 839.6 840.3 841.7 876.3 878.5 879.1 879.4 879.6 696.0 842.3 858.5 872.4 874.8 627.0 627.2 627.6 629.0 632.2 13923.6 13978.9 13990.5 13993.4 14010.4 13478.3 13952.8 14070.2 14091.7 14106.3 12282.3 12440.8 12516.9 12762.3 12899.6 4238.3 4279.3 4314.5 4318.8 4487.1 84457.0 87175.0 87778.6 87922.1 88925.1 9776.6 10865.3 10913.9 10953.2 11281.5 22665.4 22701.1 22953.8 23009.8 23056.5 8248.9 8344.6 8432.6 8551.8 8564.5 4555.4 4751.9 4777.3 4884.0 4975.2 3626.3 3666.8 3731.1 3738.0 3741.4 575.4 576.6 576.7 578.3 594.8 703.7 703.8 721.2 755.7 761.5 9239.6 9873.4 10031.3 10157.8 10188.7 670.7 733.2 758.6 765.1 780.4 4669.2 4877.5 4897.4 4916.6 4947.4 129329.4 130852.3 131135.0 132080.3 136238.3 111121.0 111551.4 112116.4 115409.0 116862.0 91589.7 95023.0 97808.5 99308.0 102783.5 8892.7 9366.0 9509.7 9580.0 9670.5 44529.3 50635.5 52492.2 53538.5 53721.0 79492.0 79551.0 79813.6 81428.2 82322.0 13608.0 13653.8 13792.3 13945.2 14048.2 13374.4 13704.3 14059.7 14060.1 14447.7 12264.3 12683.2 13007.4 13134.9 13771.0 12561.3 12703.6 12737.9 12770.7 13056.4 12640.9 12938.0 13018.9 13019.7 13320.6 10073.8 10649.6 10734.8 10917.6 10948.0 11013.6 11056.1 11205.5 11293.8 11329.7 15:39:47 INFO - PROCESS | 7986 | -------- Summary: end -------- 15:39:47 INFO - PROCESS | 7986 | 15:39:48 INFO - TEST-INFO | 7986: exit 0 15:39:48 INFO - TEST-OK | dromaeo_css | took 1115345ms 15:39:48 INFO - TEST-START | kraken 15:39:48 INFO - operating with platform_type : linux_ 15:39:48 INFO - Initialising browser for kraken test... 15:39:48 INFO - TEST-INFO | started process 8287 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpggTnw7/profile http://localhost:55611/getInfo.html) 15:39:52 INFO - PROCESS | 8287 | __metrics Screen width/height:1600/1200 15:39:52 INFO - PROCESS | 8287 | colorDepth:24 15:39:52 INFO - PROCESS | 8287 | Browser inner width/height: 1024/697 15:39:52 INFO - PROCESS | 8287 | __metrics 15:39:52 INFO - PROCESS | 8287 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:39:52 INFO - PROCESS | 8287 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:39:53 INFO - PROCESS | 8287 | console.error: PushService: 15:39:53 INFO - PROCESS | 8287 | stateChangeProcessEnqueue: Error transitioning state 15:39:53 INFO - PROCESS | 8287 | UnknownError 15:39:54 INFO - TEST-INFO | 8287: exit 0 15:39:54 INFO - Browser initialized. 15:39:54 INFO - Running cycle 1/1 for kraken test... 15:39:54 INFO - TEST-INFO | started process 8366 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpggTnw7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:05 INFO - PROCESS | 8366 | RSS: Main: 156536832 15:40:05 INFO - PROCESS | 8366 | 15:40:08 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55611/tests/kraken/driver.html?testName=audio-beat-detection) 15:40:09 INFO - PROCESS | 8366 | RSS: Main: 204349440 15:40:09 INFO - PROCESS | 8366 | 15:40:11 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55611/tests/kraken/driver.html?testName=audio-dft) 15:40:12 INFO - PROCESS | 8366 | RSS: Main: 195010560 15:40:12 INFO - PROCESS | 8366 | 15:40:15 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55611/tests/kraken/driver.html?testName=audio-fft) 15:40:16 INFO - PROCESS | 8366 | RSS: Main: 275804160 15:40:16 INFO - PROCESS | 8366 | 15:40:18 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55611/tests/kraken/driver.html?testName=audio-oscillator) 15:40:18 INFO - PROCESS | 8366 | RSS: Main: 186019840 15:40:18 INFO - PROCESS | 8366 | 15:40:21 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55611/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:40:21 INFO - PROCESS | 8366 | RSS: Main: 190939136 15:40:21 INFO - PROCESS | 8366 | 15:40:24 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55611/tests/kraken/driver.html?testName=imaging-darkroom) 15:40:25 INFO - PROCESS | 8366 | RSS: Main: 225697792 15:40:25 INFO - PROCESS | 8366 | 15:40:28 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55611/tests/kraken/driver.html?testName=imaging-desaturate) 15:40:29 INFO - PROCESS | 8366 | RSS: Main: 226054144 15:40:29 INFO - PROCESS | 8366 | 15:40:32 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55611/tests/kraken/driver.html?testName=json-parse-financial) 15:40:32 INFO - PROCESS | 8366 | RSS: Main: 226115584 15:40:32 INFO - PROCESS | 8366 | 15:40:34 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55611/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:40:34 INFO - PROCESS | 8366 | RSS: Main: 188682240 15:40:34 INFO - PROCESS | 8366 | 15:40:36 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55611/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:40:37 INFO - PROCESS | 8366 | RSS: Main: 196689920 15:40:37 INFO - PROCESS | 8366 | 15:40:39 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55611/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:40:39 INFO - PROCESS | 8366 | RSS: Main: 194138112 15:40:39 INFO - PROCESS | 8366 | 15:40:42 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55611/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:40:42 INFO - PROCESS | 8366 | RSS: Main: 187441152 15:40:42 INFO - PROCESS | 8366 | 15:40:45 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55611/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:40:46 INFO - PROCESS | 8366 | RSS: Main: 192065536 15:40:46 INFO - PROCESS | 8366 | 15:40:47 INFO - PROCESS | 8366 | Cycle 1(1): loaded http://localhost:55611/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55611/tests/kraken/driver.html?testName=ai-astar) 15:40:48 INFO - PROCESS | 8366 | RSS: Main: 242778112 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | __start_tp_report 15:40:48 INFO - PROCESS | 8366 | _x_x_mozilla_page_load 15:40:48 INFO - PROCESS | 8366 | _x_x_mozilla_page_load_details 15:40:48 INFO - PROCESS | 8366 | |i|pagename|runs| 15:40:48 INFO - PROCESS | 8366 | |0;ai-astar;105;110;111;110;106;111;113;111;112;112 15:40:48 INFO - PROCESS | 8366 | |1;audio-beat-detection;153;117;121;175;122;118;140;121;161;120 15:40:48 INFO - PROCESS | 8366 | |2;audio-dft;161;162;162;162;160;160;161;161;162;160 15:40:48 INFO - PROCESS | 8366 | |3;audio-fft;75;75;74;118;75;78;75;73;101;76 15:40:48 INFO - PROCESS | 8366 | |4;audio-oscillator;85;86;111;85;85;86;114;84;86;137 15:40:48 INFO - PROCESS | 8366 | |5;imaging-gaussian-blur;117;117;116;117;116;116;118;117;117;117 15:40:48 INFO - PROCESS | 8366 | |6;imaging-darkroom;145;146;144;147;147;146;145;146;145;146 15:40:48 INFO - PROCESS | 8366 | |7;imaging-desaturate;95;95;95;94;94;95;95;94;94;95 15:40:48 INFO - PROCESS | 8366 | |8;json-parse-financial;52;50;51;50;50;52;49;49;51;49 15:40:48 INFO - PROCESS | 8366 | |9;json-stringify-tinderbox;62;63;62;63;62;62;63;62;62;63 15:40:48 INFO - PROCESS | 8366 | |10;stanford-crypto-aes;79;77;79;83;78;88;80;83;79;79 15:40:48 INFO - PROCESS | 8366 | |11;stanford-crypto-ccm;126;122;121;125;121;123;130;124;140;120 15:40:48 INFO - PROCESS | 8366 | |12;stanford-crypto-pbkdf2;162;164;164;164;163;161;166;162;162;166 15:40:48 INFO - PROCESS | 8366 | |13;stanford-crypto-sha256-iterative;64;63;65;65;65;64;63;64;64;67 15:40:48 INFO - PROCESS | 8366 | __end_tp_report 15:40:48 INFO - PROCESS | 8366 | __start_cc_report 15:40:48 INFO - PROCESS | 8366 | _x_x_mozilla_cycle_collect,1655 15:40:48 INFO - PROCESS | 8366 | __end_cc_report 15:40:48 INFO - PROCESS | 8366 | __startTimestamp1471560048219__endTimestamp 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | ------- Summary: start ------- 15:40:48 INFO - PROCESS | 8366 | Number of tests: 14 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:2.60 (2.3%) stddev-sans-first:2.00 15:40:48 INFO - PROCESS | 8366 | Values: 105.0 110.0 111.0 110.0 106.0 111.0 113.0 111.0 112.0 112.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:131.00 stddev:21.14 (16.1%) stddev-sans-first:21.38 15:40:48 INFO - PROCESS | 8366 | Values: 153.0 117.0 121.0 175.0 122.0 118.0 140.0 121.0 161.0 120.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:0.88 (0.5%) stddev-sans-first:0.93 15:40:48 INFO - PROCESS | 8366 | Values: 161.0 162.0 162.0 162.0 160.0 160.0 161.0 161.0 162.0 160.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:15.09 (20.1%) stddev-sans-first:15.79 15:40:48 INFO - PROCESS | 8366 | Values: 75.0 75.0 74.0 118.0 75.0 78.0 75.0 73.0 101.0 76.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:85.00 stddev:18.37 (21.6%) stddev-sans-first:19.06 15:40:48 INFO - PROCESS | 8366 | Values: 85.0 86.0 111.0 85.0 85.0 86.0 114.0 84.0 86.0 137.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 15:40:48 INFO - PROCESS | 8366 | Values: 117.0 117.0 116.0 117.0 116.0 116.0 118.0 117.0 117.0 117.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 15:40:48 INFO - PROCESS | 8366 | Values: 145.0 146.0 144.0 147.0 147.0 146.0 145.0 146.0 145.0 146.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 15:40:48 INFO - PROCESS | 8366 | Values: 95.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 94.0 95.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:1.16 (2.3%) stddev-sans-first:1.05 15:40:48 INFO - PROCESS | 8366 | Values: 52.0 50.0 51.0 50.0 50.0 52.0 49.0 49.0 51.0 49.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#9] json-stringify-tinderbox Cycles:10 Average:62.40 Median:62.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 15:40:48 INFO - PROCESS | 8366 | Values: 62.0 63.0 62.0 63.0 62.0 62.0 63.0 62.0 62.0 63.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:79.50 stddev:3.27 (4.1%) stddev-sans-first:3.43 15:40:48 INFO - PROCESS | 8366 | Values: 79.0 77.0 79.0 83.0 78.0 88.0 80.0 83.0 79.0 79.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:124.50 stddev:5.98 (4.8%) stddev-sans-first:6.33 15:40:48 INFO - PROCESS | 8366 | Values: 126.0 122.0 121.0 125.0 121.0 123.0 130.0 124.0 140.0 120.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.40 Median:164.00 stddev:1.71 (1.0%) stddev-sans-first:1.74 15:40:48 INFO - PROCESS | 8366 | Values: 162.0 164.0 164.0 164.0 163.0 161.0 166.0 162.0 162.0 166.0 15:40:48 INFO - PROCESS | 8366 | 15:40:48 INFO - PROCESS | 8366 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.50 stddev:1.17 (1.8%) stddev-sans-first:1.24 15:40:48 INFO - PROCESS | 8366 | Values: 64.0 63.0 65.0 65.0 65.0 64.0 63.0 64.0 64.0 67.0 15:40:48 INFO - PROCESS | 8366 | -------- Summary: end -------- 15:40:48 INFO - PROCESS | 8366 | 15:40:49 INFO - TEST-INFO | 8366: exit 0 15:40:49 INFO - TEST-OK | kraken | took 61010ms 15:40:49 INFO - SUITE-END | took 1176s 15:40:49 INFO - Completed test suite (00:19:36) 15:40:49 INFO - javascript benchmark 15:40:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3001.2946957465006, 3006.3686625808577, 3035.681253468385, 3037.544368344748, 3045.101694999975, 5246.475352464751, 5276.445973172815, 5341.359036915565, 5404.567634589233, 5413.83758487245, 5403.594730395226, 5420.13277875539, 5427.0773968425365, 5480.780768769255, 5488.1493368527945, 4872.269159626049, 4915.213565829468, 4964.429090654567, 4979.153543897528, 5085.440601533847, 4995.35060442142, 5025.3718285214345, 5044.218146187347, 5050.873728156807, 5051.3180720602795, 4835.685680430796, 4857.684250523723, 4898.485659005789, 4916.926246106311, 4996.950030499703, 4869.732164730938, 4871.610271178332, 4888.120138375091, 4934.975325123387, 4937.580305674022, 4897.51024897511, 4920.876978075541, 4923.753812309406, 4950.87622809429, 4972.602191824645, 5285.154375299933, 5336.599755018389, 5400.270963419927, 5413.648112872651, 5424.376196737387, 5744.22452968848, 5793.942060579382, 5802.477777000048, 5840.620359676607, 5846.064629659234, 5845.06478963368, 5881.2354393928945, 5882.441168089024, 5883.0881213411985, 5889.823305300848, 5270.736463176864, 5320.52115309624, 5386.622936394415, 5390.54180394663, 5452.645578037414, 5943.613665111754, 5955.13650520672, 5956.821295361146, 5958.136070269787, 5960.016597261403, 5667.0, 5668.489835914787, 5719.170720245541, 5719.513841323451, 5724.427557244242, 5634.211210430461, 5711.485966263056, 5745.396733343023, 5751.079827227623, 5794.1308803679785, 5732.541396688255, 5754.280714910637, 5755.971220143872, 5764.596478246483, 5795.217645617788, 5733.31200255972, 5767.077267637242, 5772.94227057724, 5807.680577568274, 5810.6513609183585, 4305.935410968838, 4360.912781744348, 4362.738235705804, 4404.053128577371, 4412.602865742098, 3933.272344616255, 3975.1453437511072, 3975.403689446606, 3975.4633124528395, 3976.045749020272, 5391.703456309862, 5394.838154855361, 5399.406065332811, 5401.405845357008, 5401.675899446046, 5798.797042103506, 5840.561957853178, 5853.619514731528, 5863.120531920246, 5904.5571582131515, 5417.702026388586, 5440.7551660174595, 5450.809221677301, 5465.234867118606, 5470.781168753285, 4944.307796908436, 4980.05378977993, 5043.571296439771, 5044.2685810558, 5090.26191202281, 5990.610610310316, 6082.026875699867, 6082.817515474543, 6130.1724267224245, 6141.048137538599, 5802.6808525531505, 5845.976954032943, 5849.1226316052935, 5852.912206316823, 5855.180274761622, 5863.413658634273, 5942.61373010753, 6078.057901025348, 6086.239220097488, 6100.145979562776, 5659.236003139688, 5708.885822283448, 5709.914351284817, 5758.0, 5764.106563482667, 5807.65154090739, 5820.912686309624, 5820.970895145497, 5823.388544202798, 5827.9708601458415, 5492.615516913777, 5598.300212473441, 5679.261695979496, 5694.914576281442, 5697.974364614243, 5614.69119198448, 5687.658740475585, 5695.345035320825, 5729.9713501432225, 5734.0, 5579.944200558105, 5621.5221706155435, 5623.465770751772, 5629.240052592848, 5629.380768115422, 5517.448255174611, 5657.660540367591, 5738.225339579103, 5747.942520574741, 5753.309602847685, 5556.111022236423, 5618.381977982337, 5636.915446268227, 5663.858403539944, 5674.546036317167], "value": 5339.335952396814, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.5725046708646, 589.6697849204443, 595.8391234366721, 605.9030555111183, 610.5237914426747, 31706.0, 41946.37080443792, 42514.36228456576, 42860.35709464353, 42881.0, 45296.77351613245, 45632.0, 45794.0, 45823.770881145545, 45835.77082114593, 9144.634214631407, 9253.537323133816, 9288.58201380936, 9305.11601397866, 9305.767355816091, 9009.459432434074, 9143.588538515749, 9167.495787731705, 9172.724818255463, 9202.217811486033, 9185.265178785681, 9230.584623691917, 9247.768805779842, 9248.953755231249, 9255.676051338205, 9155.176034156924, 9197.21823644991, 9197.678081267124, 9202.539873006323, 9203.309751768644, 9023.233025192867, 9106.362554621179, 9106.81786364269, 9109.81780364389, 9112.179903808688, 42723.14553708908, 43118.0, 43120.56879431228, 43126.0, 43191.56808431907, 3507.2284097498764, 3537.3102245062223, 3709.7774133552075, 3758.229562939618, 4017.9215212408003, 30482.06070838101, 31425.37149257002, 33489.54381580041, 33715.0, 33910.15224619379, 4436.423264975565, 4444.20004399208, 4554.658400619934, 4724.031573527453, 4774.546418090275, 380377.0, 385778.0, 388485.99999999715, 388640.0, 389114.0, 55847.0, 65581.6720916402, 66490.66754666196, 66595.0, 66675.66662166755, 65494.67252663706, 66214.33785662179, 66521.6673916637, 73225.63387183136, 73568.0, 17464.65070698579, 17731.29074837018, 17753.556161096076, 17764.644707105785, 17766.733498997266, 12153.756924861453, 13147.276899770604, 13205.867941320656, 13224.669383265495, 13225.603231903058, 19846.90076549608, 20394.69407958882, 20518.794812051987, 20915.8954205228, 21063.0, 7844.294013538694, 7933.167017463199, 7970.960145199237, 8058.919410805935, 8063.0245589830865, 982.729749318943, 1018.8420794776821, 1063.2397835547465, 1073.4257172412722, 1116.6203490813164, 33437.665623343455, 33609.0, 33759.66240337614, 33950.32099357999, 34601.61593536281, 1096.7719441552858, 1104.6741211342512, 1148.4028305281374, 1187.7683851648846, 1244.3094082783962, 16451.42420015318, 17395.56511087233, 17553.0, 17748.29006839712, 17799.911000445172, 583062.0, 586816.0, 587591.0, 588397.0, 589456.0, 59982.70008649929, 60530.697346513865, 60726.0, 60862.391376086554, 60932.0, 1220.7924652809045, 1367.6922692394132, 1374.4021350712471, 1379.4551152294787, 1414.5485016559767, 31247.218819529695, 33679.66320336765, 34811.825940870134, 34915.4762678555, 34958.82520587381, 51706.48293517167, 52452.213216801014, 52463.0, 52521.47478525319, 52560.73719631377, 393923.0, 395459.0, 396107.0, 396412.0, 396813.0, 29443.0, 29803.55294670625, 29932.85033574905, 31383.529247060855, 31662.0, 49268.0, 49707.50292497173, 49852.75073624609, 49920.0, 50051.499485004686, 39737.0, 40230.798846005586, 40661.1867762649, 42105.78947105245, 42642.36036459517, 39766.0, 39861.60138398579, 41435.7928210357, 41440.96476953938, 42419.7879010603, 42465.787671061444, 43076.35385469158, 43268.13463730771, 44964.55035449604, 45823.541764581925, 38570.925835055205, 44230.77884610556, 44259.55740442683, 44498.77750611226, 44730.0, 33416.49875251922, 35780.28439431249, 35985.640143599274, 36038.0, 36082.27835443329], "value": 21081.67489694664, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.7733359984008, 398.1102236501421, 402.875108716298, 403.84653831544, 418.6734347209176, 14171.574852754407, 18434.815651843495, 18718.251269949204, 18923.337683181093, 19630.312939047137, 16807.495775126736, 17232.913835430805, 17297.913510432463, 17656.735148972744, 17911.910440447813, 5594.216809646653, 5595.076812325961, 5824.941750582503, 5826.883462330751, 5829.533637309027, 5640.605157638967, 5716.114002329646, 5763.0, 5771.884562308751, 5777.104548794943, 5537.473939975695, 5680.4319568043275, 5743.253377060976, 5754.424557544233, 5756.453136951983, 5620.213170156161, 5706.743196556165, 5711.885762284731, 5715.514181294595, 5717.599768016241, 5003.624728145367, 5010.749462526859, 5104.3619547556555, 5129.769160387791, 5131.486851314876, 23330.883345583163, 23451.88274058636, 23455.296341109795, 23538.29385118449, 23569.0, 2325.2210509479346, 2600.310917606817, 2752.6421565196397, 3116.9844150779327, 3173.8571764270664, 14432.0, 14535.127892326494, 14684.0, 14689.70620587593, 14718.190499522521, 1871.0, 1976.8912709800954, 1979.1192919150983, 2033.6542787725973, 2060.9690854637192, 80770.59614701947, 81200.18799811986, 81590.18409815885, 82929.0, 83823.5808820958, 10437.791244175225, 10470.319429237228, 10610.20423468243, 10618.840717389245, 10966.177536684781, 14885.627859303604, 14900.552983410515, 14905.850941490664, 14909.850901491063, 14910.552683419515, 7947.284744373034, 8164.265216130577, 8194.09864914859, 8336.374771892133, 8684.60919258635, 7947.880781788278, 8075.0, 8151.633176507072, 8344.207300306462, 8516.70191543293, 6428.77499287532, 6613.834654133685, 6621.304762999912, 6633.27239125804, 6703.698333575001, 1912.2828939147819, 1920.4910698664864, 1939.1758502636324, 1942.3298961858136, 1994.321930543622, 927.6521304510809, 1012.4431562640518, 1033.4574348467115, 1034.1158309645296, 1073.4847273308856, 19538.41384758431, 20143.39569812908, 20219.595608087755, 20261.29085482001, 20336.08487618061, 1020.8417695257247, 1040.5629635553087, 1057.4554104636118, 1066.4934156275863, 1086.0, 10156.746081348025, 11714.23857449263, 11828.40857957116, 11848.585299514476, 11864.169508134348, 454768.0, 462158.0, 463106.0, 474039.0, 474751.0, 80126.5993670028, 80261.19738802654, 80744.0, 80797.0, 86269.5686521576, 972.7957129002989, 974.0697633759884, 977.4428575711918, 982.9311948163703, 989.0, 16941.37408701653, 17267.740983885502, 18219.817801821813, 18257.543561411072, 18430.907845460686, 31047.068587942398, 42360.0, 45263.321050183615, 45371.77314113409, 46433.76783116063, 63672.363276368495, 64107.67946160239, 64576.67711661412, 64597.0, 66376.6681166591, 8304.70933517324, 8613.052564217805, 8616.310695144248, 8638.222559969632, 9098.090190980849, 12824.358782061046, 13303.534376296782, 13425.462981480632, 13628.727425451634, 13761.518346858213, 11144.33134011962, 11251.324920504796, 11374.601888933848, 11479.36863472517, 11741.41292935367, 11029.503672334924, 11216.158788091088, 11394.202405831513, 11398.435352045723, 11425.657230283105, 10588.0, 11315.321080735182, 11531.827022594836, 11854.822177667169, 11876.346800926032, 8567.61445734931, 8587.95706021466, 8603.698870539502, 8625.654973801229, 9028.232600228792, 10651.680449586525, 11266.324020558794, 11359.886401136213, 11449.713757156138, 11543.884561154617], "value": 9402.272396803424, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.3659516622354, 1507.7889095526625, 1533.7162624914392, 1661.9916900415496, 1780.3234770787108, 8529.658813647455, 9063.139001794836, 9078.773030674229, 9386.014468480813, 9388.155066044072, 9235.21500672442, 9344.813103737939, 9365.063493650632, 9365.203957663607, 9365.578548965295, 2120.4804822818355, 2338.0998315648426, 2420.8305418620657, 2445.938851528717, 2448.045262347686, 2443.5723748344058, 2472.49313890652, 2477.7770000699934, 2478.157426474998, 2481.0447977528693, 2348.7181538215355, 2355.5171189906114, 2369.455025344172, 2369.9052037918464, 2420.4311986683115, 2277.681124642535, 2312.710911136108, 2322.3381336319153, 2331.6152834782242, 2364.881755912215, 2391.390195500154, 2403.0, 2405.927822165338, 2407.337982054931, 2407.735149133594, 9245.583948722257, 9309.813803723955, 9323.300752443525, 9331.90668093324, 9347.252219822398, 5252.737363131866, 5299.072662284123, 5302.814401495929, 5443.455654434525, 5453.727313634342, 3323.6344002159744, 3423.1271025888486, 3508.8771892983623, 3515.2442224921765, 3558.1104723819044, 1201.915865889388, 1245.819356193356, 1251.4431078170219, 1260.2312589320507, 1269.1433282534254, 11872.050235981102, 11888.465019074163, 11909.464074116599, 11991.22057066288, 11997.400129993553, 4304.849330273488, 4387.605115539617, 4570.908581828411, 4575.7483338416705, 4610.723356598548, 9013.324000699973, 9014.414063085878, 9018.323625728097, 9022.503762293005, 9022.639094436281, 5103.795848166106, 5106.029854327666, 5125.231215317657, 5131.7434128293435, 5135.948640513622, 4371.775902747236, 4805.063012712558, 4821.927671084936, 4833.492483289274, 4947.777350019258, 3841.2125514269505, 3923.2349691809823, 3974.4833171687496, 3983.203359328146, 3992.48097747291, 1198.2151690642643, 1219.4207751318231, 1220.8473940757406, 1229.9631011069682, 1232.89520390766, 935.3686261773275, 999.5352161245056, 1011.3881101933224, 1018.3686114608842, 1024.8565200871883, 6085.482733967574, 6163.599366041282, 6176.5234226861485, 6285.402886725845, 6471.158749362553, 470.90817290627996, 475.139996606144, 485.922252439608, 496.8782648251129, 497.0729589315972, 4139.917201656011, 4191.895202619959, 4226.154769046141, 4317.70468859341, 4327.5239723630375, 11304.604338848101, 11510.30938143714, 11534.250273732181, 11561.479733412016, 11596.246243994114, 11505.827412588818, 11509.25189862673, 11515.53937842477, 11518.0, 11520.596779112691, 8105.473144245605, 8442.746717598482, 8453.915460845314, 8529.82940341202, 8623.094575069777, 6022.789202378071, 6138.278584949473, 6367.61794292344, 6458.128152699325, 6489.994050922114, 10502.842457362993, 10641.787164256826, 10765.623203188165, 10903.127749780157, 10919.781604368027, 10769.0, 10928.344299342065, 10975.50610222558, 10982.725431863948, 11033.944830276118, 7431.739889103856, 7663.923360766321, 7765.72819951299, 7873.921260787319, 7978.0825205102155, 9145.77135571616, 9216.400933939272, 9219.631214751334, 9221.585028673591, 9231.215346695337, 7836.960815195887, 7961.681532738858, 8194.50832950025, 8214.712485062993, 8242.464239824392, 7712.829260552375, 7837.529748215125, 7846.568438735924, 8054.114047454658, 8172.387070969582, 7052.259512751292, 7052.365287124184, 7052.576845389293, 7127.6457473079945, 7135.0, 5008.749562521786, 5092.439831618593, 5181.896362072813, 5227.817026404057, 5314.202869569595, 5308.920366194588, 5316.680999140064, 5398.271233383443, 5449.128139497661, 5553.694546799965], "value": 4665.24090388679, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.0208918067995, 339.123366098635, 347.83130181861793, 348.0532950374979, 349.08713713638844, 5336.866578335544, 5375.6774593524415, 5388.40727519973, 5407.756650950707, 5416.8916621667595, 5037.697738135706, 5085.008423357447, 5205.245239440279, 5211.843644690665, 5301.561281810372, 4121.505419349674, 4142.295809712341, 4148.315527937887, 4149.481314835645, 4162.105147393309, 4155.376693495969, 4183.602557757008, 4184.267753143203, 4186.225548273579, 4197.4753155855515, 3974.56915510416, 3993.5407428145845, 4144.626983571478, 4149.5228048774325, 4159.563245859186, 4143.316352801814, 4151.377293405983, 4159.272127377697, 4159.563245859172, 4178.540360560336, 3725.9068523286865, 3732.7760334379755, 3739.5325584301963, 3742.7380083394173, 3756.6619004289473, 5057.9494205057845, 5116.797552575142, 5135.435102138762, 5201.713905735183, 5204.817831375922, 4724.385829842098, 4735.195016847144, 4746.691465054795, 4758.143534163849, 4797.880052998668, 5435.918461223085, 5451.792024230193, 5471.972640136773, 5490.423505531901, 5494.1758736189895, 3883.1262965832743, 4009.0979529605897, 4071.5114186297833, 4072.5316588592395, 4090.386442033689, 5641.661500309994, 5643.661380317194, 5657.773689052433, 5673.0, 5674.829755107312, 4171.561985991488, 4172.666186705056, 4179.26862799009, 4181.142865712522, 4196.643285320782, 3926.2147570486018, 4132.628063474302, 4270.829166833354, 4271.594198551133, 4274.529801721808, 4685.8594242172785, 4691.82704323919, 4694.554017368345, 4707.728913193487, 4719.480857105716, 5544.3346798384455, 5550.639208451437, 5560.777568897199, 5624.91562626561, 5629.3112066380745, 1357.8234829472106, 1415.2216281045385, 1419.9077059991273, 1427.928603569838, 1437.73401920645, 919.1543779722671, 949.6296444386695, 984.9753756156153, 994.8159590475785, 997.2022382094441, 5256.816011439581, 5299.178627312773, 5306.92039619406, 5334.359876814807, 5354.491323324278, 5583.078791999355, 5614.803481878114, 5619.291267763257, 5638.379778224392, 5661.1508273758445, 5197.58419326453, 5263.578913686896, 5264.52619264268, 5278.155495120763, 5310.123829568155, 2953.896613618513, 2970.0792754246254, 2971.925701857471, 2975.5834183214365, 2979.3147576057195, 5522.2544956431175, 5732.649613658464, 5775.88448231033, 5780.1907732917425, 5807.912881306784, 5673.461021202979, 5688.544916406678, 5696.686682232517, 5703.600747947604, 5707.71461426927, 5770.596058275964, 5779.971100144557, 5796.971015145066, 5805.477507024388, 5822.777216784438, 5378.704171270618, 5525.696086715269, 5586.581006424453, 5597.0, 5602.411746766531, 5470.480304371078, 5477.1510415885605, 5510.228568000403, 5522.972385138049, 5534.806281780118, 5492.505674489315, 5744.741486633028, 5746.13807928814, 5789.363170051207, 5820.912686309794, 4002.219567184371, 4032.2540330039037, 4035.838566457426, 4052.169305292467, 4065.715399921977, 5564.8330550083565, 5583.134614134816, 5587.189857470726, 5606.6636001840025, 5650.3219613646625, 5278.2610434539965, 5403.270558474713, 5419.512243898068, 5426.918596220981, 5448.618596698193, 5377.623566350474, 5402.540784033401, 5415.48552887475, 5418.674879507243, 5435.565154787687, 5223.764930578057, 5260.605454590997, 5373.328333958255, 5385.518982279905, 5398.568114550905, 5494.461550765792, 5520.420355862577, 5554.722263886709, 5571.637843540157, 5576.525995290446, 5451.0, 5456.181572764117, 5456.53619442336, 5464.28964234647, 5464.863378415572], "value": 4221.2586011934745, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.9657115429806, 767.9385649148072, 805.7824387415397, 818.333058557276, 846.9153084691528, 14273.857261427383, 14866.628334291649, 15324.233788310596, 15332.92333538332, 15657.765133523006, 13886.375113119908, 13918.095323803946, 14194.787078193835, 14203.78694319586, 14208.431662733505, 675.6047712088434, 797.5732982854174, 803.3091541274499, 823.4112609484217, 839.6935118681668, 833.4041160570191, 838.6226198210799, 839.6389552492426, 840.2815592668272, 841.7306461932184, 876.3471213945619, 878.4992554244084, 879.0769691823591, 879.3888247667874, 879.6393478673745, 696.0186137546061, 842.2588122452264, 858.5406807358033, 872.3631748823362, 874.7681864305932, 626.959247648906, 627.2473032361185, 627.595201095291, 628.9842753931142, 632.2255237334275, 13923.58229253124, 13978.930105349407, 13990.510332138429, 13993.440262389595, 14010.369533371022, 13478.258695771729, 13952.790708139386, 14070.22613756243, 14091.71816563663, 14106.294685265799, 12282.263064216175, 12440.75118497625, 12516.937415312865, 12762.298073605947, 12899.613011609667, 4238.279492486285, 4279.315309550489, 4314.460692413449, 4318.848840290606, 4487.147441986012, 84457.0, 87175.0, 87778.56110719405, 87922.12077879203, 88925.11074889233, 9776.560054797552, 10865.293755905841, 10913.945430272797, 10953.17851161166, 11281.548738050386, 22665.4333641657, 22701.09195632156, 22953.7704622955, 23009.769902301097, 23056.53886922252, 8248.876266855881, 8344.582770861432, 8432.620532076071, 8551.828963420698, 8564.486130832045, 4555.385023021916, 4751.881202969954, 4777.331173635694, 4884.0476107159475, 4975.203967365204, 3626.256617393428, 3666.7799932004164, 3731.141837377364, 3737.9626203738158, 3741.43878418234, 575.3556017260695, 576.6165499942575, 576.7145263094795, 578.2800413485154, 594.8334466349429, 703.7044441334651, 703.8240439890028, 721.1706537481938, 755.6712829918893, 761.5392687424139, 9239.630414783467, 9873.358231714916, 10031.347962382568, 10157.847632285522, 10188.694339169837, 670.7149461478851, 733.2301083861923, 758.635854789704, 765.0551568812513, 780.4302858913026, 4669.229577119737, 4877.487863774252, 4897.363342765418, 4916.631252656065, 4947.431045429822, 129329.35335323263, 130852.3457382707, 131135.0, 132080.3395983014, 136238.31880840732, 111121.0, 111551.44224278827, 112116.43941780402, 115409.0, 116862.0, 91589.71949067348, 95023.0, 97808.51095744476, 99308.0, 102783.4860825706, 8892.73321800347, 9366.0, 9509.707140506789, 9579.952100239454, 9670.516474176404, 44529.33206001978, 50635.493645064555, 52492.21261681154, 53538.46461535335, 53721.0, 79492.0, 79551.0, 79813.60093199497, 81428.18571814206, 82322.0, 13608.0, 13653.79519307231, 13792.310384480936, 13945.233012184428, 14048.227347495986, 13374.398152082986, 13704.314784260947, 14059.718805624036, 14060.08609440383, 14447.711045779235, 12264.32546209967, 12683.175593586388, 13007.414666340226, 13134.868651313365, 13771.0, 12561.309127998047, 12703.618891433272, 12737.93631031839, 12770.744585108432, 13056.412461439068, 12640.87359126397, 12938.0, 13018.869811302146, 13019.739605208033, 13320.600381988555, 10073.798524029626, 10649.574017039231, 10734.785304294026, 10917.617883374043, 10947.993050473571, 11013.559457621606, 11056.093341858776, 11205.495752690986, 11293.830592541282, 11329.660110196708], "value": 7678.175596741212, "unit": "score"}], "name": "dromaeo_css", "value": 7368.420111972513, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 110.0, 111.0, 110.0, 106.0, 111.0, 113.0, 111.0, 112.0, 112.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 117.0, 121.0, 175.0, 122.0, 118.0, 140.0, 121.0, 161.0, 120.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 162.0, 160.0, 160.0, 161.0, 161.0, 162.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 118.0, 75.0, 78.0, 75.0, 73.0, 101.0, 76.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 111.0, 85.0, 85.0, 86.0, 114.0, 84.0, 86.0, 137.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 118.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 144.0, 147.0, 147.0, 146.0, 145.0, 146.0, 145.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 51.0, 50.0, 50.0, 52.0, 49.0, 49.0, 51.0, 49.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 63.0, 62.0, 62.0, 63.0, 62.0, 62.0, 63.0], "value": 62.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 79.0, 83.0, 78.0, 88.0, 80.0, 83.0, 79.0, 79.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 122.0, 121.0, 125.0, 121.0, 123.0, 130.0, 124.0, 140.0, 120.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 164.0, 164.0, 163.0, 161.0, 166.0, 162.0, 162.0, 166.0], "value": 163.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 65.0, 65.0, 64.0, 63.0, 64.0, 64.0, 67.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1487.2, "alertThreshold": 2.0}]} 15:40:49 INFO - Return code: 0 15:40:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:40:49 INFO - # TBPL SUCCESS # 15:40:49 INFO - Running post-action listener: _resource_record_post_action 15:40:49 INFO - [mozharness: 2016-08-18 22:40:49.517205Z] Finished run-tests step (success) 15:40:49 INFO - Running post-run listener: _resource_record_post_run 15:40:49 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 0; Write bytes: 408494080; Read time: 0; Write time: 244784 15:40:49 INFO - TinderboxPrint: CPU usage
12.9% 15:40:49 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:40:49 INFO - TinderboxPrint: I/O write bytes / time
408,494,080 / 244,784 15:40:49 INFO - TinderboxPrint: CPU idle
8,295.1 (87.0%) 15:40:49 INFO - TinderboxPrint: CPU user
1,188.4 (12.5%) 15:40:49 INFO - TinderboxPrint: Swap in / out
0 / 0 15:40:49 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 118308864; Read time: 0; Write time: 79296 15:40:49 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 289820672; Read time: 0; Write time: 165484 15:40:50 INFO - Running post-run listener: _upload_blobber_files 15:40:50 INFO - Blob upload gear active. 15:40:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:40:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:40:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:40:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:40:50 INFO - (blobuploader) - INFO - Open directory for files ... 15:40:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:40:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:40:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:51 INFO - (blobuploader) - INFO - Done attempting. 15:40:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:40:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:40:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:55 INFO - (blobuploader) - INFO - Done attempting. 15:40:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:40:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:40:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:40:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:40:56 INFO - (blobuploader) - INFO - Done attempting. 15:40:56 INFO - (blobuploader) - INFO - Iteration through files over. 15:40:56 INFO - Return code: 0 15:40:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:40:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:40:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69c4e23d3c231111f350a30f5db61c7245a5aa7c9b3c4c7ae9352990869f1559cd79243bfc842ac66951cd07d898ee9514571d8a3cef86f4cc59bcf7a304189f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e8b258af766425d84a5bc37a26e0be2f1e0b85ca9e1c4a5c883b81d9dcb1f7bff1073acf91772b79e234273599a9830914ee43a526358bed3005036dde4362d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5031f71c90124fea31683b849395a70bccebcbcf924a4af56cb6fc98a9182ffb5edbd2b0c7f9930f2c9bd65c57c2a7af3f536bbba7fb9a2bd88ca12565f0c73b"} 15:40:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:40:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:40:56 INFO - Contents: 15:40:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69c4e23d3c231111f350a30f5db61c7245a5aa7c9b3c4c7ae9352990869f1559cd79243bfc842ac66951cd07d898ee9514571d8a3cef86f4cc59bcf7a304189f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e8b258af766425d84a5bc37a26e0be2f1e0b85ca9e1c4a5c883b81d9dcb1f7bff1073acf91772b79e234273599a9830914ee43a526358bed3005036dde4362d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5031f71c90124fea31683b849395a70bccebcbcf924a4af56cb6fc98a9182ffb5edbd2b0c7f9930f2c9bd65c57c2a7af3f536bbba7fb9a2bd88ca12565f0c73b"} 15:40:56 INFO - Running post-run listener: copy_logs_to_upload_dir 15:40:56 INFO - Copying logs to upload dir... 15:40:56 INFO - mkdir: /builds/slave/test/build/upload/logs 15:40:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.807040 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-18 15:40:56.188137) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 15:40:56.192022) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/69c4e23d3c231111f350a30f5db61c7245a5aa7c9b3c4c7ae9352990869f1559cd79243bfc842ac66951cd07d898ee9514571d8a3cef86f4cc59bcf7a304189f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e8b258af766425d84a5bc37a26e0be2f1e0b85ca9e1c4a5c883b81d9dcb1f7bff1073acf91772b79e234273599a9830914ee43a526358bed3005036dde4362d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5031f71c90124fea31683b849395a70bccebcbcf924a4af56cb6fc98a9182ffb5edbd2b0c7f9930f2c9bd65c57c2a7af3f536bbba7fb9a2bd88ca12565f0c73b"} build_url:https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/HLCtasf4T2-xn2D3X_mf3g/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/sha512/69c4e23d3c231111f350a30f5db61c7245a5aa7c9b3c4c7ae9352990869f1559cd79243bfc842ac66951cd07d898ee9514571d8a3cef86f4cc59bcf7a304189f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e8b258af766425d84a5bc37a26e0be2f1e0b85ca9e1c4a5c883b81d9dcb1f7bff1073acf91772b79e234273599a9830914ee43a526358bed3005036dde4362d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5031f71c90124fea31683b849395a70bccebcbcf924a4af56cb6fc98a9182ffb5edbd2b0c7f9930f2c9bd65c57c2a7af3f536bbba7fb9a2bd88ca12565f0c73b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 15:40:56.216806) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 15:40:56.217099) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1471558815.906293-1032967532 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004621 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 15:40:56.299550) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 15:40:56.299895) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 15:40:56.300183) ========= ========= Total master_lag: 0.17 =========