builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-042 starttime: 1469032389.26 results: success (0) buildid: 20160720075824 builduid: f6bae55b0339475eb21fce1e7ea4c052 revision: a71e7db89da62e577f8995c886cc74272997a1bb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:09.260550) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:09.261025) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:09.261311) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:09.276830) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:09.277268) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-20 09:33:09-- 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% 43.0M=0s 2016-07-20 09:33:09 (43.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112312 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:09.403666) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:09.404037) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015329 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:09.454125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:09.454482) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a71e7db89da62e577f8995c886cc74272997a1bb --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 a71e7db89da62e577f8995c886cc74272997a1bb --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=74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-20 09:33:09,943 truncating revision to first 12 chars 2016-07-20 09:33:09,943 Setting DEBUG logging. 2016-07-20 09:33:09,943 attempt 1/10 2016-07-20 09:33:09,943 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a71e7db89da6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 09:33:10,249 unpacking tar archive at: autoland-a71e7db89da6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.666655 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:10.136359) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:10.136779) ========= script_repo_revision: a71e7db89da62e577f8995c886cc74272997a1bb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:10.137339) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:10.137634) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 09:33:10.150755) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-07-20 09:33:10.151156) ========= /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=74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:33:10 INFO - MultiFileLogger online at 20160720 09:33:10 in /builds/slave/test 09:33:10 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 09:33:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:33:10 INFO - {'append_to_log': False, 09:33:10 INFO - 'base_work_dir': '/builds/slave/test', 09:33:10 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 09:33:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:33:10 INFO - 'branch': 'Autoland-Non-PGO', 09:33:10 INFO - 'buildbot_json_path': 'buildprops.json', 09:33:10 INFO - 'config_files': ('talos/linux_config.py',), 09:33:10 INFO - 'default_actions': ('clobber', 09:33:10 INFO - 'read-buildbot-config', 09:33:10 INFO - 'download-and-extract', 09:33:10 INFO - 'populate-webroot', 09:33:10 INFO - 'create-virtualenv', 09:33:10 INFO - 'install', 09:33:10 INFO - 'run-tests'), 09:33:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:33:10 INFO - 'download_minidump_stackwalk': True, 09:33:10 INFO - 'download_symbols': 'ondemand', 09:33:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:33:10 INFO - 'tooltool.py': '/tools/tooltool.py', 09:33:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:33:10 INFO - '/tools/misc-python/virtualenv.py')}, 09:33:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:33:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:33:10 INFO - 'installer_path': 'installer.exe', 09:33:10 INFO - 'log_level': 'info', 09:33:10 INFO - 'log_name': 'talos', 09:33:10 INFO - 'log_to_console': True, 09:33:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:33:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:33:10 INFO - 'opt_config_files': (), 09:33:10 INFO - 'pip_index': False, 09:33:10 INFO - 'sps_profile': False, 09:33:10 INFO - 'sps_profile_interval': 0, 09:33:10 INFO - 'suite': 'dromaeojs', 09:33:10 INFO - 'system_bits': '32', 09:33:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:33:10 INFO - 'title': 'talos-linux64-ix-042', 09:33:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:33:10 INFO - 'use_talos_json': True, 09:33:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:33:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:33:10 INFO - 'work_dir': 'build'} 09:33:10 INFO - [mozharness: 2016-07-20 16:33:10.730120Z] Running clobber step. 09:33:10 INFO - Running pre-action listener: _resource_record_pre_action 09:33:10 INFO - Running main action method: clobber 09:33:10 INFO - rmtree: /builds/slave/test/build 09:33:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:33:11 INFO - Running post-action listener: _resource_record_post_action 09:33:11 INFO - [mozharness: 2016-07-20 16:33:11.225781Z] Finished clobber step (success) 09:33:11 INFO - [mozharness: 2016-07-20 16:33:11.225884Z] Running read-buildbot-config step. 09:33:11 INFO - Running pre-action listener: _resource_record_pre_action 09:33:11 INFO - Running main action method: read_buildbot_config 09:33:11 INFO - Using buildbot properties: 09:33:11 INFO - { 09:33:11 INFO - "project": "", 09:33:11 INFO - "product": "firefox", 09:33:11 INFO - "script_repo_revision": "production", 09:33:11 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 09:33:11 INFO - "repository": "", 09:33:11 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 09:33:11 INFO - "buildid": "20160720075824", 09:33:11 INFO - "pgo_build": "False", 09:33:11 INFO - "basedir": "/builds/slave/test", 09:33:11 INFO - "buildnumber": 82, 09:33:11 INFO - "slavename": "talos-linux64-ix-042", 09:33:11 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:33:11 INFO - "platform": "ubuntu64_hw", 09:33:11 INFO - "scheduler": "tests-autoland-linux64-talos", 09:33:11 INFO - "branch": "autoland", 09:33:11 INFO - "repo_path": "integration/autoland", 09:33:11 INFO - "revision": "a71e7db89da62e577f8995c886cc74272997a1bb", 09:33:11 INFO - "stage_platform": "linux64", 09:33:11 INFO - "builduid": "f6bae55b0339475eb21fce1e7ea4c052", 09:33:11 INFO - "slavebuilddir": "test" 09:33:11 INFO - } 09:33:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:33:11 INFO - Running post-action listener: _resource_record_post_action 09:33:11 INFO - [mozharness: 2016-07-20 16:33:11.228355Z] Finished read-buildbot-config step (success) 09:33:11 INFO - [mozharness: 2016-07-20 16:33:11.228445Z] Running download-and-extract step. 09:33:11 INFO - Running pre-action listener: _resource_record_pre_action 09:33:11 INFO - Running main action method: download_and_extract 09:33:11 INFO - mkdir: /builds/slave/test/build/tests 09:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:11 INFO - https://queue.taskcluster.net/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:33:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:33:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:33:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:33:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:33:11 INFO - Downloaded 1361 bytes. 09:33:11 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:33:11 INFO - Using the following test package requirements: 09:33:11 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:33:11 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:11 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:33:11 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:11 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:33:11 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:11 INFO - u'jsshell-linux-x86_64.zip'], 09:33:11 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:11 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:33:11 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:33:11 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:11 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:33:11 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:11 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:33:11 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:11 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:33:11 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:33:11 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:33:11 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:33:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:11 INFO - https://queue.taskcluster.net/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:33:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:33:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:33:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:33:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:33:13 INFO - Downloaded 22730093 bytes. 09:33:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:33:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:33:14 INFO - Return code: 0 09:33:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:14 INFO - https://queue.taskcluster.net/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:33:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:33:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:33:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:33:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:33:15 INFO - Downloaded 22730093 bytes. 09:33:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:33:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:33:17 INFO - Return code: 0 09:33:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:17 INFO - https://queue.taskcluster.net/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:33:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:33:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:33:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:33:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:33:18 INFO - Downloaded 14403317 bytes. 09:33:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:33:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:33:18 INFO - Return code: 0 09:33:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:18 INFO - https://queue.taskcluster.net/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:33:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:33:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:33:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:33:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:33:19 INFO - Downloaded 57143061 bytes. 09:33:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:33:19 INFO - mkdir: /builds/slave/test/properties 09:33:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:33:19 INFO - Writing to file /builds/slave/test/properties/build_url 09:33:19 INFO - Contents: 09:33:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:33:20 INFO - Running post-action listener: _resource_record_post_action 09:33:20 INFO - Running post-action listener: set_extra_try_arguments 09:33:20 INFO - [mozharness: 2016-07-20 16:33:20.453865Z] Finished download-and-extract step (success) 09:33:20 INFO - [mozharness: 2016-07-20 16:33:20.454097Z] Running populate-webroot step. 09:33:20 INFO - Running pre-action listener: _resource_record_pre_action 09:33:20 INFO - Running main action method: populate_webroot 09:33:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:33:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:33:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:33:20 INFO - '--tppagecycles', 09:33:20 INFO - '7'], 09:33:20 INFO - 'tests': ['tsvgm']}}, 09:33:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:33:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:33:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:33:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:33:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:33:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:33:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:20 INFO - 'talos_options': ['--disable-e10s'], 09:33:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:33:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:20 INFO - 'talos_options': ['--disable-e10s'], 09:33:20 INFO - 'tests': ['damp', 'tps']}, 09:33:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:20 INFO - 'tests': ['damp', 'tps']}, 09:33:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:33:20 INFO - 'tests': ['dromaeo_dom']}, 09:33:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:33:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:33:20 INFO - 'tests': ['basic_compositor_video']}, 09:33:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:33:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:33:20 INFO - 'tests': ['a11yr', 09:33:20 INFO - 'ts_paint', 09:33:20 INFO - 'tpaint', 09:33:20 INFO - 'sessionrestore', 09:33:20 INFO - 'sessionrestore_no_auto_restore', 09:33:20 INFO - 'tabpaint']}, 09:33:20 INFO - 'other-e10s': {'tests': ['a11yr', 09:33:20 INFO - 'ts_paint', 09:33:20 INFO - 'tpaint', 09:33:20 INFO - 'sessionrestore', 09:33:20 INFO - 'sessionrestore_no_auto_restore', 09:33:20 INFO - 'tabpaint']}, 09:33:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:33:20 INFO - 'ts_paint', 09:33:20 INFO - 'tpaint', 09:33:20 INFO - 'sessionrestore', 09:33:20 INFO - 'sessionrestore_no_auto_restore', 09:33:20 INFO - 'tabpaint']}, 09:33:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:33:20 INFO - 'ts_paint', 09:33:20 INFO - 'tpaint', 09:33:20 INFO - 'sessionrestore', 09:33:20 INFO - 'sessionrestore_no_auto_restore', 09:33:20 INFO - 'tabpaint']}, 09:33:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:33:20 INFO - 'tests': ['a11yr', 09:33:20 INFO - 'ts_paint', 09:33:20 INFO - 'tpaint', 09:33:20 INFO - 'sessionrestore', 09:33:20 INFO - 'sessionrestore_no_auto_restore', 09:33:20 INFO - 'tabpaint']}, 09:33:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:33:20 INFO - 'tests': ['a11yr', 09:33:20 INFO - 'ts_paint', 09:33:20 INFO - 'tpaint', 09:33:20 INFO - 'sessionrestore', 09:33:20 INFO - 'sessionrestore_no_auto_restore', 09:33:20 INFO - 'tabpaint']}, 09:33:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:33:20 INFO - 'tests': ['tsvgx', 09:33:20 INFO - 'tsvgr_opacity', 09:33:20 INFO - 'tart', 09:33:20 INFO - 'tscrollx', 09:33:20 INFO - 'cart']}, 09:33:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:33:20 INFO - 'tsvgr_opacity', 09:33:20 INFO - 'tart', 09:33:20 INFO - 'tscrollx', 09:33:20 INFO - 'cart']}, 09:33:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:20 INFO - 'talos_options': ['--disable-e10s'], 09:33:20 INFO - 'tests': ['tp5o']}, 09:33:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:20 INFO - 'tests': ['tp5o']}, 09:33:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:20 INFO - 'talos_options': ['--disable-e10s', 09:33:20 INFO - '--xperf_path', 09:33:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:20 INFO - 'tests': ['tp5n']}, 09:33:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:33:20 INFO - 'talos_options': ['--xperf_path', 09:33:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:33:20 INFO - 'tests': ['tp5n']}}, 09:33:20 INFO - 'talos.zip': {'path': '', 09:33:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:33:20 INFO - Running post-action listener: _resource_record_post_action 09:33:20 INFO - [mozharness: 2016-07-20 16:33:20.469717Z] Finished populate-webroot step (success) 09:33:20 INFO - [mozharness: 2016-07-20 16:33:20.469807Z] Running create-virtualenv step. 09:33:20 INFO - Running pre-action listener: _resource_record_pre_action 09:33:20 INFO - Running main action method: create_virtualenv 09:33:20 INFO - Creating virtualenv /builds/slave/test/build/venv 09:33:20 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 09:33:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:33:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:33:20 INFO - Using real prefix '/usr' 09:33:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:33:21 INFO - Installing distribute.............................................................................................................................................................................................done. 09:33:23 INFO - Installing pip.................done. 09:33:23 INFO - Return code: 0 09:33:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:23 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 0x1a0f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cce3b0>, '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 0x1ccf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', '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 09:33:23 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 09:33:23 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 09:33:23 INFO - Using env: {'DISPLAY': ':0', 09:33:23 INFO - 'HOME': '/home/cltbld', 09:33:23 INFO - 'LANG': 'en_US.UTF-8', 09:33:23 INFO - 'LANGUAGE': 'en_US:en', 09:33:23 INFO - 'LOGNAME': 'cltbld', 09:33:23 INFO - 'MAIL': '/var/mail/cltbld', 09:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:23 INFO - 'MOZ_NO_REMOTE': '1', 09:33:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:23 INFO - 'NO_EM_RESTART': '1', 09:33:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:23 INFO - 'PWD': '/builds/slave/test', 09:33:23 INFO - 'SHELL': '/bin/bash', 09:33:23 INFO - 'SHLVL': '1', 09:33:23 INFO - 'TERM': 'linux', 09:33:23 INFO - 'TMOUT': '86400', 09:33:23 INFO - 'USER': 'cltbld', 09:33:23 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', 09:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:23 INFO - '_': '/tools/buildbot/bin/python'} 09:33:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:23 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 09:33:23 INFO - Cleaning up... 09:33:23 INFO - Return code: 0 09:33:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:23 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 0x1a0f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cce3b0>, '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 0x1ccf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', '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 09:33:23 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 09:33:23 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 09:33:23 INFO - Using env: {'DISPLAY': ':0', 09:33:23 INFO - 'HOME': '/home/cltbld', 09:33:23 INFO - 'LANG': 'en_US.UTF-8', 09:33:23 INFO - 'LANGUAGE': 'en_US:en', 09:33:23 INFO - 'LOGNAME': 'cltbld', 09:33:23 INFO - 'MAIL': '/var/mail/cltbld', 09:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:23 INFO - 'MOZ_NO_REMOTE': '1', 09:33:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:23 INFO - 'NO_EM_RESTART': '1', 09:33:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:23 INFO - 'PWD': '/builds/slave/test', 09:33:23 INFO - 'SHELL': '/bin/bash', 09:33:23 INFO - 'SHLVL': '1', 09:33:23 INFO - 'TERM': 'linux', 09:33:23 INFO - 'TMOUT': '86400', 09:33:23 INFO - 'USER': 'cltbld', 09:33:23 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', 09:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:23 INFO - '_': '/tools/buildbot/bin/python'} 09:33:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:23 INFO - Downloading/unpacking psutil>=3.1.1 09:33:23 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 09:33:23 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 09:33:23 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 09:33:23 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 09:33:23 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 09:33:23 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 09:33:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:33:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:25 INFO - Installing collected packages: psutil 09:33:25 INFO - Running setup.py install for psutil 09:33:25 INFO - building 'psutil._psutil_linux' extension 09:33:25 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 09:33:25 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 09:33:25 INFO - building 'psutil._psutil_posix' extension 09:33:25 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 09:33:26 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 09:33:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:33:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:33:26 INFO - Successfully installed psutil 09:33:26 INFO - Cleaning up... 09:33:26 INFO - Return code: 0 09:33:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:26 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 0x1a0f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cce3b0>, '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 0x1ccf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', '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 09:33:26 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 09:33:26 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 09:33:26 INFO - Using env: {'DISPLAY': ':0', 09:33:26 INFO - 'HOME': '/home/cltbld', 09:33:26 INFO - 'LANG': 'en_US.UTF-8', 09:33:26 INFO - 'LANGUAGE': 'en_US:en', 09:33:26 INFO - 'LOGNAME': 'cltbld', 09:33:26 INFO - 'MAIL': '/var/mail/cltbld', 09:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:26 INFO - 'MOZ_NO_REMOTE': '1', 09:33:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:26 INFO - 'NO_EM_RESTART': '1', 09:33:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:26 INFO - 'PWD': '/builds/slave/test', 09:33:26 INFO - 'SHELL': '/bin/bash', 09:33:26 INFO - 'SHLVL': '1', 09:33:26 INFO - 'TERM': 'linux', 09:33:26 INFO - 'TMOUT': '86400', 09:33:26 INFO - 'USER': 'cltbld', 09:33:26 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', 09:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:26 INFO - '_': '/tools/buildbot/bin/python'} 09:33:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:33:26 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 09:33:26 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 09:33:26 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 09:33:26 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 09:33:26 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 09:33:26 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 09:33:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:33:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:33:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:33:28 INFO - Installing collected packages: mozsystemmonitor 09:33:28 INFO - Running setup.py install for mozsystemmonitor 09:33:28 INFO - Successfully installed mozsystemmonitor 09:33:28 INFO - Cleaning up... 09:33:28 INFO - Return code: 0 09:33:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:28 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 0x1a0f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cce3b0>, '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 0x1ccf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', '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 09:33:28 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 09:33:28 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 09:33:28 INFO - Using env: {'DISPLAY': ':0', 09:33:28 INFO - 'HOME': '/home/cltbld', 09:33:28 INFO - 'LANG': 'en_US.UTF-8', 09:33:28 INFO - 'LANGUAGE': 'en_US:en', 09:33:28 INFO - 'LOGNAME': 'cltbld', 09:33:28 INFO - 'MAIL': '/var/mail/cltbld', 09:33:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:28 INFO - 'MOZ_NO_REMOTE': '1', 09:33:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:28 INFO - 'NO_EM_RESTART': '1', 09:33:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:28 INFO - 'PWD': '/builds/slave/test', 09:33:28 INFO - 'SHELL': '/bin/bash', 09:33:28 INFO - 'SHLVL': '1', 09:33:28 INFO - 'TERM': 'linux', 09:33:28 INFO - 'TMOUT': '86400', 09:33:28 INFO - 'USER': 'cltbld', 09:33:28 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', 09:33:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:28 INFO - '_': '/tools/buildbot/bin/python'} 09:33:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:28 INFO - Downloading/unpacking jsonschema==2.5.1 09:33:28 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 09:33:28 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 09:33:28 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 09:33:28 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 09:33:28 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 09:33:28 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 09:33:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:33:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:30 INFO - Downloading functools32-3.2.3-2.tar.gz 09:33:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:33:30 INFO - warning: no files found matching '*.txt' 09:33:30 INFO - no previously-included directories found matching 'build' 09:33:30 INFO - no previously-included directories found matching 'dist' 09:33:30 INFO - no previously-included directories found matching '.git*' 09:33:30 INFO - Installing collected packages: jsonschema, functools32 09:33:30 INFO - Running setup.py install for functools32 09:33:31 INFO - warning: no files found matching '*.txt' 09:33:31 INFO - no previously-included directories found matching 'build' 09:33:31 INFO - no previously-included directories found matching 'dist' 09:33:31 INFO - no previously-included directories found matching '.git*' 09:33:31 INFO - Successfully installed jsonschema functools32 09:33:31 INFO - Cleaning up... 09:33:31 INFO - Return code: 0 09:33:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:31 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 0x1a0f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cce3b0>, '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 0x1ccf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', '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 09:33:31 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 09:33:31 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 09:33:31 INFO - Using env: {'DISPLAY': ':0', 09:33:31 INFO - 'HOME': '/home/cltbld', 09:33:31 INFO - 'LANG': 'en_US.UTF-8', 09:33:31 INFO - 'LANGUAGE': 'en_US:en', 09:33:31 INFO - 'LOGNAME': 'cltbld', 09:33:31 INFO - 'MAIL': '/var/mail/cltbld', 09:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:31 INFO - 'MOZ_NO_REMOTE': '1', 09:33:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:31 INFO - 'NO_EM_RESTART': '1', 09:33:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:31 INFO - 'PWD': '/builds/slave/test', 09:33:31 INFO - 'SHELL': '/bin/bash', 09:33:31 INFO - 'SHLVL': '1', 09:33:31 INFO - 'TERM': 'linux', 09:33:31 INFO - 'TMOUT': '86400', 09:33:31 INFO - 'USER': 'cltbld', 09:33:31 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', 09:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:31 INFO - '_': '/tools/buildbot/bin/python'} 09:33:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:31 INFO - Downloading/unpacking blobuploader==1.2.4 09:33:31 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 09:33:31 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 09:33:31 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 09:33:31 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 09:33:31 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 09:33:31 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 09:33:33 INFO - Downloading blobuploader-1.2.4.tar.gz 09:33:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:33:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:33:33 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 09:33:33 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 09:33:33 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 09:33:33 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 09:33:33 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 09:33:33 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 09:33:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:33:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:33:33 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 09:33:33 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 09:33:33 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 09:33:33 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 09:33:33 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 09:33:33 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 09:33:33 INFO - Downloading docopt-0.6.1.tar.gz 09:33:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:33:33 INFO - Installing collected packages: blobuploader, requests, docopt 09:33:33 INFO - Running setup.py install for blobuploader 09:33:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:33:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:33:34 INFO - Running setup.py install for requests 09:33:34 INFO - Running setup.py install for docopt 09:33:34 INFO - Successfully installed blobuploader requests docopt 09:33:34 INFO - Cleaning up... 09:33:34 INFO - Return code: 0 09:33:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:34 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 0x1a0f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cce3b0>, '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 0x1ccf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', '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 09:33:34 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 09:33:34 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 09:33:34 INFO - Using env: {'DISPLAY': ':0', 09:33:34 INFO - 'HOME': '/home/cltbld', 09:33:34 INFO - 'LANG': 'en_US.UTF-8', 09:33:34 INFO - 'LANGUAGE': 'en_US:en', 09:33:34 INFO - 'LOGNAME': 'cltbld', 09:33:34 INFO - 'MAIL': '/var/mail/cltbld', 09:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:34 INFO - 'MOZ_NO_REMOTE': '1', 09:33:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:34 INFO - 'NO_EM_RESTART': '1', 09:33:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:34 INFO - 'PWD': '/builds/slave/test', 09:33:34 INFO - 'SHELL': '/bin/bash', 09:33:34 INFO - 'SHLVL': '1', 09:33:34 INFO - 'TERM': 'linux', 09:33:34 INFO - 'TMOUT': '86400', 09:33:34 INFO - 'USER': 'cltbld', 09:33:34 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', 09:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:34 INFO - '_': '/tools/buildbot/bin/python'} 09:33:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:34 INFO - Running setup.py (path:/tmp/pip-k0f5jx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:34 INFO - Running setup.py (path:/tmp/pip-ImNzVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:34 INFO - Running setup.py (path:/tmp/pip-0WR2a5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:35 INFO - Running setup.py (path:/tmp/pip-ay1rlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:35 INFO - Running setup.py (path:/tmp/pip-a9vGJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:35 INFO - Running setup.py (path:/tmp/pip-3WMCzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:35 INFO - Running setup.py (path:/tmp/pip-n6aiQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:35 INFO - Running setup.py (path:/tmp/pip-_x1S5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:35 INFO - Running setup.py (path:/tmp/pip-_taUfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:35 INFO - Running setup.py (path:/tmp/pip-rtyQK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:35 INFO - Running setup.py (path:/tmp/pip-di9Iok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:35 INFO - Running setup.py (path:/tmp/pip-fXUiV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:35 INFO - Running setup.py (path:/tmp/pip-JPawa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:35 INFO - Running setup.py (path:/tmp/pip-vVW0CJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:35 INFO - Running setup.py (path:/tmp/pip-RfUwFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:35 INFO - Running setup.py (path:/tmp/pip-8LMVxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:35 INFO - Running setup.py (path:/tmp/pip-w4pD6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:33:36 INFO - Running setup.py install for manifestparser 09:33:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:33:36 INFO - Running setup.py install for mozcrash 09:33:36 INFO - Running setup.py install for mozdebug 09:33:36 INFO - Running setup.py install for mozdevice 09:33:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:33:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:33:36 INFO - Running setup.py install for mozfile 09:33:36 INFO - Running setup.py install for mozhttpd 09:33:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:33:36 INFO - Running setup.py install for mozinfo 09:33:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:33:36 INFO - Running setup.py install for mozInstall 09:33:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:33:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:33:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:33:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:33:36 INFO - Running setup.py install for mozleak 09:33:36 INFO - Running setup.py install for mozlog 09:33:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:33:37 INFO - Running setup.py install for moznetwork 09:33:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:33:37 INFO - Running setup.py install for mozprocess 09:33:37 INFO - Running setup.py install for mozprofile 09:33:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:33:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:33:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:33:37 INFO - Running setup.py install for mozrunner 09:33:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:33:37 INFO - Running setup.py install for mozscreenshot 09:33:37 INFO - Running setup.py install for moztest 09:33:37 INFO - Running setup.py install for mozversion 09:33:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:33:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:33:37 INFO - Cleaning up... 09:33:37 INFO - Return code: 0 09:33:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:37 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 0x1a0f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cce3b0>, '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 0x1ccf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', '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 09:33:37 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 09:33:37 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 09:33:37 INFO - Using env: {'DISPLAY': ':0', 09:33:37 INFO - 'HOME': '/home/cltbld', 09:33:37 INFO - 'LANG': 'en_US.UTF-8', 09:33:37 INFO - 'LANGUAGE': 'en_US:en', 09:33:37 INFO - 'LOGNAME': 'cltbld', 09:33:37 INFO - 'MAIL': '/var/mail/cltbld', 09:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:37 INFO - 'MOZ_NO_REMOTE': '1', 09:33:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:37 INFO - 'NO_EM_RESTART': '1', 09:33:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:37 INFO - 'PWD': '/builds/slave/test', 09:33:37 INFO - 'SHELL': '/bin/bash', 09:33:37 INFO - 'SHLVL': '1', 09:33:37 INFO - 'TERM': 'linux', 09:33:37 INFO - 'TMOUT': '86400', 09:33:37 INFO - 'USER': 'cltbld', 09:33:37 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', 09:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:37 INFO - '_': '/tools/buildbot/bin/python'} 09:33:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:33:38 INFO - Running setup.py (path:/tmp/pip-55Mxci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:33:38 INFO - Running setup.py (path:/tmp/pip-QRFFXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:33:38 INFO - Running setup.py (path:/tmp/pip-zwcTC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:33:38 INFO - Running setup.py (path:/tmp/pip-4LIOpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:33:38 INFO - Running setup.py (path:/tmp/pip-8aa_4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:33:38 INFO - Running setup.py (path:/tmp/pip-ngtcxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:33:38 INFO - Running setup.py (path:/tmp/pip-rMZtXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:33:38 INFO - Running setup.py (path:/tmp/pip-UNKW6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:33:38 INFO - Running setup.py (path:/tmp/pip-a4Ps8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:33:38 INFO - Running setup.py (path:/tmp/pip-So7Wk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:33:38 INFO - Running setup.py (path:/tmp/pip-nVzj8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:33:38 INFO - Running setup.py (path:/tmp/pip-QYEa0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:33:38 INFO - Running setup.py (path:/tmp/pip-UsrAmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:33:38 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)) 09:33:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:33:38 INFO - Running setup.py (path:/tmp/pip-qtNvpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:33:39 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)) 09:33:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:39 INFO - Running setup.py (path:/tmp/pip-alQ3og-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:33:39 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)) 09:33:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:33:39 INFO - Running setup.py (path:/tmp/pip-qbfoIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:33:39 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)) 09:33:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:33:39 INFO - Running setup.py (path:/tmp/pip-9zzMLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:33:39 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)) 09:33:39 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)) 09:33:39 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)) 09:33:39 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)) 09:33:39 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)) 09:33:39 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)) 09:33:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:33:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:33:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:33:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:33:41 INFO - Downloading blessings-1.6.tar.gz 09:33:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:33:41 INFO - Installing collected packages: blessings 09:33:41 INFO - Running setup.py install for blessings 09:33:41 INFO - Successfully installed blessings 09:33:41 INFO - Cleaning up... 09:33:41 INFO - Return code: 0 09:33:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:33:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:33:41 INFO - Reading from file tmpfile_stdout 09:33:41 INFO - Current package versions: 09:33:41 INFO - argparse == 1.2.1 09:33:41 INFO - blessings == 1.6 09:33:41 INFO - blobuploader == 1.2.4 09:33:41 INFO - docopt == 0.6.1 09:33:41 INFO - functools32 == 3.2.3-2 09:33:41 INFO - jsonschema == 2.5.1 09:33:41 INFO - manifestparser == 1.1 09:33:41 INFO - mozInstall == 1.12 09:33:41 INFO - mozcrash == 0.17 09:33:41 INFO - mozdebug == 0.1 09:33:41 INFO - mozdevice == 0.48 09:33:41 INFO - mozfile == 1.2 09:33:41 INFO - mozhttpd == 0.7 09:33:41 INFO - mozinfo == 0.9 09:33:41 INFO - mozleak == 0.1 09:33:41 INFO - mozlog == 3.3 09:33:41 INFO - moznetwork == 0.27 09:33:41 INFO - mozprocess == 0.23 09:33:41 INFO - mozprofile == 0.28 09:33:41 INFO - mozrunner == 6.12 09:33:41 INFO - mozscreenshot == 0.1 09:33:41 INFO - mozsystemmonitor == 0.3 09:33:41 INFO - moztest == 0.7 09:33:41 INFO - mozversion == 1.4 09:33:41 INFO - psutil == 3.1.1 09:33:41 INFO - requests == 1.2.3 09:33:41 INFO - wsgiref == 0.1.2 09:33:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:41 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 0x1a0f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cce3b0>, '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 0x1ccf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', '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 09:33:41 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 09:33:41 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 09:33:41 INFO - Using env: {'DISPLAY': ':0', 09:33:41 INFO - 'HOME': '/home/cltbld', 09:33:41 INFO - 'LANG': 'en_US.UTF-8', 09:33:41 INFO - 'LANGUAGE': 'en_US:en', 09:33:41 INFO - 'LOGNAME': 'cltbld', 09:33:41 INFO - 'MAIL': '/var/mail/cltbld', 09:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:41 INFO - 'MOZ_NO_REMOTE': '1', 09:33:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:41 INFO - 'NO_EM_RESTART': '1', 09:33:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:41 INFO - 'PWD': '/builds/slave/test', 09:33:41 INFO - 'SHELL': '/bin/bash', 09:33:41 INFO - 'SHLVL': '1', 09:33:41 INFO - 'TERM': 'linux', 09:33:41 INFO - 'TMOUT': '86400', 09:33:41 INFO - 'USER': 'cltbld', 09:33:41 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', 09:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:41 INFO - '_': '/tools/buildbot/bin/python'} 09:33:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:41 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)) 09:33:41 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)) 09:33:41 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)) 09:33:41 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)) 09:33:41 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)) 09:33:41 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)) 09:33:41 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)) 09:33:41 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)) 09:33:41 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)) 09:33:41 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)) 09:33:41 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)) 09:33:41 INFO - Cleaning up... 09:33:41 INFO - Return code: 0 09:33:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:33:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:33:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:33:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a0f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cce3b0>, '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 0x1ccf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf740>, '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': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', '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 09:33:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:33:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:33:41 INFO - Using env: {'DISPLAY': ':0', 09:33:41 INFO - 'HOME': '/home/cltbld', 09:33:41 INFO - 'LANG': 'en_US.UTF-8', 09:33:41 INFO - 'LANGUAGE': 'en_US:en', 09:33:41 INFO - 'LOGNAME': 'cltbld', 09:33:41 INFO - 'MAIL': '/var/mail/cltbld', 09:33:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:41 INFO - 'MOZ_NO_REMOTE': '1', 09:33:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:41 INFO - 'NO_EM_RESTART': '1', 09:33:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:41 INFO - 'PWD': '/builds/slave/test', 09:33:41 INFO - 'SHELL': '/bin/bash', 09:33:41 INFO - 'SHLVL': '1', 09:33:41 INFO - 'TERM': 'linux', 09:33:41 INFO - 'TMOUT': '86400', 09:33:41 INFO - 'USER': 'cltbld', 09:33:41 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', 09:33:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:41 INFO - '_': '/tools/buildbot/bin/python'} 09:33:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:33:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:33:42 INFO - Cleaning up... 09:33:42 INFO - Return code: 0 09:33:42 INFO - Running post-action listener: _resource_record_post_action 09:33:42 INFO - Running post-action listener: _start_resource_monitoring 09:33:42 INFO - Starting resource monitoring. 09:33:42 INFO - [mozharness: 2016-07-20 16:33:42.217043Z] Finished create-virtualenv step (success) 09:33:42 INFO - [mozharness: 2016-07-20 16:33:42.217558Z] Running install step. 09:33:42 INFO - Running pre-action listener: _resource_record_pre_action 09:33:42 INFO - Running main action method: install 09:33:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:33:42 INFO - Reading from file tmpfile_stdout 09:33:42 INFO - Detecting whether we're running mozinstall >=1.0... 09:33:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:33:42 INFO - Reading from file tmpfile_stdout 09:33:42 INFO - Output received: 09:33:42 INFO - Usage: mozinstall [options] installer 09:33:42 INFO - Options: 09:33:42 INFO - -h, --help show this help message and exit 09:33:42 INFO - -d DEST, --destination=DEST 09:33:42 INFO - Directory to install application into. [default: 09:33:42 INFO - "/builds/slave/test"] 09:33:42 INFO - --app=APP Application being installed. [default: firefox] 09:33:42 INFO - mkdir: /builds/slave/test/build/application 09:33:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:33:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:33:58 INFO - Reading from file tmpfile_stdout 09:33:58 INFO - Output received: 09:33:58 INFO - /builds/slave/test/build/application/firefox/firefox 09:33:58 INFO - Running post-action listener: _resource_record_post_action 09:33:58 INFO - [mozharness: 2016-07-20 16:33:58.879676Z] Finished install step (success) 09:33:58 INFO - [mozharness: 2016-07-20 16:33:58.879954Z] Running run-tests step. 09:33:58 INFO - Running pre-action listener: _resource_record_pre_action 09:33:58 INFO - Running main action method: run_tests 09:33:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:33:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:33:58 INFO - Python 2.7.3 09:33:58 INFO - Return code: 0 09:33:58 INFO - grabbing minidump binary from tooltool 09:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:33:58 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 0x1cce3b0>, '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 0x1ccf2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ccf740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:33:58 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 09:33:58 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 09:33:58 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 09:33:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:33:58 INFO - Return code: 0 09:33:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:33:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:33:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:33:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:33:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:33:58 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/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 09:33:58 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/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --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 09:33:58 INFO - Using env: {'DISPLAY': ':0', 09:33:58 INFO - 'HOME': '/home/cltbld', 09:33:58 INFO - 'LANG': 'en_US.UTF-8', 09:33:58 INFO - 'LANGUAGE': 'en_US:en', 09:33:58 INFO - 'LOGNAME': 'cltbld', 09:33:58 INFO - 'MAIL': '/var/mail/cltbld', 09:33:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:33:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:33:58 INFO - 'MOZ_NO_REMOTE': '1', 09:33:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:33:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:33:58 INFO - 'NO_EM_RESTART': '1', 09:33:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:33:58 INFO - 'PWD': '/builds/slave/test', 09:33:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:33:58 INFO - 'SHELL': '/bin/bash', 09:33:58 INFO - 'SHLVL': '1', 09:33:58 INFO - 'TERM': 'linux', 09:33:58 INFO - 'TMOUT': '86400', 09:33:58 INFO - 'USER': 'cltbld', 09:33:58 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747', 09:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:33:58 INFO - '_': '/tools/buildbot/bin/python'} 09:33:58 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/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 09:33:59 INFO - mozversion application_buildid: 20160720075824 09:33:59 INFO - mozversion application_changeset: a71e7db89da62e577f8995c886cc74272997a1bb 09:33:59 INFO - mozversion application_display_name: Nightly 09:33:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:33:59 INFO - mozversion application_name: Firefox 09:33:59 INFO - mozversion application_remotingname: firefox 09:33:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:33:59 INFO - mozversion application_vendor: Mozilla 09:33:59 INFO - mozversion application_version: 50.0a1 09:33:59 INFO - mozversion platform_buildid: 20160720075824 09:33:59 INFO - mozversion platform_changeset: a71e7db89da62e577f8995c886cc74272997a1bb 09:33:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:33:59 INFO - mozversion platform_version: 50.0a1 09:33:59 INFO - using testdate: 1469032439 09:33:59 INFO - actual date: 1469032439 09:33:59 INFO - starting webserver on 'localhost:58891' 09:33:59 INFO - SUITE-START | Running 2 tests 09:33:59 INFO - TEST-START | dromaeo_css 09:33:59 INFO - operating with platform_type : linux_ 09:33:59 INFO - Initialising browser for dromaeo_css test... 09:33:59 INFO - TEST-INFO | started process 29851 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkYsX8F/profile http://localhost:58891/getInfo.html) 09:34:03 INFO - PROCESS | 29851 | __metrics Screen width/height:1600/1200 09:34:03 INFO - PROCESS | 29851 | colorDepth:24 09:34:03 INFO - PROCESS | 29851 | Browser inner width/height: 1024/697 09:34:03 INFO - PROCESS | 29851 | __metrics 09:34:04 INFO - PROCESS | 29851 | 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" 09:34:04 INFO - PROCESS | 29851 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:05 INFO - TEST-INFO | 29851: exit 0 09:34:05 INFO - Browser initialized. 09:34:05 INFO - Running cycle 1/1 for dromaeo_css test... 09:34:05 INFO - TEST-INFO | started process 29933 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkYsX8F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:17 INFO - PROCESS | 29933 | RSS: Main: 158392320 09:34:17 INFO - PROCESS | 29933 | 09:35:08 INFO - PROCESS | 29933 | 1469032508946 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] 09:37:20 INFO - PROCESS | 29933 | Cycle 1(1): loaded http://localhost:58891/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58891/tests/dromaeo/cssquery-ext.html) 09:37:22 INFO - PROCESS | 29933 | RSS: Main: 240238592 09:37:22 INFO - PROCESS | 29933 | 09:40:24 INFO - PROCESS | 29933 | Cycle 1(1): loaded http://localhost:58891/tests/dromaeo/cssquery-ext.html (next: http://localhost:58891/tests/dromaeo/cssquery-jquery.html) 09:40:25 INFO - PROCESS | 29933 | RSS: Main: 248594432 09:40:25 INFO - PROCESS | 29933 | 09:43:27 INFO - PROCESS | 29933 | Cycle 1(1): loaded http://localhost:58891/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58891/tests/dromaeo/cssquery-mootools.html) 09:43:28 INFO - PROCESS | 29933 | RSS: Main: 223657984 09:43:28 INFO - PROCESS | 29933 | 09:46:30 INFO - PROCESS | 29933 | Cycle 1(1): loaded http://localhost:58891/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58891/tests/dromaeo/cssquery-prototype.html) 09:46:31 INFO - PROCESS | 29933 | RSS: Main: 231669760 09:46:31 INFO - PROCESS | 29933 | 09:49:34 INFO - PROCESS | 29933 | Cycle 1(1): loaded http://localhost:58891/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58891/tests/dromaeo/cssquery-yui.html) 09:49:34 INFO - PROCESS | 29933 | RSS: Main: 190513152 09:49:34 INFO - PROCESS | 29933 | 09:52:38 INFO - PROCESS | 29933 | Cycle 1(1): loaded http://localhost:58891/tests/dromaeo/cssquery-yui.html (next: http://localhost:58891/tests/dromaeo/cssquery-dojo.html) 09:52:38 INFO - PROCESS | 29933 | RSS: Main: 267210752 09:52:38 INFO - PROCESS | 29933 | 09:52:38 INFO - PROCESS | 29933 | __start_tp_report 09:52:38 INFO - PROCESS | 29933 | _x_x_mozilla_page_load 09:52:38 INFO - PROCESS | 29933 | _x_x_mozilla_page_load_details 09:52:38 INFO - PROCESS | 29933 | |i|pagename|runs| 09:52:38 INFO - PROCESS | 29933 | |0;dojo.html;2608.608708693696;2614.816962812604;2640.0363867188476;2652.8673566321677;2660.295021819218;4653.325267836161;4712.976435117824;4729.196036673769;4752.287156926454;4768.952310476899;4754.239321708527;4793.784279707413;4799.5680388765;4800.735959522225;4846.563809257166;4323.329883868006;4347.956520434803;4431.889202769924;4488.37162797207;4520.434945631796;4402.31764076567;4470.284754439291;4478.126765280772;4487.169873573399;4498.50516443191;4280.892977675552;4291.97854010731;4296.74219546825;4357.302831546937;4404.559544045602;4371.125774845043;4377.890552736175;4415.183191109623;4440.023194897118;4497.370368148462;4363.956360436387;4407.823687052514;4408.625266852321;4442.178197033559;4459.487158976727;4817.205161148407;4865.099956508057;4871.683340582851;4872.439669438025;4882.2432522959;4981.775820088104;5053.620978426633;5063.8480845574695;5064.848054558369;5135.820246291362;5078.974605126988;5107.080725469414;5132.255822905658;5139.9206166705035;5142.125838607446;4626.467956185082;4789.353437285957;4794.760261986957;4844.006978569385;4853.053654537402;5181.896362072737;5236.345456817898;5251;5305.469453054741;5316.282301889196;5036.043151801146;5037.874053148701;5075.390953085654;5087.745612719424;5088.821891233789;4868.756562171877;5110.974445127825;5167.9224811627855;5169.198774189931;5175.8095638002715;5032.672876263031;5078.898422031539;5084.847454576368;5126.769295381717;5147.047796157723;5040.747962601855;5045.445001049881;5045.949540504548;5050.924236136461;5161.561267292323;4423.8451654192595;4428.3800267962515;4436.423264975532;4454.309582014793;4483.820647174141;3690.2619476104887;3719.814009299524;3753.7560058596105;3815.2178803345246;3855.6722678572073;4432.246518091933;4432.401625780542;4433.312836510346;4485.304777759453;4486.663500237495;5145.4854514549;5153.433122356538;5157.922631160535;5169.198774190007;5204.843854684365;4308.159908817813;4369.890752731207;4379.233634114017;4418.5802348776815;4429.047754732748;4693.9765301173275;4697.6241900647865;4704.905901882012;4719;4720.645951553647;4856.587190088882;4881.023795240895;4887.633427492881;4892.4373697023875;4921.753912304371;5004.899902002013;5096.057229412422;5099.92350114756;5183;5229.738513074407;4871.878203044952;4886.096072226507;4886.096072226649;4905.190643543918;4909.361782968192;5080.46655101209;5081.669691470042;5085.00842335741;5100.974495127501;5141.948580514147;4991.80032798684;5010.674306170087;5051.570616497639;5145.202493613496;5156.716380599103;4893.951060489349;4950.628702847373;4955.108080545537;4968.652194346361;4980.05378977993;4911.7298548580175;4928.704277743347;4938.1358262304675;4940.456549779449;4988.575971042502;4725.1967165581245;4864.902701946012;4875.439324477588;4922.1140194765285;4926.187179115406;4945.8021679132435;5001.649884508195;5004.374453193351;5035;5053.823116190915;4969.925451118163;4972.7762250699525;4976.0794253063295;4977.104121258209;5033.5721463676 09:52:38 INFO - PROCESS | 29933 | |1;ext.html;566.8129517259305;577.7457918515853;611.0833749375936;614.7448808744372;626.3235705438125;27310;30377.544336834966;30502.847485762595;40469;41967;43109.78445107778;43467.78266108665;43481.34777978325;43583.128337433234;43623.34564981528;7991.560464174483;8129.349652027823;8129.5528745918955;8133;8133.10535841057;7520;7968.8406231875615;8060.234277743634;9986.151177149915;10153.847692284622;10399.688009359732;10484.737881552985;10486.737831554234;10584.629537966173;10586.047255746982;10416.583336666563;10490.318129321646;10498.422586757726;10499.842502362471;10499.895001050007;7519.097708274987;10107.545160467724;10330.7417314567;10385.065344119066;10470;40354.59645403567;40749.79625101885;40778.592214078075;40787.59212407868;40797.38803917944;3014.5628883811723;3714.5542534895717;3719.256148770257;3745.8688945887034;3794.260119276742;29559.556606650916;29929;30304.54543181832;30313.090607281818;30321;3532.6643968822923;3547.308274886398;3586.1214002569536;3646.908827279313;3694.649008344203;259491;260454;260808;263153;263371.9999999981;55544;55705.44294557084;55912.720436397554;55922.72038639781;56029.719851400485;59285.40714592799;59303;59355.109673354935;59378;59471.70264148652;14922.70154596902;15195.088294702353;15211.239438028055;15218.086914785148;15226.847731522765;11360.602378916697;11423.771524569629;11434.142439317084;11459.942700286445;11460.54157833694;14864.175806596711;15288.38846446152;16749.906261717286;17063.23215455283;17182.19586943378;5783.797567085216;5814;5860.941390586125;5885.705714714247;5964.254468191476;835.6448509383488;862.9332748068341;867.4708427859001;883.1874675298741;983.1692220074026;27718.3070423237;28858.85570572176;29152.41695166127;29620.407591848474;29722.40555188884;969.8254314223508;985.2905907746411;1004.2668851738272;1034.5189486888632;1078.385320367383;15146.848531514544;15382.769258461132;15573.454929077654;15813.68372632541;15865.127417991891;383307;383519;383600;383989;385078;50513.74743126261;50647.493525065016;50703.492965069876;50796.74601626968;50862.23706644403;1076.7523469601879;1156.7223866272034;1175.6766889105393;1201.0871737479404;1204.6205445284706;28418.857905710338;29847.253818654706;30158;30262.54606180953;30668.386632267677;43209.56790432055;43715;43715.56284437242;43787.562124378346;43813.56186438095;298655;299757;300359;300820;300973;32354.83822580966;32745.67254327426;32843.83578082094;33585.664143358255;36654.74298479124;36201.094972624844;36393.63606363902;36398;43334.34998475089;43976.78011609922;34903;36104.458433123;36178.63821361752;37150.628493714714;38708.41937370998;36335.636643633225;36764.080897977765;36897;37213.44179837251;37546.8122659385;35463;35628.46557301695;36442.8177859109;36659.266814663024;36706.4494032595;31010.534841976936;32669.346613068083;33661.83169084139;33758.13055890277;33852.32295354128;24947.126850560144;25631.487370252864;25721.485570288114;25735;25747.227583172535 09:52:38 INFO - PROCESS | 29933 | |2;jquery.html;341.6259196180182;362.60476081071636;363.4403019350201;363.6054880454705;387.6956589077575;12978.41597128129;16173.676526469464;16732.916335418307;18586;18721;17061.57346066349;17413;17646.911765441157;17845.7323140153;17867.553311167227;4947.406311242656;5216.452272511388;5295.126304159829;5297.44376840432;5304.946950530503;3771.7925514096714;5210.088234558956;5287.154055351145;5867.76528938842;6197.597156184834;6223.097650840626;6244.781432649858;6246.656433896134;6252.468540174091;6258.561900666955;6026.8493287667725;6170.043643235305;6183.814485565395;6194.845128871769;6201;3439.2949445363643;5039.269305950654;5452.700101494416;5452.945470545323;5462.535684466825;20680.58638827215;21691.67462488064;21839;21865;22109.557808843732;1849.8057703941026;1913.913873875665;1964.499052741556;2160.805527502532;2295.5064661097776;13165.144265622705;13704.725905481935;13713.657158571015;13731.189046050451;14180;1699.7960244770584;1723.7845269341333;1758.516407987801;1764.0738612228554;1814.4012475882928;61064;65304.67347663231;76351.12839844497;76801.61599192025;79106.20893791046;9492.572834222477;9630.374025688308;9640.951795240979;9759.316847820726;9792.657256995972;13155.60533184006;13192.823184174697;13205.405756740969;13215.33923303831;13232.13991090576;7158.319959603829;7177.282271772781;7411.258874112654;7413.332800047914;7571.507851989603;7131.429485641136;7155.67799449026;7165.498415110997;7344.632768361561;7376.631168441664;6003.909941350883;6097.512199024068;6137.3862613738265;6218.347073557302;6329.715162817684;1678.2951160512614;1713.5973046334102;1749.4748082613544;1809.2853322937628;1820.4356649438716;379.24340939825225;380.43695330910407;404.92913740095366;407.5964794853074;650.9218893732782;10384.42885641297;10533.894661053291;10797.892021079688;11263.26788758728;11364.94317528407;394.56575348608015;408.33033824527797;415.1924506834239;419.13867003308496;422.7823867262265;5661.915071273934;5688.516476099495;5753.971230143822;5829.562782791308;5982.371850955587;428218;434552;437043;441005;445649;46502.30246546304;47680.7615961918;47846;48675.513244867805;55905;414.23366771472536;421.3868820865694;422.06933711166806;423.68435515540506;424.4333814357805;9800.31397802147;9885.901140988499;10019.198464123;10023.849642255225;10043.4476103815;36426;37039.25921481609;37496.6250337504;38350.23299534049;38559.228815422975;47357.289640656105;51025.48974510207;51063.48936510736;53029.46970530245;54883.72558137184;7705.1909549496495;7789.64946577394;7810.414218933716;7932.325752311118;8157.2250636189465;11062.557497700323;12061.879381206076;12119.81820272679;12290.20113692607;12505.249685018927;10269;10316.27786054969;10443.321184123008;10929.945350273198;11078.833817492905;9350.859737104085;9438.480883551196;9661.893337704243;10205.591776328863;10286.794264114826;10119.848202276824;10157.898421015696;10602.628907988183;10778.784424311312;10920.399378034284;7358.779236622696;7544.924550754422;7601.65792539326;7752.92247077522;8044.115147333906;9482.478463684563;10217.23370747182;10286.331388459726;10772.622958196425;11027.61403350879 09:52:38 INFO - PROCESS | 29933 | |3;mootools.html;1402.060619385012;1444.3644796289634;1542.5835024543373;1665.3838079910436;1758.6570618729336;7586.279303466169;8128.983877015373;8129.390295727814;8137.796555086125;8141.877871831927;6939.0979172707475;7528.623568821551;7794.181610930856;7800.453968222227;7986.640601172947;2227.3986023773573;2258.627326491127;2261.389424855288;2264.230161745006;2264.320703788865;2182.334388011656;2224.377174391173;2240.4622890506243;2244.9775502245016;2245.1805091141778;1893.5266183454137;2168.8481806273567;2230.308604332662;2233.932982010542;2234.54191890662;2205.514786746913;2221.8666879987254;2226.7773222677765;2228.687983682286;2229.2866282789514;2151.4836439254623;2176.4994051368276;2181.4110190248543;2181.520065585569;2182.694422780812;8057.717979870735;8100.473469224482;8111.43219974602;8114.188581141898;8116.715914942949;4674.626029917598;4681.204195286776;4727.929081063752;4731.858044258678;4812.037592481518;2759.0619189475474;2842.2752198189537;2848.5157523220896;2852.686204517501;2862.370278538739;922.5110691333537;952.6665667016489;964.2527041542791;973.6154219083493;1011.8381059030594;11000.504977276041;11016.283941543774;11037.668869933916;11042.503087361169;11049.121649319635;4069.491313585802;4206.179794939957;4329.76186309756;4351.912961740747;4356.237658409766;9244.676436324697;9247.0752924707;9358.15776580111;9360.859587106199;9363.297752668577;4734.171519983989;4753.667243292936;4759.928601070987;4766.666333356632;4791.329213910055;3622.8913132606062;3803.1633040730994;3930.331843586597;4398.3842262083335;4402.581754733295;3607.603163651996;3770.208256266215;3773.3019391412677;3775;3795.791231482295;1156;1174.2503669532334;1180.1620849196995;1186.4483015397882;1189.8393716848288;672.414998950906;797.4298376660715;804.7263930263739;819.3486178488116;827.962741676626;5156;5192.1952097424955;5229.764660590283;5266.052110620049;5270.8155214567305;366.13774560909206;372.94778730977686;380.9104860357814;390.79287977372036;391.3718481836668;3205.8557364918634;3263.4288999425007;3289.819059951678;3347.9982841010005;3350.0954742219465;9468.337216394924;9915;9997.150242229347;9998.850017249748;10008.549615267331;9739.97730238329;9939.453330066772;9946.303758736818;9960.203183745283;9974.80050398988;7487.288707572772;7641.579713115832;7642.388608911274;7642.656080476392;7647.579383133981;5130.384353877409;5168.302279192261;5169.896602067863;5185.092608793521;5393.541548968382;9069.818603627758;9100.45397276139;9110.544472776468;9194;9293.72118836436;9235.07649235071;9251.074892510695;9411.529423528933;9486.288528360263;9545.331826772337;6994.440444764508;7010.228874823867;7070.858582828418;7072.9646351767915;7073.964630176816;7625.733099341718;7653.464257502144;7679.846403071795;7705.113911899979;7709.113451953097;6790.5925644461495;6961.095057642474;6961.89557156633;6997.7200911962955;7169.247229040876;6769.221539522821;6861.416779573792;6871.415929646036;6887.001384799276;6976.093107895941;6449.7420103195345;6513.446357059703;6552.475801935739;6585.506087043395;6594.868102638016;4542.046170304141;4551.06703125865;4577.56513131252;4649.186392381387;4698.765061746967;4299.548547402603;4525.683202175816;4581.496035436033;4641.141388843075;4821.276808478756 09:52:38 INFO - PROCESS | 29933 | |4;prototype.html;300.3437489086346;300.4126931848236;307.9491883839167;309.8125633991435;311.38346074771965;4704.270838020112;4834.468208497063;4845.709257444556;4848.563629273364;4850.5391987761195;4653.1856925038155;4790.281457781334;4792.579747227645;4798.0643774464;4816.157172494817;3816.255830113129;3826.7129965252716;3833.8466461341513;3846.3461211594026;3846.903827404309;3640.8361623726864;3853;3856;3871.8257678404398;3878.204967981571;3641.5448068991377;3806.5432148142263;3830.3105441020743;3833.290841194388;3846.9230615387673;3825.3688141456764;3825.9426108608395;3830.5020347354944;3836.558795738498;3837.6354246346555;3406.625271220164;3411.4712219606;3415.316936612687;3421.2815308785184;3427.3488037272837;4531.660125490568;4741.762911854394;4743.430788305391;4751.334813126131;4761.452432970218;4332.783360831977;4341.240282950471;4345.34819777036;4354.281543545311;4355.107203023373;5097.133487307166;5105.744712764347;5112.156494178456;5113.744312784346;5133.512316329943;3536.8585256589704;3631.4371272452813;3633.94549081764;3640;3657.9817100914593;5253.6322457428;5323.520883120537;5325.174597937326;5328.6269961102735;5342.786288548454;3727.422249551324;3731.003912906582;3735;3745.438184272381;3746.4754934309217;3791.7156213284115;3794.487744154559;3804.3912973924594;3804.752691075071;3811.7712937223855;4240.787960601958;4296.957030429656;4299.892502687458;4306.557303303366;4314.848980285675;5071.39143302806;5105.540501354822;5117.897642047138;5126.615503837227;5155.9742201288755;1246.0903540415366;1271.7011502296953;1308.4700696218047;1315.2503073248347;1321.3063141850605;874.8643960186181;889.9332550058772;899.822429720079;900.9189172974463;905.7237542549453;4501.617362524156;4509.2559727644575;4527.547245275446;4540.682152249311;4552.931706024412;5231.293775340357;5236.120133926325;5255.948810237891;5267.3152490176035;5270.341207349082;4315.395844581761;4439.933400998988;4483.686141970031;4490.842820501267;4497.505274419811;2466.8273220874366;2480.940819120126;2488.8133389995824;2498.0382552717333;2514.559952008391;4339.869803905888;4392.516823149451;4408.53710360414;4431.468223813099;4442.422485076984;4708;5066.46802085783;5394.460553944574;5426.511613954763;5430.375506816767;4994.800207991712;5013;5028.2457631354855;5036.496350365081;5045.293658887758;5203.81786637481;5365.543928766098;5385.40760516335;5397.9460205397445;5412.673894895775;5169.991851588904;5181.248718935658;5190.325257716473;5194.649391158402;5199.610029247746;4965.329680493087;5006.849794506171;5010.398752149766;5016.297718319511;5023.572996295356;3746.7939263340777;3760.172761992357;3762.7177961652437;3768.849246030128;3787.3182827091387;5111.7188554628365;5237.8428647140645;5248.501392367719;5262.421133675216;5280.894382112259;5095.719735414587;5097.61767867404;5120.436751957207;5124.128898087409;5134.691918484754;4974.602031837372;5114.258432527337;5127.333446652061;5128.564072053768;5163.690178589297;4806.807727690853;4893.412790465167;5022.372203474566;5055.368078990126;5104.846854594368;5064.265681476239;5074.67014644047;5075.401248606825;5080.46655101209;5081.390233172043;4996.450390456974;5003.374578177728;5005.349304590525;5011.523905228997;5018.623603229699 09:52:38 INFO - PROCESS | 29933 | |5;yui.html;631.3654776949165;631.4222486424921;653.7711800869694;682.6927882452895;690.7893092606756;13118.47526098956;14154.929225353884;14277.428902843874;14277.785833212498;14285.714285714306;13652.38564264608;13758.899288056957;13774;13819.447222111103;13834.100783449094;626.4581137316211;642.1267076775581;781.2226834299879;782.2881178127905;797.3421926910294;789.9526028438283;803.9839203215932;808.8422757562278;808.9514629122242;809.7125520440247;849.8937632795901;853.607340623314;855.8159995600945;857.014433401587;857.1728282207669;656.9277379488252;808.6078252047752;834.8205135895751;845.602566793606;847.0343808058818;618.9845253868644;619.6003577692363;619.8171539395889;621.5897507644933;621.7606221604715;13312.600621981355;13376.197428154343;13379.063465557414;13446.260455674934;13481.59555213345;12450.377481125908;12677.873221267762;12737.172083814523;13071.411786469536;13445.86554134456;11840.822387664191;11850.525978960832;12028.759424811455;12176.756464870654;12667.11330206886;4063.227986682521;4086.7983756446406;4142.337226043848;4225.19721252961;4235.152969406131;62650.68674656643;62990;63270.68364658147;63780.68109659422;63882.0417693735;9944.651937182163;10025.34835235722;10153.13698335635;10269.94865025685;10421.218408619385;17784;18168.27326906909;18386.81613183878;18390.264389424272;18568.535786605175;6505.4795616350575;6535.607863528204;6641.601503909781;6793.082933803873;6864.4165245954655;3664.9633503664622;3684.613115622875;3691.9446208306895;3706.29580379727;3714.591394946554;2915.548090046046;3017.3814368054495;3022.5012872875727;3034.575159477675;3295.110320213529;578.0606514414077;588.5968111843401;589.0751520113381;593.9821805345847;602.8944934636509;429.9644509761097;518.5410911343413;533.2933862632028;565.9377468478464;566.7902875935848;8428.662853485914;8552.529610871461;8697.869531957025;8869.68956086534;9055.501947392957;445.56557356577724;573.6070791507831;582.2256398989334;582.5572564850744;585.3765739487433;3943.4084887266567;3997.120633460634;4015.0966032643005;4195.077083041726;4330.040905916442;101630;101915;104009.47995260126;106219.46890265653;106793.4660326709;86567;87535;88682.55658721794;90516;92352;76425;77025.61487192528;78511.60744196242;78850.6057469709;80009.59995199987;7988.400869934894;7997.280244777998;8132.6746930122135;8269.545175015191;8383.245507904317;45764.31353529633;46392.30411543897;46814.53185468238;47092.29361559648;47981;64320.67839660959;68719.65640171767;68805;69391.65304173446;71451.64274178595;9136.817263654822;9453.858192126985;9529.332946693665;9817.410955342702;9963.25275604318;11649.650510484698;11871.881281187423;12100.273983561014;12281.815772763595;12402.75194496123;10208.642697505551;10292.845607315747;10563.260571760211;10930.070943969851;10970.616028438966;10787.298825576312;10868.836967445337;10878.782424351626;11339.376334301693;11359.886401135884;10262.794744105226;10592.682219533426;10779.083777878654;11112.444377780917;11174.720631984266;8075.515469071875;8313.376496762647;8384.496930184208;8399.580020999048;8570.142985701379;9029.729108126767;9272.6290948364;9330.30022748283;9528.380655257386;10073.254597099658 09:52:38 INFO - PROCESS | 29933 | __end_tp_report 09:52:38 INFO - PROCESS | 29933 | __start_cc_report 09:52:38 INFO - PROCESS | 29933 | _x_x_mozilla_cycle_collect,1004 09:52:38 INFO - PROCESS | 29933 | __end_cc_report 09:52:38 INFO - PROCESS | 29933 | __startTimestamp1469033558440__endTimestamp 09:52:38 INFO - PROCESS | 29933 | 09:52:38 INFO - PROCESS | 29933 | ------- Summary: start ------- 09:52:38 INFO - PROCESS | 29933 | Number of tests: 6 09:52:38 INFO - PROCESS | 29933 | 09:52:38 INFO - PROCESS | 29933 | [#0] dojo.html Cycles:165 Average:4738.14 Median:4886.10 stddev:498.42 (10.2%) stddev-sans-first:471.13 09:52:38 INFO - PROCESS | 29933 | Values: 2608.6 2614.8 2640.0 2652.9 2660.3 4653.3 4713.0 4729.2 4752.3 4769.0 4754.2 4793.8 4799.6 4800.7 4846.6 4323.3 4348.0 4431.9 4488.4 4520.4 4402.3 4470.3 4478.1 4487.2 4498.5 4280.9 4292.0 4296.7 4357.3 4404.6 4371.1 4377.9 4415.2 4440.0 4497.4 4364.0 4407.8 4408.6 4442.2 4459.5 4817.2 4865.1 4871.7 4872.4 4882.2 4981.8 5053.6 5063.8 5064.8 5135.8 5079.0 5107.1 5132.3 5139.9 5142.1 4626.5 4789.4 4794.8 4844.0 4853.1 5181.9 5236.3 5251.0 5305.5 5316.3 5036.0 5037.9 5075.4 5087.7 5088.8 4868.8 5111.0 5167.9 5169.2 5175.8 5032.7 5078.9 5084.8 5126.8 5147.0 5040.7 5045.4 5045.9 5050.9 5161.6 4423.8 4428.4 4436.4 4454.3 4483.8 3690.3 3719.8 3753.8 3815.2 3855.7 4432.2 4432.4 4433.3 4485.3 4486.7 5145.5 5153.4 5157.9 5169.2 5204.8 4308.2 4369.9 4379.2 4418.6 4429.0 4694.0 4697.6 4704.9 4719.0 4720.6 4856.6 4881.0 4887.6 4892.4 4921.8 5004.9 5096.1 5099.9 5183.0 5229.7 4871.9 4886.1 4886.1 4905.2 4909.4 5080.5 5081.7 5085.0 5101.0 5141.9 4991.8 5010.7 5051.6 5145.2 5156.7 4894.0 4950.6 4955.1 4968.7 4980.1 4911.7 4928.7 4938.1 4940.5 4988.6 4725.2 4864.9 4875.4 4922.1 4926.2 4945.8 5001.6 5004.4 5035.0 5053.8 4969.9 4972.8 4976.1 4977.1 5033.6 09:52:38 INFO - PROCESS | 29933 | 09:52:38 INFO - PROCESS | 29933 | [#1] ext.html Cycles:180 Average:47947.65 Median:19607.29 stddev:83796.71 (427.4%) stddev-sans-first:83956.27 09:52:38 INFO - PROCESS | 29933 | Values: 566.8 577.7 611.1 614.7 626.3 27310.0 30377.5 30502.8 40469.0 41967.0 43109.8 43467.8 43481.3 43583.1 43623.3 7991.6 8129.3 8129.6 8133.0 8133.1 7520.0 7968.8 8060.2 9986.2 10153.8 10399.7 10484.7 10486.7 10584.6 10586.0 10416.6 10490.3 10498.4 10499.8 10499.9 7519.1 10107.5 10330.7 10385.1 10470.0 40354.6 40749.8 40778.6 40787.6 40797.4 3014.6 3714.6 3719.3 3745.9 3794.3 29559.6 29929.0 30304.5 30313.1 30321.0 3532.7 3547.3 3586.1 3646.9 3694.6 259491.0 260454.0 260808.0 263153.0 263372.0 55544.0 55705.4 55912.7 55922.7 56029.7 59285.4 59303.0 59355.1 59378.0 59471.7 14922.7 15195.1 15211.2 15218.1 15226.8 11360.6 11423.8 11434.1 11459.9 11460.5 14864.2 15288.4 16749.9 17063.2 17182.2 5783.8 5814.0 5860.9 5885.7 5964.3 835.6 862.9 867.5 883.2 983.2 27718.3 28858.9 29152.4 29620.4 29722.4 969.8 985.3 1004.3 1034.5 1078.4 15146.8 15382.8 15573.5 15813.7 15865.1 383307.0 383519.0 383600.0 383989.0 385078.0 50513.7 50647.5 50703.5 50796.7 50862.2 1076.8 1156.7 1175.7 1201.1 1204.6 28418.9 29847.3 30158.0 30262.5 30668.4 43209.6 43715.0 43715.6 43787.6 43813.6 298655.0 299757.0 300359.0 300820.0 300973.0 32354.8 32745.7 32843.8 33585.7 36654.7 36201.1 36393.6 36398.0 43334.3 43976.8 34903.0 36104.5 36178.6 37150.6 38708.4 36335.6 36764.1 36897.0 37213.4 37546.8 35463.0 35628.5 36442.8 36659.3 36706.4 31010.5 32669.3 33661.8 33758.1 33852.3 24947.1 25631.5 25721.5 25735.0 25747.2 09:52:38 INFO - PROCESS | 29933 | 09:52:38 INFO - PROCESS | 29933 | [#2] jquery.html Cycles:180 Average:24749.66 Median:421.73 stddev:71630.31 (16985.0%) stddev-sans-first:71807.81 09:52:38 INFO - PROCESS | 29933 | Values: 341.6 362.6 363.4 363.6 387.7 12978.4 16173.7 16732.9 18586.0 18721.0 17061.6 17413.0 17646.9 17845.7 17867.6 4947.4 5216.5 5295.1 5297.4 5304.9 3771.8 5210.1 5287.2 5867.8 6197.6 6223.1 6244.8 6246.7 6252.5 6258.6 6026.8 6170.0 6183.8 6194.8 6201.0 3439.3 5039.3 5452.7 5452.9 5462.5 20680.6 21691.7 21839.0 21865.0 22109.6 1849.8 1913.9 1964.5 2160.8 2295.5 13165.1 13704.7 13713.7 13731.2 14180.0 1699.8 1723.8 1758.5 1764.1 1814.4 61064.0 65304.7 76351.1 76801.6 79106.2 9492.6 9630.4 9641.0 9759.3 9792.7 13155.6 13192.8 13205.4 13215.3 13232.1 7158.3 7177.3 7411.3 7413.3 7571.5 7131.4 7155.7 7165.5 7344.6 7376.6 6003.9 6097.5 6137.4 6218.3 6329.7 1678.3 1713.6 1749.5 1809.3 1820.4 379.2 380.4 404.9 407.6 650.9 10384.4 10533.9 10797.9 11263.3 11364.9 394.6 408.3 415.2 419.1 422.8 5661.9 5688.5 5754.0 5829.6 5982.4 428218.0 434552.0 437043.0 441005.0 445649.0 46502.3 47680.8 47846.0 48675.5 55905.0 414.2 421.4 422.1 423.7 424.4 9800.3 9885.9 10019.2 10023.8 10043.4 36426.0 37039.3 37496.6 38350.2 38559.2 47357.3 51025.5 51063.5 53029.5 54883.7 7705.2 7789.6 7810.4 7932.3 8157.2 11062.6 12061.9 12119.8 12290.2 12505.2 10269.0 10316.3 10443.3 10929.9 11078.8 9350.9 9438.5 9661.9 10205.6 10286.8 10119.8 10157.9 10602.6 10778.8 10920.4 7358.8 7544.9 7601.7 7752.9 8044.1 9482.5 10217.2 10286.3 10772.6 11027.6 09:52:38 INFO - PROCESS | 29933 | 09:52:38 INFO - PROCESS | 29933 | [#3] mootools.html Cycles:180 Average:5230.26 Median:4816.66 stddev:3006.34 (62.4%) stddev-sans-first:3001.01 09:52:38 INFO - PROCESS | 29933 | Values: 1402.1 1444.4 1542.6 1665.4 1758.7 7586.3 8129.0 8129.4 8137.8 8141.9 6939.1 7528.6 7794.2 7800.5 7986.6 2227.4 2258.6 2261.4 2264.2 2264.3 2182.3 2224.4 2240.5 2245.0 2245.2 1893.5 2168.8 2230.3 2233.9 2234.5 2205.5 2221.9 2226.8 2228.7 2229.3 2151.5 2176.5 2181.4 2181.5 2182.7 8057.7 8100.5 8111.4 8114.2 8116.7 4674.6 4681.2 4727.9 4731.9 4812.0 2759.1 2842.3 2848.5 2852.7 2862.4 922.5 952.7 964.3 973.6 1011.8 11000.5 11016.3 11037.7 11042.5 11049.1 4069.5 4206.2 4329.8 4351.9 4356.2 9244.7 9247.1 9358.2 9360.9 9363.3 4734.2 4753.7 4759.9 4766.7 4791.3 3622.9 3803.2 3930.3 4398.4 4402.6 3607.6 3770.2 3773.3 3775.0 3795.8 1156.0 1174.3 1180.2 1186.4 1189.8 672.4 797.4 804.7 819.3 828.0 5156.0 5192.2 5229.8 5266.1 5270.8 366.1 372.9 380.9 390.8 391.4 3205.9 3263.4 3289.8 3348.0 3350.1 9468.3 9915.0 9997.2 9998.9 10008.5 9740.0 9939.5 9946.3 9960.2 9974.8 7487.3 7641.6 7642.4 7642.7 7647.6 5130.4 5168.3 5169.9 5185.1 5393.5 9069.8 9100.5 9110.5 9194.0 9293.7 9235.1 9251.1 9411.5 9486.3 9545.3 6994.4 7010.2 7070.9 7073.0 7074.0 7625.7 7653.5 7679.8 7705.1 7709.1 6790.6 6961.1 6961.9 6997.7 7169.2 6769.2 6861.4 6871.4 6887.0 6976.1 6449.7 6513.4 6552.5 6585.5 6594.9 4542.0 4551.1 4577.6 4649.2 4698.8 4299.5 4525.7 4581.5 4641.1 4821.3 09:52:38 INFO - PROCESS | 29933 | 09:52:38 INFO - PROCESS | 29933 | [#4] prototype.html Cycles:180 Average:4221.57 Median:4747.38 stddev:1231.35 (25.9%) stddev-sans-first:1199.12 09:52:38 INFO - PROCESS | 29933 | Values: 300.3 300.4 307.9 309.8 311.4 4704.3 4834.5 4845.7 4848.6 4850.5 4653.2 4790.3 4792.6 4798.1 4816.2 3816.3 3826.7 3833.8 3846.3 3846.9 3640.8 3853.0 3856.0 3871.8 3878.2 3641.5 3806.5 3830.3 3833.3 3846.9 3825.4 3825.9 3830.5 3836.6 3837.6 3406.6 3411.5 3415.3 3421.3 3427.3 4531.7 4741.8 4743.4 4751.3 4761.5 4332.8 4341.2 4345.3 4354.3 4355.1 5097.1 5105.7 5112.2 5113.7 5133.5 3536.9 3631.4 3633.9 3640.0 3658.0 5253.6 5323.5 5325.2 5328.6 5342.8 3727.4 3731.0 3735.0 3745.4 3746.5 3791.7 3794.5 3804.4 3804.8 3811.8 4240.8 4297.0 4299.9 4306.6 4314.8 5071.4 5105.5 5117.9 5126.6 5156.0 1246.1 1271.7 1308.5 1315.3 1321.3 874.9 889.9 899.8 900.9 905.7 4501.6 4509.3 4527.5 4540.7 4552.9 5231.3 5236.1 5255.9 5267.3 5270.3 4315.4 4439.9 4483.7 4490.8 4497.5 2466.8 2480.9 2488.8 2498.0 2514.6 4339.9 4392.5 4408.5 4431.5 4442.4 4708.0 5066.5 5394.5 5426.5 5430.4 4994.8 5013.0 5028.2 5036.5 5045.3 5203.8 5365.5 5385.4 5397.9 5412.7 5170.0 5181.2 5190.3 5194.6 5199.6 4965.3 5006.8 5010.4 5016.3 5023.6 3746.8 3760.2 3762.7 3768.8 3787.3 5111.7 5237.8 5248.5 5262.4 5280.9 5095.7 5097.6 5120.4 5124.1 5134.7 4974.6 5114.3 5127.3 5128.6 5163.7 4806.8 4893.4 5022.4 5055.4 5104.8 5064.3 5074.7 5075.4 5080.5 5081.4 4996.5 5003.4 5005.3 5011.5 5018.6 09:52:38 INFO - PROCESS | 29933 | 09:52:38 INFO - PROCESS | 29933 | [#5] yui.html Cycles:180 Average:18366.70 Median:570.20 stddev:26936.02 (4724.0%) stddev-sans-first:26978.67 09:52:38 INFO - PROCESS | 29933 | Values: 631.4 631.4 653.8 682.7 690.8 13118.5 14154.9 14277.4 14277.8 14285.7 13652.4 13758.9 13774.0 13819.4 13834.1 626.5 642.1 781.2 782.3 797.3 790.0 804.0 808.8 809.0 809.7 849.9 853.6 855.8 857.0 857.2 656.9 808.6 834.8 845.6 847.0 619.0 619.6 619.8 621.6 621.8 13312.6 13376.2 13379.1 13446.3 13481.6 12450.4 12677.9 12737.2 13071.4 13445.9 11840.8 11850.5 12028.8 12176.8 12667.1 4063.2 4086.8 4142.3 4225.2 4235.2 62650.7 62990.0 63270.7 63780.7 63882.0 9944.7 10025.3 10153.1 10269.9 10421.2 17784.0 18168.3 18386.8 18390.3 18568.5 6505.5 6535.6 6641.6 6793.1 6864.4 3665.0 3684.6 3691.9 3706.3 3714.6 2915.5 3017.4 3022.5 3034.6 3295.1 578.1 588.6 589.1 594.0 602.9 430.0 518.5 533.3 565.9 566.8 8428.7 8552.5 8697.9 8869.7 9055.5 445.6 573.6 582.2 582.6 585.4 3943.4 3997.1 4015.1 4195.1 4330.0 101630.0 101915.0 104009.5 106219.5 106793.5 86567.0 87535.0 88682.6 90516.0 92352.0 76425.0 77025.6 78511.6 78850.6 80009.6 7988.4 7997.3 8132.7 8269.5 8383.2 45764.3 46392.3 46814.5 47092.3 47981.0 64320.7 68719.7 68805.0 69391.7 71451.6 9136.8 9453.9 9529.3 9817.4 9963.3 11649.7 11871.9 12100.3 12281.8 12402.8 10208.6 10292.8 10563.3 10930.1 10970.6 10787.3 10868.8 10878.8 11339.4 11359.9 10262.8 10592.7 10779.1 11112.4 11174.7 8075.5 8313.4 8384.5 8399.6 8570.1 9029.7 9272.6 9330.3 9528.4 10073.3 09:52:38 INFO - PROCESS | 29933 | -------- Summary: end -------- 09:52:38 INFO - PROCESS | 29933 | 09:52:39 INFO - TEST-INFO | 29933: exit 0 09:52:39 INFO - TEST-OK | dromaeo_css | took 1120121ms 09:52:39 INFO - TEST-START | kraken 09:52:39 INFO - operating with platform_type : linux_ 09:52:39 INFO - Initialising browser for kraken test... 09:52:39 INFO - TEST-INFO | started process 30124 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqk3448/profile http://localhost:58891/getInfo.html) 09:52:43 INFO - PROCESS | 30124 | __metrics Screen width/height:1600/1200 09:52:43 INFO - PROCESS | 30124 | colorDepth:24 09:52:43 INFO - PROCESS | 30124 | Browser inner width/height: 1024/697 09:52:43 INFO - PROCESS | 30124 | __metrics 09:52:44 INFO - PROCESS | 30124 | 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" 09:52:44 INFO - PROCESS | 30124 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:52:45 INFO - TEST-INFO | 30124: exit 0 09:52:45 INFO - Browser initialized. 09:52:45 INFO - Running cycle 1/1 for kraken test... 09:52:45 INFO - TEST-INFO | started process 30204 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqk3448/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:52:57 INFO - PROCESS | 30204 | RSS: Main: 158736384 09:52:57 INFO - PROCESS | 30204 | 09:52:59 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58891/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:00 INFO - PROCESS | 30204 | RSS: Main: 215519232 09:53:00 INFO - PROCESS | 30204 | 09:53:03 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58891/tests/kraken/driver.html?testName=audio-dft) 09:53:04 INFO - PROCESS | 30204 | RSS: Main: 193540096 09:53:04 INFO - PROCESS | 30204 | 09:53:07 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58891/tests/kraken/driver.html?testName=audio-fft) 09:53:08 INFO - PROCESS | 30204 | RSS: Main: 273125376 09:53:08 INFO - PROCESS | 30204 | 09:53:10 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58891/tests/kraken/driver.html?testName=audio-oscillator) 09:53:10 INFO - PROCESS | 30204 | RSS: Main: 182005760 09:53:10 INFO - PROCESS | 30204 | 09:53:13 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58891/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:13 INFO - PROCESS | 30204 | RSS: Main: 185937920 09:53:13 INFO - PROCESS | 30204 | 09:53:16 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58891/tests/kraken/driver.html?testName=imaging-darkroom) 09:53:17 INFO - PROCESS | 30204 | RSS: Main: 222658560 09:53:17 INFO - PROCESS | 30204 | 09:53:20 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58891/tests/kraken/driver.html?testName=imaging-desaturate) 09:53:21 INFO - PROCESS | 30204 | RSS: Main: 221929472 09:53:21 INFO - PROCESS | 30204 | 09:53:24 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58891/tests/kraken/driver.html?testName=json-parse-financial) 09:53:24 INFO - PROCESS | 30204 | RSS: Main: 221495296 09:53:24 INFO - PROCESS | 30204 | 09:53:26 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58891/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:53:27 INFO - PROCESS | 30204 | RSS: Main: 186531840 09:53:27 INFO - PROCESS | 30204 | 09:53:29 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58891/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:53:29 INFO - PROCESS | 30204 | RSS: Main: 191430656 09:53:29 INFO - PROCESS | 30204 | 09:53:31 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58891/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:53:32 INFO - PROCESS | 30204 | RSS: Main: 195956736 09:53:32 INFO - PROCESS | 30204 | 09:53:35 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:53:35 INFO - PROCESS | 30204 | RSS: Main: 190533632 09:53:35 INFO - PROCESS | 30204 | 09:53:38 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:53:39 INFO - PROCESS | 30204 | RSS: Main: 194383872 09:53:39 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | Cycle 1(1): loaded http://localhost:58891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58891/tests/kraken/driver.html?testName=ai-astar) 09:53:41 INFO - PROCESS | 30204 | RSS: Main: 244740096 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | __start_tp_report 09:53:41 INFO - PROCESS | 30204 | _x_x_mozilla_page_load 09:53:41 INFO - PROCESS | 30204 | _x_x_mozilla_page_load_details 09:53:41 INFO - PROCESS | 30204 | |i|pagename|runs| 09:53:41 INFO - PROCESS | 30204 | |0;ai-astar;106;115;113;114;114;113;114;112;113;114 09:53:41 INFO - PROCESS | 30204 | |1;audio-beat-detection;159;123;123;181;130;129;147;121;167;122 09:53:41 INFO - PROCESS | 30204 | |2;audio-dft;162;163;161;160;162;161;162;163;161;161 09:53:41 INFO - PROCESS | 30204 | |3;audio-fft;74;76;76;112;76;77;83;76;113;75 09:53:41 INFO - PROCESS | 30204 | |4;audio-oscillator;85;84;115;84;85;85;119;85;85;123 09:53:41 INFO - PROCESS | 30204 | |5;imaging-gaussian-blur;117;116;116;117;117;117;116;116;116;116 09:53:41 INFO - PROCESS | 30204 | |6;imaging-darkroom;146;147;145;146;146;145;146;146;146;146 09:53:41 INFO - PROCESS | 30204 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 09:53:41 INFO - PROCESS | 30204 | |8;json-parse-financial;65;62;63;63;64;63;64;63;64;64 09:53:41 INFO - PROCESS | 30204 | |9;json-stringify-tinderbox;65;66;66;65;66;65;65;67;68;65 09:53:41 INFO - PROCESS | 30204 | |10;stanford-crypto-aes;86;79;79;78;79;78;78;78;80;84 09:53:41 INFO - PROCESS | 30204 | |11;stanford-crypto-ccm;127;131;129;135;136;124;138;127;170;138 09:53:41 INFO - PROCESS | 30204 | |12;stanford-crypto-pbkdf2;166;163;165;171;162;161;162;162;161;163 09:53:41 INFO - PROCESS | 30204 | |13;stanford-crypto-sha256-iterative;66;67;66;66;66;66;67;66;66;68 09:53:41 INFO - PROCESS | 30204 | __end_tp_report 09:53:41 INFO - PROCESS | 30204 | __start_cc_report 09:53:41 INFO - PROCESS | 30204 | _x_x_mozilla_cycle_collect,1949 09:53:41 INFO - PROCESS | 30204 | __end_cc_report 09:53:41 INFO - PROCESS | 30204 | __startTimestamp1469033621447__endTimestamp 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | ------- Summary: start ------- 09:53:41 INFO - PROCESS | 30204 | Number of tests: 14 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:2.53 (2.2%) stddev-sans-first:0.88 09:53:41 INFO - PROCESS | 30204 | Values: 106.0 115.0 113.0 114.0 114.0 113.0 114.0 112.0 113.0 114.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:138.50 stddev:21.87 (15.8%) stddev-sans-first:22.11 09:53:41 INFO - PROCESS | 30204 | Values: 159.0 123.0 123.0 181.0 130.0 129.0 147.0 121.0 167.0 122.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 09:53:41 INFO - PROCESS | 30204 | Values: 162.0 163.0 161.0 160.0 162.0 161.0 162.0 163.0 161.0 161.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#3] audio-fft Cycles:10 Average:83.80 Median:76.00 stddev:15.32 (20.2%) stddev-sans-first:15.83 09:53:41 INFO - PROCESS | 30204 | Values: 74.0 76.0 76.0 112.0 76.0 77.0 83.0 76.0 113.0 75.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:85.00 stddev:16.67 (19.6%) stddev-sans-first:17.29 09:53:41 INFO - PROCESS | 30204 | Values: 85.0 84.0 115.0 84.0 85.0 85.0 119.0 85.0 85.0 123.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 09:53:41 INFO - PROCESS | 30204 | Values: 117.0 116.0 116.0 117.0 117.0 117.0 116.0 116.0 116.0 116.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 09:53:41 INFO - PROCESS | 30204 | Values: 146.0 147.0 145.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:53:41 INFO - PROCESS | 30204 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:64.00 stddev:0.85 (1.3%) stddev-sans-first:0.71 09:53:41 INFO - PROCESS | 30204 | Values: 65.0 62.0 63.0 63.0 64.0 63.0 64.0 63.0 64.0 64.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:1.03 (1.6%) stddev-sans-first:1.05 09:53:41 INFO - PROCESS | 30204 | Values: 65.0 66.0 66.0 65.0 66.0 65.0 65.0 67.0 68.0 65.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:79.00 stddev:2.81 (3.6%) stddev-sans-first:1.92 09:53:41 INFO - PROCESS | 30204 | Values: 86.0 79.0 79.0 78.0 79.0 78.0 78.0 78.0 80.0 84.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#11] stanford-crypto-ccm Cycles:10 Average:135.50 Median:135.50 stddev:13.09 (9.7%) stddev-sans-first:13.52 09:53:41 INFO - PROCESS | 30204 | Values: 127.0 131.0 129.0 135.0 136.0 124.0 138.0 127.0 170.0 138.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.60 Median:163.00 stddev:3.06 (1.9%) stddev-sans-first:3.12 09:53:41 INFO - PROCESS | 30204 | Values: 166.0 163.0 165.0 171.0 162.0 161.0 162.0 162.0 161.0 163.0 09:53:41 INFO - PROCESS | 30204 | 09:53:41 INFO - PROCESS | 30204 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 09:53:41 INFO - PROCESS | 30204 | Values: 66.0 67.0 66.0 66.0 66.0 66.0 67.0 66.0 66.0 68.0 09:53:41 INFO - PROCESS | 30204 | -------- Summary: end -------- 09:53:41 INFO - PROCESS | 30204 | 09:53:42 INFO - TEST-INFO | 30204: exit 0 09:53:42 INFO - TEST-OK | kraken | took 63248ms 09:53:42 INFO - SUITE-END | took 1183s 09:53:42 INFO - Completed test suite (00:19:43) 09:53:42 INFO - javascript benchmark 09:53:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2608.608708693696, 2614.816962812604, 2640.0363867188476, 2652.8673566321677, 2660.295021819218, 4653.325267836161, 4712.976435117824, 4729.196036673769, 4752.287156926454, 4768.952310476899, 4754.239321708527, 4793.784279707413, 4799.5680388765, 4800.735959522225, 4846.563809257166, 4323.329883868006, 4347.956520434803, 4431.889202769924, 4488.37162797207, 4520.434945631796, 4402.31764076567, 4470.284754439291, 4478.126765280772, 4487.169873573399, 4498.50516443191, 4280.892977675552, 4291.97854010731, 4296.74219546825, 4357.302831546937, 4404.559544045602, 4371.125774845043, 4377.890552736175, 4415.183191109623, 4440.023194897118, 4497.370368148462, 4363.956360436387, 4407.823687052514, 4408.625266852321, 4442.178197033559, 4459.487158976727, 4817.205161148407, 4865.099956508057, 4871.683340582851, 4872.439669438025, 4882.2432522959, 4981.775820088104, 5053.620978426633, 5063.8480845574695, 5064.848054558369, 5135.820246291362, 5078.974605126988, 5107.080725469414, 5132.255822905658, 5139.9206166705035, 5142.125838607446, 4626.467956185082, 4789.353437285957, 4794.760261986957, 4844.006978569385, 4853.053654537402, 5181.896362072737, 5236.345456817898, 5251.0, 5305.469453054741, 5316.282301889196, 5036.043151801146, 5037.874053148701, 5075.390953085654, 5087.745612719424, 5088.821891233789, 4868.756562171877, 5110.974445127825, 5167.9224811627855, 5169.198774189931, 5175.8095638002715, 5032.672876263031, 5078.898422031539, 5084.847454576368, 5126.769295381717, 5147.047796157723, 5040.747962601855, 5045.445001049881, 5045.949540504548, 5050.924236136461, 5161.561267292323, 4423.8451654192595, 4428.3800267962515, 4436.423264975532, 4454.309582014793, 4483.820647174141, 3690.2619476104887, 3719.814009299524, 3753.7560058596105, 3815.2178803345246, 3855.6722678572073, 4432.246518091933, 4432.401625780542, 4433.312836510346, 4485.304777759453, 4486.663500237495, 5145.4854514549, 5153.433122356538, 5157.922631160535, 5169.198774190007, 5204.843854684365, 4308.159908817813, 4369.890752731207, 4379.233634114017, 4418.5802348776815, 4429.047754732748, 4693.9765301173275, 4697.6241900647865, 4704.905901882012, 4719.0, 4720.645951553647, 4856.587190088882, 4881.023795240895, 4887.633427492881, 4892.4373697023875, 4921.753912304371, 5004.899902002013, 5096.057229412422, 5099.92350114756, 5183.0, 5229.738513074407, 4871.878203044952, 4886.096072226507, 4886.096072226649, 4905.190643543918, 4909.361782968192, 5080.46655101209, 5081.669691470042, 5085.00842335741, 5100.974495127501, 5141.948580514147, 4991.80032798684, 5010.674306170087, 5051.570616497639, 5145.202493613496, 5156.716380599103, 4893.951060489349, 4950.628702847373, 4955.108080545537, 4968.652194346361, 4980.05378977993, 4911.7298548580175, 4928.704277743347, 4938.1358262304675, 4940.456549779449, 4988.575971042502, 4725.1967165581245, 4864.902701946012, 4875.439324477588, 4922.1140194765285, 4926.187179115406, 4945.8021679132435, 5001.649884508195, 5004.374453193351, 5035.0, 5053.823116190915, 4969.925451118163, 4972.7762250699525, 4976.0794253063295, 4977.104121258209, 5033.5721463676], "value": 4705.740998928539, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.8129517259305, 577.7457918515853, 611.0833749375936, 614.7448808744372, 626.3235705438125, 27310.0, 30377.544336834966, 30502.847485762595, 40469.0, 41967.0, 43109.78445107778, 43467.78266108665, 43481.34777978325, 43583.128337433234, 43623.34564981528, 7991.560464174483, 8129.349652027823, 8129.5528745918955, 8133.0, 8133.10535841057, 7520.0, 7968.8406231875615, 8060.234277743634, 9986.151177149915, 10153.847692284622, 10399.688009359732, 10484.737881552985, 10486.737831554234, 10584.629537966173, 10586.047255746982, 10416.583336666563, 10490.318129321646, 10498.422586757726, 10499.842502362471, 10499.895001050007, 7519.097708274987, 10107.545160467724, 10330.7417314567, 10385.065344119066, 10470.0, 40354.59645403567, 40749.79625101885, 40778.592214078075, 40787.59212407868, 40797.38803917944, 3014.5628883811723, 3714.5542534895717, 3719.256148770257, 3745.8688945887034, 3794.260119276742, 29559.556606650916, 29929.0, 30304.54543181832, 30313.090607281818, 30321.0, 3532.6643968822923, 3547.308274886398, 3586.1214002569536, 3646.908827279313, 3694.649008344203, 259491.0, 260454.0, 260808.0, 263153.0, 263371.9999999981, 55544.0, 55705.44294557084, 55912.720436397554, 55922.72038639781, 56029.719851400485, 59285.40714592799, 59303.0, 59355.109673354935, 59378.0, 59471.70264148652, 14922.70154596902, 15195.088294702353, 15211.239438028055, 15218.086914785148, 15226.847731522765, 11360.602378916697, 11423.771524569629, 11434.142439317084, 11459.942700286445, 11460.54157833694, 14864.175806596711, 15288.38846446152, 16749.906261717286, 17063.23215455283, 17182.19586943378, 5783.797567085216, 5814.0, 5860.941390586125, 5885.705714714247, 5964.254468191476, 835.6448509383488, 862.9332748068341, 867.4708427859001, 883.1874675298741, 983.1692220074026, 27718.3070423237, 28858.85570572176, 29152.41695166127, 29620.407591848474, 29722.40555188884, 969.8254314223508, 985.2905907746411, 1004.2668851738272, 1034.5189486888632, 1078.385320367383, 15146.848531514544, 15382.769258461132, 15573.454929077654, 15813.68372632541, 15865.127417991891, 383307.0, 383519.0, 383600.0, 383989.0, 385078.0, 50513.74743126261, 50647.493525065016, 50703.492965069876, 50796.74601626968, 50862.23706644403, 1076.7523469601879, 1156.7223866272034, 1175.6766889105393, 1201.0871737479404, 1204.6205445284706, 28418.857905710338, 29847.253818654706, 30158.0, 30262.54606180953, 30668.386632267677, 43209.56790432055, 43715.0, 43715.56284437242, 43787.562124378346, 43813.56186438095, 298655.0, 299757.0, 300359.0, 300820.0, 300973.0, 32354.83822580966, 32745.67254327426, 32843.83578082094, 33585.664143358255, 36654.74298479124, 36201.094972624844, 36393.63606363902, 36398.0, 43334.34998475089, 43976.78011609922, 34903.0, 36104.458433123, 36178.63821361752, 37150.628493714714, 38708.41937370998, 36335.636643633225, 36764.080897977765, 36897.0, 37213.44179837251, 37546.8122659385, 35463.0, 35628.46557301695, 36442.8177859109, 36659.266814663024, 36706.4494032595, 31010.534841976936, 32669.346613068083, 33661.83169084139, 33758.13055890277, 33852.32295354128, 24947.126850560144, 25631.487370252864, 25721.485570288114, 25735.0, 25747.227583172535], "value": 18336.532740076265, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.6259196180182, 362.60476081071636, 363.4403019350201, 363.6054880454705, 387.6956589077575, 12978.41597128129, 16173.676526469464, 16732.916335418307, 18586.0, 18721.0, 17061.57346066349, 17413.0, 17646.911765441157, 17845.7323140153, 17867.553311167227, 4947.406311242656, 5216.452272511388, 5295.126304159829, 5297.44376840432, 5304.946950530503, 3771.7925514096714, 5210.088234558956, 5287.154055351145, 5867.76528938842, 6197.597156184834, 6223.097650840626, 6244.781432649858, 6246.656433896134, 6252.468540174091, 6258.561900666955, 6026.8493287667725, 6170.043643235305, 6183.814485565395, 6194.845128871769, 6201.0, 3439.2949445363643, 5039.269305950654, 5452.700101494416, 5452.945470545323, 5462.535684466825, 20680.58638827215, 21691.67462488064, 21839.0, 21865.0, 22109.557808843732, 1849.8057703941026, 1913.913873875665, 1964.499052741556, 2160.805527502532, 2295.5064661097776, 13165.144265622705, 13704.725905481935, 13713.657158571015, 13731.189046050451, 14180.0, 1699.7960244770584, 1723.7845269341333, 1758.516407987801, 1764.0738612228554, 1814.4012475882928, 61064.0, 65304.67347663231, 76351.12839844497, 76801.61599192025, 79106.20893791046, 9492.572834222477, 9630.374025688308, 9640.951795240979, 9759.316847820726, 9792.657256995972, 13155.60533184006, 13192.823184174697, 13205.405756740969, 13215.33923303831, 13232.13991090576, 7158.319959603829, 7177.282271772781, 7411.258874112654, 7413.332800047914, 7571.507851989603, 7131.429485641136, 7155.67799449026, 7165.498415110997, 7344.632768361561, 7376.631168441664, 6003.909941350883, 6097.512199024068, 6137.3862613738265, 6218.347073557302, 6329.715162817684, 1678.2951160512614, 1713.5973046334102, 1749.4748082613544, 1809.2853322937628, 1820.4356649438716, 379.24340939825225, 380.43695330910407, 404.92913740095366, 407.5964794853074, 650.9218893732782, 10384.42885641297, 10533.894661053291, 10797.892021079688, 11263.26788758728, 11364.94317528407, 394.56575348608015, 408.33033824527797, 415.1924506834239, 419.13867003308496, 422.7823867262265, 5661.915071273934, 5688.516476099495, 5753.971230143822, 5829.562782791308, 5982.371850955587, 428218.0, 434552.0, 437043.0, 441005.0, 445649.0, 46502.30246546304, 47680.7615961918, 47846.0, 48675.513244867805, 55905.0, 414.23366771472536, 421.3868820865694, 422.06933711166806, 423.68435515540506, 424.4333814357805, 9800.31397802147, 9885.901140988499, 10019.198464123, 10023.849642255225, 10043.4476103815, 36426.0, 37039.25921481609, 37496.6250337504, 38350.23299534049, 38559.228815422975, 47357.289640656105, 51025.48974510207, 51063.48936510736, 53029.46970530245, 54883.72558137184, 7705.1909549496495, 7789.64946577394, 7810.414218933716, 7932.325752311118, 8157.2250636189465, 11062.557497700323, 12061.879381206076, 12119.81820272679, 12290.20113692607, 12505.249685018927, 10269.0, 10316.27786054969, 10443.321184123008, 10929.945350273198, 11078.833817492905, 9350.859737104085, 9438.480883551196, 9661.893337704243, 10205.591776328863, 10286.794264114826, 10119.848202276824, 10157.898421015696, 10602.628907988183, 10778.784424311312, 10920.399378034284, 7358.779236622696, 7544.924550754422, 7601.65792539326, 7752.92247077522, 8044.115147333906, 9482.478463684563, 10217.23370747182, 10286.331388459726, 10772.622958196425, 11027.61403350879], "value": 7601.756456808084, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.060619385012, 1444.3644796289634, 1542.5835024543373, 1665.3838079910436, 1758.6570618729336, 7586.279303466169, 8128.983877015373, 8129.390295727814, 8137.796555086125, 8141.877871831927, 6939.0979172707475, 7528.623568821551, 7794.181610930856, 7800.453968222227, 7986.640601172947, 2227.3986023773573, 2258.627326491127, 2261.389424855288, 2264.230161745006, 2264.320703788865, 2182.334388011656, 2224.377174391173, 2240.4622890506243, 2244.9775502245016, 2245.1805091141778, 1893.5266183454137, 2168.8481806273567, 2230.308604332662, 2233.932982010542, 2234.54191890662, 2205.514786746913, 2221.8666879987254, 2226.7773222677765, 2228.687983682286, 2229.2866282789514, 2151.4836439254623, 2176.4994051368276, 2181.4110190248543, 2181.520065585569, 2182.694422780812, 8057.717979870735, 8100.473469224482, 8111.43219974602, 8114.188581141898, 8116.715914942949, 4674.626029917598, 4681.204195286776, 4727.929081063752, 4731.858044258678, 4812.037592481518, 2759.0619189475474, 2842.2752198189537, 2848.5157523220896, 2852.686204517501, 2862.370278538739, 922.5110691333537, 952.6665667016489, 964.2527041542791, 973.6154219083493, 1011.8381059030594, 11000.504977276041, 11016.283941543774, 11037.668869933916, 11042.503087361169, 11049.121649319635, 4069.491313585802, 4206.179794939957, 4329.76186309756, 4351.912961740747, 4356.237658409766, 9244.676436324697, 9247.0752924707, 9358.15776580111, 9360.859587106199, 9363.297752668577, 4734.171519983989, 4753.667243292936, 4759.928601070987, 4766.666333356632, 4791.329213910055, 3622.8913132606062, 3803.1633040730994, 3930.331843586597, 4398.3842262083335, 4402.581754733295, 3607.603163651996, 3770.208256266215, 3773.3019391412677, 3775.0, 3795.791231482295, 1156.0, 1174.2503669532334, 1180.1620849196995, 1186.4483015397882, 1189.8393716848288, 672.414998950906, 797.4298376660715, 804.7263930263739, 819.3486178488116, 827.962741676626, 5156.0, 5192.1952097424955, 5229.764660590283, 5266.052110620049, 5270.8155214567305, 366.13774560909206, 372.94778730977686, 380.9104860357814, 390.79287977372036, 391.3718481836668, 3205.8557364918634, 3263.4288999425007, 3289.819059951678, 3347.9982841010005, 3350.0954742219465, 9468.337216394924, 9915.0, 9997.150242229347, 9998.850017249748, 10008.549615267331, 9739.97730238329, 9939.453330066772, 9946.303758736818, 9960.203183745283, 9974.80050398988, 7487.288707572772, 7641.579713115832, 7642.388608911274, 7642.656080476392, 7647.579383133981, 5130.384353877409, 5168.302279192261, 5169.896602067863, 5185.092608793521, 5393.541548968382, 9069.818603627758, 9100.45397276139, 9110.544472776468, 9194.0, 9293.72118836436, 9235.07649235071, 9251.074892510695, 9411.529423528933, 9486.288528360263, 9545.331826772337, 6994.440444764508, 7010.228874823867, 7070.858582828418, 7072.9646351767915, 7073.964630176816, 7625.733099341718, 7653.464257502144, 7679.846403071795, 7705.113911899979, 7709.113451953097, 6790.5925644461495, 6961.095057642474, 6961.89557156633, 6997.7200911962955, 7169.247229040876, 6769.221539522821, 6861.416779573792, 6871.415929646036, 6887.001384799276, 6976.093107895941, 6449.7420103195345, 6513.446357059703, 6552.475801935739, 6585.506087043395, 6594.868102638016, 4542.046170304141, 4551.06703125865, 4577.56513131252, 4649.186392381387, 4698.765061746967, 4299.548547402603, 4525.683202175816, 4581.496035436033, 4641.141388843075, 4821.276808478756], "value": 4103.65250774213, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.3437489086346, 300.4126931848236, 307.9491883839167, 309.8125633991435, 311.38346074771965, 4704.270838020112, 4834.468208497063, 4845.709257444556, 4848.563629273364, 4850.5391987761195, 4653.1856925038155, 4790.281457781334, 4792.579747227645, 4798.0643774464, 4816.157172494817, 3816.255830113129, 3826.7129965252716, 3833.8466461341513, 3846.3461211594026, 3846.903827404309, 3640.8361623726864, 3853.0, 3856.0, 3871.8257678404398, 3878.204967981571, 3641.5448068991377, 3806.5432148142263, 3830.3105441020743, 3833.290841194388, 3846.9230615387673, 3825.3688141456764, 3825.9426108608395, 3830.5020347354944, 3836.558795738498, 3837.6354246346555, 3406.625271220164, 3411.4712219606, 3415.316936612687, 3421.2815308785184, 3427.3488037272837, 4531.660125490568, 4741.762911854394, 4743.430788305391, 4751.334813126131, 4761.452432970218, 4332.783360831977, 4341.240282950471, 4345.34819777036, 4354.281543545311, 4355.107203023373, 5097.133487307166, 5105.744712764347, 5112.156494178456, 5113.744312784346, 5133.512316329943, 3536.8585256589704, 3631.4371272452813, 3633.94549081764, 3640.0, 3657.9817100914593, 5253.6322457428, 5323.520883120537, 5325.174597937326, 5328.6269961102735, 5342.786288548454, 3727.422249551324, 3731.003912906582, 3735.0, 3745.438184272381, 3746.4754934309217, 3791.7156213284115, 3794.487744154559, 3804.3912973924594, 3804.752691075071, 3811.7712937223855, 4240.787960601958, 4296.957030429656, 4299.892502687458, 4306.557303303366, 4314.848980285675, 5071.39143302806, 5105.540501354822, 5117.897642047138, 5126.615503837227, 5155.9742201288755, 1246.0903540415366, 1271.7011502296953, 1308.4700696218047, 1315.2503073248347, 1321.3063141850605, 874.8643960186181, 889.9332550058772, 899.822429720079, 900.9189172974463, 905.7237542549453, 4501.617362524156, 4509.2559727644575, 4527.547245275446, 4540.682152249311, 4552.931706024412, 5231.293775340357, 5236.120133926325, 5255.948810237891, 5267.3152490176035, 5270.341207349082, 4315.395844581761, 4439.933400998988, 4483.686141970031, 4490.842820501267, 4497.505274419811, 2466.8273220874366, 2480.940819120126, 2488.8133389995824, 2498.0382552717333, 2514.559952008391, 4339.869803905888, 4392.516823149451, 4408.53710360414, 4431.468223813099, 4442.422485076984, 4708.0, 5066.46802085783, 5394.460553944574, 5426.511613954763, 5430.375506816767, 4994.800207991712, 5013.0, 5028.2457631354855, 5036.496350365081, 5045.293658887758, 5203.81786637481, 5365.543928766098, 5385.40760516335, 5397.9460205397445, 5412.673894895775, 5169.991851588904, 5181.248718935658, 5190.325257716473, 5194.649391158402, 5199.610029247746, 4965.329680493087, 5006.849794506171, 5010.398752149766, 5016.297718319511, 5023.572996295356, 3746.7939263340777, 3760.172761992357, 3762.7177961652437, 3768.849246030128, 3787.3182827091387, 5111.7188554628365, 5237.8428647140645, 5248.501392367719, 5262.421133675216, 5280.894382112259, 5095.719735414587, 5097.61767867404, 5120.436751957207, 5124.128898087409, 5134.691918484754, 4974.602031837372, 5114.258432527337, 5127.333446652061, 5128.564072053768, 5163.690178589297, 4806.807727690853, 4893.412790465167, 5022.372203474566, 5055.368078990126, 5104.846854594368, 5064.265681476239, 5074.67014644047, 5075.401248606825, 5080.46655101209, 5081.390233172043, 4996.450390456974, 5003.374578177728, 5005.349304590525, 5011.523905228997, 5018.623603229699], "value": 3832.949748852097, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.3654776949165, 631.4222486424921, 653.7711800869694, 682.6927882452895, 690.7893092606756, 13118.47526098956, 14154.929225353884, 14277.428902843874, 14277.785833212498, 14285.714285714306, 13652.38564264608, 13758.899288056957, 13774.0, 13819.447222111103, 13834.100783449094, 626.4581137316211, 642.1267076775581, 781.2226834299879, 782.2881178127905, 797.3421926910294, 789.9526028438283, 803.9839203215932, 808.8422757562278, 808.9514629122242, 809.7125520440247, 849.8937632795901, 853.607340623314, 855.8159995600945, 857.014433401587, 857.1728282207669, 656.9277379488252, 808.6078252047752, 834.8205135895751, 845.602566793606, 847.0343808058818, 618.9845253868644, 619.6003577692363, 619.8171539395889, 621.5897507644933, 621.7606221604715, 13312.600621981355, 13376.197428154343, 13379.063465557414, 13446.260455674934, 13481.59555213345, 12450.377481125908, 12677.873221267762, 12737.172083814523, 13071.411786469536, 13445.86554134456, 11840.822387664191, 11850.525978960832, 12028.759424811455, 12176.756464870654, 12667.11330206886, 4063.227986682521, 4086.7983756446406, 4142.337226043848, 4225.19721252961, 4235.152969406131, 62650.68674656643, 62990.0, 63270.68364658147, 63780.68109659422, 63882.0417693735, 9944.651937182163, 10025.34835235722, 10153.13698335635, 10269.94865025685, 10421.218408619385, 17784.0, 18168.27326906909, 18386.81613183878, 18390.264389424272, 18568.535786605175, 6505.4795616350575, 6535.607863528204, 6641.601503909781, 6793.082933803873, 6864.4165245954655, 3664.9633503664622, 3684.613115622875, 3691.9446208306895, 3706.29580379727, 3714.591394946554, 2915.548090046046, 3017.3814368054495, 3022.5012872875727, 3034.575159477675, 3295.110320213529, 578.0606514414077, 588.5968111843401, 589.0751520113381, 593.9821805345847, 602.8944934636509, 429.9644509761097, 518.5410911343413, 533.2933862632028, 565.9377468478464, 566.7902875935848, 8428.662853485914, 8552.529610871461, 8697.869531957025, 8869.68956086534, 9055.501947392957, 445.56557356577724, 573.6070791507831, 582.2256398989334, 582.5572564850744, 585.3765739487433, 3943.4084887266567, 3997.120633460634, 4015.0966032643005, 4195.077083041726, 4330.040905916442, 101630.0, 101915.0, 104009.47995260126, 106219.46890265653, 106793.4660326709, 86567.0, 87535.0, 88682.55658721794, 90516.0, 92352.0, 76425.0, 77025.61487192528, 78511.60744196242, 78850.6057469709, 80009.59995199987, 7988.400869934894, 7997.280244777998, 8132.6746930122135, 8269.545175015191, 8383.245507904317, 45764.31353529633, 46392.30411543897, 46814.53185468238, 47092.29361559648, 47981.0, 64320.67839660959, 68719.65640171767, 68805.0, 69391.65304173446, 71451.64274178595, 9136.817263654822, 9453.858192126985, 9529.332946693665, 9817.410955342702, 9963.25275604318, 11649.650510484698, 11871.881281187423, 12100.273983561014, 12281.815772763595, 12402.75194496123, 10208.642697505551, 10292.845607315747, 10563.260571760211, 10930.070943969851, 10970.616028438966, 10787.298825576312, 10868.836967445337, 10878.782424351626, 11339.376334301693, 11359.886401135884, 10262.794744105226, 10592.682219533426, 10779.083777878654, 11112.444377780917, 11174.720631984266, 8075.515469071875, 8313.376496762647, 8384.496930184208, 8399.580020999048, 8570.142985701379, 9029.729108126767, 9272.6290948364, 9330.30022748283, 9528.380655257386, 10073.254597099658], "value": 6603.336712370394, "unit": "score"}], "name": "dromaeo_css", "value": 6390.910964978664, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 113.0, 114.0, 114.0, 113.0, 114.0, 112.0, 113.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 123.0, 123.0, 181.0, 130.0, 129.0, 147.0, 121.0, 167.0, 122.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 161.0, 160.0, 162.0, 161.0, 162.0, 163.0, 161.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 112.0, 76.0, 77.0, 83.0, 76.0, 113.0, 75.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 115.0, 84.0, 85.0, 85.0, 119.0, 85.0, 85.0, 123.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 63.0, 63.0, 64.0, 63.0, 64.0, 63.0, 64.0, 64.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 66.0, 65.0, 65.0, 67.0, 68.0, 65.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 79.0, 79.0, 78.0, 79.0, 78.0, 78.0, 78.0, 80.0, 84.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 131.0, 129.0, 135.0, 136.0, 124.0, 138.0, 127.0, 170.0, 138.0], "value": 135.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 165.0, 171.0, 162.0, 161.0, 162.0, 162.0, 161.0, 163.0], "value": 163.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0, 68.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1525.4, "alertThreshold": 2.0}]} 09:53:42 INFO - Return code: 0 09:53:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:53:42 INFO - # TBPL SUCCESS # 09:53:42 INFO - Running post-action listener: _resource_record_post_action 09:53:42 INFO - [mozharness: 2016-07-20 16:53:42.773023Z] Finished run-tests step (success) 09:53:42 INFO - Running post-run listener: _resource_record_post_run 09:53:42 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 8192; Write bytes: 539578368; Read time: 220; Write time: 490656 09:53:42 INFO - TinderboxPrint: CPU usage
13.0% 09:53:42 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 220 09:53:42 INFO - TinderboxPrint: I/O write bytes / time
539,578,368 / 490,656 09:53:42 INFO - TinderboxPrint: CPU idle
8,334.2 (87.0%) 09:53:42 INFO - TinderboxPrint: CPU user
1,198.3 (12.5%) 09:53:42 INFO - TinderboxPrint: Swap in / out
0 / 0 09:53:42 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 250384384; Read time: 0; Write time: 302148 09:53:42 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289193984; Read time: 200; Write time: 188508 09:53:43 INFO - Running post-run listener: _upload_blobber_files 09:53:43 INFO - Blob upload gear active. 09:53:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:53:43 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 09:53:43 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'] 09:53:43 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 09:53:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:53:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:53:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:53:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:44 INFO - (blobuploader) - INFO - Done attempting. 09:53:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:53:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:53:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:45 INFO - (blobuploader) - INFO - Done attempting. 09:53:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:53:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:53:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:53:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:53:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:53:59 INFO - (blobuploader) - INFO - Done attempting. 09:53:59 INFO - (blobuploader) - INFO - Iteration through files over. 09:53:59 INFO - Return code: 0 09:53:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:53:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:53:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ab17f4874d7c713c7dc26b2b272d4dc7a3b2b8070c081c17e29550510a21fe8a08de62a66dfd0d9422a4af4192ecb7a3b79d65e43c601dca05c96aed54116a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/412d2c78e05a98c1f703bf45424cd930b34e30b5ee5f2fe2660b3481205ad4ab28de8bef84542c58037da2fc0543692e45028004e73e3e40181f4c55069a0975", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/077e3314f132cbe0dbf4285c0eb97a935ec1971377fa4c29ee837ad366e255ba8b0c417af9bd1e9657572787be5a0e6a8cab9da942c0ea3e23430d83129044fc"} 09:53:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:53:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:53:59 INFO - Contents: 09:53:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ab17f4874d7c713c7dc26b2b272d4dc7a3b2b8070c081c17e29550510a21fe8a08de62a66dfd0d9422a4af4192ecb7a3b79d65e43c601dca05c96aed54116a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/412d2c78e05a98c1f703bf45424cd930b34e30b5ee5f2fe2660b3481205ad4ab28de8bef84542c58037da2fc0543692e45028004e73e3e40181f4c55069a0975", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/077e3314f132cbe0dbf4285c0eb97a935ec1971377fa4c29ee837ad366e255ba8b0c417af9bd1e9657572787be5a0e6a8cab9da942c0ea3e23430d83129044fc"} 09:53:59 INFO - Running post-run listener: copy_logs_to_upload_dir 09:53:59 INFO - Copying logs to upload dir... 09:53:59 INFO - mkdir: /builds/slave/test/build/upload/logs 09:53:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.196380 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-07-20 09:53:59.356563) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 09:53:59.359743) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747 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/ab17f4874d7c713c7dc26b2b272d4dc7a3b2b8070c081c17e29550510a21fe8a08de62a66dfd0d9422a4af4192ecb7a3b79d65e43c601dca05c96aed54116a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/412d2c78e05a98c1f703bf45424cd930b34e30b5ee5f2fe2660b3481205ad4ab28de8bef84542c58037da2fc0543692e45028004e73e3e40181f4c55069a0975", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/077e3314f132cbe0dbf4285c0eb97a935ec1971377fa4c29ee837ad366e255ba8b0c417af9bd1e9657572787be5a0e6a8cab9da942c0ea3e23430d83129044fc"} build_url:https://queue.taskcluster.net/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011165 build_url: 'https://queue.taskcluster.net/v1/task/Ee51dVQnTkyn8rE0EskevQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ab17f4874d7c713c7dc26b2b272d4dc7a3b2b8070c081c17e29550510a21fe8a08de62a66dfd0d9422a4af4192ecb7a3b79d65e43c601dca05c96aed54116a5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/412d2c78e05a98c1f703bf45424cd930b34e30b5ee5f2fe2660b3481205ad4ab28de8bef84542c58037da2fc0543692e45028004e73e3e40181f4c55069a0975", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/077e3314f132cbe0dbf4285c0eb97a935ec1971377fa4c29ee837ad366e255ba8b0c417af9bd1e9657572787be5a0e6a8cab9da942c0ea3e23430d83129044fc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 09:53:59.386015) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:53:59.386486) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1469032388.901133-1935258747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004492 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:53:59.470347) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 09:53:59.470734) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 09:53:59.471124) ========= ========= Total master_lag: 0.17 =========