builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-065 starttime: 1482298255.53 results: success (0) buildid: 20161220202254 builduid: b73a7546e03d4a068e616701eae893e7 revision: 19a951289cad3bbfa1f01470f0a9e7d515443c79 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:55.526707) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:55.527101) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:55.527390) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:55.543728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:55.544002) ========= 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=523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-20 21:30:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.4M=0s 2016-12-20 21:30:56 (50.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.323382 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:55.881690) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:55.882053) ========= 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=523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020169 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:55.931487) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:55.931875) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 19a951289cad3bbfa1f01470f0a9e7d515443c79 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 19a951289cad3bbfa1f01470f0a9e7d515443c79 --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=523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-20 21:30:56,853 truncating revision to first 12 chars 2016-12-20 21:30:56,853 Setting DEBUG logging. 2016-12-20 21:30:56,853 attempt 1/10 2016-12-20 21:30:56,853 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/19a951289cad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-20 21:30:57,185 unpacking tar archive at: graphics-19a951289cad/testing/mozharness/ program finished with exit code 0 elapsedTime=0.644522 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:56.589631) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:56.589924) ========= script_repo_revision: 19a951289cad3bbfa1f01470f0a9e7d515443c79 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:56.590353) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:56.590642) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 21:30:56.599144) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-12-20 21:30:56.599421) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:30:57 INFO - MultiFileLogger online at 20161220 21:30:57 in /builds/slave/test 21:30:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 21:30:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:30:57 INFO - {'append_to_log': False, 21:30:57 INFO - 'base_work_dir': '/builds/slave/test', 21:30:57 INFO - 'blob_upload_branch': 'Graphics', 21:30:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:30:57 INFO - 'branch': 'Graphics', 21:30:57 INFO - 'buildbot_json_path': 'buildprops.json', 21:30:57 INFO - 'config_files': ('talos/linux_config.py',), 21:30:57 INFO - 'default_actions': ('clobber', 21:30:57 INFO - 'read-buildbot-config', 21:30:57 INFO - 'download-and-extract', 21:30:57 INFO - 'populate-webroot', 21:30:57 INFO - 'create-virtualenv', 21:30:57 INFO - 'install', 21:30:57 INFO - 'run-tests'), 21:30:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:30:57 INFO - 'download_minidump_stackwalk': True, 21:30:57 INFO - 'download_symbols': 'ondemand', 21:30:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:30:57 INFO - 'tooltool.py': '/tools/tooltool.py', 21:30:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:30:57 INFO - '/tools/misc-python/virtualenv.py')}, 21:30:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:30:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:30:57 INFO - 'installer_path': 'installer.exe', 21:30:57 INFO - 'log_level': 'info', 21:30:57 INFO - 'log_name': 'talos', 21:30:57 INFO - 'log_to_console': True, 21:30:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:30:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:30:57 INFO - 'opt_config_files': (), 21:30:57 INFO - 'pip_index': False, 21:30:57 INFO - 'sps_profile': False, 21:30:57 INFO - 'sps_profile_interval': 0, 21:30:57 INFO - 'suite': 'dromaeojs', 21:30:57 INFO - 'system_bits': '32', 21:30:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:30:57 INFO - 'title': 'talos-linux64-ix-065', 21:30:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:30:57 INFO - 'use_talos_json': True, 21:30:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:30:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:30:57 INFO - 'work_dir': 'build'} 21:30:57 INFO - [mozharness: 2016-12-21 05:30:57.614808Z] Running clobber step. 21:30:57 INFO - Running pre-action listener: _resource_record_pre_action 21:30:57 INFO - Running main action method: clobber 21:30:57 INFO - rmtree: /builds/slave/test/build 21:30:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:30:58 INFO - Running post-action listener: _resource_record_post_action 21:30:58 INFO - [mozharness: 2016-12-21 05:30:58.587538Z] Finished clobber step (success) 21:30:58 INFO - [mozharness: 2016-12-21 05:30:58.587638Z] Running read-buildbot-config step. 21:30:58 INFO - Running pre-action listener: _resource_record_pre_action 21:30:58 INFO - Running main action method: read_buildbot_config 21:30:58 INFO - Using buildbot properties: 21:30:58 INFO - { 21:30:58 INFO - "project": "", 21:30:58 INFO - "product": "firefox", 21:30:58 INFO - "script_repo_revision": "default", 21:30:58 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 21:30:58 INFO - "repository": "", 21:30:58 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 21:30:58 INFO - "buildid": "20161220202254", 21:30:58 INFO - "pgo_build": "False", 21:30:58 INFO - "basedir": "/builds/slave/test", 21:30:58 INFO - "buildnumber": 35, 21:30:58 INFO - "slavename": "talos-linux64-ix-065", 21:30:58 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:30:58 INFO - "platform": "ubuntu64_hw", 21:30:58 INFO - "scheduler": "tests-graphics-linux64-talos", 21:30:58 INFO - "branch": "graphics", 21:30:58 INFO - "repo_path": "projects/graphics", 21:30:58 INFO - "revision": "19a951289cad3bbfa1f01470f0a9e7d515443c79", 21:30:58 INFO - "stage_platform": "linux64", 21:30:58 INFO - "builduid": "b73a7546e03d4a068e616701eae893e7", 21:30:58 INFO - "slavebuilddir": "test" 21:30:58 INFO - } 21:30:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 21:30:58 INFO - Running post-action listener: _resource_record_post_action 21:30:58 INFO - [mozharness: 2016-12-21 05:30:58.590076Z] Finished read-buildbot-config step (success) 21:30:58 INFO - [mozharness: 2016-12-21 05:30:58.590167Z] Running download-and-extract step. 21:30:58 INFO - Running pre-action listener: _resource_record_pre_action 21:30:58 INFO - Running main action method: download_and_extract 21:30:58 INFO - mkdir: /builds/slave/test/build/tests 21:30:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:30:58 INFO - trying https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:30:58 INFO - Downloading https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:30:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:30:59 INFO - Downloaded 1361 bytes. 21:30:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 21:30:59 INFO - Using the following test package requirements: 21:30:59 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:30:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:30:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:30:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:30:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:30:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:30:59 INFO - u'jsshell-linux-x86_64.zip'], 21:30:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:30:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:30:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 21:30:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:30:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:30:59 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:30:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:30:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:30:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:30:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 21:30:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:30:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:30:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:30:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:30:59 INFO - Fetch https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:30:59 INFO - Expected file size: 20258275 21:30:59 INFO - Obtained file size: 20258275 21:31:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:31:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 21:31:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:31:03 INFO - Fetch https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 21:31:04 INFO - Expected file size: 20258275 21:31:04 INFO - Obtained file size: 20258275 21:31:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 21:31:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:31:09 INFO - Fetch https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 21:31:10 INFO - Expected file size: 14409734 21:31:10 INFO - Obtained file size: 14409734 21:31: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:11 INFO - trying https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:31:11 INFO - Downloading https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:31:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:31:15 INFO - Downloaded 55645609 bytes. 21:31:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:31:15 INFO - mkdir: /builds/slave/test/properties 21:31:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:31:15 INFO - Writing to file /builds/slave/test/properties/build_url 21:31:15 INFO - Contents: 21:31:15 INFO - build_url:https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 21:31:15 INFO - Running post-action listener: _resource_record_post_action 21:31:15 INFO - Running post-action listener: set_extra_try_arguments 21:31:15 INFO - [mozharness: 2016-12-21 05:31:15.893340Z] Finished download-and-extract step (success) 21:31:15 INFO - [mozharness: 2016-12-21 05:31:15.893569Z] Running populate-webroot step. 21:31:15 INFO - Running pre-action listener: _resource_record_pre_action 21:31:15 INFO - Running main action method: populate_webroot 21:31:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:31:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:31:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:31:15 INFO - '--tppagecycles', 21:31:15 INFO - '7'], 21:31:15 INFO - 'tests': ['tsvgm']}}, 21:31:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:31:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:31:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:31:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:31:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:31:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:31:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:31:15 INFO - 'talos_options': ['--disable-e10s'], 21:31:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:31:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:31:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:31:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:31:15 INFO - 'talos_options': ['--disable-e10s'], 21:31:15 INFO - 'tests': ['damp', 'tps']}, 21:31:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:31:15 INFO - 'tests': ['damp', 'tps']}, 21:31:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:31:15 INFO - 'tests': ['dromaeo_dom']}, 21:31:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:31:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:31:15 INFO - 'tests': ['basic_compositor_video']}, 21:31:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:31:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:31:15 INFO - 'tests': ['a11yr', 21:31:15 INFO - 'ts_paint', 21:31:15 INFO - 'tpaint', 21:31:15 INFO - 'sessionrestore', 21:31:15 INFO - 'sessionrestore_no_auto_restore', 21:31:15 INFO - 'tabpaint']}, 21:31:15 INFO - 'other-e10s': {'tests': ['a11yr', 21:31:15 INFO - 'ts_paint', 21:31:15 INFO - 'tpaint', 21:31:15 INFO - 'sessionrestore', 21:31:15 INFO - 'sessionrestore_no_auto_restore', 21:31:15 INFO - 'tabpaint']}, 21:31:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:31:15 INFO - 'ts_paint', 21:31:15 INFO - 'tpaint', 21:31:15 INFO - 'sessionrestore', 21:31:15 INFO - 'sessionrestore_no_auto_restore', 21:31:15 INFO - 'tabpaint']}, 21:31:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:31:15 INFO - 'ts_paint', 21:31:15 INFO - 'tpaint', 21:31:15 INFO - 'sessionrestore', 21:31:15 INFO - 'sessionrestore_no_auto_restore', 21:31:15 INFO - 'tabpaint']}, 21:31:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:31:15 INFO - 'tests': ['a11yr', 21:31:15 INFO - 'ts_paint', 21:31:15 INFO - 'tpaint', 21:31:15 INFO - 'sessionrestore', 21:31:15 INFO - 'sessionrestore_no_auto_restore', 21:31:15 INFO - 'tabpaint']}, 21:31:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:31:15 INFO - 'tests': ['a11yr', 21:31:15 INFO - 'ts_paint', 21:31:15 INFO - 'tpaint', 21:31:15 INFO - 'sessionrestore', 21:31:15 INFO - 'sessionrestore_no_auto_restore', 21:31:15 INFO - 'tabpaint']}, 21:31:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:31:15 INFO - 'tests': ['tsvgx', 21:31:15 INFO - 'tsvgr_opacity', 21:31:15 INFO - 'tart', 21:31:15 INFO - 'tscrollx', 21:31:15 INFO - 'cart', 21:31:15 INFO - 'tsvg_static']}, 21:31:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:31:15 INFO - 'tsvgr_opacity', 21:31:15 INFO - 'tart', 21:31:15 INFO - 'tscrollx', 21:31:15 INFO - 'cart', 21:31:15 INFO - 'tsvg_static']}, 21:31:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:31:15 INFO - 'talos_options': ['--disable-e10s'], 21:31:15 INFO - 'tests': ['tp5o']}, 21:31:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:31:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:31:15 INFO - 'talos_options': ['--disable-e10s', 21:31:15 INFO - '--xperf_path', 21:31:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:31:15 INFO - 'tests': ['tp5n']}, 21:31:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:31:15 INFO - 'talos_options': ['--xperf_path', 21:31:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:31:15 INFO - 'tests': ['tp5n']}}, 21:31:15 INFO - 'talos.zip': {'path': '', 21:31:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:31:15 INFO - Running post-action listener: _resource_record_post_action 21:31:15 INFO - [mozharness: 2016-12-21 05:31:15.909603Z] Finished populate-webroot step (success) 21:31:15 INFO - [mozharness: 2016-12-21 05:31:15.909692Z] Running create-virtualenv step. 21:31:15 INFO - Running pre-action listener: _resource_record_pre_action 21:31:15 INFO - Running main action method: create_virtualenv 21:31:15 INFO - Creating virtualenv /builds/slave/test/build/venv 21:31:15 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 21:31:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:31:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:31:16 INFO - Using real prefix '/usr' 21:31:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:31:16 INFO - Installing distribute.............................................................................................................................................................................................done. 21:31:18 INFO - Installing pip.................done. 21:31:18 INFO - Return code: 0 21:31:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:31:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:31:18 INFO - Reading from file tmpfile_stdout 21:31:18 INFO - Output received: 21:31:18 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 21:31:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:18 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 0xf181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12873f0>, '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 0x1287cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12881a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', '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 21:31:18 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 21:31:18 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 21:31:18 INFO - Using env: {'DISPLAY': ':0', 21:31:18 INFO - 'HOME': '/home/cltbld', 21:31:18 INFO - 'LANG': 'en_US.UTF-8', 21:31:18 INFO - 'LANGUAGE': 'en_US:en', 21:31:18 INFO - 'LOGNAME': 'cltbld', 21:31:18 INFO - 'MAIL': '/var/mail/cltbld', 21:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:18 INFO - 'MOZ_NO_REMOTE': '1', 21:31:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:18 INFO - 'NO_EM_RESTART': '1', 21:31:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:18 INFO - 'PWD': '/builds/slave/test', 21:31:18 INFO - 'SHELL': '/bin/bash', 21:31:18 INFO - 'SHLVL': '1', 21:31:18 INFO - 'TERM': 'linux', 21:31:18 INFO - 'TMOUT': '86400', 21:31:18 INFO - 'USER': 'cltbld', 21:31:18 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', 21:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:18 INFO - '_': '/tools/buildbot/bin/python'} 21:31:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:18 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 21:31:18 INFO - Cleaning up... 21:31:18 INFO - Return code: 0 21:31:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:18 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 0xf181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12873f0>, '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 0x1287cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12881a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', '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 21:31:18 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 21:31:18 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 21:31:18 INFO - Using env: {'DISPLAY': ':0', 21:31:18 INFO - 'HOME': '/home/cltbld', 21:31:18 INFO - 'LANG': 'en_US.UTF-8', 21:31:18 INFO - 'LANGUAGE': 'en_US:en', 21:31:18 INFO - 'LOGNAME': 'cltbld', 21:31:18 INFO - 'MAIL': '/var/mail/cltbld', 21:31:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:18 INFO - 'MOZ_NO_REMOTE': '1', 21:31:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:18 INFO - 'NO_EM_RESTART': '1', 21:31:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:18 INFO - 'PWD': '/builds/slave/test', 21:31:18 INFO - 'SHELL': '/bin/bash', 21:31:18 INFO - 'SHLVL': '1', 21:31:18 INFO - 'TERM': 'linux', 21:31:18 INFO - 'TMOUT': '86400', 21:31:18 INFO - 'USER': 'cltbld', 21:31:18 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', 21:31:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:18 INFO - '_': '/tools/buildbot/bin/python'} 21:31:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:19 INFO - Downloading/unpacking psutil>=3.1.1 21:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:31:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:31:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:31:21 INFO - Installing collected packages: psutil 21:31:21 INFO - Running setup.py install for psutil 21:31:21 INFO - building 'psutil._psutil_linux' extension 21:31:21 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 21:31:21 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 21:31:21 INFO - building 'psutil._psutil_posix' extension 21:31:21 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 21:31:21 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 21:31:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:31:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:31:21 INFO - Successfully installed psutil 21:31:21 INFO - Cleaning up... 21:31:21 INFO - Return code: 0 21:31:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:21 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 0xf181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12873f0>, '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 0x1287cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12881a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', '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 21:31:21 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 21:31:21 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 21:31:21 INFO - Using env: {'DISPLAY': ':0', 21:31:21 INFO - 'HOME': '/home/cltbld', 21:31:21 INFO - 'LANG': 'en_US.UTF-8', 21:31:21 INFO - 'LANGUAGE': 'en_US:en', 21:31:21 INFO - 'LOGNAME': 'cltbld', 21:31:21 INFO - 'MAIL': '/var/mail/cltbld', 21:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:21 INFO - 'MOZ_NO_REMOTE': '1', 21:31:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:21 INFO - 'NO_EM_RESTART': '1', 21:31:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:21 INFO - 'PWD': '/builds/slave/test', 21:31:21 INFO - 'SHELL': '/bin/bash', 21:31:21 INFO - 'SHLVL': '1', 21:31:21 INFO - 'TERM': 'linux', 21:31:21 INFO - 'TMOUT': '86400', 21:31:21 INFO - 'USER': 'cltbld', 21:31:21 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', 21:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:21 INFO - '_': '/tools/buildbot/bin/python'} 21:31:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:31:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:31:24 INFO - Installing collected packages: mozsystemmonitor 21:31:24 INFO - Running setup.py install for mozsystemmonitor 21:31:24 INFO - Successfully installed mozsystemmonitor 21:31:24 INFO - Cleaning up... 21:31:24 INFO - Return code: 0 21:31:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:24 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 0xf181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12873f0>, '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 0x1287cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12881a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', '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 21:31:24 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 21:31:24 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 21:31:24 INFO - Using env: {'DISPLAY': ':0', 21:31:24 INFO - 'HOME': '/home/cltbld', 21:31:24 INFO - 'LANG': 'en_US.UTF-8', 21:31:24 INFO - 'LANGUAGE': 'en_US:en', 21:31:24 INFO - 'LOGNAME': 'cltbld', 21:31:24 INFO - 'MAIL': '/var/mail/cltbld', 21:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:24 INFO - 'MOZ_NO_REMOTE': '1', 21:31:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:24 INFO - 'NO_EM_RESTART': '1', 21:31:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:24 INFO - 'PWD': '/builds/slave/test', 21:31:24 INFO - 'SHELL': '/bin/bash', 21:31:24 INFO - 'SHLVL': '1', 21:31:24 INFO - 'TERM': 'linux', 21:31:24 INFO - 'TMOUT': '86400', 21:31:24 INFO - 'USER': 'cltbld', 21:31:24 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', 21:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:24 INFO - '_': '/tools/buildbot/bin/python'} 21:31:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:24 INFO - Downloading/unpacking jsonschema==2.5.1 21:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:31:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:31:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:31:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31:26 INFO - Downloading functools32-3.2.3-2.tar.gz 21:31:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:31:26 INFO - warning: no files found matching '*.txt' 21:31:26 INFO - no previously-included directories found matching 'build' 21:31:26 INFO - no previously-included directories found matching 'dist' 21:31:26 INFO - no previously-included directories found matching '.git*' 21:31:26 INFO - Installing collected packages: jsonschema, functools32 21:31:26 INFO - Running setup.py install for functools32 21:31:26 INFO - warning: no files found matching '*.txt' 21:31:26 INFO - no previously-included directories found matching 'build' 21:31:26 INFO - no previously-included directories found matching 'dist' 21:31:26 INFO - no previously-included directories found matching '.git*' 21:31:26 INFO - Successfully installed jsonschema functools32 21:31:26 INFO - Cleaning up... 21:31:26 INFO - Return code: 0 21:31:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31: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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12873f0>, '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 0x1287cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12881a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', '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 21:31: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:31: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 functools32==3.2.3-2 21:31:26 INFO - Using env: {'DISPLAY': ':0', 21:31:26 INFO - 'HOME': '/home/cltbld', 21:31:26 INFO - 'LANG': 'en_US.UTF-8', 21:31:26 INFO - 'LANGUAGE': 'en_US:en', 21:31:26 INFO - 'LOGNAME': 'cltbld', 21:31:26 INFO - 'MAIL': '/var/mail/cltbld', 21:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:26 INFO - 'MOZ_NO_REMOTE': '1', 21:31:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:26 INFO - 'NO_EM_RESTART': '1', 21:31:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:26 INFO - 'PWD': '/builds/slave/test', 21:31:26 INFO - 'SHELL': '/bin/bash', 21:31:26 INFO - 'SHLVL': '1', 21:31:26 INFO - 'TERM': 'linux', 21:31:26 INFO - 'TMOUT': '86400', 21:31:26 INFO - 'USER': 'cltbld', 21:31:26 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', 21:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:26 INFO - '_': '/tools/buildbot/bin/python'} 21:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:31:27 INFO - Cleaning up... 21:31:27 INFO - Return code: 0 21:31:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:27 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 0xf181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12873f0>, '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 0x1287cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12881a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', '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 21:31:27 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 21:31:27 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 21:31:27 INFO - Using env: {'DISPLAY': ':0', 21:31:27 INFO - 'HOME': '/home/cltbld', 21:31:27 INFO - 'LANG': 'en_US.UTF-8', 21:31:27 INFO - 'LANGUAGE': 'en_US:en', 21:31:27 INFO - 'LOGNAME': 'cltbld', 21:31:27 INFO - 'MAIL': '/var/mail/cltbld', 21:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:27 INFO - 'MOZ_NO_REMOTE': '1', 21:31:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:27 INFO - 'NO_EM_RESTART': '1', 21:31:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:27 INFO - 'PWD': '/builds/slave/test', 21:31:27 INFO - 'SHELL': '/bin/bash', 21:31:27 INFO - 'SHLVL': '1', 21:31:27 INFO - 'TERM': 'linux', 21:31:27 INFO - 'TMOUT': '86400', 21:31:27 INFO - 'USER': 'cltbld', 21:31:27 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', 21:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:27 INFO - '_': '/tools/buildbot/bin/python'} 21:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:27 INFO - Downloading/unpacking blobuploader==1.2.4 21:31:27 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 21:31:27 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 21:31:27 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 21:31:27 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 21:31:27 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 21:31:27 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 21:31:29 INFO - Downloading blobuploader-1.2.4.tar.gz 21:31:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:31:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:31:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:29 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 21:31:30 INFO - Downloading docopt-0.6.1.tar.gz 21:31:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:31:30 INFO - Installing collected packages: blobuploader, requests, docopt 21:31:30 INFO - Running setup.py install for blobuploader 21:31:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:31:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:31:30 INFO - Running setup.py install for requests 21:31:30 INFO - Running setup.py install for docopt 21:31:30 INFO - Successfully installed blobuploader requests docopt 21:31:30 INFO - Cleaning up... 21:31:30 INFO - Return code: 0 21:31:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:30 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 0xf181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12873f0>, '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 0x1287cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12881a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', '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 21:31:30 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 21:31:30 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 21:31:30 INFO - Using env: {'DISPLAY': ':0', 21:31:30 INFO - 'HOME': '/home/cltbld', 21:31:30 INFO - 'LANG': 'en_US.UTF-8', 21:31:30 INFO - 'LANGUAGE': 'en_US:en', 21:31:30 INFO - 'LOGNAME': 'cltbld', 21:31:30 INFO - 'MAIL': '/var/mail/cltbld', 21:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:30 INFO - 'MOZ_NO_REMOTE': '1', 21:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:30 INFO - 'NO_EM_RESTART': '1', 21:31:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:30 INFO - 'PWD': '/builds/slave/test', 21:31:30 INFO - 'SHELL': '/bin/bash', 21:31:30 INFO - 'SHLVL': '1', 21:31:30 INFO - 'TERM': 'linux', 21:31:30 INFO - 'TMOUT': '86400', 21:31:30 INFO - 'USER': 'cltbld', 21:31:30 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', 21:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:30 INFO - '_': '/tools/buildbot/bin/python'} 21:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:31:30 INFO - Running setup.py (path:/tmp/pip-IrrSQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:31:31 INFO - Running setup.py (path:/tmp/pip-WwYuve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:31:31 INFO - Running setup.py (path:/tmp/pip-Qvwcbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:31:31 INFO - Running setup.py (path:/tmp/pip-lw0pCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:31:31 INFO - Running setup.py (path:/tmp/pip-221Tcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:31:31 INFO - Running setup.py (path:/tmp/pip-Q8appj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:31:31 INFO - Running setup.py (path:/tmp/pip-47glTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:31:31 INFO - Running setup.py (path:/tmp/pip-j0yOuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:31:31 INFO - Running setup.py (path:/tmp/pip-udqJCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:31:31 INFO - Running setup.py (path:/tmp/pip-skebdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:31:31 INFO - Running setup.py (path:/tmp/pip-JG5h88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:31:31 INFO - Running setup.py (path:/tmp/pip-lHzhgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:31:31 INFO - Running setup.py (path:/tmp/pip-nXmcc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:31:31 INFO - Running setup.py (path:/tmp/pip-docivU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:32 INFO - Running setup.py (path:/tmp/pip-ld7N0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:31:32 INFO - Running setup.py (path:/tmp/pip-6IA4MX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:31:32 INFO - Running setup.py (path:/tmp/pip-D8uDj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:31:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:31:32 INFO - Running setup.py install for manifestparser 21:31:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:31:32 INFO - Running setup.py install for mozcrash 21:31:32 INFO - Running setup.py install for mozdebug 21:31:32 INFO - Running setup.py install for mozdevice 21:31:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:31:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:31:32 INFO - Running setup.py install for mozfile 21:31:32 INFO - Running setup.py install for mozhttpd 21:31:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:31:32 INFO - Running setup.py install for mozinfo 21:31:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:31:32 INFO - Running setup.py install for mozInstall 21:31:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:31:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:31:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:31:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:31:33 INFO - Running setup.py install for mozleak 21:31:33 INFO - Running setup.py install for mozlog 21:31:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:31:33 INFO - Running setup.py install for moznetwork 21:31:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:31:33 INFO - Running setup.py install for mozprocess 21:31:33 INFO - Running setup.py install for mozprofile 21:31:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:31:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:31:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:31:33 INFO - Running setup.py install for mozrunner 21:31:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:31:33 INFO - Running setup.py install for mozscreenshot 21:31:33 INFO - Running setup.py install for moztest 21:31:33 INFO - Running setup.py install for mozversion 21:31:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:31:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:31:34 INFO - Cleaning up... 21:31:34 INFO - Return code: 0 21:31:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:31: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:34 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 0xf181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12873f0>, '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 0x1287cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12881a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', '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 21:31:34 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 21:31:34 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 21:31:34 INFO - Using env: {'DISPLAY': ':0', 21:31:34 INFO - 'HOME': '/home/cltbld', 21:31:34 INFO - 'LANG': 'en_US.UTF-8', 21:31:34 INFO - 'LANGUAGE': 'en_US:en', 21:31:34 INFO - 'LOGNAME': 'cltbld', 21:31:34 INFO - 'MAIL': '/var/mail/cltbld', 21:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:34 INFO - 'MOZ_NO_REMOTE': '1', 21:31:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:34 INFO - 'NO_EM_RESTART': '1', 21:31:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:34 INFO - 'PWD': '/builds/slave/test', 21:31:34 INFO - 'SHELL': '/bin/bash', 21:31:34 INFO - 'SHLVL': '1', 21:31:34 INFO - 'TERM': 'linux', 21:31:34 INFO - 'TMOUT': '86400', 21:31:34 INFO - 'USER': 'cltbld', 21:31:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', 21:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:34 INFO - '_': '/tools/buildbot/bin/python'} 21:31:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:31:34 INFO - Running setup.py (path:/tmp/pip-3OCM4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:31:34 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)) 21:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:31:34 INFO - Running setup.py (path:/tmp/pip-bxPg1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 21:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:31:34 INFO - Running setup.py (path:/tmp/pip-J2vPXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:31:34 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)) 21:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:31:34 INFO - Running setup.py (path:/tmp/pip-Uujapx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:31:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 21:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:31:34 INFO - Running setup.py (path:/tmp/pip-6IANXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:31:34 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)) 21:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:31:34 INFO - Running setup.py (path:/tmp/pip-QyR4T7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:31:34 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)) 21:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:31:34 INFO - Running setup.py (path:/tmp/pip-bcsyt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:31:34 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)) 21:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:31:34 INFO - Running setup.py (path:/tmp/pip-NUIUSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:31:34 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)) 21:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:31:34 INFO - Running setup.py (path:/tmp/pip-Uphkvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:31:34 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)) 21:31:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:31:34 INFO - Running setup.py (path:/tmp/pip-aLnoFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 21:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:31:35 INFO - Running setup.py (path:/tmp/pip-cRWOFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:31:35 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)) 21:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:31:35 INFO - Running setup.py (path:/tmp/pip-HcCVkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:31:35 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)) 21:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:31:35 INFO - Running setup.py (path:/tmp/pip-kqvBsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:31:35 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)) 21:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:31:35 INFO - Running setup.py (path:/tmp/pip-BMMPfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 21:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:35 INFO - Running setup.py (path:/tmp/pip-ng2pMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:35 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)) 21:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:31:35 INFO - Running setup.py (path:/tmp/pip-pk2wF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:31:35 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)) 21:31:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:31:35 INFO - Running setup.py (path:/tmp/pip-2I9rKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:31:35 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)) 21:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 21:31:35 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)) 21:31:35 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:31:35 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 21:31:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:31:35 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 21:31:35 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 21:31:35 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 21:31:35 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 21:31:35 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 21:31:35 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 21:31:37 INFO - Downloading blessings-1.6.tar.gz 21:31:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:31:37 INFO - Installing collected packages: blessings 21:31:37 INFO - Running setup.py install for blessings 21:31:37 INFO - Successfully installed blessings 21:31:37 INFO - Cleaning up... 21:31:37 INFO - Return code: 0 21:31:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:31:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:31:37 INFO - Reading from file tmpfile_stdout 21:31:37 INFO - Current package versions: 21:31:37 INFO - argparse == 1.2.1 21:31:37 INFO - blessings == 1.6 21:31:37 INFO - blobuploader == 1.2.4 21:31:37 INFO - docopt == 0.6.1 21:31:37 INFO - functools32 == 3.2.3-2 21:31:37 INFO - jsonschema == 2.5.1 21:31:37 INFO - manifestparser == 1.1 21:31:37 INFO - mozInstall == 1.12 21:31:37 INFO - mozcrash == 1.0 21:31:37 INFO - mozdebug == 0.1 21:31:37 INFO - mozdevice == 0.49 21:31:37 INFO - mozfile == 1.2 21:31:37 INFO - mozhttpd == 0.7 21:31:37 INFO - mozinfo == 0.9 21:31:37 INFO - mozleak == 0.1 21:31:37 INFO - mozlog == 3.4 21:31:37 INFO - moznetwork == 0.27 21:31:37 INFO - mozprocess == 0.23 21:31:37 INFO - mozprofile == 0.28 21:31:37 INFO - mozrunner == 6.13 21:31:37 INFO - mozscreenshot == 0.1 21:31:37 INFO - mozsystemmonitor == 0.3 21:31:37 INFO - moztest == 0.7 21:31:37 INFO - mozversion == 1.4 21:31:37 INFO - psutil == 3.1.1 21:31:37 INFO - requests == 1.2.3 21:31:37 INFO - wsgiref == 0.1.2 21:31:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:37 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 0xf181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12873f0>, '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 0x1287cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12881a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', '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 21:31:37 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 21:31:37 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 21:31:37 INFO - Using env: {'DISPLAY': ':0', 21:31:38 INFO - 'HOME': '/home/cltbld', 21:31:38 INFO - 'LANG': 'en_US.UTF-8', 21:31:38 INFO - 'LANGUAGE': 'en_US:en', 21:31:38 INFO - 'LOGNAME': 'cltbld', 21:31:38 INFO - 'MAIL': '/var/mail/cltbld', 21:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:38 INFO - 'MOZ_NO_REMOTE': '1', 21:31:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:38 INFO - 'NO_EM_RESTART': '1', 21:31:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:38 INFO - 'PWD': '/builds/slave/test', 21:31:38 INFO - 'SHELL': '/bin/bash', 21:31:38 INFO - 'SHLVL': '1', 21:31:38 INFO - 'TERM': 'linux', 21:31:38 INFO - 'TMOUT': '86400', 21:31:38 INFO - 'USER': 'cltbld', 21:31:38 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', 21:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:38 INFO - '_': '/tools/buildbot/bin/python'} 21:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:38 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)) 21:31:38 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)) 21:31:38 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)) 21:31:38 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)) 21:31:38 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)) 21:31:38 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)) 21:31:38 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)) 21:31:38 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)) 21:31:38 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)) 21:31:38 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)) 21:31:38 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)) 21:31:38 INFO - Cleaning up... 21:31:38 INFO - Return code: 0 21:31:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:38 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 0xf181f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1289c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12873f0>, '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 0x1287cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12881a0>, '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': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', '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 21:31:38 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 21:31:38 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 21:31:38 INFO - Using env: {'DISPLAY': ':0', 21:31:38 INFO - 'HOME': '/home/cltbld', 21:31:38 INFO - 'LANG': 'en_US.UTF-8', 21:31:38 INFO - 'LANGUAGE': 'en_US:en', 21:31:38 INFO - 'LOGNAME': 'cltbld', 21:31:38 INFO - 'MAIL': '/var/mail/cltbld', 21:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:38 INFO - 'MOZ_NO_REMOTE': '1', 21:31:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:38 INFO - 'NO_EM_RESTART': '1', 21:31:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:38 INFO - 'PWD': '/builds/slave/test', 21:31:38 INFO - 'SHELL': '/bin/bash', 21:31:38 INFO - 'SHLVL': '1', 21:31:38 INFO - 'TERM': 'linux', 21:31:38 INFO - 'TMOUT': '86400', 21:31:38 INFO - 'USER': 'cltbld', 21:31:38 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', 21:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:38 INFO - '_': '/tools/buildbot/bin/python'} 21:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:31:38 INFO - Cleaning up... 21:31:38 INFO - Return code: 0 21:31:38 INFO - Running post-action listener: _resource_record_post_action 21:31:38 INFO - Running post-action listener: _start_resource_monitoring 21:31:38 INFO - Starting resource monitoring. 21:31:38 INFO - [mozharness: 2016-12-21 05:31:38.487968Z] Finished create-virtualenv step (success) 21:31:38 INFO - [mozharness: 2016-12-21 05:31:38.488504Z] Running install step. 21:31:38 INFO - Running pre-action listener: _resource_record_pre_action 21:31:38 INFO - Running main action method: install 21:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:31:38 INFO - Reading from file tmpfile_stdout 21:31:38 INFO - Detecting whether we're running mozinstall >=1.0... 21:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:31:38 INFO - Reading from file tmpfile_stdout 21:31:38 INFO - Output received: 21:31:38 INFO - Usage: mozinstall [options] installer 21:31:38 INFO - Options: 21:31:38 INFO - -h, --help show this help message and exit 21:31:38 INFO - -d DEST, --destination=DEST 21:31:38 INFO - Directory to install application into. [default: 21:31:38 INFO - "/builds/slave/test"] 21:31:38 INFO - --app=APP Application being installed. [default: firefox] 21:31:38 INFO - mkdir: /builds/slave/test/build/application 21:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:31:54 INFO - Reading from file tmpfile_stdout 21:31:54 INFO - Output received: 21:31:54 INFO - /builds/slave/test/build/application/firefox/firefox 21:31:54 INFO - Running post-action listener: _resource_record_post_action 21:31:54 INFO - [mozharness: 2016-12-21 05:31:54.844484Z] Finished install step (success) 21:31:54 INFO - [mozharness: 2016-12-21 05:31:54.844729Z] Running run-tests step. 21:31:54 INFO - Running pre-action listener: _resource_record_pre_action 21:31:54 INFO - Running main action method: run_tests 21:31:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:31:54 INFO - Python 2.7.3 21:31:54 INFO - Return code: 0 21:31:54 INFO - grabbing minidump binary from tooltool 21:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:31:54 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 0x12873f0>, '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 0x1287cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12881a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:31:54 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 21:31:54 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 21:31:54 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 21:31:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:31:54 INFO - Return code: 0 21:31:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:31:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:31:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:31:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:31:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:31:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 21:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --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 21:31:54 INFO - Using env: {'DISPLAY': ':0', 21:31:54 INFO - 'HOME': '/home/cltbld', 21:31:54 INFO - 'LANG': 'en_US.UTF-8', 21:31:54 INFO - 'LANGUAGE': 'en_US:en', 21:31:54 INFO - 'LOGNAME': 'cltbld', 21:31:54 INFO - 'MAIL': '/var/mail/cltbld', 21:31:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:31:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:54 INFO - 'MOZ_NO_REMOTE': '1', 21:31:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:31:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:31:54 INFO - 'NO_EM_RESTART': '1', 21:31:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:54 INFO - 'PWD': '/builds/slave/test', 21:31:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:31:54 INFO - 'SHELL': '/bin/bash', 21:31:54 INFO - 'SHLVL': '1', 21:31:54 INFO - 'TERM': 'linux', 21:31:54 INFO - 'TMOUT': '86400', 21:31:54 INFO - 'USER': 'cltbld', 21:31:54 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757', 21:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:54 INFO - '_': '/tools/buildbot/bin/python'} 21:31:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 21:31:55 INFO - mozversion application_buildid: 20161220202254 21:31:55 INFO - mozversion application_changeset: 19a951289cad3bbfa1f01470f0a9e7d515443c79 21:31:55 INFO - mozversion application_display_name: Nightly 21:31:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:31:55 INFO - mozversion application_name: Firefox 21:31:55 INFO - mozversion application_remotingname: firefox 21:31:55 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 21:31:55 INFO - mozversion application_vendor: Mozilla 21:31:55 INFO - mozversion application_version: 53.0a1 21:31:55 INFO - mozversion platform_buildid: 20161220202254 21:31:55 INFO - mozversion platform_changeset: 19a951289cad3bbfa1f01470f0a9e7d515443c79 21:31:55 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 21:31:55 INFO - mozversion platform_version: 53.0a1 21:31:55 INFO - using testdate: 1482298315 21:31:55 INFO - actual date: 1482298315 21:31:55 INFO - starting webserver on 'localhost:46049' 21:31:55 INFO - SUITE-START | Running 2 tests 21:31:55 INFO - TEST-START | dromaeo_css 21:31:55 INFO - operating with platform_type : linux_ 21:31:55 INFO - Initialising browser for dromaeo_css test... 21:31:55 INFO - TEST-INFO | started process 16562 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3SFX6m/profile http://localhost:46049/getInfo.html) 21:31:59 INFO - PROCESS | 16562 | __metrics Screen width/height:1600/1200 21:31:59 INFO - PROCESS | 16562 | colorDepth:24 21:31:59 INFO - PROCESS | 16562 | Browser inner width/height: 1024/697 21:31:59 INFO - PROCESS | 16562 | __metrics 21:31:59 INFO - PROCESS | 16562 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:31:59 INFO - PROCESS | 16562 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:32:00 INFO - PROCESS | 16562 | console.error: PushService: 21:32:00 INFO - PROCESS | 16562 | stateChangeProcessEnqueue: Error transitioning state 21:32:00 INFO - PROCESS | 16562 | UnknownError 21:32:00 INFO - TEST-INFO | 16562: exit 0 21:32:00 INFO - Browser initialized. 21:32:00 INFO - Running cycle 1/1 for dromaeo_css test... 21:32:00 INFO - TEST-INFO | started process 16643 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3SFX6m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:32:13 INFO - PROCESS | 16643 | RSS: Main: 155566080 21:32:13 INFO - PROCESS | 16643 | 21:33:03 INFO - PROCESS | 16643 | 1482298383826 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] 21:35:18 INFO - PROCESS | 16643 | Cycle 1(1): loaded http://localhost:46049/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46049/tests/dromaeo/cssquery-ext.html) 21:35:20 INFO - PROCESS | 16643 | RSS: Main: 269053952 21:35:20 INFO - PROCESS | 16643 | 21:38:22 INFO - PROCESS | 16643 | Cycle 1(1): loaded http://localhost:46049/tests/dromaeo/cssquery-ext.html (next: http://localhost:46049/tests/dromaeo/cssquery-jquery.html) 21:38:23 INFO - PROCESS | 16643 | RSS: Main: 250630144 21:38:23 INFO - PROCESS | 16643 | 21:41:25 INFO - PROCESS | 16643 | Cycle 1(1): loaded http://localhost:46049/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46049/tests/dromaeo/cssquery-mootools.html) 21:41:26 INFO - PROCESS | 16643 | RSS: Main: 191090688 21:41:26 INFO - PROCESS | 16643 | 21:44:28 INFO - PROCESS | 16643 | Cycle 1(1): loaded http://localhost:46049/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46049/tests/dromaeo/cssquery-prototype.html) 21:44:29 INFO - PROCESS | 16643 | RSS: Main: 221585408 21:44:29 INFO - PROCESS | 16643 | 21:47:32 INFO - PROCESS | 16643 | Cycle 1(1): loaded http://localhost:46049/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46049/tests/dromaeo/cssquery-yui.html) 21:47:33 INFO - PROCESS | 16643 | RSS: Main: 190353408 21:47:33 INFO - PROCESS | 16643 | 21:50:36 INFO - PROCESS | 16643 | Cycle 1(1): loaded http://localhost:46049/tests/dromaeo/cssquery-yui.html (next: http://localhost:46049/tests/dromaeo/cssquery-dojo.html) 21:50:36 INFO - PROCESS | 16643 | RSS: Main: 289345536 21:50:36 INFO - PROCESS | 16643 | 21:50:36 INFO - PROCESS | 16643 | __start_tp_report 21:50:36 INFO - PROCESS | 16643 | _x_x_mozilla_page_load 21:50:36 INFO - PROCESS | 16643 | _x_x_mozilla_page_load_details 21:50:36 INFO - PROCESS | 16643 | |i|pagename|runs| 21:50:36 INFO - PROCESS | 16643 | |0;dojo.html;2605.869706514674;2627.8291911025794;2641.3132585527765;2653.27035065357;2653.6682914635658;4709.5525925037155;4775.426948766144;4793.9760301198485;4807.062622788553;4820.011897561;4803.207470767328;4814.157522433569;4820.228763397857;4848.1758101122805;4889.877753056176;4334.241507736149;4336.284513055342;4380.802863871133;4405.118976204756;4447.288433850585;4041.2520072039656;4371.388005679208;4436.9556304436865;4463.754493502856;4511.368408422823;4306.0526684129445;4344.522102568715;4447.888802779924;4494.37078808967;4519.163954668397;4370.6940514164025;4394.033312671239;4394.07724377881;4396.582324679182;4400.955990440087;4371.628411585019;4372.234858899712;4375.846845360397;4379.08039311745;4381.737095774231;4733.290006499018;4753.548412900769;4765;4770.809167633291;4772.379590653228;5057.115004874132;5145.125328694131;5147.974260128713;5190.4550022247495;5196.272521846944;5131.050755610224;5174.456682048369;5177.042247184283;5177.249298851645;5224.712640804754;4662.323963025342;4684.6486513510945;4762.476127625959;4796.592289655384;4806.206975849016;5175;5259.684418934876;5296.311479507639;5314.28257185275;5322.600804939645;5002;5038;5048;5050.621203409685;5097.286379906816;5137;5137.2037334213255;5141.948580514222;5148.639595228298;5152.227165925066;4898.314236006961;4902.044101400193;4923.5814955729165;5028.84913452597;5042.319286896295;5072.695638261716;5079.644424890223;5080.77136528857;5081.23781432788;5091.1090559152;4405.889852753707;4411.867643970686;4414.580614841585;4417.845375411845;4459.598636122764;3828.062124779391;3849.4418309344987;3852.8844134675446;3872.496575445174;3882.320593896057;4308.073764140725;4350.499692535335;4351.673624478113;4373.759443230653;4403.053343531156;5115.897682046339;5118.539331460186;5125.948740512622;5147.613928955343;5157.071727089085;4372.890677733082;4415.602595766396;4418.8453404130705;4462.152191083748;4464.977675111603;4515.864524064284;4539.251023581138;4557.065801510748;4583.266677331678;4632.3977882875015;4790.4251489821445;4809.831655892027;4835.44392394865;4845.9757701211265;4849.102915960559;5171.431142574239;5182.922256166236;5184.403793563789;5187.170052791535;5230.5815534757885;4642.071585682945;4709.976450117728;4716.316134160596;4756.310335001336;4788.904221915612;4929.531694489017;4933.889874778146;4976.054549635558;4981.975090124526;5039.823606173766;5048.343715316985;5057.8229761958155;5058.140116180185;5058.949410505995;5124;4945.307656927961;4946.4558898521855;4990.251462280542;4999.008133306826;5000.074986127578;4881.292212629078;4894.314795928644;4896.4124305083615;4905.092557876804;4955.4301255356095;4869.732164730832;4928.8767780805765;4930.654854160318;4940.975295123645;4989.650724449254;5039.47085556026;5040.697558146523;5045.091883461012;5052.267421223975;5092.694438333712;4795.520447955177;4905.730184839868;4909.4354149273295;4910.8772280693265;4958.281049247911 21:50:36 INFO - PROCESS | 16643 | |1;ext.html;529.7695502456431;536.401911868267;539.8002738986576;545.8826352334247;553.24758328673;27382.72617273827;30120.84939575302;37481.62518374819;38798.41802372967;38831.50516453526;39916.20167596646;40159.397609035885;40160;40203.19593608126;40253.59746402535;7283.635818209082;7448.4041276697735;7452.55284682918;7454.366378857777;7465.589392583406;7124.928750712478;7325.706971721124;9612.279079069063;9855.063768941938;9872.111509964101;9823.95088024559;9906.455144967023;9915.752106197331;9916.652917147901;9927.702168934908;9867.358621689604;9933.453660048694;9935;9935.602575896992;9939.204863610894;9759.219262458986;9833.950830245803;9839.90160098397;9840.16358609519;9842.90157098427;35621;35912.64087359119;35942;35955.64044359575;35969.82015089934;2843.5876797864194;2852.2584128126628;2901.2601786544506;2915.839628820414;3306.35526072416;26971.46057078867;27020.32449188786;27608.723912761016;27771;28004.43991120166;3321.4021476134285;3440.2431465077643;3447.0692912913623;3456.654334566548;3498.772579782306;226387;226806.4429047066;227416;227605;228752;44917.32624010643;45175.83769136129;45181.77409112999;45193.32210016852;45266.773666131456;54066;54074;54151.45848541543;54214.186787198225;54278.18582721263;14161.575152745434;14411.85588144126;14460.710785784226;14466;14468.27658617086;10997.945010274898;11105.05607023412;11111.611093611686;11112.166587505808;11115.72210694739;14079.929600352138;14486.275686215859;15881.205939702968;16311.553267009805;16503.257353419125;5994.790182343597;6099.451049405575;6147.1558532440085;6211.565190436717;6302;730.1126587770673;793.5119901260798;857.5455008845322;894.7584152278848;922.6447817590142;25551.744482555307;26629.86685066562;27176.624064697648;27292.727072729416;27684.723152768216;922.6447817590276;927.536231884058;949.1931857920712;955.8900726416551;978.5694294510392;13064;13165.868341316464;13296.534621288209;13337.866621333662;13425.597232083053;326866;327468;327618;327876;328081;36396.818015909754;37385.813070935015;45555.08889822185;46943;47039;1138.299945533491;1152.1877076660767;1165.5221359242669;1170.7131752720638;1172.947217375203;24653.38366540851;25088.62367064459;25369.619455708547;25889.352766180997;25982.090626828725;36509;36861.631383686894;36928;36954.44568331423;36997.260054798215;235657;236464;236520;236581;236890;22452.550948981254;22761.544769104858;22810.65784013208;22836.657450138595;22847.28645351572;34525.30949380948;35346.46980295246;35388.82305588559;35534.11164720903;36169.63830361768;30023.249418763833;30446.238844029078;30699.539506907862;30700.539491908086;31112;29590.40819183647;30089.54865676973;30111.69888301089;30504.237394065327;31169.37661246717;29879.55180672335;30346.241343966576;30455.695443045286;30668.693313066582;30771;25862.32745954431;28409.28976775597;28686;28694.71305287004;28789.56815647725;22214.444638884157;22271.443213919134;22362.77637223607;22374.440638984157;22575 21:50:36 INFO - PROCESS | 16643 | |2;jquery.html;341.6634614904317;357.55305867665436;368.2818503917361;374.99062523436913;388.9727719059665;12322.554913807113;12459.937700311497;13250.66873328166;13449.462021519128;16665.41671041514;16095.436659716912;16202.918985405056;16369.918150409261;16596.25316860741;16638.251278692456;4901.975490122544;4944.604431645478;5048.318477005612;5072.670276432039;5077.49225077493;3711.740178187528;5039.244113382985;5048.217526283432;5085.084684756743;5087.516685914832;3869.419587061935;5613.66318020918;5875.6474611523445;5931.644101353911;5976.402359764011;5609;5695.373508914016;5973.372795856438;5986.07215881539;5986.850328741816;5062.620303477217;5068.543831055186;5075.543201111917;5079.492050794928;5083.313752643383;19571.804281957284;19896.20415183391;19901.313322701393;19969.201231950705;20045;1860.823221793941;1876.6622008038548;1908.4847091285415;2429.465517586128;2480.888360023803;12230.877691223062;12871.163374380636;13186.538471153559;13413.731725365438;13488;1690.5435532406182;1732.2118436111539;1758.762567053457;1792.5966657502088;1846.2799508191815;51239.487605123846;57359;61961.690191548754;62189;62782;9252.686828292926;9387.718368448957;9586.376885502421;9774.31579789408;9871.950640246752;12660.430280637393;12663.683407914876;12678.429470673842;12680.936595316964;12687.49250030007;6816.591004539743;6832.897506537406;6947.861042779116;7055;7354.9773812202075;6537.444317232993;6579.835504112435;6699.464042876558;6759.898601520981;6858.177018757781;5808.128780682931;5983.700814959235;6029.095635654687;6115.143879856823;6252.624842509464;1650.8761842861593;1792.139772909011;1808.6887006919878;1822.9908850455663;1824.80839511852;782.8434313137395;823.7322870067132;855.1662129423886;888.560162719458;888.657866721304;15987.520374388787;16239.675206495804;16568.254428550743;17009.149542522824;17458.912705436393;801.4389927050971;817.9795505112301;844.8648216285487;861.4185424838209;917.504467717616;9208.987011428737;9775.951120244354;9823.36148150368;9878.259130565237;9886.604535818487;334299;335421;342934;343453;343800;45612;50832;53912;63581;66277.20023567743;976.0581039297113;999.4353190447374;1004.924630652704;1006.8439391894268;1011.9981218593687;15322.310496027929;15384.923075384551;15650.37398504047;15688.843111568738;16066.70896764;26722;29784.702152979058;30049.699503005566;33537.329253415286;33880.32239355249;42034.369484457144;42141;42668.359974599785;43864;44277.77861110674;7419.752408799968;7478.2895624915545;7554.093508778982;7640.770776876702;7851.646675899484;11230.887691122984;11561.30632162073;11691.240069395462;11758.3532905691;12188.817167742669;9463.2429405646;10139.391636501834;10224.437655928697;10418.479076046318;10594.894051059699;9712.077352651488;9774.364666296668;10111.797764044824;10191.898081019095;10298.176145908159;10379.462615073602;10502.4223667699;10569.207309451667;10650.73373165646;10798.190135739695;7392.852142957218;7473.17795042534;7556.357709594527;7611.809704757426;7836.177201393772;10326.225533085199;10358.378497290187;10390.402399400149;10435.426051567236;10696.946515267375 21:50:36 INFO - PROCESS | 16643 | |3;mootools.html;1292.6962163891483;1329.6277042428121;1388.4862600837691;1592.8267938002436;1633.509947015895;7120.359167674908;7512.399008079355;7513.398928085754;7516.0229170207795;7522.473426860122;6426.839329016765;6601.801945941617;6602.47180225582;6750.831229219272;7379.704811807521;1886.0663971334209;2090.247510896076;2146.0986385717956;2149.322963266574;2154.450615093157;2147.75300840403;2152.4834039830484;2156.3962090614573;2158.028887000848;2158.7625361210253;2069.379186244128;2136.3377353020537;2154.1598776477185;2154.709114269569;2156.2345367394582;2050.3131450963947;2057.2902348689827;2081.646120159569;2096.4025252802876;2098.401955442707;2103.49516116131;2114.534802343497;2114.756802967663;2137.070374387147;2141.839362047837;7373;7434.9256507434775;7491.063617047869;7536.73621423253;7542.472026958115;4419.779011049467;4552.931706024412;4553.68124231304;4554.612857907049;4560.065186636732;2990.5215165573404;3004.278973046475;3075.154332558542;3077.553754705555;3080.199148221456;863.4775960543906;865.9523726195057;884.8539990901494;890.1943740914418;896.5293221058931;9966.850497242547;9973;9992.500374981295;9994.500274986221;10002.049805268487;4118.814653340607;4243.108947121077;4259.254630439661;4279.657627389802;4311.051568654891;7575.204603516661;7686.884696729554;7692.653830577638;7697;7700.037495313086;4478.41780568524;4479.148961697267;4486.68593198473;4487.685861989695;4490.685652004328;4046.352583586612;4155.522114956819;4204.705670603028;4209.873703788891;4210.157968406331;2974.1969668189467;3036.726694597497;3204.150900011499;3314.767966242388;3334.9333013339597;1002.518790980319;1018.3584341864726;1020.0870221152028;1021.6526381030485;1026.2508368890603;754.97357592484;776.6116941529235;813.6826637611337;829.1708291708292;839.6053854688287;4851.58761505276;4854.441739200037;4875.7074575525585;4913.754312284372;5006.4743201964;730.9780706578811;766.4826242286434;767.9308862202429;791.2759824760279;793.5714714054461;3241.2059045533524;3280.327532855759;3318.070940136766;3330.9500357494658;3380.695737383647;8716.564171791511;8831.823363532694;9026.323025772961;9056.547172641473;9100.362974591848;8803.823923521493;9034.32242581809;9054.909450905407;9061.13919177677;9067.909320906838;6896.344847239504;7059.258777828357;7062.540934839321;7067.611281379471;7069.434445244368;4628.305754136906;4943.011397720398;4984.277279794482;5031.345925029723;5041.571466425395;7994.200579941959;8219.712310069119;8223.383246256435;8274.089850116603;8300.086990430927;8342.08237093907;8353;8360.83278334442;8374.455660382057;8471.745847624581;6434.742610295536;6580.276169621434;6582.637954912526;6582.80251592453;6584.04531342943;7103.715851365888;7105.502614816914;7111.217766045627;7152.499325047404;7177.174624917992;6479.287278399466;6491.610503369813;6492.935070649422;6493.902591461227;6504.6747662615735;6320.08358787964;6391.93608063932;6409.487241020801;6424.550281480439;6438.6136831790245;5496.0107180707855;5519.862003449946;5541.722913854372;5593.328800543961;5602.411746766694;4202.3696445533415;4210.6841986850495;4254;4277.165952639206;4366.454193225847;4361.127774445187;4384.56154384559;4389.561043895713;4531.048479819275;4615.423072115986 21:50:36 INFO - PROCESS | 16643 | |4;prototype.html;352.86943830782616;356.70928193522275;359.6924629441828;364.21329927356913;368.37376460017975;4843.878903027422;4864.318995340646;4882.682625629332;4896.828610998616;4906.460289368167;4563.680542362036;4672.859814205571;4753.90492190156;4806.567408933195;4853.296272040552;3886.650201481866;3888.805559722017;3891.630295121973;3896.0909154514297;3920.5491368492703;3881.359575670011;3892.124272038797;3909.2376986487648;3922.333203355422;3925.391564307537;3624.4382120771193;3677.264547090579;3843.9621302248515;3900;3918.6473217410426;3837.692984561242;3840.36633955397;3847.1343947611845;3848.461215429842;3854.8458061677497;3467.7572569920117;3470.288590838897;3471.427214509603;3476.148343655795;3488.354654388946;4675.322078298662;4700.929486057677;4701.64737644678;4709.858704238878;4712.222483290253;4285.764282964436;4395.802188901475;4432.955670443319;4444.511103778582;4481.946742515506;5043.747812609355;5069.898602027939;5099.566536844372;5125.641704948192;5135.948640513584;3847.2690188864026;3856.132370216707;3871.8064096795047;3884.533855937277;3904.863329783444;5116.6674166179455;5291.788328466857;5313.973430132902;5325.893482130375;5384.273123128367;3945.684345252373;3949.743266687656;3952.980235098806;3971.4241434991764;3981.5222173338807;4013.8857670887573;4017.6183262590594;4021.416894550274;4023.8591649292134;4032.7378720383667;4196.454460920061;4413.360062790942;4447.911041779146;4460.3532487789735;4474.373587697725;5079.822206222764;5084.618653600995;5087.618428617869;5095.541401273903;5101.7194054327365;1357.9932100339436;1358.6535433464403;1358.8641135886332;1367.7538043152329;1378.3521744780137;930.1256818590504;944.8110377924443;948.9762755931157;958.6932181701928;995.4475266227313;4063.817128229178;4237.427947227085;4372.562743725602;4387.3857659927635;4413.602775750198;5110.872228194325;5115.514026167508;5124.668965172593;5133.307003554547;5137.17805151174;4208.579142085767;4378;4385.627221686193;4422.204003279377;4433.935855394682;2711.9050833221136;2712.91861244163;2719.9048033319136;2720.360715231919;2730.317420644839;4708.599769019672;4719.174144524694;4764.213904705754;4788.018456216468;4817.566419022305;5074.416442109131;5121.18198039698;5179.533841954242;5288.139658049568;5293.682379057269;4681.50844161365;4701.576858082789;4703.412073490814;4716.858494245178;4740.6681532292405;5077.289179514944;5104.183330667075;5110.437851836221;5134.28120063184;5139;4782.504445820917;4899.877503062452;4980.626453015966;5010.198368261061;5012.949870501248;4675.579197872208;4682.344471773887;4733.408323959505;4741.193997020584;4761.595264402565;3857.1707082977296;3857.5534174684494;3867.5939026401825;3890.9221815562964;3906.9609303906595;4913.248580794766;5126.231065340229;5181.3005244291535;5184.407796101949;5202.973985130202;4983.579679791242;4985.6011519077665;4986.775595098156;5000.074986127578;5021.623378246573;4980.256910081587;4987.132398870592;5002.2496625506465;5002.699838009731;5018.42288136869;4755.239161734106;4798.880027999188;4834.220323314038;4923.4584195737725;4932.136876046749;4899.608031357554;4982.75086245679;4990.47600002009;5167.509086636764;5180.099144479103;5035.018171456677;5043.722595257149;5048.419431765247;5050.393952725696;5072.721000345016 21:50:36 INFO - PROCESS | 16643 | |5;yui.html;611.6574718157831;645.2837350540901;645.8503863123639;705.3616477088234;707.3068392974884;12164.270143791378;13289.534866279682;13296.867031329684;13305.46778128876;13324.067315287934;12920.418581163847;12963.092583519157;13007.349632518384;13031;13101.737965240689;616.5684021185166;627.4854619212248;753.9623018849036;754.3098065270271;767.5816679909462;779.7582749347692;781.9257170568787;782.4796510320658;782.487470706688;782.6556315221313;801.9398545109111;805.8227190018187;807.9757607271791;816.1226681317578;818.3085292927497;649.6881496881476;657.6580178307254;658.0392626764929;658.5258613798057;810.3274282345639;486.67392846792734;583.1981026089128;593.715016791938;598.0938877600439;601.425638515217;13068.673283167902;13117.47530098795;13120.737585248336;13131.146475479063;13134.080614356999;12091.45588448522;12135.332556709378;12177.086718496059;12494.250344979331;12570.560030398892;11178.52290203617;11284.774304513945;11305.417241586185;11334.546618135264;11457.541698332057;4072.1448495815916;4104.220198162339;4115.32097203961;4116.135611521584;4125.968507873032;59233.70383148057;60962.69518652423;61758;62219.68890155611;62385.376146238414;9775.778027746532;9943.552540135712;10116.190704743603;10283.331583447201;10724.731881702864;19089.618207636046;19102.044897755335;19111.426657200307;19111.713324300148;19114;5766.625169363978;6033.849153771103;6041.939580604226;6143.784967526115;6321.850570805948;3578.516900218437;3733.3839916413485;3737.1591391936595;3769.2838360711376;3777.58446570877;2800.2579316481147;2864.1837076433535;2888.465633857743;2892.103447931148;2892.117904039288;487.198558371484;510.4461659099844;512.7820676212594;521.6661336744487;530.1226470191839;467.9064187162581;522.9932380168237;548.5912994818885;573.1116769008021;583.7635757518212;7580.469367144247;7687.346575541142;7849.215078492105;8019.278264956182;8162.1837816219095;500.964932454732;513.7893463679874;517.0666946162138;538.6337290642323;540.4406439335269;3713.721470889694;3716.2381711749026;3818.4654148419245;3821.8471261149243;3851.537815462106;51107.48892511027;59856;63989.36010639834;66426;66511.33488665149;55553.72223138859;56144;56292.43707562954;57223;57663.711681441324;53078.73460632672;54185.72907135518;55147.72426137843;55650;55785;7242.094738157731;7574.280443357872;7623.352015078559;7669.118051424158;7855.096663883726;36989.81505092565;37024.81487592545;37545.81227093847;37634;39355.21289574135;44428.777856110515;47747;49624.751876240385;50173.24740128828;51305;9628.32601717873;9737.22102231834;11142.721431964266;11177.66467005991;11394.829077563678;11743.47154378072;11871.347075910908;12807.679808004874;13229.140105893086;13313.603551360848;11556.88443115558;11893.286402815858;12268.877311226774;12804.871951280367;12963.611091667264;12041.518339266331;12516.436760345989;12571.245725256515;12752.553660621834;13359.53241636538;11593.362365070003;12101.394930253628;12516.499340026661;12633.81049284243;13024.41390137465;9404.38871473352;10067;10280.897191027994;10375.273730839072;10629.149668026694;8891.244244239311;9773.218142548436;9781.119699227103;9794.510274486105;9829.410235385614 21:50:36 INFO - PROCESS | 16643 | __end_tp_report 21:50:36 INFO - PROCESS | 16643 | __start_cc_report 21:50:36 INFO - PROCESS | 16643 | _x_x_mozilla_cycle_collect,1337 21:50:36 INFO - PROCESS | 16643 | __end_cc_report 21:50:36 INFO - PROCESS | 16643 | __startTimestamp1482299436809__endTimestamp 21:50:36 INFO - PROCESS | 16643 | 21:50:36 INFO - PROCESS | 16643 | ------- Summary: start ------- 21:50:36 INFO - PROCESS | 16643 | Number of tests: 6 21:50:36 INFO - PROCESS | 16643 | 21:50:36 INFO - PROCESS | 16643 | [#0] dojo.html Cycles:165 Average:4722.02 Median:4845.98 stddev:497.30 (10.3%) stddev-sans-first:470.31 21:50:36 INFO - PROCESS | 16643 | Values: 2605.9 2627.8 2641.3 2653.3 2653.7 4709.6 4775.4 4794.0 4807.1 4820.0 4803.2 4814.2 4820.2 4848.2 4889.9 4334.2 4336.3 4380.8 4405.1 4447.3 4041.3 4371.4 4437.0 4463.8 4511.4 4306.1 4344.5 4447.9 4494.4 4519.2 4370.7 4394.0 4394.1 4396.6 4401.0 4371.6 4372.2 4375.8 4379.1 4381.7 4733.3 4753.5 4765.0 4770.8 4772.4 5057.1 5145.1 5148.0 5190.5 5196.3 5131.1 5174.5 5177.0 5177.2 5224.7 4662.3 4684.6 4762.5 4796.6 4806.2 5175.0 5259.7 5296.3 5314.3 5322.6 5002.0 5038.0 5048.0 5050.6 5097.3 5137.0 5137.2 5141.9 5148.6 5152.2 4898.3 4902.0 4923.6 5028.8 5042.3 5072.7 5079.6 5080.8 5081.2 5091.1 4405.9 4411.9 4414.6 4417.8 4459.6 3828.1 3849.4 3852.9 3872.5 3882.3 4308.1 4350.5 4351.7 4373.8 4403.1 5115.9 5118.5 5125.9 5147.6 5157.1 4372.9 4415.6 4418.8 4462.2 4465.0 4515.9 4539.3 4557.1 4583.3 4632.4 4790.4 4809.8 4835.4 4846.0 4849.1 5171.4 5182.9 5184.4 5187.2 5230.6 4642.1 4710.0 4716.3 4756.3 4788.9 4929.5 4933.9 4976.1 4982.0 5039.8 5048.3 5057.8 5058.1 5058.9 5124.0 4945.3 4946.5 4990.3 4999.0 5000.1 4881.3 4894.3 4896.4 4905.1 4955.4 4869.7 4928.9 4930.7 4941.0 4989.7 5039.5 5040.7 5045.1 5052.3 5092.7 4795.5 4905.7 4909.4 4910.9 4958.3 21:50:36 INFO - PROCESS | 16643 | 21:50:36 INFO - PROCESS | 16643 | [#1] ext.html Cycles:180 Average:41012.28 Median:165693.68 stddev:70084.53 (42.3%) stddev-sans-first:70215.23 21:50:36 INFO - PROCESS | 16643 | Values: 529.8 536.4 539.8 545.9 553.2 27382.7 30120.8 37481.6 38798.4 38831.5 39916.2 40159.4 40160.0 40203.2 40253.6 7283.6 7448.4 7452.6 7454.4 7465.6 7124.9 7325.7 9612.3 9855.1 9872.1 9824.0 9906.5 9915.8 9916.7 9927.7 9867.4 9933.5 9935.0 9935.6 9939.2 9759.2 9834.0 9839.9 9840.2 9842.9 35621.0 35912.6 35942.0 35955.6 35969.8 2843.6 2852.3 2901.3 2915.8 3306.4 26971.5 27020.3 27608.7 27771.0 28004.4 3321.4 3440.2 3447.1 3456.7 3498.8 226387.0 226806.4 227416.0 227605.0 228752.0 44917.3 45175.8 45181.8 45193.3 45266.8 54066.0 54074.0 54151.5 54214.2 54278.2 14161.6 14411.9 14460.7 14466.0 14468.3 10997.9 11105.1 11111.6 11112.2 11115.7 14079.9 14486.3 15881.2 16311.6 16503.3 5994.8 6099.5 6147.2 6211.6 6302.0 730.1 793.5 857.5 894.8 922.6 25551.7 26629.9 27176.6 27292.7 27684.7 922.6 927.5 949.2 955.9 978.6 13064.0 13165.9 13296.5 13337.9 13425.6 326866.0 327468.0 327618.0 327876.0 328081.0 36396.8 37385.8 45555.1 46943.0 47039.0 1138.3 1152.2 1165.5 1170.7 1172.9 24653.4 25088.6 25369.6 25889.4 25982.1 36509.0 36861.6 36928.0 36954.4 36997.3 235657.0 236464.0 236520.0 236581.0 236890.0 22452.6 22761.5 22810.7 22836.7 22847.3 34525.3 35346.5 35388.8 35534.1 36169.6 30023.2 30446.2 30699.5 30700.5 31112.0 29590.4 30089.5 30111.7 30504.2 31169.4 29879.6 30346.2 30455.7 30668.7 30771.0 25862.3 28409.3 28686.0 28694.7 28789.6 22214.4 22271.4 22362.8 22374.4 22575.0 21:50:36 INFO - PROCESS | 16643 | 21:50:36 INFO - PROCESS | 16643 | [#2] jquery.html Cycles:180 Average:21632.42 Median:2129.20 stddev:55714.47 (2616.7%) stddev-sans-first:55847.83 21:50:36 INFO - PROCESS | 16643 | Values: 341.7 357.6 368.3 375.0 389.0 12322.6 12459.9 13250.7 13449.5 16665.4 16095.4 16202.9 16369.9 16596.3 16638.3 4902.0 4944.6 5048.3 5072.7 5077.5 3711.7 5039.2 5048.2 5085.1 5087.5 3869.4 5613.7 5875.6 5931.6 5976.4 5609.0 5695.4 5973.4 5986.1 5986.9 5062.6 5068.5 5075.5 5079.5 5083.3 19571.8 19896.2 19901.3 19969.2 20045.0 1860.8 1876.7 1908.5 2429.5 2480.9 12230.9 12871.2 13186.5 13413.7 13488.0 1690.5 1732.2 1758.8 1792.6 1846.3 51239.5 57359.0 61961.7 62189.0 62782.0 9252.7 9387.7 9586.4 9774.3 9872.0 12660.4 12663.7 12678.4 12680.9 12687.5 6816.6 6832.9 6947.9 7055.0 7355.0 6537.4 6579.8 6699.5 6759.9 6858.2 5808.1 5983.7 6029.1 6115.1 6252.6 1650.9 1792.1 1808.7 1823.0 1824.8 782.8 823.7 855.2 888.6 888.7 15987.5 16239.7 16568.3 17009.1 17458.9 801.4 818.0 844.9 861.4 917.5 9209.0 9776.0 9823.4 9878.3 9886.6 334299.0 335421.0 342934.0 343453.0 343800.0 45612.0 50832.0 53912.0 63581.0 66277.2 976.1 999.4 1004.9 1006.8 1012.0 15322.3 15384.9 15650.4 15688.8 16066.7 26722.0 29784.7 30049.7 33537.3 33880.3 42034.4 42141.0 42668.4 43864.0 44277.8 7419.8 7478.3 7554.1 7640.8 7851.6 11230.9 11561.3 11691.2 11758.4 12188.8 9463.2 10139.4 10224.4 10418.5 10594.9 9712.1 9774.4 10111.8 10191.9 10298.2 10379.5 10502.4 10569.2 10650.7 10798.2 7392.9 7473.2 7556.4 7611.8 7836.2 10326.2 10358.4 10390.4 10435.4 10696.9 21:50:36 INFO - PROCESS | 16643 | 21:50:36 INFO - PROCESS | 16643 | [#3] mootools.html Cycles:180 Average:4833.16 Median:5268.79 stddev:2645.22 (50.2%) stddev-sans-first:2639.25 21:50:36 INFO - PROCESS | 16643 | Values: 1292.7 1329.6 1388.5 1592.8 1633.5 7120.4 7512.4 7513.4 7516.0 7522.5 6426.8 6601.8 6602.5 6750.8 7379.7 1886.1 2090.2 2146.1 2149.3 2154.5 2147.8 2152.5 2156.4 2158.0 2158.8 2069.4 2136.3 2154.2 2154.7 2156.2 2050.3 2057.3 2081.6 2096.4 2098.4 2103.5 2114.5 2114.8 2137.1 2141.8 7373.0 7434.9 7491.1 7536.7 7542.5 4419.8 4552.9 4553.7 4554.6 4560.1 2990.5 3004.3 3075.2 3077.6 3080.2 863.5 866.0 884.9 890.2 896.5 9966.9 9973.0 9992.5 9994.5 10002.0 4118.8 4243.1 4259.3 4279.7 4311.1 7575.2 7686.9 7692.7 7697.0 7700.0 4478.4 4479.1 4486.7 4487.7 4490.7 4046.4 4155.5 4204.7 4209.9 4210.2 2974.2 3036.7 3204.2 3314.8 3334.9 1002.5 1018.4 1020.1 1021.7 1026.3 755.0 776.6 813.7 829.2 839.6 4851.6 4854.4 4875.7 4913.8 5006.5 731.0 766.5 767.9 791.3 793.6 3241.2 3280.3 3318.1 3331.0 3380.7 8716.6 8831.8 9026.3 9056.5 9100.4 8803.8 9034.3 9054.9 9061.1 9067.9 6896.3 7059.3 7062.5 7067.6 7069.4 4628.3 4943.0 4984.3 5031.3 5041.6 7994.2 8219.7 8223.4 8274.1 8300.1 8342.1 8353.0 8360.8 8374.5 8471.7 6434.7 6580.3 6582.6 6582.8 6584.0 7103.7 7105.5 7111.2 7152.5 7177.2 6479.3 6491.6 6492.9 6493.9 6504.7 6320.1 6391.9 6409.5 6424.6 6438.6 5496.0 5519.9 5541.7 5593.3 5602.4 4202.4 4210.7 4254.0 4277.2 4366.5 4361.1 4384.6 4389.6 4531.0 4615.4 21:50:36 INFO - PROCESS | 16643 | 21:50:36 INFO - PROCESS | 16643 | [#4] prototype.html Cycles:180 Average:4219.60 Median:4709.23 stddev:1167.83 (24.8%) stddev-sans-first:1134.47 21:50:36 INFO - PROCESS | 16643 | Values: 352.9 356.7 359.7 364.2 368.4 4843.9 4864.3 4882.7 4896.8 4906.5 4563.7 4672.9 4753.9 4806.6 4853.3 3886.7 3888.8 3891.6 3896.1 3920.5 3881.4 3892.1 3909.2 3922.3 3925.4 3624.4 3677.3 3844.0 3900.0 3918.6 3837.7 3840.4 3847.1 3848.5 3854.8 3467.8 3470.3 3471.4 3476.1 3488.4 4675.3 4700.9 4701.6 4709.9 4712.2 4285.8 4395.8 4433.0 4444.5 4481.9 5043.7 5069.9 5099.6 5125.6 5135.9 3847.3 3856.1 3871.8 3884.5 3904.9 5116.7 5291.8 5314.0 5325.9 5384.3 3945.7 3949.7 3953.0 3971.4 3981.5 4013.9 4017.6 4021.4 4023.9 4032.7 4196.5 4413.4 4447.9 4460.4 4474.4 5079.8 5084.6 5087.6 5095.5 5101.7 1358.0 1358.7 1358.9 1367.8 1378.4 930.1 944.8 949.0 958.7 995.4 4063.8 4237.4 4372.6 4387.4 4413.6 5110.9 5115.5 5124.7 5133.3 5137.2 4208.6 4378.0 4385.6 4422.2 4433.9 2711.9 2712.9 2719.9 2720.4 2730.3 4708.6 4719.2 4764.2 4788.0 4817.6 5074.4 5121.2 5179.5 5288.1 5293.7 4681.5 4701.6 4703.4 4716.9 4740.7 5077.3 5104.2 5110.4 5134.3 5139.0 4782.5 4899.9 4980.6 5010.2 5012.9 4675.6 4682.3 4733.4 4741.2 4761.6 3857.2 3857.6 3867.6 3890.9 3907.0 4913.2 5126.2 5181.3 5184.4 5203.0 4983.6 4985.6 4986.8 5000.1 5021.6 4980.3 4987.1 5002.2 5002.7 5018.4 4755.2 4798.9 4834.2 4923.5 4932.1 4899.6 4982.8 4990.5 5167.5 5180.1 5035.0 5043.7 5048.4 5050.4 5072.7 21:50:36 INFO - PROCESS | 16643 | 21:50:36 INFO - PROCESS | 16643 | [#5] yui.html Cycles:180 Average:14854.28 Median:25062.86 stddev:18343.95 (73.2%) stddev-sans-first:18364.23 21:50:36 INFO - PROCESS | 16643 | Values: 611.7 645.3 645.9 705.4 707.3 12164.3 13289.5 13296.9 13305.5 13324.1 12920.4 12963.1 13007.3 13031.0 13101.7 616.6 627.5 754.0 754.3 767.6 779.8 781.9 782.5 782.5 782.7 801.9 805.8 808.0 816.1 818.3 649.7 657.7 658.0 658.5 810.3 486.7 583.2 593.7 598.1 601.4 13068.7 13117.5 13120.7 13131.1 13134.1 12091.5 12135.3 12177.1 12494.3 12570.6 11178.5 11284.8 11305.4 11334.5 11457.5 4072.1 4104.2 4115.3 4116.1 4126.0 59233.7 60962.7 61758.0 62219.7 62385.4 9775.8 9943.6 10116.2 10283.3 10724.7 19089.6 19102.0 19111.4 19111.7 19114.0 5766.6 6033.8 6041.9 6143.8 6321.9 3578.5 3733.4 3737.2 3769.3 3777.6 2800.3 2864.2 2888.5 2892.1 2892.1 487.2 510.4 512.8 521.7 530.1 467.9 523.0 548.6 573.1 583.8 7580.5 7687.3 7849.2 8019.3 8162.2 501.0 513.8 517.1 538.6 540.4 3713.7 3716.2 3818.5 3821.8 3851.5 51107.5 59856.0 63989.4 66426.0 66511.3 55553.7 56144.0 56292.4 57223.0 57663.7 53078.7 54185.7 55147.7 55650.0 55785.0 7242.1 7574.3 7623.4 7669.1 7855.1 36989.8 37024.8 37545.8 37634.0 39355.2 44428.8 47747.0 49624.8 50173.2 51305.0 9628.3 9737.2 11142.7 11177.7 11394.8 11743.5 11871.3 12807.7 13229.1 13313.6 11556.9 11893.3 12268.9 12804.9 12963.6 12041.5 12516.4 12571.2 12752.6 13359.5 11593.4 12101.4 12516.5 12633.8 13024.4 9404.4 10067.0 10280.9 10375.3 10629.1 8891.2 9773.2 9781.1 9794.5 9829.4 21:50:36 INFO - PROCESS | 16643 | -------- Summary: end -------- 21:50:36 INFO - PROCESS | 16643 | 21:50:37 INFO - TEST-INFO | 16643: exit 0 21:50:37 INFO - TEST-OK | dromaeo_css | took 1122531ms 21:50:37 INFO - TEST-START | kraken 21:50:37 INFO - operating with platform_type : linux_ 21:50:37 INFO - Initialising browser for kraken test... 21:50:37 INFO - TEST-INFO | started process 16948 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSp3DXz/profile http://localhost:46049/getInfo.html) 21:50:41 INFO - PROCESS | 16948 | __metrics Screen width/height:1600/1200 21:50:41 INFO - PROCESS | 16948 | colorDepth:24 21:50:41 INFO - PROCESS | 16948 | Browser inner width/height: 1024/697 21:50:41 INFO - PROCESS | 16948 | __metrics 21:50:42 INFO - PROCESS | 16948 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:50:42 INFO - PROCESS | 16948 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:50:42 INFO - PROCESS | 16948 | console.error: PushService: 21:50:42 INFO - PROCESS | 16948 | stateChangeProcessEnqueue: Error transitioning state 21:50:42 INFO - PROCESS | 16948 | UnknownError 21:50:43 INFO - TEST-INFO | 16948: exit 0 21:50:43 INFO - Browser initialized. 21:50:43 INFO - Running cycle 1/1 for kraken test... 21:50:43 INFO - TEST-INFO | started process 17033 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSp3DXz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:50:55 INFO - PROCESS | 17033 | RSS: Main: 155181056 21:50:55 INFO - PROCESS | 17033 | 21:50:58 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46049/tests/kraken/driver.html?testName=audio-beat-detection) 21:50:59 INFO - PROCESS | 17033 | RSS: Main: 209338368 21:50:59 INFO - PROCESS | 17033 | 21:51:01 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46049/tests/kraken/driver.html?testName=audio-dft) 21:51:02 INFO - PROCESS | 17033 | RSS: Main: 187641856 21:51:02 INFO - PROCESS | 17033 | 21:51:05 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46049/tests/kraken/driver.html?testName=audio-fft) 21:51:06 INFO - PROCESS | 17033 | RSS: Main: 269582336 21:51:06 INFO - PROCESS | 17033 | 21:51:08 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46049/tests/kraken/driver.html?testName=audio-oscillator) 21:51:08 INFO - PROCESS | 17033 | RSS: Main: 181362688 21:51:08 INFO - PROCESS | 17033 | 21:51:11 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46049/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:51:11 INFO - PROCESS | 17033 | RSS: Main: 184455168 21:51:11 INFO - PROCESS | 17033 | 21:51:15 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46049/tests/kraken/driver.html?testName=imaging-darkroom) 21:51:15 INFO - PROCESS | 17033 | RSS: Main: 223928320 21:51:15 INFO - PROCESS | 17033 | 21:51:19 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46049/tests/kraken/driver.html?testName=imaging-desaturate) 21:51:19 INFO - PROCESS | 17033 | RSS: Main: 218144768 21:51:19 INFO - PROCESS | 17033 | 21:51:22 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46049/tests/kraken/driver.html?testName=json-parse-financial) 21:51:23 INFO - PROCESS | 17033 | RSS: Main: 220401664 21:51:23 INFO - PROCESS | 17033 | 21:51:24 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46049/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:51:25 INFO - PROCESS | 17033 | RSS: Main: 183312384 21:51:25 INFO - PROCESS | 17033 | 21:51:27 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46049/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:51:28 INFO - PROCESS | 17033 | RSS: Main: 191041536 21:51:28 INFO - PROCESS | 17033 | 21:51:30 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46049/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:51:30 INFO - PROCESS | 17033 | RSS: Main: 198787072 21:51:30 INFO - PROCESS | 17033 | 21:51:33 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46049/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:51:34 INFO - PROCESS | 17033 | RSS: Main: 208986112 21:51:34 INFO - PROCESS | 17033 | 21:51:37 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46049/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:51:38 INFO - PROCESS | 17033 | RSS: Main: 205111296 21:51:38 INFO - PROCESS | 17033 | 21:51:39 INFO - PROCESS | 17033 | Cycle 1(1): loaded http://localhost:46049/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46049/tests/kraken/driver.html?testName=ai-astar) 21:51:40 INFO - PROCESS | 17033 | RSS: Main: 255946752 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | __start_tp_report 21:51:40 INFO - PROCESS | 17033 | _x_x_mozilla_page_load 21:51:40 INFO - PROCESS | 17033 | _x_x_mozilla_page_load_details 21:51:40 INFO - PROCESS | 17033 | |i|pagename|runs| 21:51:40 INFO - PROCESS | 17033 | |0;ai-astar;110;116;115;115;116;115;116;115;114;116 21:51:40 INFO - PROCESS | 17033 | |1;audio-beat-detection;156;130;123;163;129;124;124;124;142;127 21:51:40 INFO - PROCESS | 17033 | |2;audio-dft;162;163;163;163;163;162;160;161;161;160 21:51:40 INFO - PROCESS | 17033 | |3;audio-fft;75;76;76;111;78;77;77;77;94;76 21:51:40 INFO - PROCESS | 17033 | |4;audio-oscillator;85;85;104;86;85;85;110;86;85;105 21:51:40 INFO - PROCESS | 17033 | |5;imaging-gaussian-blur;127;128;128;127;127;128;127;128;128;127 21:51:40 INFO - PROCESS | 17033 | |6;imaging-darkroom;148;148;147;148;147;148;147;148;147;147 21:51:40 INFO - PROCESS | 17033 | |7;imaging-desaturate;95;96;96;96;95;95;95;96;95;95 21:51:40 INFO - PROCESS | 17033 | |8;json-parse-financial;64;62;63;63;64;65;64;64;66;64 21:51:40 INFO - PROCESS | 17033 | |9;json-stringify-tinderbox;66;67;65;66;67;66;66;68;66;71 21:51:40 INFO - PROCESS | 17033 | |10;stanford-crypto-aes;86;85;84;91;86;84;84;85;85;83 21:51:40 INFO - PROCESS | 17033 | |11;stanford-crypto-ccm;139;150;144;151;133;135;137;137;189;141 21:51:40 INFO - PROCESS | 17033 | |12;stanford-crypto-pbkdf2;184;180;178;184;179;179;179;184;178;177 21:51:40 INFO - PROCESS | 17033 | |13;stanford-crypto-sha256-iterative;67;74;68;68;68;68;69;68;68;67 21:51:40 INFO - PROCESS | 17033 | __end_tp_report 21:51:40 INFO - PROCESS | 17033 | __start_cc_report 21:51:40 INFO - PROCESS | 17033 | _x_x_mozilla_cycle_collect,1984 21:51:40 INFO - PROCESS | 17033 | __end_cc_report 21:51:40 INFO - PROCESS | 17033 | __startTimestamp1482299500205__endTimestamp 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | ------- Summary: start ------- 21:51:40 INFO - PROCESS | 17033 | Number of tests: 14 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.50 stddev:1.81 (1.6%) stddev-sans-first:0.71 21:51:40 INFO - PROCESS | 17033 | Values: 110.0 116.0 115.0 115.0 116.0 115.0 116.0 115.0 114.0 116.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:129.50 stddev:14.53 (11.2%) stddev-sans-first:13.09 21:51:40 INFO - PROCESS | 17033 | Values: 156.0 130.0 123.0 163.0 129.0 124.0 124.0 124.0 142.0 127.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.50 stddev:1.23 (0.8%) stddev-sans-first:1.30 21:51:40 INFO - PROCESS | 17033 | Values: 162.0 163.0 163.0 163.0 163.0 162.0 160.0 161.0 161.0 160.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#3] audio-fft Cycles:10 Average:81.70 Median:77.00 stddev:11.70 (15.2%) stddev-sans-first:12.16 21:51:40 INFO - PROCESS | 17033 | Values: 75.0 76.0 76.0 111.0 78.0 77.0 77.0 77.0 94.0 76.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:85.00 stddev:10.29 (12.1%) stddev-sans-first:10.63 21:51:40 INFO - PROCESS | 17033 | Values: 85.0 85.0 104.0 86.0 85.0 85.0 110.0 86.0 85.0 105.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#5] imaging-gaussian-blur Cycles:10 Average:127.50 Median:128.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 21:51:40 INFO - PROCESS | 17033 | Values: 127.0 128.0 128.0 127.0 127.0 128.0 127.0 128.0 128.0 127.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 21:51:40 INFO - PROCESS | 17033 | Values: 148.0 148.0 147.0 148.0 147.0 148.0 147.0 148.0 147.0 147.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 21:51:40 INFO - PROCESS | 17033 | Values: 95.0 96.0 96.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:64.00 stddev:1.10 (1.7%) stddev-sans-first:1.17 21:51:40 INFO - PROCESS | 17033 | Values: 64.0 62.0 63.0 63.0 64.0 65.0 64.0 64.0 66.0 64.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:66.50 stddev:1.69 (2.5%) stddev-sans-first:1.76 21:51:40 INFO - PROCESS | 17033 | Values: 66.0 67.0 65.0 66.0 67.0 66.0 66.0 68.0 66.0 71.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.00 stddev:2.21 (2.6%) stddev-sans-first:2.33 21:51:40 INFO - PROCESS | 17033 | Values: 86.0 85.0 84.0 91.0 86.0 84.0 84.0 85.0 85.0 83.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:142.50 stddev:16.39 (11.5%) stddev-sans-first:17.21 21:51:40 INFO - PROCESS | 17033 | Values: 139.0 150.0 144.0 151.0 133.0 135.0 137.0 137.0 189.0 141.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.20 Median:179.50 stddev:2.74 (1.5%) stddev-sans-first:2.54 21:51:40 INFO - PROCESS | 17033 | Values: 184.0 180.0 178.0 184.0 179.0 179.0 179.0 184.0 178.0 177.0 21:51:40 INFO - PROCESS | 17033 | 21:51:40 INFO - PROCESS | 17033 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.00 stddev:2.01 (3.0%) stddev-sans-first:2.06 21:51:40 INFO - PROCESS | 17033 | Values: 67.0 74.0 68.0 68.0 68.0 68.0 69.0 68.0 68.0 67.0 21:51:40 INFO - PROCESS | 17033 | -------- Summary: end -------- 21:51:40 INFO - PROCESS | 17033 | 21:51:41 INFO - TEST-INFO | 17033: exit 0 21:51:41 INFO - TEST-OK | kraken | took 63730ms 21:51:41 INFO - SUITE-END | took 1186s 21:51:41 INFO - Completed test suite (00:19:46) 21:51:41 INFO - javascript benchmark 21:51:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2605.869706514674, 2627.8291911025794, 2641.3132585527765, 2653.27035065357, 2653.6682914635658, 4709.5525925037155, 4775.426948766144, 4793.9760301198485, 4807.062622788553, 4820.011897561, 4803.207470767328, 4814.157522433569, 4820.228763397857, 4848.1758101122805, 4889.877753056176, 4334.241507736149, 4336.284513055342, 4380.802863871133, 4405.118976204756, 4447.288433850585, 4041.2520072039656, 4371.388005679208, 4436.9556304436865, 4463.754493502856, 4511.368408422823, 4306.0526684129445, 4344.522102568715, 4447.888802779924, 4494.37078808967, 4519.163954668397, 4370.6940514164025, 4394.033312671239, 4394.07724377881, 4396.582324679182, 4400.955990440087, 4371.628411585019, 4372.234858899712, 4375.846845360397, 4379.08039311745, 4381.737095774231, 4733.290006499018, 4753.548412900769, 4765.0, 4770.809167633291, 4772.379590653228, 5057.115004874132, 5145.125328694131, 5147.974260128713, 5190.4550022247495, 5196.272521846944, 5131.050755610224, 5174.456682048369, 5177.042247184283, 5177.249298851645, 5224.712640804754, 4662.323963025342, 4684.6486513510945, 4762.476127625959, 4796.592289655384, 4806.206975849016, 5175.0, 5259.684418934876, 5296.311479507639, 5314.28257185275, 5322.600804939645, 5002.0, 5038.0, 5048.0, 5050.621203409685, 5097.286379906816, 5137.0, 5137.2037334213255, 5141.948580514222, 5148.639595228298, 5152.227165925066, 4898.314236006961, 4902.044101400193, 4923.5814955729165, 5028.84913452597, 5042.319286896295, 5072.695638261716, 5079.644424890223, 5080.77136528857, 5081.23781432788, 5091.1090559152, 4405.889852753707, 4411.867643970686, 4414.580614841585, 4417.845375411845, 4459.598636122764, 3828.062124779391, 3849.4418309344987, 3852.8844134675446, 3872.496575445174, 3882.320593896057, 4308.073764140725, 4350.499692535335, 4351.673624478113, 4373.759443230653, 4403.053343531156, 5115.897682046339, 5118.539331460186, 5125.948740512622, 5147.613928955343, 5157.071727089085, 4372.890677733082, 4415.602595766396, 4418.8453404130705, 4462.152191083748, 4464.977675111603, 4515.864524064284, 4539.251023581138, 4557.065801510748, 4583.266677331678, 4632.3977882875015, 4790.4251489821445, 4809.831655892027, 4835.44392394865, 4845.9757701211265, 4849.102915960559, 5171.431142574239, 5182.922256166236, 5184.403793563789, 5187.170052791535, 5230.5815534757885, 4642.071585682945, 4709.976450117728, 4716.316134160596, 4756.310335001336, 4788.904221915612, 4929.531694489017, 4933.889874778146, 4976.054549635558, 4981.975090124526, 5039.823606173766, 5048.343715316985, 5057.8229761958155, 5058.140116180185, 5058.949410505995, 5124.0, 4945.307656927961, 4946.4558898521855, 4990.251462280542, 4999.008133306826, 5000.074986127578, 4881.292212629078, 4894.314795928644, 4896.4124305083615, 4905.092557876804, 4955.4301255356095, 4869.732164730832, 4928.8767780805765, 4930.654854160318, 4940.975295123645, 4989.650724449254, 5039.47085556026, 5040.697558146523, 5045.091883461012, 5052.267421223975, 5092.694438333712, 4795.520447955177, 4905.730184839868, 4909.4354149273295, 4910.8772280693265, 4958.281049247911], "value": 4689.965022583926, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.7695502456431, 536.401911868267, 539.8002738986576, 545.8826352334247, 553.24758328673, 27382.72617273827, 30120.84939575302, 37481.62518374819, 38798.41802372967, 38831.50516453526, 39916.20167596646, 40159.397609035885, 40160.0, 40203.19593608126, 40253.59746402535, 7283.635818209082, 7448.4041276697735, 7452.55284682918, 7454.366378857777, 7465.589392583406, 7124.928750712478, 7325.706971721124, 9612.279079069063, 9855.063768941938, 9872.111509964101, 9823.95088024559, 9906.455144967023, 9915.752106197331, 9916.652917147901, 9927.702168934908, 9867.358621689604, 9933.453660048694, 9935.0, 9935.602575896992, 9939.204863610894, 9759.219262458986, 9833.950830245803, 9839.90160098397, 9840.16358609519, 9842.90157098427, 35621.0, 35912.64087359119, 35942.0, 35955.64044359575, 35969.82015089934, 2843.5876797864194, 2852.2584128126628, 2901.2601786544506, 2915.839628820414, 3306.35526072416, 26971.46057078867, 27020.32449188786, 27608.723912761016, 27771.0, 28004.43991120166, 3321.4021476134285, 3440.2431465077643, 3447.0692912913623, 3456.654334566548, 3498.772579782306, 226387.0, 226806.4429047066, 227416.0, 227605.0, 228752.0, 44917.32624010643, 45175.83769136129, 45181.77409112999, 45193.32210016852, 45266.773666131456, 54066.0, 54074.0, 54151.45848541543, 54214.186787198225, 54278.18582721263, 14161.575152745434, 14411.85588144126, 14460.710785784226, 14466.0, 14468.27658617086, 10997.945010274898, 11105.05607023412, 11111.611093611686, 11112.166587505808, 11115.72210694739, 14079.929600352138, 14486.275686215859, 15881.205939702968, 16311.553267009805, 16503.257353419125, 5994.790182343597, 6099.451049405575, 6147.1558532440085, 6211.565190436717, 6302.0, 730.1126587770673, 793.5119901260798, 857.5455008845322, 894.7584152278848, 922.6447817590142, 25551.744482555307, 26629.86685066562, 27176.624064697648, 27292.727072729416, 27684.723152768216, 922.6447817590276, 927.536231884058, 949.1931857920712, 955.8900726416551, 978.5694294510392, 13064.0, 13165.868341316464, 13296.534621288209, 13337.866621333662, 13425.597232083053, 326866.0, 327468.0, 327618.0, 327876.0, 328081.0, 36396.818015909754, 37385.813070935015, 45555.08889822185, 46943.0, 47039.0, 1138.299945533491, 1152.1877076660767, 1165.5221359242669, 1170.7131752720638, 1172.947217375203, 24653.38366540851, 25088.62367064459, 25369.619455708547, 25889.352766180997, 25982.090626828725, 36509.0, 36861.631383686894, 36928.0, 36954.44568331423, 36997.260054798215, 235657.0, 236464.0, 236520.0, 236581.0, 236890.0, 22452.550948981254, 22761.544769104858, 22810.65784013208, 22836.657450138595, 22847.28645351572, 34525.30949380948, 35346.46980295246, 35388.82305588559, 35534.11164720903, 36169.63830361768, 30023.249418763833, 30446.238844029078, 30699.539506907862, 30700.539491908086, 31112.0, 29590.40819183647, 30089.54865676973, 30111.69888301089, 30504.237394065327, 31169.37661246717, 29879.55180672335, 30346.241343966576, 30455.695443045286, 30668.693313066582, 30771.0, 25862.32745954431, 28409.28976775597, 28686.0, 28694.71305287004, 28789.56815647725, 22214.444638884157, 22271.443213919134, 22362.77637223607, 22374.440638984157, 22575.0], "value": 16429.434120473168, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.6634614904317, 357.55305867665436, 368.2818503917361, 374.99062523436913, 388.9727719059665, 12322.554913807113, 12459.937700311497, 13250.66873328166, 13449.462021519128, 16665.41671041514, 16095.436659716912, 16202.918985405056, 16369.918150409261, 16596.25316860741, 16638.251278692456, 4901.975490122544, 4944.604431645478, 5048.318477005612, 5072.670276432039, 5077.49225077493, 3711.740178187528, 5039.244113382985, 5048.217526283432, 5085.084684756743, 5087.516685914832, 3869.419587061935, 5613.66318020918, 5875.6474611523445, 5931.644101353911, 5976.402359764011, 5609.0, 5695.373508914016, 5973.372795856438, 5986.07215881539, 5986.850328741816, 5062.620303477217, 5068.543831055186, 5075.543201111917, 5079.492050794928, 5083.313752643383, 19571.804281957284, 19896.20415183391, 19901.313322701393, 19969.201231950705, 20045.0, 1860.823221793941, 1876.6622008038548, 1908.4847091285415, 2429.465517586128, 2480.888360023803, 12230.877691223062, 12871.163374380636, 13186.538471153559, 13413.731725365438, 13488.0, 1690.5435532406182, 1732.2118436111539, 1758.762567053457, 1792.5966657502088, 1846.2799508191815, 51239.487605123846, 57359.0, 61961.690191548754, 62189.0, 62782.0, 9252.686828292926, 9387.718368448957, 9586.376885502421, 9774.31579789408, 9871.950640246752, 12660.430280637393, 12663.683407914876, 12678.429470673842, 12680.936595316964, 12687.49250030007, 6816.591004539743, 6832.897506537406, 6947.861042779116, 7055.0, 7354.9773812202075, 6537.444317232993, 6579.835504112435, 6699.464042876558, 6759.898601520981, 6858.177018757781, 5808.128780682931, 5983.700814959235, 6029.095635654687, 6115.143879856823, 6252.624842509464, 1650.8761842861593, 1792.139772909011, 1808.6887006919878, 1822.9908850455663, 1824.80839511852, 782.8434313137395, 823.7322870067132, 855.1662129423886, 888.560162719458, 888.657866721304, 15987.520374388787, 16239.675206495804, 16568.254428550743, 17009.149542522824, 17458.912705436393, 801.4389927050971, 817.9795505112301, 844.8648216285487, 861.4185424838209, 917.504467717616, 9208.987011428737, 9775.951120244354, 9823.36148150368, 9878.259130565237, 9886.604535818487, 334299.0, 335421.0, 342934.0, 343453.0, 343800.0, 45612.0, 50832.0, 53912.0, 63581.0, 66277.20023567743, 976.0581039297113, 999.4353190447374, 1004.924630652704, 1006.8439391894268, 1011.9981218593687, 15322.310496027929, 15384.923075384551, 15650.37398504047, 15688.843111568738, 16066.70896764, 26722.0, 29784.702152979058, 30049.699503005566, 33537.329253415286, 33880.32239355249, 42034.369484457144, 42141.0, 42668.359974599785, 43864.0, 44277.77861110674, 7419.752408799968, 7478.2895624915545, 7554.093508778982, 7640.770776876702, 7851.646675899484, 11230.887691122984, 11561.30632162073, 11691.240069395462, 11758.3532905691, 12188.817167742669, 9463.2429405646, 10139.391636501834, 10224.437655928697, 10418.479076046318, 10594.894051059699, 9712.077352651488, 9774.364666296668, 10111.797764044824, 10191.898081019095, 10298.176145908159, 10379.462615073602, 10502.4223667699, 10569.207309451667, 10650.73373165646, 10798.190135739695, 7392.852142957218, 7473.17795042534, 7556.357709594527, 7611.809704757426, 7836.177201393772, 10326.225533085199, 10358.378497290187, 10390.402399400149, 10435.426051567236, 10696.946515267375], "value": 8038.795603193272, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.6962163891483, 1329.6277042428121, 1388.4862600837691, 1592.8267938002436, 1633.509947015895, 7120.359167674908, 7512.399008079355, 7513.398928085754, 7516.0229170207795, 7522.473426860122, 6426.839329016765, 6601.801945941617, 6602.47180225582, 6750.831229219272, 7379.704811807521, 1886.0663971334209, 2090.247510896076, 2146.0986385717956, 2149.322963266574, 2154.450615093157, 2147.75300840403, 2152.4834039830484, 2156.3962090614573, 2158.028887000848, 2158.7625361210253, 2069.379186244128, 2136.3377353020537, 2154.1598776477185, 2154.709114269569, 2156.2345367394582, 2050.3131450963947, 2057.2902348689827, 2081.646120159569, 2096.4025252802876, 2098.401955442707, 2103.49516116131, 2114.534802343497, 2114.756802967663, 2137.070374387147, 2141.839362047837, 7373.0, 7434.9256507434775, 7491.063617047869, 7536.73621423253, 7542.472026958115, 4419.779011049467, 4552.931706024412, 4553.68124231304, 4554.612857907049, 4560.065186636732, 2990.5215165573404, 3004.278973046475, 3075.154332558542, 3077.553754705555, 3080.199148221456, 863.4775960543906, 865.9523726195057, 884.8539990901494, 890.1943740914418, 896.5293221058931, 9966.850497242547, 9973.0, 9992.500374981295, 9994.500274986221, 10002.049805268487, 4118.814653340607, 4243.108947121077, 4259.254630439661, 4279.657627389802, 4311.051568654891, 7575.204603516661, 7686.884696729554, 7692.653830577638, 7697.0, 7700.037495313086, 4478.41780568524, 4479.148961697267, 4486.68593198473, 4487.685861989695, 4490.685652004328, 4046.352583586612, 4155.522114956819, 4204.705670603028, 4209.873703788891, 4210.157968406331, 2974.1969668189467, 3036.726694597497, 3204.150900011499, 3314.767966242388, 3334.9333013339597, 1002.518790980319, 1018.3584341864726, 1020.0870221152028, 1021.6526381030485, 1026.2508368890603, 754.97357592484, 776.6116941529235, 813.6826637611337, 829.1708291708292, 839.6053854688287, 4851.58761505276, 4854.441739200037, 4875.7074575525585, 4913.754312284372, 5006.4743201964, 730.9780706578811, 766.4826242286434, 767.9308862202429, 791.2759824760279, 793.5714714054461, 3241.2059045533524, 3280.327532855759, 3318.070940136766, 3330.9500357494658, 3380.695737383647, 8716.564171791511, 8831.823363532694, 9026.323025772961, 9056.547172641473, 9100.362974591848, 8803.823923521493, 9034.32242581809, 9054.909450905407, 9061.13919177677, 9067.909320906838, 6896.344847239504, 7059.258777828357, 7062.540934839321, 7067.611281379471, 7069.434445244368, 4628.305754136906, 4943.011397720398, 4984.277279794482, 5031.345925029723, 5041.571466425395, 7994.200579941959, 8219.712310069119, 8223.383246256435, 8274.089850116603, 8300.086990430927, 8342.08237093907, 8353.0, 8360.83278334442, 8374.455660382057, 8471.745847624581, 6434.742610295536, 6580.276169621434, 6582.637954912526, 6582.80251592453, 6584.04531342943, 7103.715851365888, 7105.502614816914, 7111.217766045627, 7152.499325047404, 7177.174624917992, 6479.287278399466, 6491.610503369813, 6492.935070649422, 6493.902591461227, 6504.6747662615735, 6320.08358787964, 6391.93608063932, 6409.487241020801, 6424.550281480439, 6438.6136831790245, 5496.0107180707855, 5519.862003449946, 5541.722913854372, 5593.328800543961, 5602.411746766694, 4202.3696445533415, 4210.6841986850495, 4254.0, 4277.165952639206, 4366.454193225847, 4361.127774445187, 4384.56154384559, 4389.561043895713, 4531.048479819275, 4615.423072115986], "value": 3913.8695631437613, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.86943830782616, 356.70928193522275, 359.6924629441828, 364.21329927356913, 368.37376460017975, 4843.878903027422, 4864.318995340646, 4882.682625629332, 4896.828610998616, 4906.460289368167, 4563.680542362036, 4672.859814205571, 4753.90492190156, 4806.567408933195, 4853.296272040552, 3886.650201481866, 3888.805559722017, 3891.630295121973, 3896.0909154514297, 3920.5491368492703, 3881.359575670011, 3892.124272038797, 3909.2376986487648, 3922.333203355422, 3925.391564307537, 3624.4382120771193, 3677.264547090579, 3843.9621302248515, 3900.0, 3918.6473217410426, 3837.692984561242, 3840.36633955397, 3847.1343947611845, 3848.461215429842, 3854.8458061677497, 3467.7572569920117, 3470.288590838897, 3471.427214509603, 3476.148343655795, 3488.354654388946, 4675.322078298662, 4700.929486057677, 4701.64737644678, 4709.858704238878, 4712.222483290253, 4285.764282964436, 4395.802188901475, 4432.955670443319, 4444.511103778582, 4481.946742515506, 5043.747812609355, 5069.898602027939, 5099.566536844372, 5125.641704948192, 5135.948640513584, 3847.2690188864026, 3856.132370216707, 3871.8064096795047, 3884.533855937277, 3904.863329783444, 5116.6674166179455, 5291.788328466857, 5313.973430132902, 5325.893482130375, 5384.273123128367, 3945.684345252373, 3949.743266687656, 3952.980235098806, 3971.4241434991764, 3981.5222173338807, 4013.8857670887573, 4017.6183262590594, 4021.416894550274, 4023.8591649292134, 4032.7378720383667, 4196.454460920061, 4413.360062790942, 4447.911041779146, 4460.3532487789735, 4474.373587697725, 5079.822206222764, 5084.618653600995, 5087.618428617869, 5095.541401273903, 5101.7194054327365, 1357.9932100339436, 1358.6535433464403, 1358.8641135886332, 1367.7538043152329, 1378.3521744780137, 930.1256818590504, 944.8110377924443, 948.9762755931157, 958.6932181701928, 995.4475266227313, 4063.817128229178, 4237.427947227085, 4372.562743725602, 4387.3857659927635, 4413.602775750198, 5110.872228194325, 5115.514026167508, 5124.668965172593, 5133.307003554547, 5137.17805151174, 4208.579142085767, 4378.0, 4385.627221686193, 4422.204003279377, 4433.935855394682, 2711.9050833221136, 2712.91861244163, 2719.9048033319136, 2720.360715231919, 2730.317420644839, 4708.599769019672, 4719.174144524694, 4764.213904705754, 4788.018456216468, 4817.566419022305, 5074.416442109131, 5121.18198039698, 5179.533841954242, 5288.139658049568, 5293.682379057269, 4681.50844161365, 4701.576858082789, 4703.412073490814, 4716.858494245178, 4740.6681532292405, 5077.289179514944, 5104.183330667075, 5110.437851836221, 5134.28120063184, 5139.0, 4782.504445820917, 4899.877503062452, 4980.626453015966, 5010.198368261061, 5012.949870501248, 4675.579197872208, 4682.344471773887, 4733.408323959505, 4741.193997020584, 4761.595264402565, 3857.1707082977296, 3857.5534174684494, 3867.5939026401825, 3890.9221815562964, 3906.9609303906595, 4913.248580794766, 5126.231065340229, 5181.3005244291535, 5184.407796101949, 5202.973985130202, 4983.579679791242, 4985.6011519077665, 4986.775595098156, 5000.074986127578, 5021.623378246573, 4980.256910081587, 4987.132398870592, 5002.2496625506465, 5002.699838009731, 5018.42288136869, 4755.239161734106, 4798.880027999188, 4834.220323314038, 4923.4584195737725, 4932.136876046749, 4899.608031357554, 4982.75086245679, 4990.47600002009, 5167.509086636764, 5180.099144479103, 5035.018171456677, 5043.722595257149, 5048.419431765247, 5050.393952725696, 5072.721000345016], "value": 3869.65860608474, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [611.6574718157831, 645.2837350540901, 645.8503863123639, 705.3616477088234, 707.3068392974884, 12164.270143791378, 13289.534866279682, 13296.867031329684, 13305.46778128876, 13324.067315287934, 12920.418581163847, 12963.092583519157, 13007.349632518384, 13031.0, 13101.737965240689, 616.5684021185166, 627.4854619212248, 753.9623018849036, 754.3098065270271, 767.5816679909462, 779.7582749347692, 781.9257170568787, 782.4796510320658, 782.487470706688, 782.6556315221313, 801.9398545109111, 805.8227190018187, 807.9757607271791, 816.1226681317578, 818.3085292927497, 649.6881496881476, 657.6580178307254, 658.0392626764929, 658.5258613798057, 810.3274282345639, 486.67392846792734, 583.1981026089128, 593.715016791938, 598.0938877600439, 601.425638515217, 13068.673283167902, 13117.47530098795, 13120.737585248336, 13131.146475479063, 13134.080614356999, 12091.45588448522, 12135.332556709378, 12177.086718496059, 12494.250344979331, 12570.560030398892, 11178.52290203617, 11284.774304513945, 11305.417241586185, 11334.546618135264, 11457.541698332057, 4072.1448495815916, 4104.220198162339, 4115.32097203961, 4116.135611521584, 4125.968507873032, 59233.70383148057, 60962.69518652423, 61758.0, 62219.68890155611, 62385.376146238414, 9775.778027746532, 9943.552540135712, 10116.190704743603, 10283.331583447201, 10724.731881702864, 19089.618207636046, 19102.044897755335, 19111.426657200307, 19111.713324300148, 19114.0, 5766.625169363978, 6033.849153771103, 6041.939580604226, 6143.784967526115, 6321.850570805948, 3578.516900218437, 3733.3839916413485, 3737.1591391936595, 3769.2838360711376, 3777.58446570877, 2800.2579316481147, 2864.1837076433535, 2888.465633857743, 2892.103447931148, 2892.117904039288, 487.198558371484, 510.4461659099844, 512.7820676212594, 521.6661336744487, 530.1226470191839, 467.9064187162581, 522.9932380168237, 548.5912994818885, 573.1116769008021, 583.7635757518212, 7580.469367144247, 7687.346575541142, 7849.215078492105, 8019.278264956182, 8162.1837816219095, 500.964932454732, 513.7893463679874, 517.0666946162138, 538.6337290642323, 540.4406439335269, 3713.721470889694, 3716.2381711749026, 3818.4654148419245, 3821.8471261149243, 3851.537815462106, 51107.48892511027, 59856.0, 63989.36010639834, 66426.0, 66511.33488665149, 55553.72223138859, 56144.0, 56292.43707562954, 57223.0, 57663.711681441324, 53078.73460632672, 54185.72907135518, 55147.72426137843, 55650.0, 55785.0, 7242.094738157731, 7574.280443357872, 7623.352015078559, 7669.118051424158, 7855.096663883726, 36989.81505092565, 37024.81487592545, 37545.81227093847, 37634.0, 39355.21289574135, 44428.777856110515, 47747.0, 49624.751876240385, 50173.24740128828, 51305.0, 9628.32601717873, 9737.22102231834, 11142.721431964266, 11177.66467005991, 11394.829077563678, 11743.47154378072, 11871.347075910908, 12807.679808004874, 13229.140105893086, 13313.603551360848, 11556.88443115558, 11893.286402815858, 12268.877311226774, 12804.871951280367, 12963.611091667264, 12041.518339266331, 12516.436760345989, 12571.245725256515, 12752.553660621834, 13359.53241636538, 11593.362365070003, 12101.394930253628, 12516.499340026661, 12633.81049284243, 13024.41390137465, 9404.38871473352, 10067.0, 10280.897191027994, 10375.273730839072, 10629.149668026694, 8891.244244239311, 9773.218142548436, 9781.119699227103, 9794.510274486105, 9829.410235385614], "value": 6197.234652420898, "unit": "score"}], "name": "dromaeo_css", "value": 6224.215687703859, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 116.0, 115.0, 115.0, 116.0, 115.0, 116.0, 115.0, 114.0, 116.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 130.0, 123.0, 163.0, 129.0, 124.0, 124.0, 124.0, 142.0, 127.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 163.0, 163.0, 162.0, 160.0, 161.0, 161.0, 160.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 111.0, 78.0, 77.0, 77.0, 77.0, 94.0, 76.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 104.0, 86.0, 85.0, 85.0, 110.0, 86.0, 85.0, 105.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 128.0, 127.0, 127.0, 128.0, 127.0, 128.0, 128.0, 127.0], "value": 127.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 148.0, 147.0, 148.0, 147.0, 148.0, 147.0, 147.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 63.0, 64.0, 65.0, 64.0, 64.0, 66.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 66.0, 67.0, 66.0, 66.0, 68.0, 66.0, 71.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 91.0, 86.0, 84.0, 84.0, 85.0, 85.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 150.0, 144.0, 151.0, 133.0, 135.0, 137.0, 137.0, 189.0, 141.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 180.0, 178.0, 184.0, 179.0, 179.0, 179.0, 184.0, 178.0, 177.0], "value": 180.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 68.0, 68.0, 68.0, 68.0, 69.0, 68.0, 68.0, 67.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1564.8, "alertThreshold": 2.0}]} 21:51:41 INFO - Return code: 0 21:51:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:51:41 INFO - # TBPL SUCCESS # 21:51:41 INFO - Running post-action listener: _resource_record_post_action 21:51:41 INFO - [mozharness: 2016-12-21 05:51:41.992562Z] Finished run-tests step (success) 21:51:41 INFO - Running post-run listener: _resource_record_post_run 21:51:42 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 381620224; Read time: 12; Write time: 294140 21:51:42 INFO - TinderboxPrint: CPU usage
13.2% 21:51:42 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 21:51:42 INFO - TinderboxPrint: I/O write bytes / time
381,620,224 / 294,140 21:51:42 INFO - TinderboxPrint: CPU idle
8,337.2 (86.8%) 21:51:42 INFO - TinderboxPrint: CPU user
1,220.5 (12.7%) 21:51:42 INFO - TinderboxPrint: Swap in / out
0 / 0 21:51:42 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99098624; Read time: 0; Write time: 64756 21:51:42 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 282521600; Read time: 0; Write time: 229384 21:51:42 INFO - Running post-run listener: _upload_blobber_files 21:51:42 INFO - Blob upload gear active. 21:51:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:51:42 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 21:51:42 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:51:42 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:51:43 INFO - (blobuploader) - INFO - Open directory for files ... 21:51:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:51:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:51:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:51:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:51:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:51:43 INFO - (blobuploader) - INFO - Done attempting. 21:51:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:51:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:51:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:51:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:51:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:51:47 INFO - (blobuploader) - INFO - Done attempting. 21:51:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:51:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:51:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:51:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:51:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:51:48 INFO - (blobuploader) - INFO - Done attempting. 21:51:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:51:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:51:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:51:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:51:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:51:49 INFO - (blobuploader) - INFO - Done attempting. 21:51:49 INFO - (blobuploader) - INFO - Iteration through files over. 21:51:49 INFO - Return code: 0 21:51:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:51:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:51:49 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/26a132d8f923ba9d7338552662f8afa39e1a5549926d48f8e01c59ec55b454a1c2f559619a6a65bc41f1bf0e0a9c340624c1ac07021a209ed8ef285c9f819400", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e8b56b1cb83b0508e703fd5b10f8a3d04c22c8ca215f9debb4e52df6d8e4f6bc81716dab7f9743103482d599234e65d2fccb2271e2e484097fbef9a8e1e31012", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2e6f377c047c7d3106572d67b0a0e84de8db4f0cd499d1769e53bd5d5f19b2cb08e9bf6cfa0edc485a33df27c46043dbe20fe215b681eccce4cb4792638406c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ffbd42c7d3153b84ecaa6e86db72c043c53820aeb91db05a6fb6864c5fd269e6b46fa1b09197b9dc343ff3713a4692fc7a75565b5b4fb0981a253788bf36b01c"} 21:51:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:51:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:51:49 INFO - Contents: 21:51:49 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/26a132d8f923ba9d7338552662f8afa39e1a5549926d48f8e01c59ec55b454a1c2f559619a6a65bc41f1bf0e0a9c340624c1ac07021a209ed8ef285c9f819400", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e8b56b1cb83b0508e703fd5b10f8a3d04c22c8ca215f9debb4e52df6d8e4f6bc81716dab7f9743103482d599234e65d2fccb2271e2e484097fbef9a8e1e31012", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2e6f377c047c7d3106572d67b0a0e84de8db4f0cd499d1769e53bd5d5f19b2cb08e9bf6cfa0edc485a33df27c46043dbe20fe215b681eccce4cb4792638406c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ffbd42c7d3153b84ecaa6e86db72c043c53820aeb91db05a6fb6864c5fd269e6b46fa1b09197b9dc343ff3713a4692fc7a75565b5b4fb0981a253788bf36b01c"} 21:51:49 INFO - Running post-run listener: copy_logs_to_upload_dir 21:51:49 INFO - Copying logs to upload dir... 21:51:49 INFO - mkdir: /builds/slave/test/build/upload/logs 21:51:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.795881 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-12-20 21:51:48.409044) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 21:51:48.413178) ========= 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=523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/26a132d8f923ba9d7338552662f8afa39e1a5549926d48f8e01c59ec55b454a1c2f559619a6a65bc41f1bf0e0a9c340624c1ac07021a209ed8ef285c9f819400", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e8b56b1cb83b0508e703fd5b10f8a3d04c22c8ca215f9debb4e52df6d8e4f6bc81716dab7f9743103482d599234e65d2fccb2271e2e484097fbef9a8e1e31012", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2e6f377c047c7d3106572d67b0a0e84de8db4f0cd499d1769e53bd5d5f19b2cb08e9bf6cfa0edc485a33df27c46043dbe20fe215b681eccce4cb4792638406c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ffbd42c7d3153b84ecaa6e86db72c043c53820aeb91db05a6fb6864c5fd269e6b46fa1b09197b9dc343ff3713a4692fc7a75565b5b4fb0981a253788bf36b01c"} build_url:https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011397 build_url: 'https://queue.taskcluster.net/v1/task/NwdOiafGRoy2C5qTHb1i4Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/26a132d8f923ba9d7338552662f8afa39e1a5549926d48f8e01c59ec55b454a1c2f559619a6a65bc41f1bf0e0a9c340624c1ac07021a209ed8ef285c9f819400", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/e8b56b1cb83b0508e703fd5b10f8a3d04c22c8ca215f9debb4e52df6d8e4f6bc81716dab7f9743103482d599234e65d2fccb2271e2e484097fbef9a8e1e31012", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2e6f377c047c7d3106572d67b0a0e84de8db4f0cd499d1769e53bd5d5f19b2cb08e9bf6cfa0edc485a33df27c46043dbe20fe215b681eccce4cb4792638406c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ffbd42c7d3153b84ecaa6e86db72c043c53820aeb91db05a6fb6864c5fd269e6b46fa1b09197b9dc343ff3713a4692fc7a75565b5b4fb0981a253788bf36b01c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 21:51:48.439682) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 21:51:48.439992) ========= 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=523ca41ca1e0623de333ad52000001bf-1482297976.844815-1443525757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004572 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 21:51:48.523108) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 21:51:48.523428) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 21:51:48.523960) ========= ========= Total master_lag: 0.16 =========