builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-055 starttime: 1470690323.93 results: success (0) buildid: 20160808124426 builduid: 5f6f3baf0c084f47b1058b99a93ba245 revision: 7d0cb37025ded808b8e53c874d29d772cd7c54a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:23.926667) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:23.927216) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:23.927557) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:23.951875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:23.952224) ========= 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=bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-08 14:05:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-08-08 14:05:24 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101640 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:24.073927) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:24.074539) ========= 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=bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021525 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:24.131469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:24.131854) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 7d0cb37025ded808b8e53c874d29d772cd7c54a2 --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 7d0cb37025ded808b8e53c874d29d772cd7c54a2 --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=bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-08 14:05:24,535 truncating revision to first 12 chars 2016-08-08 14:05:24,535 Setting DEBUG logging. 2016-08-08 14:05:24,535 attempt 1/10 2016-08-08 14:05:24,535 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/7d0cb37025de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 14:05:24,838 unpacking tar archive at: autoland-7d0cb37025de/testing/mozharness/ program finished with exit code 0 elapsedTime=0.670758 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:24.817623) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:24.818032) ========= script_repo_revision: 7d0cb37025ded808b8e53c874d29d772cd7c54a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:24.818628) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:24.819004) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 14:05:24.830923) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-08-08 14:05:24.831434) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:05:25 INFO - MultiFileLogger online at 20160808 14:05:25 in /builds/slave/test 14:05:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 14:05:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:05:25 INFO - {'append_to_log': False, 14:05:25 INFO - 'base_work_dir': '/builds/slave/test', 14:05:25 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 14:05:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:05:25 INFO - 'branch': 'Autoland-Non-PGO', 14:05:25 INFO - 'buildbot_json_path': 'buildprops.json', 14:05:25 INFO - 'config_files': ('talos/linux_config.py',), 14:05:25 INFO - 'default_actions': ('clobber', 14:05:25 INFO - 'read-buildbot-config', 14:05:25 INFO - 'download-and-extract', 14:05:25 INFO - 'populate-webroot', 14:05:25 INFO - 'create-virtualenv', 14:05:25 INFO - 'install', 14:05:25 INFO - 'run-tests'), 14:05:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:05:25 INFO - 'download_minidump_stackwalk': True, 14:05:25 INFO - 'download_symbols': 'ondemand', 14:05:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:05:25 INFO - 'tooltool.py': '/tools/tooltool.py', 14:05:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:05:25 INFO - '/tools/misc-python/virtualenv.py')}, 14:05:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:05:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:05:25 INFO - 'installer_path': 'installer.exe', 14:05:25 INFO - 'log_level': 'info', 14:05:25 INFO - 'log_name': 'talos', 14:05:25 INFO - 'log_to_console': True, 14:05:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:05:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:05:25 INFO - 'opt_config_files': (), 14:05:25 INFO - 'pip_index': False, 14:05:25 INFO - 'sps_profile': False, 14:05:25 INFO - 'sps_profile_interval': 0, 14:05:25 INFO - 'suite': 'dromaeojs', 14:05:25 INFO - 'system_bits': '32', 14:05:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:05:25 INFO - 'title': 'talos-linux64-ix-055', 14:05:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:05:25 INFO - 'use_talos_json': True, 14:05:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:05:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:05:25 INFO - 'work_dir': 'build'} 14:05:25 INFO - [mozharness: 2016-08-08 21:05:25.321008Z] Running clobber step. 14:05:25 INFO - Running pre-action listener: _resource_record_pre_action 14:05:25 INFO - Running main action method: clobber 14:05:25 INFO - rmtree: /builds/slave/test/build 14:05:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:05:25 INFO - Running post-action listener: _resource_record_post_action 14:05:25 INFO - [mozharness: 2016-08-08 21:05:25.788366Z] Finished clobber step (success) 14:05:25 INFO - [mozharness: 2016-08-08 21:05:25.788467Z] Running read-buildbot-config step. 14:05:25 INFO - Running pre-action listener: _resource_record_pre_action 14:05:25 INFO - Running main action method: read_buildbot_config 14:05:25 INFO - Using buildbot properties: 14:05:25 INFO - { 14:05:25 INFO - "project": "", 14:05:25 INFO - "product": "firefox", 14:05:25 INFO - "script_repo_revision": "production", 14:05:25 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 14:05:25 INFO - "repository": "", 14:05:25 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 14:05:25 INFO - "buildid": "20160808124426", 14:05:25 INFO - "pgo_build": "False", 14:05:25 INFO - "basedir": "/builds/slave/test", 14:05:25 INFO - "buildnumber": 196, 14:05:25 INFO - "slavename": "talos-linux64-ix-055", 14:05:25 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:05:25 INFO - "platform": "ubuntu64_hw", 14:05:25 INFO - "scheduler": "tests-autoland-linux64-talos", 14:05:25 INFO - "branch": "autoland", 14:05:25 INFO - "repo_path": "integration/autoland", 14:05:25 INFO - "revision": "7d0cb37025ded808b8e53c874d29d772cd7c54a2", 14:05:25 INFO - "stage_platform": "linux64", 14:05:25 INFO - "builduid": "5f6f3baf0c084f47b1058b99a93ba245", 14:05:25 INFO - "slavebuilddir": "test" 14:05:25 INFO - } 14:05:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 14:05:25 INFO - Running post-action listener: _resource_record_post_action 14:05:25 INFO - [mozharness: 2016-08-08 21:05:25.790911Z] Finished read-buildbot-config step (success) 14:05:25 INFO - [mozharness: 2016-08-08 21:05:25.791000Z] Running download-and-extract step. 14:05:25 INFO - Running pre-action listener: _resource_record_pre_action 14:05:25 INFO - Running main action method: download_and_extract 14:05:25 INFO - mkdir: /builds/slave/test/build/tests 14:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:25 INFO - trying https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:05:25 INFO - Downloading https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/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 14:05:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/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 14:05:26 INFO - Downloaded 1361 bytes. 14:05:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:05:26 INFO - Using the following test package requirements: 14:05:26 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 14:05:26 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:05:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:05:26 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:05:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:05:26 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:05:26 INFO - u'jsshell-linux-x86_64.zip'], 14:05:26 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:05:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:05:26 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 14:05:26 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:05:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:05:26 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:05:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:05:26 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:05:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:05:26 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:05:26 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:05:26 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:26 INFO - trying https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:05:26 INFO - Downloading https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/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 14:05:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/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 14:05:27 INFO - Downloaded 20257866 bytes. 14:05:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:05:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:05:28 INFO - Return code: 0 14:05:28 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 14:05:28 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')]} 14:05:28 INFO - trying https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:05:28 INFO - Downloading https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/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 14:05:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/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 14:05:29 INFO - Downloaded 20257866 bytes. 14:05:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:05:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:05:30 INFO - Return code: 0 14:05: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')]} 14:05:30 INFO - trying https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 14:05:30 INFO - Downloading https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/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 14:05:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/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 14:05:32 INFO - Downloaded 14413723 bytes. 14:05:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:05:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:05:33 INFO - Return code: 0 14:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:33 INFO - trying https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:05:33 INFO - Downloading https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/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 14:05:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/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 14:05:36 INFO - Downloaded 56137394 bytes. 14:05:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:05:36 INFO - mkdir: /builds/slave/test/properties 14:05:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:05:36 INFO - Writing to file /builds/slave/test/properties/build_url 14:05:36 INFO - Contents: 14:05:36 INFO - build_url:https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:05:37 INFO - Running post-action listener: _resource_record_post_action 14:05:37 INFO - Running post-action listener: set_extra_try_arguments 14:05:37 INFO - [mozharness: 2016-08-08 21:05:37.641025Z] Finished download-and-extract step (success) 14:05:37 INFO - [mozharness: 2016-08-08 21:05:37.641259Z] Running populate-webroot step. 14:05:37 INFO - Running pre-action listener: _resource_record_pre_action 14:05:37 INFO - Running main action method: populate_webroot 14:05:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:05:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:05:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:05:37 INFO - '--tppagecycles', 14:05:37 INFO - '7'], 14:05:37 INFO - 'tests': ['tsvgm']}}, 14:05:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:05:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:05:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:05:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:05:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:05:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:05:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:37 INFO - 'talos_options': ['--disable-e10s'], 14:05:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:05:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:05:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:37 INFO - 'talos_options': ['--disable-e10s'], 14:05:37 INFO - 'tests': ['damp', 'tps']}, 14:05:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:37 INFO - 'tests': ['damp', 'tps']}, 14:05:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:05:37 INFO - 'tests': ['dromaeo_dom']}, 14:05:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:05:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:05:37 INFO - 'tests': ['basic_compositor_video']}, 14:05:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:05:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:05:37 INFO - 'tests': ['a11yr', 14:05:37 INFO - 'ts_paint', 14:05:37 INFO - 'tpaint', 14:05:37 INFO - 'sessionrestore', 14:05:37 INFO - 'sessionrestore_no_auto_restore', 14:05:37 INFO - 'tabpaint']}, 14:05:37 INFO - 'other-e10s': {'tests': ['a11yr', 14:05:37 INFO - 'ts_paint', 14:05:37 INFO - 'tpaint', 14:05:37 INFO - 'sessionrestore', 14:05:37 INFO - 'sessionrestore_no_auto_restore', 14:05:37 INFO - 'tabpaint']}, 14:05:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:05:37 INFO - 'ts_paint', 14:05:37 INFO - 'tpaint', 14:05:37 INFO - 'sessionrestore', 14:05:37 INFO - 'sessionrestore_no_auto_restore', 14:05:37 INFO - 'tabpaint']}, 14:05:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:05:37 INFO - 'ts_paint', 14:05:37 INFO - 'tpaint', 14:05:37 INFO - 'sessionrestore', 14:05:37 INFO - 'sessionrestore_no_auto_restore', 14:05:37 INFO - 'tabpaint']}, 14:05:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:05:37 INFO - 'tests': ['a11yr', 14:05:37 INFO - 'ts_paint', 14:05:37 INFO - 'tpaint', 14:05:37 INFO - 'sessionrestore', 14:05:37 INFO - 'sessionrestore_no_auto_restore', 14:05:37 INFO - 'tabpaint']}, 14:05:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:05:37 INFO - 'tests': ['a11yr', 14:05:37 INFO - 'ts_paint', 14:05:37 INFO - 'tpaint', 14:05:37 INFO - 'sessionrestore', 14:05:37 INFO - 'sessionrestore_no_auto_restore', 14:05:37 INFO - 'tabpaint']}, 14:05:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:05:37 INFO - 'tests': ['tsvgx', 14:05:37 INFO - 'tsvgr_opacity', 14:05:37 INFO - 'tart', 14:05:37 INFO - 'tscrollx', 14:05:37 INFO - 'cart']}, 14:05:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:05:37 INFO - 'tsvgr_opacity', 14:05:37 INFO - 'tart', 14:05:37 INFO - 'tscrollx', 14:05:37 INFO - 'cart']}, 14:05:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:37 INFO - 'talos_options': ['--disable-e10s'], 14:05:37 INFO - 'tests': ['tp5o']}, 14:05:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:37 INFO - 'tests': ['tp5o']}, 14:05:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:37 INFO - 'talos_options': ['--disable-e10s', 14:05:37 INFO - '--xperf_path', 14:05:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:05:37 INFO - 'tests': ['tp5n']}, 14:05:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:37 INFO - 'talos_options': ['--xperf_path', 14:05:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:05:37 INFO - 'tests': ['tp5n']}}, 14:05:37 INFO - 'talos.zip': {'path': '', 14:05:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:05:37 INFO - Running post-action listener: _resource_record_post_action 14:05:37 INFO - [mozharness: 2016-08-08 21:05:37.660246Z] Finished populate-webroot step (success) 14:05:37 INFO - [mozharness: 2016-08-08 21:05:37.660335Z] Running create-virtualenv step. 14:05:37 INFO - Running pre-action listener: _resource_record_pre_action 14:05:37 INFO - Running main action method: create_virtualenv 14:05:37 INFO - Creating virtualenv /builds/slave/test/build/venv 14:05:37 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 14:05:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:05:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:05:37 INFO - Using real prefix '/usr' 14:05:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:05:38 INFO - Installing distribute.............................................................................................................................................................................................done. 14:05:40 INFO - Installing pip.................done. 14:05:40 INFO - Return code: 0 14:05:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:05:40 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')]} 14:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:40 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')]} 14:05:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:40 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 0x1540030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1801180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff3b0>, '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 0x18002b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', '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 14:05:40 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 14:05:40 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 14:05:40 INFO - Using env: {'DISPLAY': ':0', 14:05:40 INFO - 'HOME': '/home/cltbld', 14:05:40 INFO - 'LANG': 'en_US.UTF-8', 14:05:40 INFO - 'LANGUAGE': 'en_US:en', 14:05:40 INFO - 'LOGNAME': 'cltbld', 14:05:40 INFO - 'MAIL': '/var/mail/cltbld', 14:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:40 INFO - 'MOZ_NO_REMOTE': '1', 14:05:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:40 INFO - 'NO_EM_RESTART': '1', 14:05:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:40 INFO - 'PWD': '/builds/slave/test', 14:05:40 INFO - 'SHELL': '/bin/bash', 14:05:40 INFO - 'SHLVL': '1', 14:05:40 INFO - 'TERM': 'linux', 14:05:40 INFO - 'TMOUT': '86400', 14:05:40 INFO - 'USER': 'cltbld', 14:05:40 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', 14:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:40 INFO - '_': '/tools/buildbot/bin/python'} 14:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:40 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 14:05:40 INFO - Cleaning up... 14:05:40 INFO - Return code: 0 14:05:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:05:40 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')]} 14:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:40 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')]} 14:05:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:40 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 0x1540030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1801180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff3b0>, '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 0x18002b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', '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 14:05:40 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 14:05:40 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 14:05:40 INFO - Using env: {'DISPLAY': ':0', 14:05:40 INFO - 'HOME': '/home/cltbld', 14:05:40 INFO - 'LANG': 'en_US.UTF-8', 14:05:40 INFO - 'LANGUAGE': 'en_US:en', 14:05:40 INFO - 'LOGNAME': 'cltbld', 14:05:40 INFO - 'MAIL': '/var/mail/cltbld', 14:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:40 INFO - 'MOZ_NO_REMOTE': '1', 14:05:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:40 INFO - 'NO_EM_RESTART': '1', 14:05:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:40 INFO - 'PWD': '/builds/slave/test', 14:05:40 INFO - 'SHELL': '/bin/bash', 14:05:40 INFO - 'SHLVL': '1', 14:05:40 INFO - 'TERM': 'linux', 14:05:40 INFO - 'TMOUT': '86400', 14:05:40 INFO - 'USER': 'cltbld', 14:05:40 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', 14:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:40 INFO - '_': '/tools/buildbot/bin/python'} 14:05:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:40 INFO - Downloading/unpacking psutil>=3.1.1 14:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:05:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:05:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:05:42 INFO - Installing collected packages: psutil 14:05:42 INFO - Running setup.py install for psutil 14:05:42 INFO - building 'psutil._psutil_linux' extension 14:05:42 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 14:05:42 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 14:05:42 INFO - building 'psutil._psutil_posix' extension 14:05:42 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 14:05:43 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 14:05:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:05:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:05:43 INFO - Successfully installed psutil 14:05:43 INFO - Cleaning up... 14:05:43 INFO - Return code: 0 14:05:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:05: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')]} 14:05:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05: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')]} 14:05:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1540030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1801180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff3b0>, '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 0x18002b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', '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 14:05: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:05: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 mozsystemmonitor==0.3 14:05:43 INFO - Using env: {'DISPLAY': ':0', 14:05:43 INFO - 'HOME': '/home/cltbld', 14:05:43 INFO - 'LANG': 'en_US.UTF-8', 14:05:43 INFO - 'LANGUAGE': 'en_US:en', 14:05:43 INFO - 'LOGNAME': 'cltbld', 14:05:43 INFO - 'MAIL': '/var/mail/cltbld', 14:05:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:43 INFO - 'MOZ_NO_REMOTE': '1', 14:05:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:43 INFO - 'NO_EM_RESTART': '1', 14:05:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:43 INFO - 'PWD': '/builds/slave/test', 14:05:43 INFO - 'SHELL': '/bin/bash', 14:05:43 INFO - 'SHLVL': '1', 14:05:43 INFO - 'TERM': 'linux', 14:05:43 INFO - 'TMOUT': '86400', 14:05:43 INFO - 'USER': 'cltbld', 14:05:43 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', 14:05:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:43 INFO - '_': '/tools/buildbot/bin/python'} 14:05:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:05:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:05:45 INFO - Installing collected packages: mozsystemmonitor 14:05:45 INFO - Running setup.py install for mozsystemmonitor 14:05:45 INFO - Successfully installed mozsystemmonitor 14:05:45 INFO - Cleaning up... 14:05:45 INFO - Return code: 0 14:05:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1540030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1801180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff3b0>, '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 0x18002b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', '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 14:05:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:05:45 INFO - Using env: {'DISPLAY': ':0', 14:05:45 INFO - 'HOME': '/home/cltbld', 14:05:45 INFO - 'LANG': 'en_US.UTF-8', 14:05:45 INFO - 'LANGUAGE': 'en_US:en', 14:05:45 INFO - 'LOGNAME': 'cltbld', 14:05:45 INFO - 'MAIL': '/var/mail/cltbld', 14:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:45 INFO - 'MOZ_NO_REMOTE': '1', 14:05:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:45 INFO - 'NO_EM_RESTART': '1', 14:05:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:45 INFO - 'PWD': '/builds/slave/test', 14:05:45 INFO - 'SHELL': '/bin/bash', 14:05:45 INFO - 'SHLVL': '1', 14:05:45 INFO - 'TERM': 'linux', 14:05:45 INFO - 'TMOUT': '86400', 14:05:45 INFO - 'USER': 'cltbld', 14:05:45 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', 14:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:45 INFO - '_': '/tools/buildbot/bin/python'} 14:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:45 INFO - Downloading/unpacking jsonschema==2.5.1 14:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:05:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:05:48 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 14:05:48 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 14:05:48 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 14:05:48 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 14:05:48 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 14:05:48 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 14:05:48 INFO - Downloading functools32-3.2.3-2.tar.gz 14:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:05:48 INFO - warning: no files found matching '*.txt' 14:05:48 INFO - no previously-included directories found matching 'build' 14:05:48 INFO - no previously-included directories found matching 'dist' 14:05:48 INFO - no previously-included directories found matching '.git*' 14:05:48 INFO - Installing collected packages: jsonschema, functools32 14:05:49 INFO - Running setup.py install for functools32 14:05:49 INFO - warning: no files found matching '*.txt' 14:05:49 INFO - no previously-included directories found matching 'build' 14:05:49 INFO - no previously-included directories found matching 'dist' 14:05:49 INFO - no previously-included directories found matching '.git*' 14:05:49 INFO - Successfully installed jsonschema functools32 14:05:49 INFO - Cleaning up... 14:05:49 INFO - Return code: 0 14:05:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:49 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 0x1540030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1801180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff3b0>, '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 0x18002b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', '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 14:05:49 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 14:05:49 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 14:05:49 INFO - Using env: {'DISPLAY': ':0', 14:05:49 INFO - 'HOME': '/home/cltbld', 14:05:49 INFO - 'LANG': 'en_US.UTF-8', 14:05:49 INFO - 'LANGUAGE': 'en_US:en', 14:05:49 INFO - 'LOGNAME': 'cltbld', 14:05:49 INFO - 'MAIL': '/var/mail/cltbld', 14:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:49 INFO - 'MOZ_NO_REMOTE': '1', 14:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:49 INFO - 'NO_EM_RESTART': '1', 14:05:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:49 INFO - 'PWD': '/builds/slave/test', 14:05:49 INFO - 'SHELL': '/bin/bash', 14:05:49 INFO - 'SHLVL': '1', 14:05:49 INFO - 'TERM': 'linux', 14:05:49 INFO - 'TMOUT': '86400', 14:05:49 INFO - 'USER': 'cltbld', 14:05:49 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', 14:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:49 INFO - '_': '/tools/buildbot/bin/python'} 14:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:05:49 INFO - Cleaning up... 14:05:49 INFO - Return code: 0 14:05:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:49 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 0x1540030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1801180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff3b0>, '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 0x18002b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', '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 14:05:49 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 14:05:49 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 14:05:49 INFO - Using env: {'DISPLAY': ':0', 14:05:49 INFO - 'HOME': '/home/cltbld', 14:05:49 INFO - 'LANG': 'en_US.UTF-8', 14:05:49 INFO - 'LANGUAGE': 'en_US:en', 14:05:49 INFO - 'LOGNAME': 'cltbld', 14:05:49 INFO - 'MAIL': '/var/mail/cltbld', 14:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:49 INFO - 'MOZ_NO_REMOTE': '1', 14:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:49 INFO - 'NO_EM_RESTART': '1', 14:05:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:49 INFO - 'PWD': '/builds/slave/test', 14:05:49 INFO - 'SHELL': '/bin/bash', 14:05:49 INFO - 'SHLVL': '1', 14:05:49 INFO - 'TERM': 'linux', 14:05:49 INFO - 'TMOUT': '86400', 14:05:49 INFO - 'USER': 'cltbld', 14:05:49 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', 14:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:49 INFO - '_': '/tools/buildbot/bin/python'} 14:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:49 INFO - Downloading/unpacking blobuploader==1.2.4 14:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:53 INFO - Downloading blobuploader-1.2.4.tar.gz 14:05:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:05:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:05:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:55 INFO - Downloading docopt-0.6.1.tar.gz 14:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:05:55 INFO - Installing collected packages: blobuploader, requests, docopt 14:05:55 INFO - Running setup.py install for blobuploader 14:05:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:05:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:05:55 INFO - Running setup.py install for requests 14:05:55 INFO - Running setup.py install for docopt 14:05:55 INFO - Successfully installed blobuploader requests docopt 14:05:55 INFO - Cleaning up... 14:05:55 INFO - Return code: 0 14:05:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:05: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')]} 14:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05: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')]} 14:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:55 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 0x1540030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1801180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff3b0>, '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 0x18002b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', '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 14:05:55 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 14:05:55 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 14:05:55 INFO - Using env: {'DISPLAY': ':0', 14:05:55 INFO - 'HOME': '/home/cltbld', 14:05:55 INFO - 'LANG': 'en_US.UTF-8', 14:05:55 INFO - 'LANGUAGE': 'en_US:en', 14:05:55 INFO - 'LOGNAME': 'cltbld', 14:05:55 INFO - 'MAIL': '/var/mail/cltbld', 14:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:55 INFO - 'MOZ_NO_REMOTE': '1', 14:05:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:55 INFO - 'NO_EM_RESTART': '1', 14:05:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:55 INFO - 'PWD': '/builds/slave/test', 14:05:55 INFO - 'SHELL': '/bin/bash', 14:05:55 INFO - 'SHLVL': '1', 14:05:55 INFO - 'TERM': 'linux', 14:05:55 INFO - 'TMOUT': '86400', 14:05:55 INFO - 'USER': 'cltbld', 14:05:55 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', 14:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:55 INFO - '_': '/tools/buildbot/bin/python'} 14:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:05:55 INFO - Running setup.py (path:/tmp/pip-OLDY3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:05:55 INFO - Running setup.py (path:/tmp/pip-M7Is70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:05:56 INFO - Running setup.py (path:/tmp/pip-FFFecV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:05:56 INFO - Running setup.py (path:/tmp/pip-yzJjEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:05:56 INFO - Running setup.py (path:/tmp/pip-39m5v4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:05:56 INFO - Running setup.py (path:/tmp/pip-KCAum6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:05:56 INFO - Running setup.py (path:/tmp/pip-XdQKQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:05:56 INFO - Running setup.py (path:/tmp/pip-iMObOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:05:56 INFO - Running setup.py (path:/tmp/pip-fYu3hH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:05:56 INFO - Running setup.py (path:/tmp/pip-DP_Flj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:05:56 INFO - Running setup.py (path:/tmp/pip-yh5ZMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:05:56 INFO - Running setup.py (path:/tmp/pip-Lv37xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:05:56 INFO - Running setup.py (path:/tmp/pip-ucI5Je-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:05:56 INFO - Running setup.py (path:/tmp/pip-fINH2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:05:56 INFO - Running setup.py (path:/tmp/pip-KNeyIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:05:57 INFO - Running setup.py (path:/tmp/pip-r5bDGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:05:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:05:57 INFO - Running setup.py (path:/tmp/pip-xDVf9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:05:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:05:57 INFO - Running setup.py install for manifestparser 14:05:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:05:57 INFO - Running setup.py install for mozcrash 14:05:57 INFO - Running setup.py install for mozdebug 14:05:57 INFO - Running setup.py install for mozdevice 14:05:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:05:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:05:57 INFO - Running setup.py install for mozfile 14:05:57 INFO - Running setup.py install for mozhttpd 14:05:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:05:57 INFO - Running setup.py install for mozinfo 14:05:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:05:57 INFO - Running setup.py install for mozInstall 14:05:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:05:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:05:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:05:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:05:57 INFO - Running setup.py install for mozleak 14:05:58 INFO - Running setup.py install for mozlog 14:05:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:05:58 INFO - Running setup.py install for moznetwork 14:05:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:05:58 INFO - Running setup.py install for mozprocess 14:05:58 INFO - Running setup.py install for mozprofile 14:05:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:05:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:05:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:05:58 INFO - Running setup.py install for mozrunner 14:05:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:05:58 INFO - Running setup.py install for mozscreenshot 14:05:58 INFO - Running setup.py install for moztest 14:05:58 INFO - Running setup.py install for mozversion 14:05:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:05:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:05:58 INFO - Cleaning up... 14:05:58 INFO - Return code: 0 14:05:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:58 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 0x1540030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1801180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff3b0>, '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 0x18002b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', '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 14:05:58 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 14:05:58 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 14:05:58 INFO - Using env: {'DISPLAY': ':0', 14:05:58 INFO - 'HOME': '/home/cltbld', 14:05:58 INFO - 'LANG': 'en_US.UTF-8', 14:05:58 INFO - 'LANGUAGE': 'en_US:en', 14:05:58 INFO - 'LOGNAME': 'cltbld', 14:05:58 INFO - 'MAIL': '/var/mail/cltbld', 14:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:58 INFO - 'MOZ_NO_REMOTE': '1', 14:05:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:58 INFO - 'NO_EM_RESTART': '1', 14:05:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:05:58 INFO - 'PWD': '/builds/slave/test', 14:05:58 INFO - 'SHELL': '/bin/bash', 14:05:58 INFO - 'SHLVL': '1', 14:05:58 INFO - 'TERM': 'linux', 14:05:58 INFO - 'TMOUT': '86400', 14:05:58 INFO - 'USER': 'cltbld', 14:05:58 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', 14:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:58 INFO - '_': '/tools/buildbot/bin/python'} 14:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:05:59 INFO - Running setup.py (path:/tmp/pip-znUcA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:05:59 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)) 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:05:59 INFO - Running setup.py (path:/tmp/pip-5RJ6w5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:05:59 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)) 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:05:59 INFO - Running setup.py (path:/tmp/pip-ngdcNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:05:59 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)) 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:05:59 INFO - Running setup.py (path:/tmp/pip-AkB6QR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:05:59 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)) 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:05:59 INFO - Running setup.py (path:/tmp/pip-XIrP2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:05:59 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)) 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:05:59 INFO - Running setup.py (path:/tmp/pip-3Q72Ep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:05:59 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)) 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:05:59 INFO - Running setup.py (path:/tmp/pip-_Ypppd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:05:59 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)) 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:05:59 INFO - Running setup.py (path:/tmp/pip-MI6HhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:05:59 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)) 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:05:59 INFO - Running setup.py (path:/tmp/pip-lIecwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:05:59 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)) 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:05:59 INFO - Running setup.py (path:/tmp/pip-s93npC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:05:59 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)) 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:05:59 INFO - Running setup.py (path:/tmp/pip-HW9sWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:05:59 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)) 14:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:05:59 INFO - Running setup.py (path:/tmp/pip-AFdsrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:06:00 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)) 14:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:06:00 INFO - Running setup.py (path:/tmp/pip-hzK6Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:06:00 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)) 14:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:06:00 INFO - Running setup.py (path:/tmp/pip-VG1QVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:06:00 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)) 14:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:00 INFO - Running setup.py (path:/tmp/pip-PieTHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:06:00 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)) 14:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:06:00 INFO - Running setup.py (path:/tmp/pip-z667FW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:06:00 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)) 14:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:06:00 INFO - Running setup.py (path:/tmp/pip-9v8lhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:06:00 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)) 14:06:00 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)) 14:06:00 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)) 14:06:00 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)) 14:06:00 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)) 14:06:00 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)) 14:06:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:06:00 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 14:06:00 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 14:06:00 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 14:06:00 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 14:06:00 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 14:06:00 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 14:06:02 INFO - Downloading blessings-1.6.tar.gz 14:06:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:06:02 INFO - Installing collected packages: blessings 14:06:02 INFO - Running setup.py install for blessings 14:06:02 INFO - Successfully installed blessings 14:06:02 INFO - Cleaning up... 14:06:02 INFO - Return code: 0 14:06:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:06:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:06:02 INFO - Reading from file tmpfile_stdout 14:06:02 INFO - Current package versions: 14:06:02 INFO - argparse == 1.2.1 14:06:02 INFO - blessings == 1.6 14:06:02 INFO - blobuploader == 1.2.4 14:06:02 INFO - docopt == 0.6.1 14:06:02 INFO - functools32 == 3.2.3-2 14:06:02 INFO - jsonschema == 2.5.1 14:06:02 INFO - manifestparser == 1.1 14:06:02 INFO - mozInstall == 1.12 14:06:02 INFO - mozcrash == 0.17 14:06:02 INFO - mozdebug == 0.1 14:06:02 INFO - mozdevice == 0.48 14:06:02 INFO - mozfile == 1.2 14:06:02 INFO - mozhttpd == 0.7 14:06:02 INFO - mozinfo == 0.9 14:06:02 INFO - mozleak == 0.1 14:06:02 INFO - mozlog == 3.3 14:06:02 INFO - moznetwork == 0.27 14:06:02 INFO - mozprocess == 0.23 14:06:02 INFO - mozprofile == 0.28 14:06:02 INFO - mozrunner == 6.12 14:06:02 INFO - mozscreenshot == 0.1 14:06:02 INFO - mozsystemmonitor == 0.3 14:06:02 INFO - moztest == 0.7 14:06:02 INFO - mozversion == 1.4 14:06:02 INFO - psutil == 3.1.1 14:06:02 INFO - requests == 1.2.3 14:06:02 INFO - wsgiref == 0.1.2 14:06:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:02 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 0x1540030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1801180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff3b0>, '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 0x18002b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', '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 14:06:02 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 14:06:02 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 14:06:02 INFO - Using env: {'DISPLAY': ':0', 14:06:02 INFO - 'HOME': '/home/cltbld', 14:06:02 INFO - 'LANG': 'en_US.UTF-8', 14:06:02 INFO - 'LANGUAGE': 'en_US:en', 14:06:02 INFO - 'LOGNAME': 'cltbld', 14:06:02 INFO - 'MAIL': '/var/mail/cltbld', 14:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:02 INFO - 'MOZ_NO_REMOTE': '1', 14:06:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:06:02 INFO - 'NO_EM_RESTART': '1', 14:06:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:02 INFO - 'PWD': '/builds/slave/test', 14:06:02 INFO - 'SHELL': '/bin/bash', 14:06:02 INFO - 'SHLVL': '1', 14:06:02 INFO - 'TERM': 'linux', 14:06:02 INFO - 'TMOUT': '86400', 14:06:02 INFO - 'USER': 'cltbld', 14:06:02 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', 14:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:02 INFO - '_': '/tools/buildbot/bin/python'} 14:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:03 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)) 14:06:03 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)) 14:06:03 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)) 14:06:03 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)) 14:06:03 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)) 14:06:03 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)) 14:06:03 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)) 14:06:03 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)) 14:06:03 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)) 14:06:03 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)) 14:06:03 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)) 14:06:03 INFO - Cleaning up... 14:06:03 INFO - Return code: 0 14:06:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:06:03 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')]} 14:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:03 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')]} 14:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:06:03 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 0x1540030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1801180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff3b0>, '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 0x18002b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800740>, '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': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', '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 14:06:03 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 14:06:03 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 14:06:03 INFO - Using env: {'DISPLAY': ':0', 14:06:03 INFO - 'HOME': '/home/cltbld', 14:06:03 INFO - 'LANG': 'en_US.UTF-8', 14:06:03 INFO - 'LANGUAGE': 'en_US:en', 14:06:03 INFO - 'LOGNAME': 'cltbld', 14:06:03 INFO - 'MAIL': '/var/mail/cltbld', 14:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:03 INFO - 'MOZ_NO_REMOTE': '1', 14:06:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:06:03 INFO - 'NO_EM_RESTART': '1', 14:06:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:03 INFO - 'PWD': '/builds/slave/test', 14:06:03 INFO - 'SHELL': '/bin/bash', 14:06:03 INFO - 'SHLVL': '1', 14:06:03 INFO - 'TERM': 'linux', 14:06:03 INFO - 'TMOUT': '86400', 14:06:03 INFO - 'USER': 'cltbld', 14:06:03 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', 14:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:03 INFO - '_': '/tools/buildbot/bin/python'} 14:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:06:03 INFO - Cleaning up... 14:06:03 INFO - Return code: 0 14:06:03 INFO - Running post-action listener: _resource_record_post_action 14:06:03 INFO - Running post-action listener: _start_resource_monitoring 14:06:03 INFO - Starting resource monitoring. 14:06:03 INFO - [mozharness: 2016-08-08 21:06:03.284296Z] Finished create-virtualenv step (success) 14:06:03 INFO - [mozharness: 2016-08-08 21:06:03.284727Z] Running install step. 14:06:03 INFO - Running pre-action listener: _resource_record_pre_action 14:06:03 INFO - Running main action method: install 14:06:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:06:03 INFO - Reading from file tmpfile_stdout 14:06:03 INFO - Detecting whether we're running mozinstall >=1.0... 14:06:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:06:03 INFO - Reading from file tmpfile_stdout 14:06:03 INFO - Output received: 14:06:03 INFO - Usage: mozinstall [options] installer 14:06:03 INFO - Options: 14:06:03 INFO - -h, --help show this help message and exit 14:06:03 INFO - -d DEST, --destination=DEST 14:06:03 INFO - Directory to install application into. [default: 14:06:03 INFO - "/builds/slave/test"] 14:06:03 INFO - --app=APP Application being installed. [default: firefox] 14:06:03 INFO - mkdir: /builds/slave/test/build/application 14:06:03 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'] 14:06:03 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 14:06:19 INFO - Reading from file tmpfile_stdout 14:06:19 INFO - Output received: 14:06:19 INFO - /builds/slave/test/build/application/firefox/firefox 14:06:19 INFO - Running post-action listener: _resource_record_post_action 14:06:19 INFO - [mozharness: 2016-08-08 21:06:19.805958Z] Finished install step (success) 14:06:19 INFO - [mozharness: 2016-08-08 21:06:19.806270Z] Running run-tests step. 14:06:19 INFO - Running pre-action listener: _resource_record_pre_action 14:06:19 INFO - Running main action method: run_tests 14:06:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:06:19 INFO - Python 2.7.3 14:06:19 INFO - Return code: 0 14:06:19 INFO - grabbing minidump binary from tooltool 14:06: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')]} 14:06:19 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 0x17ff3b0>, '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 0x18002b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:06:19 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 14:06:19 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 14:06:19 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 14:06:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:06:19 INFO - Return code: 0 14:06:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:06:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:06:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:06:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:06:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:06:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 14:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 14:06:19 INFO - Using env: {'DISPLAY': ':0', 14:06:19 INFO - 'HOME': '/home/cltbld', 14:06:19 INFO - 'LANG': 'en_US.UTF-8', 14:06:19 INFO - 'LANGUAGE': 'en_US:en', 14:06:19 INFO - 'LOGNAME': 'cltbld', 14:06:19 INFO - 'MAIL': '/var/mail/cltbld', 14:06:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:06:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:06:19 INFO - 'MOZ_NO_REMOTE': '1', 14:06:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:06:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:06:19 INFO - 'NO_EM_RESTART': '1', 14:06:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:06:19 INFO - 'PWD': '/builds/slave/test', 14:06:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:06:19 INFO - 'SHELL': '/bin/bash', 14:06:19 INFO - 'SHLVL': '1', 14:06:19 INFO - 'TERM': 'linux', 14:06:19 INFO - 'TMOUT': '86400', 14:06:19 INFO - 'USER': 'cltbld', 14:06:19 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692', 14:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:06:19 INFO - '_': '/tools/buildbot/bin/python'} 14:06:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 14:06:20 INFO - mozversion application_buildid: 20160808124426 14:06:20 INFO - mozversion application_changeset: 7d0cb37025ded808b8e53c874d29d772cd7c54a2 14:06:20 INFO - mozversion application_display_name: Nightly 14:06:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:06:20 INFO - mozversion application_name: Firefox 14:06:20 INFO - mozversion application_remotingname: firefox 14:06:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 14:06:20 INFO - mozversion application_vendor: Mozilla 14:06:20 INFO - mozversion application_version: 51.0a1 14:06:20 INFO - mozversion platform_buildid: 20160808124426 14:06:20 INFO - mozversion platform_changeset: 7d0cb37025ded808b8e53c874d29d772cd7c54a2 14:06:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 14:06:20 INFO - mozversion platform_version: 51.0a1 14:06:20 INFO - using testdate: 1470690380 14:06:20 INFO - actual date: 1470690380 14:06:20 INFO - starting webserver on 'localhost:39430' 14:06:20 INFO - SUITE-START | Running 2 tests 14:06:20 INFO - TEST-START | dromaeo_css 14:06:20 INFO - operating with platform_type : linux_ 14:06:20 INFO - Initialising browser for dromaeo_css test... 14:06:20 INFO - TEST-INFO | started process 16378 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6sNQ8e/profile http://localhost:39430/getInfo.html) 14:06:24 INFO - PROCESS | 16378 | __metrics Screen width/height:1600/1200 14:06:24 INFO - PROCESS | 16378 | colorDepth:24 14:06:24 INFO - PROCESS | 16378 | Browser inner width/height: 1024/697 14:06:24 INFO - PROCESS | 16378 | __metrics 14:06:25 INFO - PROCESS | 16378 | 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" 14:06:25 INFO - PROCESS | 16378 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:06:26 INFO - TEST-INFO | 16378: exit 0 14:06:26 INFO - Browser initialized. 14:06:26 INFO - Running cycle 1/1 for dromaeo_css test... 14:06:26 INFO - TEST-INFO | started process 16458 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6sNQ8e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:06:37 INFO - PROCESS | 16458 | RSS: Main: 157437952 14:06:37 INFO - PROCESS | 16458 | 14:07:29 INFO - PROCESS | 16458 | 1470690449083 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] 14:09:41 INFO - PROCESS | 16458 | Cycle 1(1): loaded http://localhost:39430/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39430/tests/dromaeo/cssquery-ext.html) 14:09:43 INFO - PROCESS | 16458 | RSS: Main: 237993984 14:09:43 INFO - PROCESS | 16458 | 14:12:45 INFO - PROCESS | 16458 | Cycle 1(1): loaded http://localhost:39430/tests/dromaeo/cssquery-ext.html (next: http://localhost:39430/tests/dromaeo/cssquery-jquery.html) 14:12:46 INFO - PROCESS | 16458 | RSS: Main: 242991104 14:12:46 INFO - PROCESS | 16458 | 14:15:48 INFO - PROCESS | 16458 | Cycle 1(1): loaded http://localhost:39430/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39430/tests/dromaeo/cssquery-mootools.html) 14:15:49 INFO - PROCESS | 16458 | RSS: Main: 188596224 14:15:49 INFO - PROCESS | 16458 | 14:18:51 INFO - PROCESS | 16458 | Cycle 1(1): loaded http://localhost:39430/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39430/tests/dromaeo/cssquery-prototype.html) 14:18:51 INFO - PROCESS | 16458 | RSS: Main: 191201280 14:18:51 INFO - PROCESS | 16458 | 14:21:54 INFO - PROCESS | 16458 | Cycle 1(1): loaded http://localhost:39430/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39430/tests/dromaeo/cssquery-yui.html) 14:21:55 INFO - PROCESS | 16458 | RSS: Main: 189902848 14:21:55 INFO - PROCESS | 16458 | 14:24:58 INFO - PROCESS | 16458 | Cycle 1(1): loaded http://localhost:39430/tests/dromaeo/cssquery-yui.html (next: http://localhost:39430/tests/dromaeo/cssquery-dojo.html) 14:24:58 INFO - PROCESS | 16458 | RSS: Main: 280137728 14:24:58 INFO - PROCESS | 16458 | 14:24:58 INFO - PROCESS | 16458 | __start_tp_report 14:24:58 INFO - PROCESS | 16458 | _x_x_mozilla_page_load 14:24:58 INFO - PROCESS | 16458 | _x_x_mozilla_page_load_details 14:24:58 INFO - PROCESS | 16458 | |i|pagename|runs| 14:24:58 INFO - PROCESS | 16458 | |0;dojo.html;2624.4882247961655;2637.367031912341;2666.066876593193;2678.933026674334;2681.262652770488;4562.406887104672;4680.5085466026;4728.148933192028;4818.060478206751;4850.1027309947685;4765.380500534926;4769.475357710658;4822.951770482294;4851.981083972361;4867.902641947159;4320.3735458358515;4350.325699516572;4421.756803375813;4474.127545128701;4475.082608065339;4401.185780630578;4482.686211965164;4504.954950450503;4508.413906192191;4526.97736511317;4254.74471531706;4315.4821421429315;4323.783810809447;4342.305231162999;4405.207062728708;4339.869803905888;4352.455943007124;4369.912601747979;4381.605655490989;4453.599176074136;4383.473983122014;4386.34204869269;4392.692511524195;4419.292913133883;4433.224185767478;4554.772261386917;4667.4632417272105;4750.382450281476;4751.619870410394;4784.043191361707;5066;5136.43499215086;5143.099957507458;5144.717040562768;5146.716930568818;5124.948750512484;5154.561862241714;5170.1986192140275;5175.637705360627;5177.249298851683;4576.58810707038;4688.413948256468;4745.454272758677;4761.880952976168;4802.471728109905;5221.399539053057;5236.371635403776;5282.418933917266;5291.417944026154;5295.391030031518;5027.748612569357;5031.371078615173;5044.117279476077;5064.898702025939;5080.339555857789;4872.6589138760655;4989.3264409305;5072.163093089673;5147.1507201312115;5156.355455568054;5080.49195080489;5081.8729531762;5082.313887625197;5112.258722485219;5121.436641969381;5068.41713202979;5076.13705670037;5078.644494885397;5084.949150508522;5115.462876397999;4243.533211346749;4292.3561465780385;4292.635126014324;4376.715513491677;4422.049259409242;3799.8290076946605;3815.5802861685193;3841;3895.182011777502;3932.5870783567884;4391.3193455014525;4410.272305069628;4410.955890441119;4441.533638967927;4455.019895622958;5109.08036553424;5152.665076770073;5153;5155.200943853709;5192;4251.894507428091;4340.696151269381;4353.782310884507;4401.867943961686;4405;4636.44362676481;4639.979204574988;4645.396098507173;4680.695754775928;4683.7423941682855;4830.058138662927;4833.275008748576;4836.105320515716;4841.612670986242;4887.437944636412;4940.827071052496;5019.7239151847;5069.847904562869;5079.3650793650795;5127.512886275797;4789.808407663654;4819.397575303087;4828.203346447796;4835.178019736723;4857.392825896763;5046.369203849519;5054.898902022012;5062.392512898476;5069.2902993581665;5105.770240339268;4828.396450443694;5078.035173317058;5119.411267704261;5127.53852153308;5170.689758614345;5018;5018.397792264952;5023.196288593807;5064.240363945438;5076.543111120017;4992.350994370709;5020.523050310215;5021.447640759587;5027.27104569828;5070.4929507049;4675.719456832601;4817.855464336076;4874.463808980939;4929.309896614549;4974.030064137459;4991.700497970133;5000.199968005102;5004.224345226496;5023.894743156499;5024.949750502595;4884.0476107158765;4884.218525035977;4885.218365061573;4895.657303988686;4912.336834527293 14:24:58 INFO - PROCESS | 16458 | |1;ext.html;552.3427121725147;572.7279542217448;573.5211098732557;580.6225953130464;591.7307625030611;28014.719852801467;37884;38908.61091389085;39007.609923900716;42302.7884860576;39403;39903.401448978286;39980.20039599206;39994.60005399945;40010.399844002364;7723.382129429646;7829.765107046797;7867.921320786776;7871.055473343207;7871.330936870373;7429.739959101433;9672.419654820698;9939.900600994006;9943.0554097361;10219.846702299472;10065.597376104946;10146.086852183302;10149.441780702058;10157.847632285522;10167.694969150938;10024.59901603935;10069.144122749574;10077.899221007769;10084.344517606369;10087.04173103554;9914.80170396588;9984.700458986243;9989.700308990741;9993.850092248622;9998.800023999553;37354.8132259337;37819.24361512755;37893.81053094717;37922.81038594817;37925.62074379248;2741.1091395296608;2885.451764164802;3055.5874956880757;3060.3726236121543;3367.983160084184;28362;28568.857155714297;28750;29078.709212907812;29648.70351296481;3493.9301213975577;3532.346515894568;3542.6988705960025;3577.6601222883783;3615.8553657853654;249305;251206;251607.99999999817;252156;252173;51236.74381628068;51518.484815151365;51562;51614.74192629013;51656.483435165166;26164.215073547824;26950.191494255203;26991.595126073124;28192.577111343347;39461.2107757849;14014.649633759238;14239.074460160056;14239.145651260751;14267.85732142686;14282.428702851978;9797.853032204523;10136.44249566281;10901.672949811518;11027.338359698444;11036.889631103586;16024.839751602567;16171.676566468605;17483.475495735147;17909.7313540297;18026.188821503063;6391.274355923874;6543.789398961208;6617.735290588419;6631.23740769808;6670.266270710218;776.3672606825419;783.4006984656742;822.1778221778221;828.7348048624382;945.1540870920487;25884.87057564738;27389.31526711848;28357.29106772306;28498.430031399257;28574.428511429654;878.4026861734083;906.805036917066;912.6805618033635;925.5742358515024;1012.6303899076784;14787.778183327258;14793;14842.18367989771;14976.70046599062;15093.54719358421;393300;394794;395258;397647;397807;48570.5142948566;48911.75544122328;48949.51050489521;48998.51001490011;49000.50999489959;1106.972325691848;1172.7302720374191;1176.3236139219982;1177.5407591039673;1179.1333369973063;25774.066944978826;26493.33766655849;26496.8675156623;26751.598726018736;27551.31121721893;42269.36595951002;42486.78756606197;42611.36082958696;42648.57351426446;42651.7867410661;346158;347958;348333;349489;350371;21096.472588185417;22287.331380058626;23009.769902300763;24804.37989050288;25228.74771252264;36992.63007369892;40386.39420408638;40500.18999619932;40783.388249175696;40899.591004089576;34672.47991280082;34881.30237395289;34939.65060349364;34982;35348.46977295394;34543.65456345505;34819.82590087033;34936.65063349334;35071;35155.824220878734;34201.486977694854;34257.14357140992;34288.82855585806;34320.82839585786;35388.64611353853;23354.299371018897;24217.51564968726;25393.49213015766;26132.73867261303;27379;24290.75709242885;24939.875300623382;25152.74847251504;25492.107776228484;25621.743782561934 14:24:58 INFO - PROCESS | 16458 | |2;jquery.html;362.38756501512427;362.664535304843;363.469334771234;371.67849809914435;380.72836725337385;16948.576285592866;17058;17533.736993945102;17587.56031099223;17604.38384656537;14884.702305953875;14919.552413427587;15023.474178403758;15264.313105910232;15347.232638368065;4921.8277360292395;5213.087709650815;5214.165733482643;5215.504527069922;5217.791288348461;3661.231141460283;3825.6365645263795;4980;5597.160425936101;5880.705964701748;5852.063669812831;5939.109133629969;5952.375000624937;5967.671778052206;5969.164316995602;5775.971120144393;6035.215421995134;6057.515398768088;6065.818025459199;6069.817905462865;5310.150375939869;5311.389190243133;5318.25544423781;5319.042572336978;6420.646864422455;22480.662790058162;23979.640305395435;24062.63906041411;24217;24277.757222427725;2084.749830020392;2110.250860944358;2173.7717539658265;2264.105678257079;2481.801455883525;13989.650258743512;14581.781273280909;14697.77953330701;14937.477188298466;15423.845761542354;1857.1642760757713;1907.5135840360758;1939.524816419972;1952.4337941996803;1963.685810270364;80683;85305;86732;88036.50961672915;89715.55142224312;16835.410760623563;16910.15449227534;17384.130793460277;17418.738718919227;17543.649127017645;29288.414231715247;29301.120966371043;29303.853480732887;29305.85347073251;29443.263918402212;8718.607662655195;8912.598933048028;9019.864702029476;9101.863472047924;9216.447013179231;7318.158411782607;7356.558606483628;7378.040854688963;7510.662020208994;7521.247875212435;8207.58962051907;8337.95831020841;8566.61450234741;8580.141985801494;8593.65625374978;2625.6192852036347;3039.298347144541;3140.4661207594763;3305.652906444837;3458.602260739997;371.3723806766556;373.1306056887446;373.3932359915138;380.44075209442076;626.3454689849119;10534.262601617966;10612.734681632866;10897.83653245202;10948.288361256493;11213.271137376045;401.76295985368773;404.3671653861697;405.9898502537401;406.05794556628337;418.51034289880914;5666.46168613981;5779.7110144493445;5837.824865254049;5848.766049358063;5993.61041532299;145712.2714386421;145766.2711686435;145982;146304.26847865692;206578;50905;67481;85306.57346713227;86884;87180.56409717911;411.31310711112513;421.52578349356975;424.08186276710575;430.262100497648;430.28143001187465;9852.359596626196;14629.707405852036;16010.119443430724;16252.268647910636;16328.836711632732;36327.63672363242;41669.1666166681;42538.57461425346;42644.360334594385;42974.570254297054;49680.75798105076;53714.46285537095;53869;54174.18738718844;55072.44927550673;7733.149353571215;7902;8080.474769139987;8101.432899696964;8404.957975210085;6724.193096828216;9576.425414475154;9846.803063938825;11738.94130529342;11786.233894796811;9554.808903821746;9913.45475998827;9946.254030947852;10008.749781255527;10017.898031216415;10187.083162515113;10361.689149325532;10584.047435730821;10613.204009699459;11073.557057717924;10032.749181270527;10078.395296282246;10115.342502737298;10165.54255058539;10319.900043631724;7592.620368981418;7683.654235559301;7783.066032076187;8049.678012879654;8094.87857682147;9822.852657210005;10319.48402579883;10389.480525973822;10432.530536125741;10617.628383006559 14:24:58 INFO - PROCESS | 16458 | |3;mootools.html;1022.1158697726464;1024.359775140537;1123.9662810115694;1194.0566952107836;1205.2828567002634;7032.578045317285;7530.209328020561;7533.510321829077;7534.246575342469;7535.472516923804;6415.967920160393;7291.8541629167385;7565.84868302635;7610.124835643903;7714.459987800842;2188.8577242479228;2211.369759618509;2214.169686367612;2215.080741492287;2217.7671344508835;2143.421276255418;2167.967480487786;2218.7892150245702;2218.966715499269;2224.132588290568;1741.2164525963303;2043.7036629688687;2121.1939463004037;2125.011869480691;2140.5932872754206;2169.7721739217473;2174.6411842046123;2186.365953873391;2194.2758889566403;2197.2199869046417;2155.902984365707;2186.8250539956766;2189.0915270162864;2190.047329411711;2195.6047911375945;7795.766127016199;7854.528728276322;7855.842883142305;7867.0952840423515;7872.409569282269;4243.108947121077;4346.174226896911;4384.386185933972;4393.055493068973;4398.538153493869;2715.4297597504547;2876.6979467155857;2882.394697113588;2906.6076079729387;2909.287224629958;880.3617377401333;940.463935556733;956.9856452153223;959.5490119643756;962.4177372689562;10648.201384896163;10668.733281667943;10689;10711.83932241017;10713.78572428547;3877.6703980161437;3991.4212439196153;4155.563665815106;4163.063310755117;4175.561566035583;9155.404898681565;9175.036621154815;9194.402363846328;9205.769855753526;9208.769780755401;4426.667999900021;4442.555744425532;4448.866534003985;4451.109778044404;4453.844115456009;4015.31739604268;4104.507459104867;4105.137921036616;4105.44576482177;4107.609777071173;3463.6536346365665;3557.9110522236633;3644.4715516250526;3645.6172101928914;3650.580183278904;1112.927659702133;1146.4840821630132;1165.8600967883908;1166.9008134308508;1172.8416663750454;511.411876342202;516.071072070272;666.3902529185838;675.3280485916447;682.1984168601873;4748.8337908173735;4872.707637541759;4877.6585639004925;4928.186849169919;5111;760.8896709977133;825.1913125137279;827.4869580859905;840.0759164918663;876.268315956171;3276.623188333372;3369.561956945581;3391.1522119470133;3395.7962522248745;3506.701930335899;9199.678011269574;9475.431474111576;9511.66709165176;9515.381500202464;9528.523573821281;9398.765030874147;9433.292503062297;9439.150476457015;9441.527923603793;9495.90504094964;7208.666396716624;7225.169105552824;7226.602536860523;7232.710691572383;7317.52436091663;4848.32123502717;4858.465568787501;4864.975675121602;4937.97531012357;4992.151334273255;8736.213740763362;8784.385093043426;8787.25308348772;8795.780105497413;8877.822443551222;8707.869381959148;8875.245604123722;9078;9081.636734530544;9088.363814532919;6421.8715625688155;6467.385598368148;6478.643674597943;6486.772962946275;6492.64290464029;6986.9650651746415;7032.2264550898335;7038.507304488637;7047.6828542714675;7048.823779405556;6208.348123446973;6249.6562689052535;6312.337204593635;6335.3981371769605;6373.490120790419;6364.288275367648;6416.13382193398;6435.678216089083;6451.161349024596;6467.320931302145;5929.258842644669;6018.3379828217985;6018.939810601838;6026.096085587197;6029.155918171372;4323.854178642599;4510.3234514821725;4524.0951809637545;4525.637948964009;4600.05698831732;4402.339649052539;4452.5547445255215;4499.003984063745;4530.139273538017;4536.705114167568 14:24:58 INFO - PROCESS | 16458 | |4;prototype.html;286.4185703022863;287.2903927299569;287.54280693697024;287.7554079032821;294.05461441465684;4786.688865223762;4804.774782430475;4808.677613656247;4825.565699087086;4871.975640121795;4810.44679861816;4823;4824.348712923755;4825.975870120645;4826.3484429601995;3563.376409128405;3659.213269147133;3671.3391589513876;3719.42348935914;3770.3778876485485;3758.830852611639;3767.133559281367;3771.981140094296;3786.8106594670157;3790.1472168761948;3739.3082279778328;3739.738218324718;3747.850085996557;3748.4377343398573;3748.8191219765686;3526.6825985661285;3634.291313193915;3731.9626803731885;3745.763897913682;3755.267722794056;3311.3708395404797;3313.3042061167184;3316.2041110133637;3323.1692076980753;3340.3820293245826;4603.240465323218;4650.790714417859;4657.417822772153;4659;4696.976515117437;4111.779638994939;4246.957530424687;4281.571842815724;4300.8709738707885;4316.503602085769;4965.478624744422;4988.775505102243;4991.926735751795;4994.9250761238245;5023.7236951967625;3624.7100231981376;3625.456181572759;3630.183208778004;3630.6369363063745;3644.8359823807727;4995.675281106753;5246.081935661244;5247.003069416799;5276.261323414725;5277.8416647500635;3585.480105384757;3623.221007483404;3624.7643903146213;3632.20091579852;3632.2372301816913;3652.936091108515;3656.305301992613;3669.43123815809;3681.116532032293;3684.8341824617955;4170.749755014709;4180.226658068267;4183.226103170923;4195.349720793282;4200.390943313202;4736.786844592001;4995.975020124876;5010.3737032870895;5038.193888977746;5052.412786921327;1251.949922003128;1255.5040758900184;1258.5783762439503;1262.9305388203736;1265.5825475467352;764.7629234937187;800.5076877720277;807.7818988873089;810.1655295046112;811.0672726364728;4250.936235956463;4319.200947824663;4406.427164468662;4464.464264288306;4472.955270447319;5182.714950677749;5192.428832828386;5202.76281199088;5203.479652034842;5210.531052205244;4418.337249412614;4431.290993441034;4434.756088415168;4458.592360125496;4462.486814016364;2633.736626337351;2708.3093811078047;2717.279920820984;2735.945281094367;2739.2877851758685;4840.661153719277;4854.854354369375;4859.198232291632;4859.65982381237;4866.245731911559;5132;5271;5271.815486457956;5279.868003299948;5293.655912365684;4814.903701926012;4830.454254638482;4832.758362081952;4850.927236091461;4865.3509867599305;5127.87180320495;5144.845654630367;5145.871353216199;5148.716820574755;5187.688738675692;4899.510048995072;5032.823851165192;5077.695338279715;5082.872928176826;5093.388793344822;4838.7338696372035;4842.58837998774;4854.2233242681;4855.684380515255;4857.854264372075;3613.4100995561867;3621.837017334279;3625.9093522662142;3636.7999760013454;3641.690456311243;4922.409310882717;5141.71720555373;5163.612729045261;5176.482351764794;5199.350081239845;4902.264660300983;4967.850964471072;4986.401631804061;4997.2504124380175;5026.421961474477;4969.751512424436;4970.950290497049;4971.229459433794;4993.450720420823;5026.115206797451;4679.27471241958;4922.753862306942;4930.235813448921;4933.260010998379;4942.8992678110935;4742.7865746040825;4898.706077635354;4985.6260780442335;4986.753311672082;5002.7993281611425;4910.680805747758;4922.261660750916;4933.531314525115;4938.580220681282;4945.480724524017 14:24:58 INFO - PROCESS | 16458 | |5;yui.html;639.5171645407717;644.9290578036416;666.7266420767484;700.7617410080571;700.9765742016656;12569.371531423427;13398.330083495835;13484.662883427907;13729.31353432328;14361.784573231384;13790.172589644628;14055.226962517083;14075;14084.295785210725;14094.718105637907;629.9811005669815;751.087428774038;770.5800338815359;770.6416516319911;772.4129661457305;643.6652940470952;644.9290578036412;646.9385408386196;647.1877793369339;752.7704050264667;807.116207752513;813.5322189740893;813.9308158806508;817.1420008990566;817.3992115794886;814.1736137820118;815.3029160068115;815.3314282288526;815.6818840652151;816.102287483769;485.14856426970795;566.2044827018046;597.378726124834;597.3996133885461;597.692188522911;11945.761084778256;12271.938640306831;12298.692532686666;12303.56937507183;12309.753804923852;11878.227915185485;12336.50653973849;12466.750664986741;12754.298513581749;12898.290594017326;11871.406429678482;12036.879631203663;12195.14633975622;12345.19756215852;12453.688657783538;4087.141700242923;4176.352665336878;4186.476690413699;4236.110416812442;4275.87172384829;68678.31321686819;69044.30955690429;70522.64738676324;71228;71462.64268678676;10417.166626669848;10538.894611053945;10545.050945414949;10613.0448259657;10668.679939601825;19614.90192549028;19624.215031398584;19647.410577682407;19649.410517684493;19662.90168549148;6596.307387724316;6707.329267073351;6791.762288319885;6823.283555226729;7044.330788575077;3663.87176448823;3697.981510092486;3723.1064544509663;3765.943510847285;3808.5810560838286;2687.2744359022954;2758.7379198976064;2766.1563223217113;2784.0951690700604;2784.4152727927362;469.4929476165733;470.81402845875687;477.49624146525747;493.8074151081081;500.6770632941732;482.995170048295;499.398225138707;502.6104768804206;506.25074889163506;528.4108219335413;6902.516823822385;7093.645317734093;7196.136463624399;7531.548107113591;7611.771646850492;471.62977063005377;479.64720164507236;491.22386629125896;496.8335607571504;506.08651384251044;3306.8677252910097;3399.5920489540918;3442.6901578858024;3521.014116047511;3588.964110358915;102008.48995754974;103962;104337.47831260796;105919;107113.46443267734;53607.731961339945;94729.5263523678;94986.52506737423;95784;98163;82372.58813706013;83048.16951830525;83680.16319836724;84236.15763842406;84379.5781021091;6736.090627765189;6899.344562266577;7052.823679408056;7084.433245340258;7116.430685545248;31320.373592527565;32772.180695482806;39374.409383859835;39957.80021099876;42451.575484244764;39507;45344.546554534034;60976.39023609884;65607.34392656204;66296.66851665711;10708.73228169302;10834.404107773833;10963.56145754161;11547.884521155018;11911.583094591648;12543.686407839512;12978.80531792005;13085.542006029744;13272.933635331761;13737.107088039245;11891.405429728306;11911.583094591995;12020.30033370406;12345.567905123278;12492.063095268073;11549.249298795552;11659.242149260272;11724.648260552198;12412.379381030732;12456.377181141088;11402.258853174517;11591.24656897299;11861.5848445304;12244.816327754912;12268.631941041784;9738.561764720745;9998.100170984355;10017.899821001696;10221.897781022391;10332.690019299433;9666.323357364918;10220.591176352862;10240.641577545048;10245.948770256102;10251.282410231212 14:24:58 INFO - PROCESS | 16458 | __end_tp_report 14:24:58 INFO - PROCESS | 16458 | __start_cc_report 14:24:58 INFO - PROCESS | 16458 | _x_x_mozilla_cycle_collect,900 14:24:58 INFO - PROCESS | 16458 | __end_cc_report 14:24:58 INFO - PROCESS | 16458 | __startTimestamp1470691498602__endTimestamp 14:24:58 INFO - PROCESS | 16458 | 14:24:58 INFO - PROCESS | 16458 | ------- Summary: start ------- 14:24:58 INFO - PROCESS | 16458 | Number of tests: 6 14:24:58 INFO - PROCESS | 16458 | 14:24:58 INFO - PROCESS | 16458 | [#0] dojo.html Cycles:165 Average:4723.96 Median:4841.61 stddev:495.26 (10.2%) stddev-sans-first:468.60 14:24:58 INFO - PROCESS | 16458 | Values: 2624.5 2637.4 2666.1 2678.9 2681.3 4562.4 4680.5 4728.1 4818.1 4850.1 4765.4 4769.5 4823.0 4852.0 4867.9 4320.4 4350.3 4421.8 4474.1 4475.1 4401.2 4482.7 4505.0 4508.4 4527.0 4254.7 4315.5 4323.8 4342.3 4405.2 4339.9 4352.5 4369.9 4381.6 4453.6 4383.5 4386.3 4392.7 4419.3 4433.2 4554.8 4667.5 4750.4 4751.6 4784.0 5066.0 5136.4 5143.1 5144.7 5146.7 5124.9 5154.6 5170.2 5175.6 5177.2 4576.6 4688.4 4745.5 4761.9 4802.5 5221.4 5236.4 5282.4 5291.4 5295.4 5027.7 5031.4 5044.1 5064.9 5080.3 4872.7 4989.3 5072.2 5147.2 5156.4 5080.5 5081.9 5082.3 5112.3 5121.4 5068.4 5076.1 5078.6 5084.9 5115.5 4243.5 4292.4 4292.6 4376.7 4422.0 3799.8 3815.6 3841.0 3895.2 3932.6 4391.3 4410.3 4411.0 4441.5 4455.0 5109.1 5152.7 5153.0 5155.2 5192.0 4251.9 4340.7 4353.8 4401.9 4405.0 4636.4 4640.0 4645.4 4680.7 4683.7 4830.1 4833.3 4836.1 4841.6 4887.4 4940.8 5019.7 5069.8 5079.4 5127.5 4789.8 4819.4 4828.2 4835.2 4857.4 5046.4 5054.9 5062.4 5069.3 5105.8 4828.4 5078.0 5119.4 5127.5 5170.7 5018.0 5018.4 5023.2 5064.2 5076.5 4992.4 5020.5 5021.4 5027.3 5070.5 4675.7 4817.9 4874.5 4929.3 4974.0 4991.7 5000.2 5004.2 5023.9 5024.9 4884.0 4884.2 4885.2 4895.7 4912.3 14:24:58 INFO - PROCESS | 16458 | 14:24:58 INFO - PROCESS | 16458 | [#1] ext.html Cycles:180 Average:47360.12 Median:191607.15 stddev:88964.29 (46.4%) stddev-sans-first:89144.45 14:24:58 INFO - PROCESS | 16458 | Values: 552.3 572.7 573.5 580.6 591.7 28014.7 37884.0 38908.6 39007.6 42302.8 39403.0 39903.4 39980.2 39994.6 40010.4 7723.4 7829.8 7867.9 7871.1 7871.3 7429.7 9672.4 9939.9 9943.1 10219.8 10065.6 10146.1 10149.4 10157.8 10167.7 10024.6 10069.1 10077.9 10084.3 10087.0 9914.8 9984.7 9989.7 9993.9 9998.8 37354.8 37819.2 37893.8 37922.8 37925.6 2741.1 2885.5 3055.6 3060.4 3368.0 28362.0 28568.9 28750.0 29078.7 29648.7 3493.9 3532.3 3542.7 3577.7 3615.9 249305.0 251206.0 251608.0 252156.0 252173.0 51236.7 51518.5 51562.0 51614.7 51656.5 26164.2 26950.2 26991.6 28192.6 39461.2 14014.6 14239.1 14239.1 14267.9 14282.4 9797.9 10136.4 10901.7 11027.3 11036.9 16024.8 16171.7 17483.5 17909.7 18026.2 6391.3 6543.8 6617.7 6631.2 6670.3 776.4 783.4 822.2 828.7 945.2 25884.9 27389.3 28357.3 28498.4 28574.4 878.4 906.8 912.7 925.6 1012.6 14787.8 14793.0 14842.2 14976.7 15093.5 393300.0 394794.0 395258.0 397647.0 397807.0 48570.5 48911.8 48949.5 48998.5 49000.5 1107.0 1172.7 1176.3 1177.5 1179.1 25774.1 26493.3 26496.9 26751.6 27551.3 42269.4 42486.8 42611.4 42648.6 42651.8 346158.0 347958.0 348333.0 349489.0 350371.0 21096.5 22287.3 23009.8 24804.4 25228.7 36992.6 40386.4 40500.2 40783.4 40899.6 34672.5 34881.3 34939.7 34982.0 35348.5 34543.7 34819.8 34936.7 35071.0 35155.8 34201.5 34257.1 34288.8 34320.8 35388.6 23354.3 24217.5 25393.5 26132.7 27379.0 24290.8 24939.9 25152.7 25492.1 25621.7 14:24:58 INFO - PROCESS | 16458 | 14:24:58 INFO - PROCESS | 16458 | [#2] jquery.html Cycles:180 Average:19192.81 Median:405.18 stddev:30847.75 (7613.4%) stddev-sans-first:30901.89 14:24:58 INFO - PROCESS | 16458 | Values: 362.4 362.7 363.5 371.7 380.7 16948.6 17058.0 17533.7 17587.6 17604.4 14884.7 14919.6 15023.5 15264.3 15347.2 4921.8 5213.1 5214.2 5215.5 5217.8 3661.2 3825.6 4980.0 5597.2 5880.7 5852.1 5939.1 5952.4 5967.7 5969.2 5776.0 6035.2 6057.5 6065.8 6069.8 5310.2 5311.4 5318.3 5319.0 6420.6 22480.7 23979.6 24062.6 24217.0 24277.8 2084.7 2110.3 2173.8 2264.1 2481.8 13989.7 14581.8 14697.8 14937.5 15423.8 1857.2 1907.5 1939.5 1952.4 1963.7 80683.0 85305.0 86732.0 88036.5 89715.6 16835.4 16910.2 17384.1 17418.7 17543.6 29288.4 29301.1 29303.9 29305.9 29443.3 8718.6 8912.6 9019.9 9101.9 9216.4 7318.2 7356.6 7378.0 7510.7 7521.2 8207.6 8338.0 8566.6 8580.1 8593.7 2625.6 3039.3 3140.5 3305.7 3458.6 371.4 373.1 373.4 380.4 626.3 10534.3 10612.7 10897.8 10948.3 11213.3 401.8 404.4 406.0 406.1 418.5 5666.5 5779.7 5837.8 5848.8 5993.6 145712.3 145766.3 145982.0 146304.3 206578.0 50905.0 67481.0 85306.6 86884.0 87180.6 411.3 421.5 424.1 430.3 430.3 9852.4 14629.7 16010.1 16252.3 16328.8 36327.6 41669.2 42538.6 42644.4 42974.6 49680.8 53714.5 53869.0 54174.2 55072.4 7733.1 7902.0 8080.5 8101.4 8405.0 6724.2 9576.4 9846.8 11738.9 11786.2 9554.8 9913.5 9946.3 10008.7 10017.9 10187.1 10361.7 10584.0 10613.2 11073.6 10032.7 10078.4 10115.3 10165.5 10319.9 7592.6 7683.7 7783.1 8049.7 8094.9 9822.9 10319.5 10389.5 10432.5 10617.6 14:24:58 INFO - PROCESS | 16458 | 14:24:58 INFO - PROCESS | 16458 | [#3] mootools.html Cycles:180 Average:4989.75 Median:4902.92 stddev:2850.53 (58.1%) stddev-sans-first:2842.93 14:24:58 INFO - PROCESS | 16458 | Values: 1022.1 1024.4 1124.0 1194.1 1205.3 7032.6 7530.2 7533.5 7534.2 7535.5 6416.0 7291.9 7565.8 7610.1 7714.5 2188.9 2211.4 2214.2 2215.1 2217.8 2143.4 2168.0 2218.8 2219.0 2224.1 1741.2 2043.7 2121.2 2125.0 2140.6 2169.8 2174.6 2186.4 2194.3 2197.2 2155.9 2186.8 2189.1 2190.0 2195.6 7795.8 7854.5 7855.8 7867.1 7872.4 4243.1 4346.2 4384.4 4393.1 4398.5 2715.4 2876.7 2882.4 2906.6 2909.3 880.4 940.5 957.0 959.5 962.4 10648.2 10668.7 10689.0 10711.8 10713.8 3877.7 3991.4 4155.6 4163.1 4175.6 9155.4 9175.0 9194.4 9205.8 9208.8 4426.7 4442.6 4448.9 4451.1 4453.8 4015.3 4104.5 4105.1 4105.4 4107.6 3463.7 3557.9 3644.5 3645.6 3650.6 1112.9 1146.5 1165.9 1166.9 1172.8 511.4 516.1 666.4 675.3 682.2 4748.8 4872.7 4877.7 4928.2 5111.0 760.9 825.2 827.5 840.1 876.3 3276.6 3369.6 3391.2 3395.8 3506.7 9199.7 9475.4 9511.7 9515.4 9528.5 9398.8 9433.3 9439.2 9441.5 9495.9 7208.7 7225.2 7226.6 7232.7 7317.5 4848.3 4858.5 4865.0 4938.0 4992.2 8736.2 8784.4 8787.3 8795.8 8877.8 8707.9 8875.2 9078.0 9081.6 9088.4 6421.9 6467.4 6478.6 6486.8 6492.6 6987.0 7032.2 7038.5 7047.7 7048.8 6208.3 6249.7 6312.3 6335.4 6373.5 6364.3 6416.1 6435.7 6451.2 6467.3 5929.3 6018.3 6018.9 6026.1 6029.2 4323.9 4510.3 4524.1 4525.6 4600.1 4402.3 4452.6 4499.0 4530.1 4536.7 14:24:58 INFO - PROCESS | 16458 | 14:24:58 INFO - PROCESS | 16458 | [#4] prototype.html Cycles:180 Average:4153.37 Median:4809.56 stddev:1212.13 (25.2%) stddev-sans-first:1180.27 14:24:58 INFO - PROCESS | 16458 | Values: 286.4 287.3 287.5 287.8 294.1 4786.7 4804.8 4808.7 4825.6 4872.0 4810.4 4823.0 4824.3 4826.0 4826.3 3563.4 3659.2 3671.3 3719.4 3770.4 3758.8 3767.1 3772.0 3786.8 3790.1 3739.3 3739.7 3747.9 3748.4 3748.8 3526.7 3634.3 3732.0 3745.8 3755.3 3311.4 3313.3 3316.2 3323.2 3340.4 4603.2 4650.8 4657.4 4659.0 4697.0 4111.8 4247.0 4281.6 4300.9 4316.5 4965.5 4988.8 4991.9 4994.9 5023.7 3624.7 3625.5 3630.2 3630.6 3644.8 4995.7 5246.1 5247.0 5276.3 5277.8 3585.5 3623.2 3624.8 3632.2 3632.2 3652.9 3656.3 3669.4 3681.1 3684.8 4170.7 4180.2 4183.2 4195.3 4200.4 4736.8 4996.0 5010.4 5038.2 5052.4 1251.9 1255.5 1258.6 1262.9 1265.6 764.8 800.5 807.8 810.2 811.1 4250.9 4319.2 4406.4 4464.5 4473.0 5182.7 5192.4 5202.8 5203.5 5210.5 4418.3 4431.3 4434.8 4458.6 4462.5 2633.7 2708.3 2717.3 2735.9 2739.3 4840.7 4854.9 4859.2 4859.7 4866.2 5132.0 5271.0 5271.8 5279.9 5293.7 4814.9 4830.5 4832.8 4850.9 4865.4 5127.9 5144.8 5145.9 5148.7 5187.7 4899.5 5032.8 5077.7 5082.9 5093.4 4838.7 4842.6 4854.2 4855.7 4857.9 3613.4 3621.8 3625.9 3636.8 3641.7 4922.4 5141.7 5163.6 5176.5 5199.4 4902.3 4967.9 4986.4 4997.3 5026.4 4969.8 4971.0 4971.2 4993.5 5026.1 4679.3 4922.8 4930.2 4933.3 4942.9 4742.8 4898.7 4985.6 4986.8 5002.8 4910.7 4922.3 4933.5 4938.6 4945.5 14:24:58 INFO - PROCESS | 16458 | 14:24:58 INFO - PROCESS | 16458 | [#5] yui.html Cycles:180 Average:18182.28 Median:39732.40 stddev:26935.85 (67.8%) stddev-sans-first:26979.20 14:24:58 INFO - PROCESS | 16458 | Values: 639.5 644.9 666.7 700.8 701.0 12569.4 13398.3 13484.7 13729.3 14361.8 13790.2 14055.2 14075.0 14084.3 14094.7 630.0 751.1 770.6 770.6 772.4 643.7 644.9 646.9 647.2 752.8 807.1 813.5 813.9 817.1 817.4 814.2 815.3 815.3 815.7 816.1 485.1 566.2 597.4 597.4 597.7 11945.8 12271.9 12298.7 12303.6 12309.8 11878.2 12336.5 12466.8 12754.3 12898.3 11871.4 12036.9 12195.1 12345.2 12453.7 4087.1 4176.4 4186.5 4236.1 4275.9 68678.3 69044.3 70522.6 71228.0 71462.6 10417.2 10538.9 10545.1 10613.0 10668.7 19614.9 19624.2 19647.4 19649.4 19662.9 6596.3 6707.3 6791.8 6823.3 7044.3 3663.9 3698.0 3723.1 3765.9 3808.6 2687.3 2758.7 2766.2 2784.1 2784.4 469.5 470.8 477.5 493.8 500.7 483.0 499.4 502.6 506.3 528.4 6902.5 7093.6 7196.1 7531.5 7611.8 471.6 479.6 491.2 496.8 506.1 3306.9 3399.6 3442.7 3521.0 3589.0 102008.5 103962.0 104337.5 105919.0 107113.5 53607.7 94729.5 94986.5 95784.0 98163.0 82372.6 83048.2 83680.2 84236.2 84379.6 6736.1 6899.3 7052.8 7084.4 7116.4 31320.4 32772.2 39374.4 39957.8 42451.6 39507.0 45344.5 60976.4 65607.3 66296.7 10708.7 10834.4 10963.6 11547.9 11911.6 12543.7 12978.8 13085.5 13272.9 13737.1 11891.4 11911.6 12020.3 12345.6 12492.1 11549.2 11659.2 11724.6 12412.4 12456.4 11402.3 11591.2 11861.6 12244.8 12268.6 9738.6 9998.1 10017.9 10221.9 10332.7 9666.3 10220.6 10240.6 10245.9 10251.3 14:24:58 INFO - PROCESS | 16458 | -------- Summary: end -------- 14:24:58 INFO - PROCESS | 16458 | 14:24:59 INFO - TEST-INFO | 16458: exit 0 14:24:59 INFO - TEST-OK | dromaeo_css | took 1119348ms 14:24:59 INFO - TEST-START | kraken 14:24:59 INFO - operating with platform_type : linux_ 14:24:59 INFO - Initialising browser for kraken test... 14:24:59 INFO - TEST-INFO | started process 16650 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGAXXE0/profile http://localhost:39430/getInfo.html) 14:25:03 INFO - PROCESS | 16650 | __metrics Screen width/height:1600/1200 14:25:03 INFO - PROCESS | 16650 | colorDepth:24 14:25:03 INFO - PROCESS | 16650 | Browser inner width/height: 1024/697 14:25:03 INFO - PROCESS | 16650 | __metrics 14:25:04 INFO - PROCESS | 16650 | 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" 14:25:04 INFO - PROCESS | 16650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:25:05 INFO - TEST-INFO | 16650: exit 0 14:25:05 INFO - Browser initialized. 14:25:05 INFO - Running cycle 1/1 for kraken test... 14:25:05 INFO - TEST-INFO | started process 16729 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGAXXE0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:25:17 INFO - PROCESS | 16729 | RSS: Main: 157900800 14:25:17 INFO - PROCESS | 16729 | 14:25:20 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39430/tests/kraken/driver.html?testName=audio-beat-detection) 14:25:20 INFO - PROCESS | 16729 | RSS: Main: 213704704 14:25:20 INFO - PROCESS | 16729 | 14:25:23 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39430/tests/kraken/driver.html?testName=audio-dft) 14:25:24 INFO - PROCESS | 16729 | RSS: Main: 188706816 14:25:24 INFO - PROCESS | 16729 | 14:25:27 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39430/tests/kraken/driver.html?testName=audio-fft) 14:25:28 INFO - PROCESS | 16729 | RSS: Main: 270508032 14:25:28 INFO - PROCESS | 16729 | 14:25:30 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39430/tests/kraken/driver.html?testName=audio-oscillator) 14:25:30 INFO - PROCESS | 16729 | RSS: Main: 180568064 14:25:30 INFO - PROCESS | 16729 | 14:25:33 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39430/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:25:33 INFO - PROCESS | 16729 | RSS: Main: 185704448 14:25:33 INFO - PROCESS | 16729 | 14:25:36 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39430/tests/kraken/driver.html?testName=imaging-darkroom) 14:25:37 INFO - PROCESS | 16729 | RSS: Main: 222216192 14:25:37 INFO - PROCESS | 16729 | 14:25:40 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39430/tests/kraken/driver.html?testName=imaging-desaturate) 14:25:41 INFO - PROCESS | 16729 | RSS: Main: 220254208 14:25:41 INFO - PROCESS | 16729 | 14:25:44 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39430/tests/kraken/driver.html?testName=json-parse-financial) 14:25:45 INFO - PROCESS | 16729 | RSS: Main: 220200960 14:25:45 INFO - PROCESS | 16729 | 14:25:46 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39430/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:25:47 INFO - PROCESS | 16729 | RSS: Main: 185520128 14:25:47 INFO - PROCESS | 16729 | 14:25:49 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39430/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:25:50 INFO - PROCESS | 16729 | RSS: Main: 190558208 14:25:50 INFO - PROCESS | 16729 | 14:25:52 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39430/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:25:52 INFO - PROCESS | 16729 | RSS: Main: 195489792 14:25:52 INFO - PROCESS | 16729 | 14:25:55 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39430/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:25:56 INFO - PROCESS | 16729 | RSS: Main: 190414848 14:25:56 INFO - PROCESS | 16729 | 14:25:59 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39430/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:25:59 INFO - PROCESS | 16729 | RSS: Main: 192774144 14:25:59 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | Cycle 1(1): loaded http://localhost:39430/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39430/tests/kraken/driver.html?testName=ai-astar) 14:26:01 INFO - PROCESS | 16729 | RSS: Main: 243490816 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | __start_tp_report 14:26:01 INFO - PROCESS | 16729 | _x_x_mozilla_page_load 14:26:01 INFO - PROCESS | 16729 | _x_x_mozilla_page_load_details 14:26:01 INFO - PROCESS | 16729 | |i|pagename|runs| 14:26:01 INFO - PROCESS | 16729 | |0;ai-astar;104;115;115;115;114;115;113;112;113;113 14:26:01 INFO - PROCESS | 16729 | |1;audio-beat-detection;144;120;122;185;121;123;146;122;164;120 14:26:01 INFO - PROCESS | 16729 | |2;audio-dft;160;161;161;164;161;163;162;162;160;161 14:26:01 INFO - PROCESS | 16729 | |3;audio-fft;75;75;77;111;78;77;77;76;99;76 14:26:01 INFO - PROCESS | 16729 | |4;audio-oscillator;85;86;130;85;85;84;118;84;84;130 14:26:01 INFO - PROCESS | 16729 | |5;imaging-gaussian-blur;117;116;117;117;117;117;116;117;117;117 14:26:01 INFO - PROCESS | 16729 | |6;imaging-darkroom;147;147;146;147;147;147;147;147;147;146 14:26:01 INFO - PROCESS | 16729 | |7;imaging-desaturate;94;95;95;95;95;95;95;94;95;94 14:26:01 INFO - PROCESS | 16729 | |8;json-parse-financial;62;61;62;63;62;62;64;64;63;62 14:26:01 INFO - PROCESS | 16729 | |9;json-stringify-tinderbox;66;65;65;66;65;65;64;65;65;65 14:26:01 INFO - PROCESS | 16729 | |10;stanford-crypto-aes;79;88;79;79;89;82;79;79;79;86 14:26:01 INFO - PROCESS | 16729 | |11;stanford-crypto-ccm;139;140;132;129;129;129;126;136;157;140 14:26:01 INFO - PROCESS | 16729 | |12;stanford-crypto-pbkdf2;171;163;171;164;165;165;163;163;165;164 14:26:01 INFO - PROCESS | 16729 | |13;stanford-crypto-sha256-iterative;67;66;66;67;67;66;67;72;70;66 14:26:01 INFO - PROCESS | 16729 | __end_tp_report 14:26:01 INFO - PROCESS | 16729 | __start_cc_report 14:26:01 INFO - PROCESS | 16729 | _x_x_mozilla_cycle_collect,1942 14:26:01 INFO - PROCESS | 16729 | __end_cc_report 14:26:01 INFO - PROCESS | 16729 | __startTimestamp1470691561793__endTimestamp 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | ------- Summary: start ------- 14:26:01 INFO - PROCESS | 16729 | Number of tests: 14 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.50 stddev:3.31 (2.9%) stddev-sans-first:1.17 14:26:01 INFO - PROCESS | 16729 | Values: 104.0 115.0 115.0 115.0 114.0 115.0 113.0 112.0 113.0 113.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:133.50 stddev:22.71 (17.0%) stddev-sans-first:23.93 14:26:01 INFO - PROCESS | 16729 | Values: 144.0 120.0 122.0 185.0 121.0 123.0 146.0 122.0 164.0 120.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#2] audio-dft Cycles:10 Average:161.50 Median:161.50 stddev:1.27 (0.8%) stddev-sans-first:1.22 14:26:01 INFO - PROCESS | 16729 | Values: 160.0 161.0 161.0 164.0 161.0 163.0 162.0 162.0 160.0 161.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#3] audio-fft Cycles:10 Average:82.10 Median:77.00 stddev:12.43 (16.1%) stddev-sans-first:12.92 14:26:01 INFO - PROCESS | 16729 | Values: 75.0 75.0 77.0 111.0 78.0 77.0 77.0 76.0 99.0 76.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:84.50 stddev:20.22 (23.9%) stddev-sans-first:20.96 14:26:01 INFO - PROCESS | 16729 | Values: 85.0 86.0 130.0 85.0 85.0 84.0 118.0 84.0 84.0 130.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 14:26:01 INFO - PROCESS | 16729 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 14:26:01 INFO - PROCESS | 16729 | Values: 147.0 147.0 146.0 147.0 147.0 147.0 147.0 147.0 147.0 146.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 14:26:01 INFO - PROCESS | 16729 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:62.50 stddev:0.97 (1.6%) stddev-sans-first:1.01 14:26:01 INFO - PROCESS | 16729 | Values: 62.0 61.0 62.0 63.0 62.0 62.0 64.0 64.0 63.0 62.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 14:26:01 INFO - PROCESS | 16729 | Values: 66.0 65.0 65.0 66.0 65.0 65.0 64.0 65.0 65.0 65.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#10] stanford-crypto-aes Cycles:10 Average:81.90 Median:80.50 stddev:4.15 (5.2%) stddev-sans-first:4.27 14:26:01 INFO - PROCESS | 16729 | Values: 79.0 88.0 79.0 79.0 89.0 82.0 79.0 79.0 79.0 86.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:137.50 stddev:9.09 (6.6%) stddev-sans-first:9.57 14:26:01 INFO - PROCESS | 16729 | Values: 139.0 140.0 132.0 129.0 129.0 129.0 126.0 136.0 157.0 140.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:165.00 stddev:3.06 (1.9%) stddev-sans-first:2.49 14:26:01 INFO - PROCESS | 16729 | Values: 171.0 163.0 171.0 164.0 165.0 165.0 163.0 163.0 165.0 164.0 14:26:01 INFO - PROCESS | 16729 | 14:26:01 INFO - PROCESS | 16729 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.00 stddev:2.01 (3.0%) stddev-sans-first:2.13 14:26:01 INFO - PROCESS | 16729 | Values: 67.0 66.0 66.0 67.0 67.0 66.0 67.0 72.0 70.0 66.0 14:26:01 INFO - PROCESS | 16729 | -------- Summary: end -------- 14:26:01 INFO - PROCESS | 16729 | 14:26:02 INFO - TEST-INFO | 16729: exit 0 14:26:02 INFO - TEST-OK | kraken | took 63512ms 14:26:02 INFO - SUITE-END | took 1182s 14:26:02 INFO - Completed test suite (00:19:42) 14:26:02 INFO - javascript benchmark 14:26:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2624.4882247961655, 2637.367031912341, 2666.066876593193, 2678.933026674334, 2681.262652770488, 4562.406887104672, 4680.5085466026, 4728.148933192028, 4818.060478206751, 4850.1027309947685, 4765.380500534926, 4769.475357710658, 4822.951770482294, 4851.981083972361, 4867.902641947159, 4320.3735458358515, 4350.325699516572, 4421.756803375813, 4474.127545128701, 4475.082608065339, 4401.185780630578, 4482.686211965164, 4504.954950450503, 4508.413906192191, 4526.97736511317, 4254.74471531706, 4315.4821421429315, 4323.783810809447, 4342.305231162999, 4405.207062728708, 4339.869803905888, 4352.455943007124, 4369.912601747979, 4381.605655490989, 4453.599176074136, 4383.473983122014, 4386.34204869269, 4392.692511524195, 4419.292913133883, 4433.224185767478, 4554.772261386917, 4667.4632417272105, 4750.382450281476, 4751.619870410394, 4784.043191361707, 5066.0, 5136.43499215086, 5143.099957507458, 5144.717040562768, 5146.716930568818, 5124.948750512484, 5154.561862241714, 5170.1986192140275, 5175.637705360627, 5177.249298851683, 4576.58810707038, 4688.413948256468, 4745.454272758677, 4761.880952976168, 4802.471728109905, 5221.399539053057, 5236.371635403776, 5282.418933917266, 5291.417944026154, 5295.391030031518, 5027.748612569357, 5031.371078615173, 5044.117279476077, 5064.898702025939, 5080.339555857789, 4872.6589138760655, 4989.3264409305, 5072.163093089673, 5147.1507201312115, 5156.355455568054, 5080.49195080489, 5081.8729531762, 5082.313887625197, 5112.258722485219, 5121.436641969381, 5068.41713202979, 5076.13705670037, 5078.644494885397, 5084.949150508522, 5115.462876397999, 4243.533211346749, 4292.3561465780385, 4292.635126014324, 4376.715513491677, 4422.049259409242, 3799.8290076946605, 3815.5802861685193, 3841.0, 3895.182011777502, 3932.5870783567884, 4391.3193455014525, 4410.272305069628, 4410.955890441119, 4441.533638967927, 4455.019895622958, 5109.08036553424, 5152.665076770073, 5153.0, 5155.200943853709, 5192.0, 4251.894507428091, 4340.696151269381, 4353.782310884507, 4401.867943961686, 4405.0, 4636.44362676481, 4639.979204574988, 4645.396098507173, 4680.695754775928, 4683.7423941682855, 4830.058138662927, 4833.275008748576, 4836.105320515716, 4841.612670986242, 4887.437944636412, 4940.827071052496, 5019.7239151847, 5069.847904562869, 5079.3650793650795, 5127.512886275797, 4789.808407663654, 4819.397575303087, 4828.203346447796, 4835.178019736723, 4857.392825896763, 5046.369203849519, 5054.898902022012, 5062.392512898476, 5069.2902993581665, 5105.770240339268, 4828.396450443694, 5078.035173317058, 5119.411267704261, 5127.53852153308, 5170.689758614345, 5018.0, 5018.397792264952, 5023.196288593807, 5064.240363945438, 5076.543111120017, 4992.350994370709, 5020.523050310215, 5021.447640759587, 5027.27104569828, 5070.4929507049, 4675.719456832601, 4817.855464336076, 4874.463808980939, 4929.309896614549, 4974.030064137459, 4991.700497970133, 5000.199968005102, 5004.224345226496, 5023.894743156499, 5024.949750502595, 4884.0476107158765, 4884.218525035977, 4885.218365061573, 4895.657303988686, 4912.336834527293], "value": 4692.367783280138, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.3427121725147, 572.7279542217448, 573.5211098732557, 580.6225953130464, 591.7307625030611, 28014.719852801467, 37884.0, 38908.61091389085, 39007.609923900716, 42302.7884860576, 39403.0, 39903.401448978286, 39980.20039599206, 39994.60005399945, 40010.399844002364, 7723.382129429646, 7829.765107046797, 7867.921320786776, 7871.055473343207, 7871.330936870373, 7429.739959101433, 9672.419654820698, 9939.900600994006, 9943.0554097361, 10219.846702299472, 10065.597376104946, 10146.086852183302, 10149.441780702058, 10157.847632285522, 10167.694969150938, 10024.59901603935, 10069.144122749574, 10077.899221007769, 10084.344517606369, 10087.04173103554, 9914.80170396588, 9984.700458986243, 9989.700308990741, 9993.850092248622, 9998.800023999553, 37354.8132259337, 37819.24361512755, 37893.81053094717, 37922.81038594817, 37925.62074379248, 2741.1091395296608, 2885.451764164802, 3055.5874956880757, 3060.3726236121543, 3367.983160084184, 28362.0, 28568.857155714297, 28750.0, 29078.709212907812, 29648.70351296481, 3493.9301213975577, 3532.346515894568, 3542.6988705960025, 3577.6601222883783, 3615.8553657853654, 249305.0, 251206.0, 251607.99999999817, 252156.0, 252173.0, 51236.74381628068, 51518.484815151365, 51562.0, 51614.74192629013, 51656.483435165166, 26164.215073547824, 26950.191494255203, 26991.595126073124, 28192.577111343347, 39461.2107757849, 14014.649633759238, 14239.074460160056, 14239.145651260751, 14267.85732142686, 14282.428702851978, 9797.853032204523, 10136.44249566281, 10901.672949811518, 11027.338359698444, 11036.889631103586, 16024.839751602567, 16171.676566468605, 17483.475495735147, 17909.7313540297, 18026.188821503063, 6391.274355923874, 6543.789398961208, 6617.735290588419, 6631.23740769808, 6670.266270710218, 776.3672606825419, 783.4006984656742, 822.1778221778221, 828.7348048624382, 945.1540870920487, 25884.87057564738, 27389.31526711848, 28357.29106772306, 28498.430031399257, 28574.428511429654, 878.4026861734083, 906.805036917066, 912.6805618033635, 925.5742358515024, 1012.6303899076784, 14787.778183327258, 14793.0, 14842.18367989771, 14976.70046599062, 15093.54719358421, 393300.0, 394794.0, 395258.0, 397647.0, 397807.0, 48570.5142948566, 48911.75544122328, 48949.51050489521, 48998.51001490011, 49000.50999489959, 1106.972325691848, 1172.7302720374191, 1176.3236139219982, 1177.5407591039673, 1179.1333369973063, 25774.066944978826, 26493.33766655849, 26496.8675156623, 26751.598726018736, 27551.31121721893, 42269.36595951002, 42486.78756606197, 42611.36082958696, 42648.57351426446, 42651.7867410661, 346158.0, 347958.0, 348333.0, 349489.0, 350371.0, 21096.472588185417, 22287.331380058626, 23009.769902300763, 24804.37989050288, 25228.74771252264, 36992.63007369892, 40386.39420408638, 40500.18999619932, 40783.388249175696, 40899.591004089576, 34672.47991280082, 34881.30237395289, 34939.65060349364, 34982.0, 35348.46977295394, 34543.65456345505, 34819.82590087033, 34936.65063349334, 35071.0, 35155.824220878734, 34201.486977694854, 34257.14357140992, 34288.82855585806, 34320.82839585786, 35388.64611353853, 23354.299371018897, 24217.51564968726, 25393.49213015766, 26132.73867261303, 27379.0, 24290.75709242885, 24939.875300623382, 25152.74847251504, 25492.107776228484, 25621.743782561934], "value": 17262.053093429946, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.38756501512427, 362.664535304843, 363.469334771234, 371.67849809914435, 380.72836725337385, 16948.576285592866, 17058.0, 17533.736993945102, 17587.56031099223, 17604.38384656537, 14884.702305953875, 14919.552413427587, 15023.474178403758, 15264.313105910232, 15347.232638368065, 4921.8277360292395, 5213.087709650815, 5214.165733482643, 5215.504527069922, 5217.791288348461, 3661.231141460283, 3825.6365645263795, 4980.0, 5597.160425936101, 5880.705964701748, 5852.063669812831, 5939.109133629969, 5952.375000624937, 5967.671778052206, 5969.164316995602, 5775.971120144393, 6035.215421995134, 6057.515398768088, 6065.818025459199, 6069.817905462865, 5310.150375939869, 5311.389190243133, 5318.25544423781, 5319.042572336978, 6420.646864422455, 22480.662790058162, 23979.640305395435, 24062.63906041411, 24217.0, 24277.757222427725, 2084.749830020392, 2110.250860944358, 2173.7717539658265, 2264.105678257079, 2481.801455883525, 13989.650258743512, 14581.781273280909, 14697.77953330701, 14937.477188298466, 15423.845761542354, 1857.1642760757713, 1907.5135840360758, 1939.524816419972, 1952.4337941996803, 1963.685810270364, 80683.0, 85305.0, 86732.0, 88036.50961672915, 89715.55142224312, 16835.410760623563, 16910.15449227534, 17384.130793460277, 17418.738718919227, 17543.649127017645, 29288.414231715247, 29301.120966371043, 29303.853480732887, 29305.85347073251, 29443.263918402212, 8718.607662655195, 8912.598933048028, 9019.864702029476, 9101.863472047924, 9216.447013179231, 7318.158411782607, 7356.558606483628, 7378.040854688963, 7510.662020208994, 7521.247875212435, 8207.58962051907, 8337.95831020841, 8566.61450234741, 8580.141985801494, 8593.65625374978, 2625.6192852036347, 3039.298347144541, 3140.4661207594763, 3305.652906444837, 3458.602260739997, 371.3723806766556, 373.1306056887446, 373.3932359915138, 380.44075209442076, 626.3454689849119, 10534.262601617966, 10612.734681632866, 10897.83653245202, 10948.288361256493, 11213.271137376045, 401.76295985368773, 404.3671653861697, 405.9898502537401, 406.05794556628337, 418.51034289880914, 5666.46168613981, 5779.7110144493445, 5837.824865254049, 5848.766049358063, 5993.61041532299, 145712.2714386421, 145766.2711686435, 145982.0, 146304.26847865692, 206578.0, 50905.0, 67481.0, 85306.57346713227, 86884.0, 87180.56409717911, 411.31310711112513, 421.52578349356975, 424.08186276710575, 430.262100497648, 430.28143001187465, 9852.359596626196, 14629.707405852036, 16010.119443430724, 16252.268647910636, 16328.836711632732, 36327.63672363242, 41669.1666166681, 42538.57461425346, 42644.360334594385, 42974.570254297054, 49680.75798105076, 53714.46285537095, 53869.0, 54174.18738718844, 55072.44927550673, 7733.149353571215, 7902.0, 8080.474769139987, 8101.432899696964, 8404.957975210085, 6724.193096828216, 9576.425414475154, 9846.803063938825, 11738.94130529342, 11786.233894796811, 9554.808903821746, 9913.45475998827, 9946.254030947852, 10008.749781255527, 10017.898031216415, 10187.083162515113, 10361.689149325532, 10584.047435730821, 10613.204009699459, 11073.557057717924, 10032.749181270527, 10078.395296282246, 10115.342502737298, 10165.54255058539, 10319.900043631724, 7592.620368981418, 7683.654235559301, 7783.066032076187, 8049.678012879654, 8094.87857682147, 9822.852657210005, 10319.48402579883, 10389.480525973822, 10432.530536125741, 10617.628383006559], "value": 8159.218128365294, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1022.1158697726464, 1024.359775140537, 1123.9662810115694, 1194.0566952107836, 1205.2828567002634, 7032.578045317285, 7530.209328020561, 7533.510321829077, 7534.246575342469, 7535.472516923804, 6415.967920160393, 7291.8541629167385, 7565.84868302635, 7610.124835643903, 7714.459987800842, 2188.8577242479228, 2211.369759618509, 2214.169686367612, 2215.080741492287, 2217.7671344508835, 2143.421276255418, 2167.967480487786, 2218.7892150245702, 2218.966715499269, 2224.132588290568, 1741.2164525963303, 2043.7036629688687, 2121.1939463004037, 2125.011869480691, 2140.5932872754206, 2169.7721739217473, 2174.6411842046123, 2186.365953873391, 2194.2758889566403, 2197.2199869046417, 2155.902984365707, 2186.8250539956766, 2189.0915270162864, 2190.047329411711, 2195.6047911375945, 7795.766127016199, 7854.528728276322, 7855.842883142305, 7867.0952840423515, 7872.409569282269, 4243.108947121077, 4346.174226896911, 4384.386185933972, 4393.055493068973, 4398.538153493869, 2715.4297597504547, 2876.6979467155857, 2882.394697113588, 2906.6076079729387, 2909.287224629958, 880.3617377401333, 940.463935556733, 956.9856452153223, 959.5490119643756, 962.4177372689562, 10648.201384896163, 10668.733281667943, 10689.0, 10711.83932241017, 10713.78572428547, 3877.6703980161437, 3991.4212439196153, 4155.563665815106, 4163.063310755117, 4175.561566035583, 9155.404898681565, 9175.036621154815, 9194.402363846328, 9205.769855753526, 9208.769780755401, 4426.667999900021, 4442.555744425532, 4448.866534003985, 4451.109778044404, 4453.844115456009, 4015.31739604268, 4104.507459104867, 4105.137921036616, 4105.44576482177, 4107.609777071173, 3463.6536346365665, 3557.9110522236633, 3644.4715516250526, 3645.6172101928914, 3650.580183278904, 1112.927659702133, 1146.4840821630132, 1165.8600967883908, 1166.9008134308508, 1172.8416663750454, 511.411876342202, 516.071072070272, 666.3902529185838, 675.3280485916447, 682.1984168601873, 4748.8337908173735, 4872.707637541759, 4877.6585639004925, 4928.186849169919, 5111.0, 760.8896709977133, 825.1913125137279, 827.4869580859905, 840.0759164918663, 876.268315956171, 3276.623188333372, 3369.561956945581, 3391.1522119470133, 3395.7962522248745, 3506.701930335899, 9199.678011269574, 9475.431474111576, 9511.66709165176, 9515.381500202464, 9528.523573821281, 9398.765030874147, 9433.292503062297, 9439.150476457015, 9441.527923603793, 9495.90504094964, 7208.666396716624, 7225.169105552824, 7226.602536860523, 7232.710691572383, 7317.52436091663, 4848.32123502717, 4858.465568787501, 4864.975675121602, 4937.97531012357, 4992.151334273255, 8736.213740763362, 8784.385093043426, 8787.25308348772, 8795.780105497413, 8877.822443551222, 8707.869381959148, 8875.245604123722, 9078.0, 9081.636734530544, 9088.363814532919, 6421.8715625688155, 6467.385598368148, 6478.643674597943, 6486.772962946275, 6492.64290464029, 6986.9650651746415, 7032.2264550898335, 7038.507304488637, 7047.6828542714675, 7048.823779405556, 6208.348123446973, 6249.6562689052535, 6312.337204593635, 6335.3981371769605, 6373.490120790419, 6364.288275367648, 6416.13382193398, 6435.678216089083, 6451.161349024596, 6467.320931302145, 5929.258842644669, 6018.3379828217985, 6018.939810601838, 6026.096085587197, 6029.155918171372, 4323.854178642599, 4510.3234514821725, 4524.0951809637545, 4525.637948964009, 4600.05698831732, 4402.339649052539, 4452.5547445255215, 4499.003984063745, 4530.139273538017, 4536.705114167568], "value": 3972.5554145586984, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.4185703022863, 287.2903927299569, 287.54280693697024, 287.7554079032821, 294.05461441465684, 4786.688865223762, 4804.774782430475, 4808.677613656247, 4825.565699087086, 4871.975640121795, 4810.44679861816, 4823.0, 4824.348712923755, 4825.975870120645, 4826.3484429601995, 3563.376409128405, 3659.213269147133, 3671.3391589513876, 3719.42348935914, 3770.3778876485485, 3758.830852611639, 3767.133559281367, 3771.981140094296, 3786.8106594670157, 3790.1472168761948, 3739.3082279778328, 3739.738218324718, 3747.850085996557, 3748.4377343398573, 3748.8191219765686, 3526.6825985661285, 3634.291313193915, 3731.9626803731885, 3745.763897913682, 3755.267722794056, 3311.3708395404797, 3313.3042061167184, 3316.2041110133637, 3323.1692076980753, 3340.3820293245826, 4603.240465323218, 4650.790714417859, 4657.417822772153, 4659.0, 4696.976515117437, 4111.779638994939, 4246.957530424687, 4281.571842815724, 4300.8709738707885, 4316.503602085769, 4965.478624744422, 4988.775505102243, 4991.926735751795, 4994.9250761238245, 5023.7236951967625, 3624.7100231981376, 3625.456181572759, 3630.183208778004, 3630.6369363063745, 3644.8359823807727, 4995.675281106753, 5246.081935661244, 5247.003069416799, 5276.261323414725, 5277.8416647500635, 3585.480105384757, 3623.221007483404, 3624.7643903146213, 3632.20091579852, 3632.2372301816913, 3652.936091108515, 3656.305301992613, 3669.43123815809, 3681.116532032293, 3684.8341824617955, 4170.749755014709, 4180.226658068267, 4183.226103170923, 4195.349720793282, 4200.390943313202, 4736.786844592001, 4995.975020124876, 5010.3737032870895, 5038.193888977746, 5052.412786921327, 1251.949922003128, 1255.5040758900184, 1258.5783762439503, 1262.9305388203736, 1265.5825475467352, 764.7629234937187, 800.5076877720277, 807.7818988873089, 810.1655295046112, 811.0672726364728, 4250.936235956463, 4319.200947824663, 4406.427164468662, 4464.464264288306, 4472.955270447319, 5182.714950677749, 5192.428832828386, 5202.76281199088, 5203.479652034842, 5210.531052205244, 4418.337249412614, 4431.290993441034, 4434.756088415168, 4458.592360125496, 4462.486814016364, 2633.736626337351, 2708.3093811078047, 2717.279920820984, 2735.945281094367, 2739.2877851758685, 4840.661153719277, 4854.854354369375, 4859.198232291632, 4859.65982381237, 4866.245731911559, 5132.0, 5271.0, 5271.815486457956, 5279.868003299948, 5293.655912365684, 4814.903701926012, 4830.454254638482, 4832.758362081952, 4850.927236091461, 4865.3509867599305, 5127.87180320495, 5144.845654630367, 5145.871353216199, 5148.716820574755, 5187.688738675692, 4899.510048995072, 5032.823851165192, 5077.695338279715, 5082.872928176826, 5093.388793344822, 4838.7338696372035, 4842.58837998774, 4854.2233242681, 4855.684380515255, 4857.854264372075, 3613.4100995561867, 3621.837017334279, 3625.9093522662142, 3636.7999760013454, 3641.690456311243, 4922.409310882717, 5141.71720555373, 5163.612729045261, 5176.482351764794, 5199.350081239845, 4902.264660300983, 4967.850964471072, 4986.401631804061, 4997.2504124380175, 5026.421961474477, 4969.751512424436, 4970.950290497049, 4971.229459433794, 4993.450720420823, 5026.115206797451, 4679.27471241958, 4922.753862306942, 4930.235813448921, 4933.260010998379, 4942.8992678110935, 4742.7865746040825, 4898.706077635354, 4985.6260780442335, 4986.753311672082, 5002.7993281611425, 4910.680805747758, 4922.261660750916, 4933.531314525115, 4938.580220681282, 4945.480724524017], "value": 3761.194682941785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [639.5171645407717, 644.9290578036416, 666.7266420767484, 700.7617410080571, 700.9765742016656, 12569.371531423427, 13398.330083495835, 13484.662883427907, 13729.31353432328, 14361.784573231384, 13790.172589644628, 14055.226962517083, 14075.0, 14084.295785210725, 14094.718105637907, 629.9811005669815, 751.087428774038, 770.5800338815359, 770.6416516319911, 772.4129661457305, 643.6652940470952, 644.9290578036412, 646.9385408386196, 647.1877793369339, 752.7704050264667, 807.116207752513, 813.5322189740893, 813.9308158806508, 817.1420008990566, 817.3992115794886, 814.1736137820118, 815.3029160068115, 815.3314282288526, 815.6818840652151, 816.102287483769, 485.14856426970795, 566.2044827018046, 597.378726124834, 597.3996133885461, 597.692188522911, 11945.761084778256, 12271.938640306831, 12298.692532686666, 12303.56937507183, 12309.753804923852, 11878.227915185485, 12336.50653973849, 12466.750664986741, 12754.298513581749, 12898.290594017326, 11871.406429678482, 12036.879631203663, 12195.14633975622, 12345.19756215852, 12453.688657783538, 4087.141700242923, 4176.352665336878, 4186.476690413699, 4236.110416812442, 4275.87172384829, 68678.31321686819, 69044.30955690429, 70522.64738676324, 71228.0, 71462.64268678676, 10417.166626669848, 10538.894611053945, 10545.050945414949, 10613.0448259657, 10668.679939601825, 19614.90192549028, 19624.215031398584, 19647.410577682407, 19649.410517684493, 19662.90168549148, 6596.307387724316, 6707.329267073351, 6791.762288319885, 6823.283555226729, 7044.330788575077, 3663.87176448823, 3697.981510092486, 3723.1064544509663, 3765.943510847285, 3808.5810560838286, 2687.2744359022954, 2758.7379198976064, 2766.1563223217113, 2784.0951690700604, 2784.4152727927362, 469.4929476165733, 470.81402845875687, 477.49624146525747, 493.8074151081081, 500.6770632941732, 482.995170048295, 499.398225138707, 502.6104768804206, 506.25074889163506, 528.4108219335413, 6902.516823822385, 7093.645317734093, 7196.136463624399, 7531.548107113591, 7611.771646850492, 471.62977063005377, 479.64720164507236, 491.22386629125896, 496.8335607571504, 506.08651384251044, 3306.8677252910097, 3399.5920489540918, 3442.6901578858024, 3521.014116047511, 3588.964110358915, 102008.48995754974, 103962.0, 104337.47831260796, 105919.0, 107113.46443267734, 53607.731961339945, 94729.5263523678, 94986.52506737423, 95784.0, 98163.0, 82372.58813706013, 83048.16951830525, 83680.16319836724, 84236.15763842406, 84379.5781021091, 6736.090627765189, 6899.344562266577, 7052.823679408056, 7084.433245340258, 7116.430685545248, 31320.373592527565, 32772.180695482806, 39374.409383859835, 39957.80021099876, 42451.575484244764, 39507.0, 45344.546554534034, 60976.39023609884, 65607.34392656204, 66296.66851665711, 10708.73228169302, 10834.404107773833, 10963.56145754161, 11547.884521155018, 11911.583094591648, 12543.686407839512, 12978.80531792005, 13085.542006029744, 13272.933635331761, 13737.107088039245, 11891.405429728306, 11911.583094591995, 12020.30033370406, 12345.567905123278, 12492.063095268073, 11549.249298795552, 11659.242149260272, 11724.648260552198, 12412.379381030732, 12456.377181141088, 11402.258853174517, 11591.24656897299, 11861.5848445304, 12244.816327754912, 12268.631941041784, 9738.561764720745, 9998.100170984355, 10017.899821001696, 10221.897781022391, 10332.690019299433, 9666.323357364918, 10220.591176352862, 10240.641577545048, 10245.948770256102, 10251.282410231212], "value": 6475.166566077022, "unit": "score"}], "name": "dromaeo_css", "value": 6323.7071094155945, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 115.0, 115.0, 114.0, 115.0, 113.0, 112.0, 113.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 120.0, 122.0, 185.0, 121.0, 123.0, 146.0, 122.0, 164.0, 120.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 164.0, 161.0, 163.0, 162.0, 162.0, 160.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 111.0, 78.0, 77.0, 77.0, 76.0, 99.0, 76.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 130.0, 85.0, 85.0, 84.0, 118.0, 84.0, 84.0, 130.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0], "value": 146.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 63.0, 62.0, 62.0, 64.0, 64.0, 63.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 66.0, 65.0, 65.0, 64.0, 65.0, 65.0, 65.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 88.0, 79.0, 79.0, 89.0, 82.0, 79.0, 79.0, 79.0, 86.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 132.0, 129.0, 129.0, 129.0, 126.0, 136.0, 157.0, 140.0], "value": 135.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 163.0, 171.0, 164.0, 165.0, 165.0, 163.0, 163.0, 165.0, 164.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 67.0, 67.0, 66.0, 67.0, 72.0, 70.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1526.6000000000004, "alertThreshold": 2.0}]} 14:26:03 INFO - Return code: 0 14:26:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:26:03 INFO - # TBPL SUCCESS # 14:26:03 INFO - Running post-action listener: _resource_record_post_action 14:26:03 INFO - [mozharness: 2016-08-08 21:26:03.076549Z] Finished run-tests step (success) 14:26:03 INFO - Running post-run listener: _resource_record_post_run 14:26:03 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 0; Write bytes: 534327296; Read time: 0; Write time: 447548 14:26:03 INFO - TinderboxPrint: CPU usage
13.0% 14:26:03 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 14:26:03 INFO - TinderboxPrint: I/O write bytes / time
534,327,296 / 447,548 14:26:03 INFO - TinderboxPrint: CPU idle
8,327.0 (87.0%) 14:26:03 INFO - TinderboxPrint: CPU user
1,198.1 (12.5%) 14:26:03 INFO - TinderboxPrint: Swap in / out
0 / 0 14:26:03 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 244961280; Read time: 0; Write time: 247904 14:26:03 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 286621696; Read time: 0; Write time: 199580 14:26:03 INFO - Running post-run listener: _upload_blobber_files 14:26:03 INFO - Blob upload gear active. 14:26:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:26:03 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 14:26:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:26:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:26:04 INFO - (blobuploader) - INFO - Open directory for files ... 14:26:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:26:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:26:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:26:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:26:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:26:04 INFO - (blobuploader) - INFO - Done attempting. 14:26:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:26:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:26:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:26:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:26:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:26:05 INFO - (blobuploader) - INFO - Done attempting. 14:26:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:26:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:26:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:26:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:26:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:26:09 INFO - (blobuploader) - INFO - Done attempting. 14:26:09 INFO - (blobuploader) - INFO - Iteration through files over. 14:26:09 INFO - Return code: 0 14:26:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:26:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:26:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f3b99df8143e3980ca3acf0f098f084a6ebe9e86b7f85db58c0fc6deabe33c5b97d0e91277add5f17ac18b68eda473097af9b51aecaddb84bc0407e9af9b7927", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/56be9df10873a945400747c77ba0bc43dca415e5c013b0c4dd2bb669c1d503a299d32080346134b22a563784888d3ba937ff9d308250272f43269f8c28b646b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/579336f28fc04128245234b31aebd2deecc11b3cc860a653b577fca3a2579ecb11d0efec3bcb2135c6dd2c5f0910326c123c13c53e56169ef97775ebb128381d"} 14:26:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:26:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:26:09 INFO - Contents: 14:26:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f3b99df8143e3980ca3acf0f098f084a6ebe9e86b7f85db58c0fc6deabe33c5b97d0e91277add5f17ac18b68eda473097af9b51aecaddb84bc0407e9af9b7927", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/56be9df10873a945400747c77ba0bc43dca415e5c013b0c4dd2bb669c1d503a299d32080346134b22a563784888d3ba937ff9d308250272f43269f8c28b646b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/579336f28fc04128245234b31aebd2deecc11b3cc860a653b577fca3a2579ecb11d0efec3bcb2135c6dd2c5f0910326c123c13c53e56169ef97775ebb128381d"} 14:26:09 INFO - Running post-run listener: copy_logs_to_upload_dir 14:26:09 INFO - Copying logs to upload dir... 14:26:09 INFO - mkdir: /builds/slave/test/build/upload/logs 14:26:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.880339 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-08-08 14:26:08.721110) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 14:26:08.724685) ========= 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=bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f3b99df8143e3980ca3acf0f098f084a6ebe9e86b7f85db58c0fc6deabe33c5b97d0e91277add5f17ac18b68eda473097af9b51aecaddb84bc0407e9af9b7927", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/56be9df10873a945400747c77ba0bc43dca415e5c013b0c4dd2bb669c1d503a299d32080346134b22a563784888d3ba937ff9d308250272f43269f8c28b646b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/579336f28fc04128245234b31aebd2deecc11b3cc860a653b577fca3a2579ecb11d0efec3bcb2135c6dd2c5f0910326c123c13c53e56169ef97775ebb128381d"} build_url:https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/cfjhmHY-S96lrjX4onLkZA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/f3b99df8143e3980ca3acf0f098f084a6ebe9e86b7f85db58c0fc6deabe33c5b97d0e91277add5f17ac18b68eda473097af9b51aecaddb84bc0407e9af9b7927", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/56be9df10873a945400747c77ba0bc43dca415e5c013b0c4dd2bb669c1d503a299d32080346134b22a563784888d3ba937ff9d308250272f43269f8c28b646b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/579336f28fc04128245234b31aebd2deecc11b3cc860a653b577fca3a2579ecb11d0efec3bcb2135c6dd2c5f0910326c123c13c53e56169ef97775ebb128381d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 14:26:08.750130) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 14:26:08.750475) ========= 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=bb9b54edd4a927dc735d112f000001d9-1470687557.642321-1570501692 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004507 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 14:26:08.832235) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 14:26:08.832571) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 14:26:08.832888) ========= ========= Total master_lag: 0.17 =========