builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-059 starttime: 1480530175.47 results: success (0) buildid: 20161130095244 builduid: 795d8b50f80647d1a1e444c8033398d1 revision: a6aabcb07a6e49cd52077674cb7e7c802d7a1ed4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:55.471550) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:55.472084) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:55.472415) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:55.487393) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:55.487703) ========= 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=3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-30 10:22:55-- 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% 223M=0s 2016-11-30 10:22:55 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.071219 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:55.573285) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:55.573644) ========= 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=3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016168 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:55.623690) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:55.624104) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a6aabcb07a6e49cd52077674cb7e7c802d7a1ed4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a6aabcb07a6e49cd52077674cb7e7c802d7a1ed4 --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=3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-30 10:22:55,758 truncating revision to first 12 chars 2016-11-30 10:22:55,758 Setting DEBUG logging. 2016-11-30 10:22:55,758 attempt 1/10 2016-11-30 10:22:55,758 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a6aabcb07a6e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-30 10:22:56,083 unpacking tar archive at: autoland-a6aabcb07a6e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.648113 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:56.289525) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:56.290543) ========= script_repo_revision: a6aabcb07a6e49cd52077674cb7e7c802d7a1ed4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:56.291781) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:56.292330) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 10:22:56.303251) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-11-30 10:22:56.303567) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:22:56 INFO - MultiFileLogger online at 20161130 10:22:56 in /builds/slave/test 10:22:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 10:22:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:22:56 INFO - {'append_to_log': False, 10:22:56 INFO - 'base_work_dir': '/builds/slave/test', 10:22:56 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 10:22:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:22:56 INFO - 'branch': 'Autoland-Non-PGO', 10:22:56 INFO - 'buildbot_json_path': 'buildprops.json', 10:22:56 INFO - 'config_files': ('talos/linux_config.py',), 10:22:56 INFO - 'default_actions': ('clobber', 10:22:56 INFO - 'read-buildbot-config', 10:22:56 INFO - 'download-and-extract', 10:22:56 INFO - 'populate-webroot', 10:22:56 INFO - 'create-virtualenv', 10:22:56 INFO - 'install', 10:22:56 INFO - 'run-tests'), 10:22:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:22:56 INFO - 'download_minidump_stackwalk': True, 10:22:56 INFO - 'download_symbols': 'ondemand', 10:22:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:22:56 INFO - 'tooltool.py': '/tools/tooltool.py', 10:22:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:22:56 INFO - '/tools/misc-python/virtualenv.py')}, 10:22:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:22:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:22:56 INFO - 'installer_path': 'installer.exe', 10:22:56 INFO - 'log_level': 'info', 10:22:56 INFO - 'log_name': 'talos', 10:22:56 INFO - 'log_to_console': True, 10:22:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:22:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:22:56 INFO - 'opt_config_files': (), 10:22:56 INFO - 'pip_index': False, 10:22:56 INFO - 'sps_profile': False, 10:22:56 INFO - 'sps_profile_interval': 0, 10:22:56 INFO - 'suite': 'dromaeojs', 10:22:56 INFO - 'system_bits': '32', 10:22:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:22:56 INFO - 'title': 'talos-linux64-ix-059', 10:22:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:22:56 INFO - 'use_talos_json': True, 10:22:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:22:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:22:56 INFO - 'work_dir': 'build'} 10:22:56 INFO - [mozharness: 2016-11-30 18:22:56.532331Z] Running clobber step. 10:22:56 INFO - Running pre-action listener: _resource_record_pre_action 10:22:56 INFO - Running main action method: clobber 10:22:56 INFO - rmtree: /builds/slave/test/build 10:22:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:22:57 INFO - Running post-action listener: _resource_record_post_action 10:22:57 INFO - [mozharness: 2016-11-30 18:22:57.007538Z] Finished clobber step (success) 10:22:57 INFO - [mozharness: 2016-11-30 18:22:57.007637Z] Running read-buildbot-config step. 10:22:57 INFO - Running pre-action listener: _resource_record_pre_action 10:22:57 INFO - Running main action method: read_buildbot_config 10:22:57 INFO - Using buildbot properties: 10:22:57 INFO - { 10:22:57 INFO - "project": "", 10:22:57 INFO - "product": "firefox", 10:22:57 INFO - "script_repo_revision": "production", 10:22:57 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 10:22:57 INFO - "repository": "", 10:22:57 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 10:22:57 INFO - "buildid": "20161130095244", 10:22:57 INFO - "pgo_build": "False", 10:22:57 INFO - "basedir": "/builds/slave/test", 10:22:57 INFO - "buildnumber": 1170, 10:22:57 INFO - "slavename": "talos-linux64-ix-059", 10:22:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:22:57 INFO - "platform": "ubuntu64_hw", 10:22:57 INFO - "scheduler": "tests-autoland-linux64-talos", 10:22:57 INFO - "branch": "autoland", 10:22:57 INFO - "repo_path": "integration/autoland", 10:22:57 INFO - "revision": "a6aabcb07a6e49cd52077674cb7e7c802d7a1ed4", 10:22:57 INFO - "stage_platform": "linux64", 10:22:57 INFO - "builduid": "795d8b50f80647d1a1e444c8033398d1", 10:22:57 INFO - "slavebuilddir": "test" 10:22:57 INFO - } 10:22:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 10:22:57 INFO - Running post-action listener: _resource_record_post_action 10:22:57 INFO - [mozharness: 2016-11-30 18:22:57.010332Z] Finished read-buildbot-config step (success) 10:22:57 INFO - [mozharness: 2016-11-30 18:22:57.010420Z] Running download-and-extract step. 10:22:57 INFO - Running pre-action listener: _resource_record_pre_action 10:22:57 INFO - Running main action method: download_and_extract 10:22:57 INFO - mkdir: /builds/slave/test/build/tests 10:22:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:22:57 INFO - trying https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 10:22:57 INFO - Downloading https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/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 10:22:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/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 10:22:57 INFO - Downloaded 1361 bytes. 10:22:57 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 10:22:57 INFO - Using the following test package requirements: 10:22:57 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 10:22:57 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:22:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:22:57 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:22:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:22:57 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:22:57 INFO - u'jsshell-linux-x86_64.zip'], 10:22:57 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:22:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:22:57 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 10:22:57 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:22:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:22:57 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:22:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:22:57 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:22:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:22:57 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:22:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:22:57 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:22:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 10:22:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:22:57 INFO - Fetch https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 10:22:59 INFO - Expected file size: 20210152 10:22:59 INFO - Obtained file size: 20210152 10:23: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 10:23:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 10:23:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:23:03 INFO - Fetch https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 10:23:04 INFO - Expected file size: 20210152 10:23:04 INFO - Obtained file size: 20210152 10:23:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 10:23:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:23:09 INFO - Fetch https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 10:23:10 INFO - Expected file size: 14409702 10:23:10 INFO - Obtained file size: 14409702 10:23:10 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')]} 10:23:10 INFO - trying https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 10:23:10 INFO - Downloading https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/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 10:23:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/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 10:23:12 INFO - Downloaded 55658327 bytes. 10:23:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 10:23:12 INFO - mkdir: /builds/slave/test/properties 10:23:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:23:12 INFO - Writing to file /builds/slave/test/properties/build_url 10:23:12 INFO - Contents: 10:23:12 INFO - build_url:https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 10:23:13 INFO - Running post-action listener: _resource_record_post_action 10:23:13 INFO - Running post-action listener: set_extra_try_arguments 10:23:13 INFO - [mozharness: 2016-11-30 18:23:13.719991Z] Finished download-and-extract step (success) 10:23:13 INFO - [mozharness: 2016-11-30 18:23:13.720216Z] Running populate-webroot step. 10:23:13 INFO - Running pre-action listener: _resource_record_pre_action 10:23:13 INFO - Running main action method: populate_webroot 10:23:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:23:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:23:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:23:13 INFO - '--tppagecycles', 10:23:13 INFO - '7'], 10:23:13 INFO - 'tests': ['tsvgm']}}, 10:23:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:23:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:23:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:23:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:23:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:23:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:23:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:23:13 INFO - 'talos_options': ['--disable-e10s'], 10:23:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:23:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:23:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:23:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:23:13 INFO - 'talos_options': ['--disable-e10s'], 10:23:13 INFO - 'tests': ['damp', 'tps']}, 10:23:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:23:13 INFO - 'tests': ['damp', 'tps']}, 10:23:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:23:13 INFO - 'tests': ['dromaeo_dom']}, 10:23:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:23:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:23:13 INFO - 'tests': ['basic_compositor_video']}, 10:23:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:23:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:23:13 INFO - 'tests': ['a11yr', 10:23:13 INFO - 'ts_paint', 10:23:13 INFO - 'tpaint', 10:23:13 INFO - 'sessionrestore', 10:23:13 INFO - 'sessionrestore_no_auto_restore', 10:23:13 INFO - 'tabpaint']}, 10:23:13 INFO - 'other-e10s': {'tests': ['a11yr', 10:23:13 INFO - 'ts_paint', 10:23:13 INFO - 'tpaint', 10:23:13 INFO - 'sessionrestore', 10:23:13 INFO - 'sessionrestore_no_auto_restore', 10:23:13 INFO - 'tabpaint']}, 10:23:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:23:13 INFO - 'ts_paint', 10:23:13 INFO - 'tpaint', 10:23:13 INFO - 'sessionrestore', 10:23:13 INFO - 'sessionrestore_no_auto_restore', 10:23:13 INFO - 'tabpaint']}, 10:23:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:23:13 INFO - 'ts_paint', 10:23:13 INFO - 'tpaint', 10:23:13 INFO - 'sessionrestore', 10:23:13 INFO - 'sessionrestore_no_auto_restore', 10:23:13 INFO - 'tabpaint']}, 10:23:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:23:13 INFO - 'tests': ['a11yr', 10:23:13 INFO - 'ts_paint', 10:23:13 INFO - 'tpaint', 10:23:13 INFO - 'sessionrestore', 10:23:13 INFO - 'sessionrestore_no_auto_restore', 10:23:13 INFO - 'tabpaint']}, 10:23:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:23:13 INFO - 'tests': ['a11yr', 10:23:13 INFO - 'ts_paint', 10:23:13 INFO - 'tpaint', 10:23:13 INFO - 'sessionrestore', 10:23:13 INFO - 'sessionrestore_no_auto_restore', 10:23:13 INFO - 'tabpaint']}, 10:23:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:23:13 INFO - 'tests': ['tsvgx', 10:23:13 INFO - 'tsvgr_opacity', 10:23:13 INFO - 'tart', 10:23:13 INFO - 'tscrollx', 10:23:13 INFO - 'cart', 10:23:13 INFO - 'tsvg_static']}, 10:23:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:23:13 INFO - 'tsvgr_opacity', 10:23:13 INFO - 'tart', 10:23:13 INFO - 'tscrollx', 10:23:13 INFO - 'cart', 10:23:13 INFO - 'tsvg_static']}, 10:23:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:23:13 INFO - 'talos_options': ['--disable-e10s'], 10:23:13 INFO - 'tests': ['tp5o']}, 10:23:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:23:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:23:13 INFO - 'talos_options': ['--disable-e10s', 10:23:13 INFO - '--xperf_path', 10:23:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:23:13 INFO - 'tests': ['tp5n']}, 10:23:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:23:13 INFO - 'talos_options': ['--xperf_path', 10:23:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:23:13 INFO - 'tests': ['tp5n']}}, 10:23:13 INFO - 'talos.zip': {'path': '', 10:23:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:23:13 INFO - Running post-action listener: _resource_record_post_action 10:23:13 INFO - [mozharness: 2016-11-30 18:23:13.736065Z] Finished populate-webroot step (success) 10:23:13 INFO - [mozharness: 2016-11-30 18:23:13.736152Z] Running create-virtualenv step. 10:23:13 INFO - Running pre-action listener: _resource_record_pre_action 10:23:13 INFO - Running main action method: create_virtualenv 10:23:13 INFO - Creating virtualenv /builds/slave/test/build/venv 10:23:13 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 10:23:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:23:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:23:13 INFO - Using real prefix '/usr' 10:23:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:23:14 INFO - Installing distribute.............................................................................................................................................................................................done. 10:23:16 INFO - Installing pip.................done. 10:23:16 INFO - Return code: 0 10:23:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:23:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:23:16 INFO - Reading from file tmpfile_stdout 10:23:16 INFO - Output received: 10:23:16 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) 10:23:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:23:16 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')]} 10:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:16 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')]} 10:23:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:16 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 0x7f8b176c0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ce130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235cfc0>, '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 0x235dec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235e350>, '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': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', '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 10:23:16 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 10:23:16 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 10:23:16 INFO - Using env: {'DISPLAY': ':0', 10:23:16 INFO - 'HOME': '/home/cltbld', 10:23:16 INFO - 'LANG': 'en_US.UTF-8', 10:23:16 INFO - 'LANGUAGE': 'en_US:en', 10:23:16 INFO - 'LOGNAME': 'cltbld', 10:23:16 INFO - 'MAIL': '/var/mail/cltbld', 10:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:16 INFO - 'MOZ_NO_REMOTE': '1', 10:23:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:16 INFO - 'NO_EM_RESTART': '1', 10:23:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:16 INFO - 'PWD': '/builds/slave/test', 10:23:16 INFO - 'SHELL': '/bin/bash', 10:23:16 INFO - 'SHLVL': '1', 10:23:16 INFO - 'TERM': 'linux', 10:23:16 INFO - 'TMOUT': '86400', 10:23:16 INFO - 'USER': 'cltbld', 10:23:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', 10:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:16 INFO - '_': '/tools/buildbot/bin/python'} 10:23:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:16 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 10:23:16 INFO - Cleaning up... 10:23:16 INFO - Return code: 0 10:23:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:23:16 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')]} 10:23:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:16 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')]} 10:23:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:16 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 0x7f8b176c0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ce130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235cfc0>, '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 0x235dec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235e350>, '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': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', '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 10:23:16 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 10:23:16 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 10:23:16 INFO - Using env: {'DISPLAY': ':0', 10:23:16 INFO - 'HOME': '/home/cltbld', 10:23:16 INFO - 'LANG': 'en_US.UTF-8', 10:23:16 INFO - 'LANGUAGE': 'en_US:en', 10:23:16 INFO - 'LOGNAME': 'cltbld', 10:23:16 INFO - 'MAIL': '/var/mail/cltbld', 10:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:16 INFO - 'MOZ_NO_REMOTE': '1', 10:23:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:16 INFO - 'NO_EM_RESTART': '1', 10:23:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:16 INFO - 'PWD': '/builds/slave/test', 10:23:16 INFO - 'SHELL': '/bin/bash', 10:23:16 INFO - 'SHLVL': '1', 10:23:16 INFO - 'TERM': 'linux', 10:23:16 INFO - 'TMOUT': '86400', 10:23:16 INFO - 'USER': 'cltbld', 10:23:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', 10:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:16 INFO - '_': '/tools/buildbot/bin/python'} 10:23:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:16 INFO - Downloading/unpacking psutil>=3.1.1 10:23:16 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 10:23:16 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 10:23:16 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 10:23:16 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 10:23:16 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 10:23:16 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 10:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:23:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:23:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:23:19 INFO - Installing collected packages: psutil 10:23:19 INFO - Running setup.py install for psutil 10:23:19 INFO - building 'psutil._psutil_linux' extension 10:23:19 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 10:23:19 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 10:23:19 INFO - building 'psutil._psutil_posix' extension 10:23:19 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 10:23:19 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 10:23:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:23:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:23:19 INFO - Successfully installed psutil 10:23:19 INFO - Cleaning up... 10:23:19 INFO - Return code: 0 10:23:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:19 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 0x7f8b176c0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ce130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235cfc0>, '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 0x235dec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235e350>, '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': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', '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 10:23:19 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 10:23:19 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 10:23:19 INFO - Using env: {'DISPLAY': ':0', 10:23:19 INFO - 'HOME': '/home/cltbld', 10:23:19 INFO - 'LANG': 'en_US.UTF-8', 10:23:19 INFO - 'LANGUAGE': 'en_US:en', 10:23:19 INFO - 'LOGNAME': 'cltbld', 10:23:19 INFO - 'MAIL': '/var/mail/cltbld', 10:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:19 INFO - 'MOZ_NO_REMOTE': '1', 10:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:19 INFO - 'NO_EM_RESTART': '1', 10:23:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:19 INFO - 'PWD': '/builds/slave/test', 10:23:19 INFO - 'SHELL': '/bin/bash', 10:23:19 INFO - 'SHLVL': '1', 10:23:19 INFO - 'TERM': 'linux', 10:23:19 INFO - 'TMOUT': '86400', 10:23:19 INFO - 'USER': 'cltbld', 10:23:19 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', 10:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:19 INFO - '_': '/tools/buildbot/bin/python'} 10:23:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:23:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:23:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:23:22 INFO - Installing collected packages: mozsystemmonitor 10:23:22 INFO - Running setup.py install for mozsystemmonitor 10:23:22 INFO - Successfully installed mozsystemmonitor 10:23:22 INFO - Cleaning up... 10:23:22 INFO - Return code: 0 10:23:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:23:22 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')]} 10:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:22 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')]} 10:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:22 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 0x7f8b176c0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ce130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235cfc0>, '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 0x235dec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235e350>, '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': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', '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 10:23:22 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 10:23:22 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 10:23:22 INFO - Using env: {'DISPLAY': ':0', 10:23:22 INFO - 'HOME': '/home/cltbld', 10:23:22 INFO - 'LANG': 'en_US.UTF-8', 10:23:22 INFO - 'LANGUAGE': 'en_US:en', 10:23:22 INFO - 'LOGNAME': 'cltbld', 10:23:22 INFO - 'MAIL': '/var/mail/cltbld', 10:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:22 INFO - 'MOZ_NO_REMOTE': '1', 10:23:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:22 INFO - 'NO_EM_RESTART': '1', 10:23:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:22 INFO - 'PWD': '/builds/slave/test', 10:23:22 INFO - 'SHELL': '/bin/bash', 10:23:22 INFO - 'SHLVL': '1', 10:23:22 INFO - 'TERM': 'linux', 10:23:22 INFO - 'TMOUT': '86400', 10:23:22 INFO - 'USER': 'cltbld', 10:23:22 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', 10:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:22 INFO - '_': '/tools/buildbot/bin/python'} 10:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:22 INFO - Downloading/unpacking jsonschema==2.5.1 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:23:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23:24 INFO - Downloading functools32-3.2.3-2.tar.gz 10:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:23:24 INFO - warning: no files found matching '*.txt' 10:23:24 INFO - no previously-included directories found matching 'build' 10:23:24 INFO - no previously-included directories found matching 'dist' 10:23:24 INFO - no previously-included directories found matching '.git*' 10:23:24 INFO - Installing collected packages: jsonschema, functools32 10:23:24 INFO - Running setup.py install for functools32 10:23:24 INFO - warning: no files found matching '*.txt' 10:23:24 INFO - no previously-included directories found matching 'build' 10:23:24 INFO - no previously-included directories found matching 'dist' 10:23:24 INFO - no previously-included directories found matching '.git*' 10:23:24 INFO - Successfully installed jsonschema functools32 10:23:24 INFO - Cleaning up... 10:23:24 INFO - Return code: 0 10:23:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:23: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')]} 10:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23: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')]} 10:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23: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', '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 0x7f8b176c0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ce130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235cfc0>, '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 0x235dec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235e350>, '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': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', '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 10:23: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:23: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 functools32==3.2.3-2 10:23:24 INFO - Using env: {'DISPLAY': ':0', 10:23:24 INFO - 'HOME': '/home/cltbld', 10:23:24 INFO - 'LANG': 'en_US.UTF-8', 10:23:24 INFO - 'LANGUAGE': 'en_US:en', 10:23:24 INFO - 'LOGNAME': 'cltbld', 10:23:24 INFO - 'MAIL': '/var/mail/cltbld', 10:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:24 INFO - 'MOZ_NO_REMOTE': '1', 10:23:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:24 INFO - 'NO_EM_RESTART': '1', 10:23:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:24 INFO - 'PWD': '/builds/slave/test', 10:23:24 INFO - 'SHELL': '/bin/bash', 10:23:24 INFO - 'SHLVL': '1', 10:23:24 INFO - 'TERM': 'linux', 10:23:24 INFO - 'TMOUT': '86400', 10:23:24 INFO - 'USER': 'cltbld', 10:23:24 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', 10:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:24 INFO - '_': '/tools/buildbot/bin/python'} 10:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:23:25 INFO - Cleaning up... 10:23:25 INFO - Return code: 0 10:23:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:25 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 0x7f8b176c0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ce130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235cfc0>, '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 0x235dec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235e350>, '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': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', '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 10:23:25 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 10:23:25 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 10:23:25 INFO - Using env: {'DISPLAY': ':0', 10:23:25 INFO - 'HOME': '/home/cltbld', 10:23:25 INFO - 'LANG': 'en_US.UTF-8', 10:23:25 INFO - 'LANGUAGE': 'en_US:en', 10:23:25 INFO - 'LOGNAME': 'cltbld', 10:23:25 INFO - 'MAIL': '/var/mail/cltbld', 10:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:25 INFO - 'MOZ_NO_REMOTE': '1', 10:23:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:25 INFO - 'NO_EM_RESTART': '1', 10:23:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:25 INFO - 'PWD': '/builds/slave/test', 10:23:25 INFO - 'SHELL': '/bin/bash', 10:23:25 INFO - 'SHLVL': '1', 10:23:25 INFO - 'TERM': 'linux', 10:23:25 INFO - 'TMOUT': '86400', 10:23:25 INFO - 'USER': 'cltbld', 10:23:25 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', 10:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:25 INFO - '_': '/tools/buildbot/bin/python'} 10:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:25 INFO - Downloading/unpacking blobuploader==1.2.4 10:23:25 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 10:23:25 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 10:23:25 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 10:23:25 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 10:23:25 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 10:23:25 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 10:23:27 INFO - Downloading blobuploader-1.2.4.tar.gz 10:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:23:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:23:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23: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 10:23:28 INFO - Downloading docopt-0.6.1.tar.gz 10:23:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:23:28 INFO - Installing collected packages: blobuploader, requests, docopt 10:23:28 INFO - Running setup.py install for blobuploader 10:23:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:23:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:23:28 INFO - Running setup.py install for requests 10:23:28 INFO - Running setup.py install for docopt 10:23:28 INFO - Successfully installed blobuploader requests docopt 10:23:28 INFO - Cleaning up... 10:23:28 INFO - Return code: 0 10:23:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:28 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 0x7f8b176c0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ce130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235cfc0>, '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 0x235dec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235e350>, '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': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', '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 10:23:28 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 10:23:28 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 10:23:28 INFO - Using env: {'DISPLAY': ':0', 10:23:28 INFO - 'HOME': '/home/cltbld', 10:23:28 INFO - 'LANG': 'en_US.UTF-8', 10:23:28 INFO - 'LANGUAGE': 'en_US:en', 10:23:28 INFO - 'LOGNAME': 'cltbld', 10:23:28 INFO - 'MAIL': '/var/mail/cltbld', 10:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:28 INFO - 'MOZ_NO_REMOTE': '1', 10:23:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:28 INFO - 'NO_EM_RESTART': '1', 10:23:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:28 INFO - 'PWD': '/builds/slave/test', 10:23:28 INFO - 'SHELL': '/bin/bash', 10:23:28 INFO - 'SHLVL': '1', 10:23:28 INFO - 'TERM': 'linux', 10:23:28 INFO - 'TMOUT': '86400', 10:23:28 INFO - 'USER': 'cltbld', 10:23:28 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', 10:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:28 INFO - '_': '/tools/buildbot/bin/python'} 10:23:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:23:28 INFO - Running setup.py (path:/tmp/pip-KMCmld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:23:29 INFO - Running setup.py (path:/tmp/pip-_17BZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:23:29 INFO - Running setup.py (path:/tmp/pip-7Sp2ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:23:29 INFO - Running setup.py (path:/tmp/pip-ma7lu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:23:29 INFO - Running setup.py (path:/tmp/pip-cjjiHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:23:29 INFO - Running setup.py (path:/tmp/pip-iwuiOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:23:29 INFO - Running setup.py (path:/tmp/pip-aFghLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:23:29 INFO - Running setup.py (path:/tmp/pip-mDuyif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:23:29 INFO - Running setup.py (path:/tmp/pip-2pGR_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:23:29 INFO - Running setup.py (path:/tmp/pip-MlxSPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:23:29 INFO - Running setup.py (path:/tmp/pip-Imo4v0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:23:29 INFO - Running setup.py (path:/tmp/pip-LeuoWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:23:29 INFO - Running setup.py (path:/tmp/pip-5Mj1Ri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:23:29 INFO - Running setup.py (path:/tmp/pip-oUhKBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:23:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:29 INFO - Running setup.py (path:/tmp/pip-1aWGGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:23:30 INFO - Running setup.py (path:/tmp/pip-eiz9oO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:23:30 INFO - Running setup.py (path:/tmp/pip-6WWuf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:23:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:23:30 INFO - Running setup.py install for manifestparser 10:23:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:23:30 INFO - Running setup.py install for mozcrash 10:23:30 INFO - Running setup.py install for mozdebug 10:23:30 INFO - Running setup.py install for mozdevice 10:23:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:23:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:23:30 INFO - Running setup.py install for mozfile 10:23:30 INFO - Running setup.py install for mozhttpd 10:23:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:23:30 INFO - Running setup.py install for mozinfo 10:23:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:23:30 INFO - Running setup.py install for mozInstall 10:23:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:23:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:23:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:23:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:23:30 INFO - Running setup.py install for mozleak 10:23:31 INFO - Running setup.py install for mozlog 10:23:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:23:31 INFO - Running setup.py install for moznetwork 10:23:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:23:31 INFO - Running setup.py install for mozprocess 10:23:31 INFO - Running setup.py install for mozprofile 10:23:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:23:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:23:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:23:31 INFO - Running setup.py install for mozrunner 10:23:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:23:31 INFO - Running setup.py install for mozscreenshot 10:23:31 INFO - Running setup.py install for moztest 10:23:31 INFO - Running setup.py install for mozversion 10:23:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:23:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:23:31 INFO - Cleaning up... 10:23:31 INFO - Return code: 0 10:23:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:31 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 0x7f8b176c0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ce130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235cfc0>, '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 0x235dec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235e350>, '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': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', '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 10:23:31 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 10:23:31 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 10:23:31 INFO - Using env: {'DISPLAY': ':0', 10:23:31 INFO - 'HOME': '/home/cltbld', 10:23:31 INFO - 'LANG': 'en_US.UTF-8', 10:23:31 INFO - 'LANGUAGE': 'en_US:en', 10:23:31 INFO - 'LOGNAME': 'cltbld', 10:23:31 INFO - 'MAIL': '/var/mail/cltbld', 10:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:31 INFO - 'MOZ_NO_REMOTE': '1', 10:23:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:31 INFO - 'NO_EM_RESTART': '1', 10:23:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:31 INFO - 'PWD': '/builds/slave/test', 10:23:31 INFO - 'SHELL': '/bin/bash', 10:23:31 INFO - 'SHLVL': '1', 10:23:31 INFO - 'TERM': 'linux', 10:23:31 INFO - 'TMOUT': '86400', 10:23:31 INFO - 'USER': 'cltbld', 10:23:31 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', 10:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:31 INFO - '_': '/tools/buildbot/bin/python'} 10:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:23:32 INFO - Running setup.py (path:/tmp/pip-vq9wbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:23:32 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)) 10:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:23:32 INFO - Running setup.py (path:/tmp/pip-WNxm0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:23:32 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)) 10:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:23:32 INFO - Running setup.py (path:/tmp/pip-2Xsgsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:23:32 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)) 10:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:23:32 INFO - Running setup.py (path:/tmp/pip-jSPzHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:23:32 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)) 10:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:23:32 INFO - Running setup.py (path:/tmp/pip-Bj5P1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:23:32 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)) 10:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:23:32 INFO - Running setup.py (path:/tmp/pip-_WPsYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:23:32 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)) 10:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:23:32 INFO - Running setup.py (path:/tmp/pip-ON_Nhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:23:32 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)) 10:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:23:32 INFO - Running setup.py (path:/tmp/pip-9n4dyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:23:32 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)) 10:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:23:32 INFO - Running setup.py (path:/tmp/pip-NrimEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:23:32 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)) 10:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:23:32 INFO - Running setup.py (path:/tmp/pip-9m95hD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:23:32 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)) 10:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:23:32 INFO - Running setup.py (path:/tmp/pip-nlsxph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:23:33 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)) 10:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:23:33 INFO - Running setup.py (path:/tmp/pip-OQHK6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:23:33 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)) 10:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:23:33 INFO - Running setup.py (path:/tmp/pip-tWXGq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:23:33 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)) 10:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:23:33 INFO - Running setup.py (path:/tmp/pip-sClQS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:23:33 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)) 10:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:33 INFO - Running setup.py (path:/tmp/pip-dy5DY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:23:33 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)) 10:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:23:33 INFO - Running setup.py (path:/tmp/pip-mwOZl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:23:33 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)) 10:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:23:33 INFO - Running setup.py (path:/tmp/pip-O1WIQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:23:33 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)) 10:23:33 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)) 10:23:33 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)) 10:23:33 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)) 10:23:33 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)) 10:23:33 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)) 10:23:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:23:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:23:35 INFO - Downloading blessings-1.6.tar.gz 10:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:23:35 INFO - Installing collected packages: blessings 10:23:35 INFO - Running setup.py install for blessings 10:23:35 INFO - Successfully installed blessings 10:23:35 INFO - Cleaning up... 10:23:35 INFO - Return code: 0 10:23:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:23:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:23:35 INFO - Reading from file tmpfile_stdout 10:23:35 INFO - Current package versions: 10:23:35 INFO - argparse == 1.2.1 10:23:35 INFO - blessings == 1.6 10:23:35 INFO - blobuploader == 1.2.4 10:23:35 INFO - docopt == 0.6.1 10:23:35 INFO - functools32 == 3.2.3-2 10:23:35 INFO - jsonschema == 2.5.1 10:23:35 INFO - manifestparser == 1.1 10:23:35 INFO - mozInstall == 1.12 10:23:35 INFO - mozcrash == 1.0 10:23:35 INFO - mozdebug == 0.1 10:23:35 INFO - mozdevice == 0.49 10:23:35 INFO - mozfile == 1.2 10:23:35 INFO - mozhttpd == 0.7 10:23:35 INFO - mozinfo == 0.9 10:23:35 INFO - mozleak == 0.1 10:23:35 INFO - mozlog == 3.4 10:23:35 INFO - moznetwork == 0.27 10:23:35 INFO - mozprocess == 0.23 10:23:35 INFO - mozprofile == 0.28 10:23:35 INFO - mozrunner == 6.13 10:23:35 INFO - mozscreenshot == 0.1 10:23:35 INFO - mozsystemmonitor == 0.3 10:23:35 INFO - moztest == 0.7 10:23:35 INFO - mozversion == 1.4 10:23:35 INFO - psutil == 3.1.1 10:23:35 INFO - requests == 1.2.3 10:23:35 INFO - wsgiref == 0.1.2 10:23:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:23:35 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')]} 10:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:35 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')]} 10:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:35 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 0x7f8b176c0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ce130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235cfc0>, '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 0x235dec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235e350>, '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': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', '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 10:23:35 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 10:23:35 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 10:23:35 INFO - Using env: {'DISPLAY': ':0', 10:23:35 INFO - 'HOME': '/home/cltbld', 10:23:35 INFO - 'LANG': 'en_US.UTF-8', 10:23:35 INFO - 'LANGUAGE': 'en_US:en', 10:23:35 INFO - 'LOGNAME': 'cltbld', 10:23:35 INFO - 'MAIL': '/var/mail/cltbld', 10:23:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:35 INFO - 'MOZ_NO_REMOTE': '1', 10:23:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:35 INFO - 'NO_EM_RESTART': '1', 10:23:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:35 INFO - 'PWD': '/builds/slave/test', 10:23:35 INFO - 'SHELL': '/bin/bash', 10:23:35 INFO - 'SHLVL': '1', 10:23:35 INFO - 'TERM': 'linux', 10:23:35 INFO - 'TMOUT': '86400', 10:23:35 INFO - 'USER': 'cltbld', 10:23:35 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', 10:23:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:35 INFO - '_': '/tools/buildbot/bin/python'} 10:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:36 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)) 10:23:36 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)) 10:23:36 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)) 10:23:36 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)) 10:23:36 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)) 10:23:36 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)) 10:23:36 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)) 10:23:36 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)) 10:23:36 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)) 10:23:36 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)) 10:23:36 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)) 10:23:36 INFO - Cleaning up... 10:23:36 INFO - Return code: 0 10:23:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:23:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b176c0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22ce130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x235cfc0>, '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 0x235dec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235e350>, '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': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', '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 10:23:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:23:36 INFO - Using env: {'DISPLAY': ':0', 10:23:36 INFO - 'HOME': '/home/cltbld', 10:23:36 INFO - 'LANG': 'en_US.UTF-8', 10:23:36 INFO - 'LANGUAGE': 'en_US:en', 10:23:36 INFO - 'LOGNAME': 'cltbld', 10:23:36 INFO - 'MAIL': '/var/mail/cltbld', 10:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:36 INFO - 'MOZ_NO_REMOTE': '1', 10:23:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:36 INFO - 'NO_EM_RESTART': '1', 10:23:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:36 INFO - 'PWD': '/builds/slave/test', 10:23:36 INFO - 'SHELL': '/bin/bash', 10:23:36 INFO - 'SHLVL': '1', 10:23:36 INFO - 'TERM': 'linux', 10:23:36 INFO - 'TMOUT': '86400', 10:23:36 INFO - 'USER': 'cltbld', 10:23:36 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', 10:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:36 INFO - '_': '/tools/buildbot/bin/python'} 10:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:23:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:23:36 INFO - Cleaning up... 10:23:36 INFO - Return code: 0 10:23:36 INFO - Running post-action listener: _resource_record_post_action 10:23:36 INFO - Running post-action listener: _start_resource_monitoring 10:23:36 INFO - Starting resource monitoring. 10:23:36 INFO - [mozharness: 2016-11-30 18:23:36.420748Z] Finished create-virtualenv step (success) 10:23:36 INFO - [mozharness: 2016-11-30 18:23:36.421225Z] Running install step. 10:23:36 INFO - Running pre-action listener: _resource_record_pre_action 10:23:36 INFO - Running main action method: install 10:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:23:36 INFO - Reading from file tmpfile_stdout 10:23:36 INFO - Detecting whether we're running mozinstall >=1.0... 10:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:23:36 INFO - Reading from file tmpfile_stdout 10:23:36 INFO - Output received: 10:23:36 INFO - Usage: mozinstall [options] installer 10:23:36 INFO - Options: 10:23:36 INFO - -h, --help show this help message and exit 10:23:36 INFO - -d DEST, --destination=DEST 10:23:36 INFO - Directory to install application into. [default: 10:23:36 INFO - "/builds/slave/test"] 10:23:36 INFO - --app=APP Application being installed. [default: firefox] 10:23:36 INFO - mkdir: /builds/slave/test/build/application 10:23:36 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'] 10:23:36 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 10:23:52 INFO - Reading from file tmpfile_stdout 10:23:52 INFO - Output received: 10:23:52 INFO - /builds/slave/test/build/application/firefox/firefox 10:23:52 INFO - Running post-action listener: _resource_record_post_action 10:23:52 INFO - [mozharness: 2016-11-30 18:23:52.730089Z] Finished install step (success) 10:23:52 INFO - [mozharness: 2016-11-30 18:23:52.730330Z] Running run-tests step. 10:23:52 INFO - Running pre-action listener: _resource_record_pre_action 10:23:52 INFO - Running main action method: run_tests 10:23:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:23:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:23:52 INFO - Python 2.7.3 10:23:52 INFO - Return code: 0 10:23:52 INFO - grabbing minidump binary from tooltool 10:23:52 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')]} 10:23:52 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 0x235cfc0>, '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 0x235dec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x235e350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:23:52 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 10:23:52 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 10:23:52 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 10:23:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:23:52 INFO - Return code: 0 10:23:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:23:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:23:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:23:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:23:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:23:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 10:23:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 10:23:52 INFO - Using env: {'DISPLAY': ':0', 10:23:52 INFO - 'HOME': '/home/cltbld', 10:23:52 INFO - 'LANG': 'en_US.UTF-8', 10:23:52 INFO - 'LANGUAGE': 'en_US:en', 10:23:52 INFO - 'LOGNAME': 'cltbld', 10:23:52 INFO - 'MAIL': '/var/mail/cltbld', 10:23:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:23:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:23:52 INFO - 'MOZ_NO_REMOTE': '1', 10:23:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:23:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:23:52 INFO - 'NO_EM_RESTART': '1', 10:23:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:23:52 INFO - 'PWD': '/builds/slave/test', 10:23:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:23:52 INFO - 'SHELL': '/bin/bash', 10:23:52 INFO - 'SHLVL': '1', 10:23:52 INFO - 'TERM': 'linux', 10:23:52 INFO - 'TMOUT': '86400', 10:23:52 INFO - 'USER': 'cltbld', 10:23:52 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575', 10:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:23:52 INFO - '_': '/tools/buildbot/bin/python'} 10:23:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 10:23:53 INFO - mozversion application_buildid: 20161130095244 10:23:53 INFO - mozversion application_changeset: a6aabcb07a6e49cd52077674cb7e7c802d7a1ed4 10:23:53 INFO - mozversion application_display_name: Nightly 10:23:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:23:53 INFO - mozversion application_name: Firefox 10:23:53 INFO - mozversion application_remotingname: firefox 10:23:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:23:53 INFO - mozversion application_vendor: Mozilla 10:23:53 INFO - mozversion application_version: 53.0a1 10:23:53 INFO - mozversion platform_buildid: 20161130095244 10:23:53 INFO - mozversion platform_changeset: a6aabcb07a6e49cd52077674cb7e7c802d7a1ed4 10:23:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:23:53 INFO - mozversion platform_version: 53.0a1 10:23:53 INFO - using testdate: 1480530233 10:23:53 INFO - actual date: 1480530233 10:23:53 INFO - starting webserver on 'localhost:53453' 10:23:53 INFO - SUITE-START | Running 2 tests 10:23:53 INFO - TEST-START | dromaeo_css 10:23:53 INFO - operating with platform_type : linux_ 10:23:53 INFO - Initialising browser for dromaeo_css test... 10:23:53 INFO - TEST-INFO | started process 15599 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpecxodi/profile http://localhost:53453/getInfo.html) 10:23:57 INFO - PROCESS | 15599 | __metrics Screen width/height:1600/1200 10:23:57 INFO - PROCESS | 15599 | colorDepth:24 10:23:57 INFO - PROCESS | 15599 | Browser inner width/height: 1024/697 10:23:57 INFO - PROCESS | 15599 | __metrics 10:23:57 INFO - PROCESS | 15599 | 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" 10:23:57 INFO - PROCESS | 15599 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:23:58 INFO - PROCESS | 15599 | console.error: PushService: 10:23:58 INFO - PROCESS | 15599 | stateChangeProcessEnqueue: Error transitioning state 10:23:58 INFO - PROCESS | 15599 | UnknownError 10:23:58 INFO - TEST-INFO | 15599: exit 0 10:23:58 INFO - Browser initialized. 10:23:58 INFO - Running cycle 1/1 for dromaeo_css test... 10:23:58 INFO - TEST-INFO | started process 15686 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpecxodi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:24:11 INFO - PROCESS | 15686 | RSS: Main: 174886912 10:24:11 INFO - PROCESS | 15686 | 10:25:02 INFO - PROCESS | 15686 | 1480530302713 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] 10:27:16 INFO - PROCESS | 15686 | Cycle 1(1): loaded http://localhost:53453/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53453/tests/dromaeo/cssquery-ext.html) 10:27:17 INFO - PROCESS | 15686 | RSS: Main: 316301312 10:27:17 INFO - PROCESS | 15686 | 10:30:20 INFO - PROCESS | 15686 | Cycle 1(1): loaded http://localhost:53453/tests/dromaeo/cssquery-ext.html (next: http://localhost:53453/tests/dromaeo/cssquery-jquery.html) 10:30:21 INFO - PROCESS | 15686 | RSS: Main: 279453696 10:30:21 INFO - PROCESS | 15686 | 10:33:23 INFO - PROCESS | 15686 | Cycle 1(1): loaded http://localhost:53453/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53453/tests/dromaeo/cssquery-mootools.html) 10:33:24 INFO - PROCESS | 15686 | RSS: Main: 226709504 10:33:24 INFO - PROCESS | 15686 | 10:36:26 INFO - PROCESS | 15686 | Cycle 1(1): loaded http://localhost:53453/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53453/tests/dromaeo/cssquery-prototype.html) 10:36:27 INFO - PROCESS | 15686 | RSS: Main: 235077632 10:36:27 INFO - PROCESS | 15686 | 10:39:30 INFO - PROCESS | 15686 | Cycle 1(1): loaded http://localhost:53453/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53453/tests/dromaeo/cssquery-yui.html) 10:39:31 INFO - PROCESS | 15686 | RSS: Main: 227094528 10:39:31 INFO - PROCESS | 15686 | 10:42:34 INFO - PROCESS | 15686 | Cycle 1(1): loaded http://localhost:53453/tests/dromaeo/cssquery-yui.html (next: http://localhost:53453/tests/dromaeo/cssquery-dojo.html) 10:42:34 INFO - PROCESS | 15686 | RSS: Main: 296050688 10:42:34 INFO - PROCESS | 15686 | 10:42:34 INFO - PROCESS | 15686 | __start_tp_report 10:42:34 INFO - PROCESS | 15686 | _x_x_mozilla_page_load 10:42:34 INFO - PROCESS | 15686 | _x_x_mozilla_page_load_details 10:42:34 INFO - PROCESS | 15686 | |i|pagename|runs| 10:42:34 INFO - PROCESS | 15686 | |0;dojo.html;2563.410415604411;2587.1979686297236;2600.7789337906283;2620.7772339351154;2626.8161228714;4490.57339552742;4581.61056310214;4606.953930460699;4640.675152739305;4643.396358473398;4575.222212223922;4598.126355992359;4599.448066232048;4601.33280674302;4617.769111544426;4215.262329092412;4232.17472592843;4236.301010333292;4276.401303817468;4286.335617979218;4266.1254442839145;4289.05640759034;4302.569743025703;4323.156984388045;4328.805203765823;4226.344916537942;4257.127288905767;4263.4031235627035;4269.2955662315835;4325.632321252698;4320.1143765527995;4320.956790432118;4323.913521729579;4325.307950727868;4335.024619460628;4162.583741625844;4174.645155161815;4187.43469631602;4252.128313695685;4287.485501739811;4516.4806047304655;4544.909101817978;4549.727016379028;4549.954500454986;4570.360149579028;4905.460399356068;4911.607071434277;4918.950810491885;4928.088303663833;4973.875653108665;4792.880177995543;4831.565159135695;4931.186354251544;4947.554720075211;4956.628252881048;4527.524609915911;4541.795619197144;4560.771961401917;4571.222892108316;4609.723416594948;4994.975025124923;4996.325496057985;4998.775055122528;4999.275105109738;5042.294078828967;4786.473487916326;4787.712737235777;4799.28010798383;4833.154198015333;4841.201201801734;4778.37881075458;4803.903921921542;4807.254875494304;4809.711417314972;4857.101436234238;4775.498572649891;4840.080384726891;4843.782029808667;4849.830255941025;4853.02939412119;4796.952030479721;4804.375431193923;4806.975965120222;4813.7352445615825;4854.199057155601;4116.629503344653;4147.419361289422;4147.958520414818;4180.540140584533;4188.4136220929095;3679.025058359537;3683.576388715332;3698.2418604186078;3708.9258214835554;3709.2581483703366;4236.76697781625;4248.830046798155;4290.570942905684;4300.311950087971;4302.870913872589;4939.086269040239;4945.9366236259375;4947.777350019258;4950.2822090796635;4991.001799640014;4159.9376009359885;4181.749095054306;4182.121754431542;4251.362295655615;4260.360945858146;4453.376527286183;4459.464864216315;4471.8658440246845;4507.954920450754;4548.840790572381;4656.580907718321;4656.62746980234;4662.184117779308;4663.230566956414;4705.670603057753;4786.61188255416;4963.627727920492;4964.4539100698175;4966.428860681068;5013.097642424399;4536.6824322297125;4608.608268297232;4649;4673.065386922697;4735.644826638085;4890.633202509897;4901.705897646153;4909.263610458317;4911.287863259879;4950.529699678525;4898;4900.044491324157;4948.035133149001;4960.23116416956;4966.826161084489;4591.6556258281435;4616.399868017136;4652.046330502307;4662.300654901792;4756.857294281177;4766.666333356632;4769.189237829508;4776.8566942991765;4812.422509298906;4817.397825271841;4686.718796872062;4714.292856071616;4724.858254252241;4726.054789042137;4780;4690.601298889496;4739.3838800955655;4775.856724298276;4785.617150628011;4795.904081918412;4736.3842700450095;4736.478987311462;4772.5227477252;4784.904301914012;4789.568938795525 10:42:34 INFO - PROCESS | 15686 | |1;ext.html;562.5527705474148;571.3372487914021;577.0824389221137;593.1666009256995;593.4243783529975;27839.582406263922;31022.84488577554;31026.069217923497;31043.37913241734;31066.223344416372;39491.210175796536;41089.178216435655;41129.383059254134;44918.32622510657;44940.77529612347;7556.659950302236;7685.154632990367;7686.308232259095;7687.423443241779;7689.923100769005;7458.254174582553;7536.886946695804;7540.0197974263265;7545.434092443062;7595.848083038336;7579.242075792405;9657.4205547667;9888.604455821758;9900.801983960317;10234.488275586227;9744.658936937209;9805.558749856273;9807.06832850878;9814.312998090136;9819.459929703899;9352.953235233848;9414.905850941472;9436.622535098588;9437.528123593862;9437.952810235973;37137;37570.812145939366;37631.81184094089;37641.24717505662;37650.62349376526;2931.3551018776047;2938.6914373990635;2992.132281638322;3139.544766008939;3236.773425860191;27919.86040069807;28179.859100704365;28257.293567660974;28379.14862554106;29892.252693682614;3474.8957531274104;3502.6847583717586;3529.911752206215;3542.291541691646;3550.378683730337;234620;235143;235639;235647;237041;51275.48724512782;51945.48054519482;52022.47977520176;52052;52101;54074.72962635162;54097.72951135219;54183.729081354344;54203.45796541984;54334;14091.295435228198;14637.92681036588;14645;14648.85351146475;14668.119912805267;11406.942965285287;11465.54137834494;11471.885281147248;11489.942550287195;11503.942480287546;14470.204138772258;14896.62758431048;16206.75689864653;16432.83567164313;16773.664526709643;5790.36306006339;6241.251049874044;6243.250809902841;6383.585066970632;6487.124238227873;793.5714714054346;865.3855762408634;910.108094067804;928.6719990413638;955.5795450001976;26350;26415.339616509358;26879.865600672263;27015.729842701323;27294;946.1059298962552;978.8923218445965;1015.4262841494532;1019.3832731197667;1032.9741756456149;13730.176189428666;14567.125972441687;14611.78082328766;14721.11673299584;14830.40678372853;341693;630337;633921;641165;644539;42583.787081064394;43192;43489.347659785126;43878;43923.34114988278;1130.3952385473897;1193.4152265389896;1199.622119032502;1206.4329765010432;1206.8129439936824;27287.590686139327;28174.71825281721;28716;29043;29391.706082938897;41446.171076577695;41847.58152418559;41916.58083419127;41931.161376771684;41966.370504441846;259770;260342;371158;381160;381981;23323.766762332158;23337;23475.530489390458;23656.645150322416;23693.05227790869;37437;37706.62293377031;37862.242755144194;38390.616093838704;39195.41206881842;32384.676153238164;32752.50871236981;32915.50626740553;32992.1751956203;33345;32119.678803211667;32276.515852261764;33292.167695807795;33625.327493449506;33756.49365259474;32948;33495.16262093467;33528.1617959553;33697.494537582446;33712.831435842665;28138;30223.244418889703;30355.232505823824;30427.239319016317;30943.381132376777;22900.4274893129;23433.531329373658;23549.411264717834;23641.52716945617;23767.286981390585 10:42:34 INFO - PROCESS | 15686 | |2;jquery.html;328.4203381032477;340.7665748961963;342.9537012503312;344.39042894077477;364.21511642410616;12404.565840195595;12763.936180319097;13335;15024.849751502481;16061.759073613905;14875.479358222437;14925.477608283712;14995.175265360402;15400.998935069241;15733.763993540106;4751.6436267280005;4939.555440010398;4960.97519512402;4961;4980.477049909761;3634.7637403568815;3804.1250512382167;4895.730734809584;5549.500544950954;5800.9129863052085;5878.647281163123;5883.588148829584;5885.7351419186025;5888;5890.469857712805;5678.290213723285;5848.766049358021;5857.886478543511;5892.852678683024;5926.762929482837;5106.489351064901;5107.565856902168;5110.2590124431745;5110.386753589593;5112;12248.571300004547;17796.199171037333;18358.816411835844;19216.423507294803;19320.4203873884;1760.6390689908537;1789.6689112514227;1807.6023274879506;1811.6467288878673;1873.1289950173098;8941.46351218929;8996.77508062297;9053.773655658595;9296.442213467213;9534.570944307523;1401.873831355183;1421.8720315171684;1431.7852322151657;1489.9255037248095;1501.1368463133742;64894.3510564893;67641.32358676399;68368.31631683669;68429;70497.2950270496;9401;9461.905380946102;9551.283653726;9672.177864881563;9819.852702209473;12931.094823362275;12967;13019.283939383426;13027.674308142183;13038.80441793374;5440.183972404171;5669.348024977098;5697.743601537941;5991.340952495222;6980.267071957372;6571.342865713486;6757.425618822357;7047.859042819115;7052.823679408056;7121.074260346226;3949.980250098731;4058.452108965311;4095.877123686294;4174.08170202561;4184.66522678185;1712.5889786451412;1756.9912150439166;1762.3038899634828;1770.9822901770817;1815.6005678750653;837.3970741066422;851.2423942691009;860.1592591853439;879.89881163667;922.3174850610634;16006.839931600767;16617.667646646998;16750;16770.161491925355;17532.824671753373;806.7418426103591;824;836.1763662792144;839.47532792005;842.5576572299592;8912.703861806809;9559.088182363555;9587.664431744855;9608.66369677058;9620.71137865865;339807;345121;346306;346705;349361;70790.2920970794;73366.63316683382;74927.62536187284;75317.24682753212;75609.62195188989;847.4195176304252;867.8568036273944;868.3400615532115;869.6347534035721;873.0265753684723;14296;14770.261486925825;14811;14889.032212906126;15393.30730117125;34971.825140874134;35223.47164792479;35339.64660353467;35596.466053009746;37069.4439583401;48234.517654824405;48606.75696621494;48676.75661621669;48824.511754882;49081.2637810426;6654.800355989328;6823.761168359084;6853.828654283683;6919.100516932766;7558.357539609195;7483.738069167553;8585.785355366166;9657.855132173163;9679.70960871175;10005.299629025898;8223.95888020556;8361.16388361159;8451.788705282417;8617.0521242662;8644.178803013703;8035.638396272065;8204.466709663853;8336.666533338599;8338.499690018618;8660.35047371437;8372.706955256537;8671.046184919527;8709.346798989975;8977.37158398932;8988.43843993875;6914.585124892523;7017;7073;7147.749828755968;7313.817154570966;8018.158093400109;8042.316403105801;8309.626066827128;8395.32837373021;8483.490990540588 10:42:34 INFO - PROCESS | 15686 | |3;mootools.html;1225.9754804903896;1321.8876395506381;1369.3153423288356;1392.5056604905258;1625.902445853248;6063.969680151598;6688.732450701972;6949.339812717796;6963.860722785541;7129.714811407537;7034.085568876052;7089.574625522472;7100.112485939258;7108.289171082902;7111.7866464006165;1792.059168936306;1852.0369407907947;2007.307478919774;2043.9284625038126;2046.0383619698719;2003.1887085730302;2054.537729010968;2056.8354531637433;2059.7940205979357;2062.783407742188;2010.2562052040767;2031.3195079648262;2033.247698351612;2033.7864524224965;2035.6844689073218;1987.5229944813286;2025.635385630586;2025.8075482829256;2026.3616960657419;2026.9493262668404;1999.800019998003;2013.0941076515626;2028.1481777653273;2029.9594008119902;2033.2680235115347;6892.069570607954;6940.375366217014;6947.826304342381;6949;6956.060931774247;3937.448757173998;4065.7967101645095;4065.7967101645095;4076.32740597801;4083.3466645336603;2474.1340530814073;2608.491344187884;2673.4653069386004;2688.2876037849987;2712.8643567821728;810.1938571121713;868.1405408645459;884.1335491218641;885.3846576629245;905.248643625789;9059.320550958639;9063.728088157366;9067.909320906772;9069.954650226773;9097.590608422637;3478.711580919931;3576.302620988882;3741.607131251234;3766.133789228438;3775.5091838060875;8002.159773223844;8004.5197288161735;8015.879761803578;8020.35837133028;8022.679092836222;4089.4070359798257;4105.815238314223;4116.814743336557;4120.423140760296;4120.7321524100835;3272.574565306495;3384;3676.0809797550614;3717.3308804415465;3730.6642402183934;3049.5883055787185;3143.528470729409;3164.651888292286;3240.3519296140867;3290.9177270568425;1062.665260442958;1074.6346242277507;1087.331291255872;1092.8688557373166;1106.336198281025;665.3114026982147;757.4054367321626;762.9504082234637;765.4832987733258;798.0662624729081;4609.5620916013595;4688.062387522509;4759.904801904012;4793.161196790547;4822.662413631012;380.43695330910407;396.6747267240835;398.27911480220894;399.93800960851115;400.20359484625385;3043.436964161637;3111.284404586958;3120.2511397264493;3125.46867032605;3208.759343049234;7786.26030527099;7954.920450795534;8171.42800003994;8171.7548473545885;8306.626201820933;8273.503589784632;8289.378296627778;8290.544020078833;8292.502449853027;8299.83802267683;6307.873842523124;6347.587406818542;6424.518161137933;6429.51778616615;6470.579412338183;4493.303537951622;4597.885052873571;4615.953840461687;4700.788464519173;4742.288656701522;7413.925860741324;7416.555006699616;7505.4746167767735;7519.624018798929;7584.2794934481135;7584.12782529994;7595.620218988918;7613.6573854177805;7638.770836874903;7671.002769639911;5778.624389414675;5852.853678658068;5875.177475153568;5878.529717622666;5889.882202356014;5737.483626473638;5740.483356497935;5964;6002.309734380602;6325.96837015812;5437.34751829783;5444.128939369682;5491.65454463659;5542.307211598551;5569.248151499496;5455.8363249102595;5487.259220005249;5542.556595472433;5553.0837411828215;5575.024370735024;5004.949950500594;5056.418511871182;5068.29043933857;5069.822556210515;5072.4420313766195;3827.4641550183555;3827.693784497178;4149.7095203336685;4169.332906734908;4211.663066954575;3961.8811435656976;4105.712600117963;4111.321631930818;4124.752514849119;4126.66986641062 10:42:34 INFO - PROCESS | 15686 | |4;prototype.html;269.3562385897705;278.3167324219042;278.6433365292425;283.66102507503535;283.8268656119768;4657.720536767796;4776.8805779855475;4784.162771514988;4831.637627177963;4842.491538388467;4652.7906244219;4710.481846996828;4731.580525842251;4741.336212930192;4756.357891684622;3770.7744982880536;3771.5662698789715;3774.0186402138893;3776.8866933992026;3784.6026167252453;3575.284943011395;3750.9624903751023;3760.9811950940207;3767.340715374812;3790.128270497787;3507.9649203507893;3706.3884459064357;3711.1278849470355;3714.145746478298;3725.3853114236117;3712.238991006864;3713.2016616427463;3713.9628603713886;3724.4599533067826;3727.1986522897437;3293.077938177314;3307.5038744188323;3307.68576985186;3309.305045940331;3314.7182489488423;4581;4661.46393164787;4665.440147182326;4700.083483720676;4714.929276060861;4224.39473000261;4231.936520952188;4270.359446083081;4270.701050926436;4298.355246712987;4979.850604481871;4997.675151115166;5000.174971129759;5011.24606387765;5016.3980322361185;3517.66582174693;3615;3616.3490571697084;3621.4386770050683;3626.909327266813;5183.611229157753;5195.246689230041;5215.504527069922;5224.544464101904;5238.109521381336;3631.074076110575;3661.9816900915325;3662.304162209172;3664.798436086041;3698.8705395311035;3738.532683414573;3743.6256374362347;3747.494088298045;3755.3052685253315;3755.4742336072973;3906.5312162540677;4052.581596441222;4062.309407400749;4288.078063216423;4291.141771645684;4956.157453232959;4965.106280869406;4968.975155124273;4972.154733695281;4985.401751789808;1152.6599653102355;1176.4294317256124;1187.228301603947;1195.623378635727;1198.2989950878834;811.5293129984594;826.7685048186397;827.5283088639538;831.6465502161557;840.1052878684181;4235.30117530604;4256.914861702749;4268.829246830154;4275.508316543637;4277.187334406515;4884.853454396374;4929.2359684248995;4936.5190442867;4946.653734238569;4953.1332016896895;4104.651104656078;4264.488261408589;4273.252180868336;4284.100338928797;4289.699721019499;2636.762691357787;2638.4723055389;2638.6437830892582;2650.443406884537;2652.1910817200555;4649.976750116295;4650.255959046536;4658.510856360102;4671.929921051119;4682.41469816273;4922.8769280768265;4959.256111583291;5044.924326135111;5052.646314758005;5074.568661663726;4607.585317321457;4621.556940079662;4623.190941585276;4629.125204292267;4637.860864174013;4895.46149923501;4938.802447902188;4952.900941981068;4975.392840190832;4983.508245877061;4821.5178482151505;4844.2699121676815;4865.756712164449;5151.5363617274625;5184.94088487794;4883.780229889592;4888.535589119028;4890.217565189695;4894.412670479423;4912.435069966999;3618.5295911531334;3628.292482965902;3633.473146393705;3633.927321453609;3651.726120541023;5074.797008119782;5181.689098654093;5235.554977826775;5239.44985776488;5269.4730526947;5017.12200364942;5019.222020586815;5039.748012599282;5042.369703787027;5073.746312684277;5018.573421259088;5046.142155833591;5065.670731402447;5073.980981316111;5128.307678463509;4717.952820471752;4767.118083154627;4964.280179374043;4998.60011199096;5027.924581131213;4995.775190116527;4996.475370086234;5048.798048078183;5108.182690769459;5116.258142569381;4928.211486162197;4969.4782047884455;4979.261406226633;5003.249512573143;5009.373828271466 10:42:34 INFO - PROCESS | 15686 | |5;yui.html;546.0716781471498;591.7307625030611;597.2415032908207;613.2303958532042;617.5893031134295;11164.553417863286;11798.587049453261;12071.818922716166;12152.392380380968;12299;11262.099032077434;11360.943195284011;11374.545018199262;11382.3739694317;11386.772264554726;610.3652201710216;739.3826155160448;756.735142700056;759.5670467833337;760.3764912771529;622.8847663182303;736.9484136110474;750.7897788619196;762.9923700762977;765.406809722464;798.5727635714894;801.707376807464;802.8153524689325;803.4817542684965;803.5219044668413;634.2674211285972;796.8844517544952;798.4849771897123;798.6446031515977;799.1768478467188;477.34365247784297;561.2142999800271;575.6948817126929;579.4611011759063;581.8516278349036;12275.877241227563;12309.322987235699;12316.81524777129;12328.81506777399;12345.62963111099;12738.172018818746;13060.608181754562;13392;13422.127561708556;13453.461861525528;8831.77920551985;9035.909640903572;9390.906090939072;12033;12174.756504869942;3119.3293441909873;3131.7651176161653;3225.9354812903844;3333;4141.772202528859;64827.3517264826;65809.34190658032;67094.32905670929;67438.32561674419;67934;9363.765905852408;9455.621775129055;9776.41341519497;9827.164691001202;9871.851922221173;15062.924685376503;15110.848891511165;15247.847521524865;15621.453249136226;15696.293666785023;5969.791057312973;6189.102580125947;6239.96880015597;6240.31356550779;6267.71795269214;3382.6279109297957;3401.6258211596705;3407.1993081625797;3486.1284678830293;3591.964080359163;2553.1957433408415;2593.4164812916943;2602.49251395976;2618.1752747884357;2630.6185603087447;440.8677396780953;441.24547024587673;447.76268577653894;449.94600647921806;450.1402321565794;456.66633574903074;501.52104739973964;512.9743512824344;527.3276572370182;527.7282199667175;6859.348361905611;7317.785555517018;7351.926480735231;7439.702411903571;7575.204603516661;492.894027784021;502.4222144533815;503.49147361165484;510.9514596113363;539.9956081688057;3372.544706464645;3404.608470025979;3423.295596478754;3555.2000799820353;3579.8926032219074;65535.34464655293;68484;69864.30135698679;69963;73189;57423.42576574264;58302.4169758297;59119.40880591225;60136;63475.36524634695;53923.460765393414;54776.45223547793;54864.45135548673;54973.45026549763;55172.448275517534;6573.3426657333885;6704.262531121671;6732.360425759545;6752.763653272112;6803.1836179656775;32186;32255.35489290154;34194.65805342014;36244.27511449809;40292.79853600713;39070.60929390784;45132.32301515545;46158;47137;47437.52562474331;10941.23411361197;11094.778104437704;11220.15848811326;11333.546658133582;12194.51221951112;13107.606771796862;13110.344482776014;13675.521356752466;13866.376013079234;14161;11277.605283815026;11301.830472542753;11476.655700329004;11624.877077208115;12073;11725.88274117248;11796.377875672948;11799.941000294944;11876.881231187577;12142.514299427923;10465.68602941913;10649.627263045757;10692.518836652214;10850.403227822546;11043.39261340634;9965.302428829913;10156.238282128723;10213.510919245906;10341.84487232677;10680.89319106799;9543.856842147234;9745.512724363894;9760.658376956773;9861.359011664219;9884.851727223953 10:42:34 INFO - PROCESS | 15686 | __end_tp_report 10:42:34 INFO - PROCESS | 15686 | __start_cc_report 10:42:34 INFO - PROCESS | 15686 | _x_x_mozilla_cycle_collect,1420 10:42:34 INFO - PROCESS | 15686 | __end_cc_report 10:42:34 INFO - PROCESS | 15686 | __startTimestamp1480531354522__endTimestamp 10:42:34 INFO - PROCESS | 15686 | 10:42:34 INFO - PROCESS | 15686 | ------- Summary: start ------- 10:42:34 INFO - PROCESS | 15686 | Number of tests: 6 10:42:34 INFO - PROCESS | 15686 | 10:42:34 INFO - PROCESS | 15686 | [#0] dojo.html Cycles:165 Average:4542.94 Median:4656.63 stddev:458.07 (9.8%) stddev-sans-first:432.35 10:42:34 INFO - PROCESS | 15686 | Values: 2563.4 2587.2 2600.8 2620.8 2626.8 4490.6 4581.6 4607.0 4640.7 4643.4 4575.2 4598.1 4599.4 4601.3 4617.8 4215.3 4232.2 4236.3 4276.4 4286.3 4266.1 4289.1 4302.6 4323.2 4328.8 4226.3 4257.1 4263.4 4269.3 4325.6 4320.1 4321.0 4323.9 4325.3 4335.0 4162.6 4174.6 4187.4 4252.1 4287.5 4516.5 4544.9 4549.7 4550.0 4570.4 4905.5 4911.6 4919.0 4928.1 4973.9 4792.9 4831.6 4931.2 4947.6 4956.6 4527.5 4541.8 4560.8 4571.2 4609.7 4995.0 4996.3 4998.8 4999.3 5042.3 4786.5 4787.7 4799.3 4833.2 4841.2 4778.4 4803.9 4807.3 4809.7 4857.1 4775.5 4840.1 4843.8 4849.8 4853.0 4797.0 4804.4 4807.0 4813.7 4854.2 4116.6 4147.4 4148.0 4180.5 4188.4 3679.0 3683.6 3698.2 3708.9 3709.3 4236.8 4248.8 4290.6 4300.3 4302.9 4939.1 4945.9 4947.8 4950.3 4991.0 4159.9 4181.7 4182.1 4251.4 4260.4 4453.4 4459.5 4471.9 4508.0 4548.8 4656.6 4656.6 4662.2 4663.2 4705.7 4786.6 4963.6 4964.5 4966.4 5013.1 4536.7 4608.6 4649.0 4673.1 4735.6 4890.6 4901.7 4909.3 4911.3 4950.5 4898.0 4900.0 4948.0 4960.2 4966.8 4591.7 4616.4 4652.0 4662.3 4756.9 4766.7 4769.2 4776.9 4812.4 4817.4 4686.7 4714.3 4724.9 4726.1 4780.0 4690.6 4739.4 4775.9 4785.6 4795.9 4736.4 4736.5 4772.5 4784.9 4789.6 10:42:34 INFO - PROCESS | 15686 | 10:42:34 INFO - PROCESS | 15686 | [#1] ext.html Cycles:180 Average:51700.90 Median:187724.75 stddev:111411.18 (59.3%) stddev-sans-first:111657.56 10:42:34 INFO - PROCESS | 15686 | Values: 562.6 571.3 577.1 593.2 593.4 27839.6 31022.8 31026.1 31043.4 31066.2 39491.2 41089.2 41129.4 44918.3 44940.8 7556.7 7685.2 7686.3 7687.4 7689.9 7458.3 7536.9 7540.0 7545.4 7595.8 7579.2 9657.4 9888.6 9900.8 10234.5 9744.7 9805.6 9807.1 9814.3 9819.5 9353.0 9414.9 9436.6 9437.5 9438.0 37137.0 37570.8 37631.8 37641.2 37650.6 2931.4 2938.7 2992.1 3139.5 3236.8 27919.9 28179.9 28257.3 28379.1 29892.3 3474.9 3502.7 3529.9 3542.3 3550.4 234620.0 235143.0 235639.0 235647.0 237041.0 51275.5 51945.5 52022.5 52052.0 52101.0 54074.7 54097.7 54183.7 54203.5 54334.0 14091.3 14637.9 14645.0 14648.9 14668.1 11406.9 11465.5 11471.9 11489.9 11503.9 14470.2 14896.6 16206.8 16432.8 16773.7 5790.4 6241.3 6243.3 6383.6 6487.1 793.6 865.4 910.1 928.7 955.6 26350.0 26415.3 26879.9 27015.7 27294.0 946.1 978.9 1015.4 1019.4 1033.0 13730.2 14567.1 14611.8 14721.1 14830.4 341693.0 630337.0 633921.0 641165.0 644539.0 42583.8 43192.0 43489.3 43878.0 43923.3 1130.4 1193.4 1199.6 1206.4 1206.8 27287.6 28174.7 28716.0 29043.0 29391.7 41446.2 41847.6 41916.6 41931.2 41966.4 259770.0 260342.0 371158.0 381160.0 381981.0 23323.8 23337.0 23475.5 23656.6 23693.1 37437.0 37706.6 37862.2 38390.6 39195.4 32384.7 32752.5 32915.5 32992.2 33345.0 32119.7 32276.5 33292.2 33625.3 33756.5 32948.0 33495.2 33528.2 33697.5 33712.8 28138.0 30223.2 30355.2 30427.2 30943.4 22900.4 23433.5 23549.4 23641.5 23767.3 10:42:34 INFO - PROCESS | 15686 | 10:42:34 INFO - PROCESS | 15686 | [#2] jquery.html Cycles:180 Average:22202.78 Median:5853.33 stddev:57348.41 (979.8%) stddev-sans-first:57485.77 10:42:34 INFO - PROCESS | 15686 | Values: 328.4 340.8 343.0 344.4 364.2 12404.6 12763.9 13335.0 15024.8 16061.8 14875.5 14925.5 14995.2 15401.0 15733.8 4751.6 4939.6 4961.0 4961.0 4980.5 3634.8 3804.1 4895.7 5549.5 5800.9 5878.6 5883.6 5885.7 5888.0 5890.5 5678.3 5848.8 5857.9 5892.9 5926.8 5106.5 5107.6 5110.3 5110.4 5112.0 12248.6 17796.2 18358.8 19216.4 19320.4 1760.6 1789.7 1807.6 1811.6 1873.1 8941.5 8996.8 9053.8 9296.4 9534.6 1401.9 1421.9 1431.8 1489.9 1501.1 64894.4 67641.3 68368.3 68429.0 70497.3 9401.0 9461.9 9551.3 9672.2 9819.9 12931.1 12967.0 13019.3 13027.7 13038.8 5440.2 5669.3 5697.7 5991.3 6980.3 6571.3 6757.4 7047.9 7052.8 7121.1 3950.0 4058.5 4095.9 4174.1 4184.7 1712.6 1757.0 1762.3 1771.0 1815.6 837.4 851.2 860.2 879.9 922.3 16006.8 16617.7 16750.0 16770.2 17532.8 806.7 824.0 836.2 839.5 842.6 8912.7 9559.1 9587.7 9608.7 9620.7 339807.0 345121.0 346306.0 346705.0 349361.0 70790.3 73366.6 74927.6 75317.2 75609.6 847.4 867.9 868.3 869.6 873.0 14296.0 14770.3 14811.0 14889.0 15393.3 34971.8 35223.5 35339.6 35596.5 37069.4 48234.5 48606.8 48676.8 48824.5 49081.3 6654.8 6823.8 6853.8 6919.1 7558.4 7483.7 8585.8 9657.9 9679.7 10005.3 8224.0 8361.2 8451.8 8617.1 8644.2 8035.6 8204.5 8336.7 8338.5 8660.4 8372.7 8671.0 8709.3 8977.4 8988.4 6914.6 7017.0 7073.0 7147.7 7313.8 8018.2 8042.3 8309.6 8395.3 8483.5 10:42:34 INFO - PROCESS | 15686 | 10:42:34 INFO - PROCESS | 15686 | [#3] mootools.html Cycles:180 Average:4443.76 Median:4721.54 stddev:2438.62 (51.6%) stddev-sans-first:2433.47 10:42:34 INFO - PROCESS | 15686 | Values: 1226.0 1321.9 1369.3 1392.5 1625.9 6064.0 6688.7 6949.3 6963.9 7129.7 7034.1 7089.6 7100.1 7108.3 7111.8 1792.1 1852.0 2007.3 2043.9 2046.0 2003.2 2054.5 2056.8 2059.8 2062.8 2010.3 2031.3 2033.2 2033.8 2035.7 1987.5 2025.6 2025.8 2026.4 2026.9 1999.8 2013.1 2028.1 2030.0 2033.3 6892.1 6940.4 6947.8 6949.0 6956.1 3937.4 4065.8 4065.8 4076.3 4083.3 2474.1 2608.5 2673.5 2688.3 2712.9 810.2 868.1 884.1 885.4 905.2 9059.3 9063.7 9067.9 9070.0 9097.6 3478.7 3576.3 3741.6 3766.1 3775.5 8002.2 8004.5 8015.9 8020.4 8022.7 4089.4 4105.8 4116.8 4120.4 4120.7 3272.6 3384.0 3676.1 3717.3 3730.7 3049.6 3143.5 3164.7 3240.4 3290.9 1062.7 1074.6 1087.3 1092.9 1106.3 665.3 757.4 763.0 765.5 798.1 4609.6 4688.1 4759.9 4793.2 4822.7 380.4 396.7 398.3 399.9 400.2 3043.4 3111.3 3120.3 3125.5 3208.8 7786.3 7954.9 8171.4 8171.8 8306.6 8273.5 8289.4 8290.5 8292.5 8299.8 6307.9 6347.6 6424.5 6429.5 6470.6 4493.3 4597.9 4616.0 4700.8 4742.3 7413.9 7416.6 7505.5 7519.6 7584.3 7584.1 7595.6 7613.7 7638.8 7671.0 5778.6 5852.9 5875.2 5878.5 5889.9 5737.5 5740.5 5964.0 6002.3 6326.0 5437.3 5444.1 5491.7 5542.3 5569.2 5455.8 5487.3 5542.6 5553.1 5575.0 5004.9 5056.4 5068.3 5069.8 5072.4 3827.5 3827.7 4149.7 4169.3 4211.7 3961.9 4105.7 4111.3 4124.8 4126.7 10:42:34 INFO - PROCESS | 15686 | 10:42:34 INFO - PROCESS | 15686 | [#4] prototype.html Cycles:180 Average:4118.97 Median:4659.99 stddev:1200.86 (25.8%) stddev-sans-first:1168.95 10:42:34 INFO - PROCESS | 15686 | Values: 269.4 278.3 278.6 283.7 283.8 4657.7 4776.9 4784.2 4831.6 4842.5 4652.8 4710.5 4731.6 4741.3 4756.4 3770.8 3771.6 3774.0 3776.9 3784.6 3575.3 3751.0 3761.0 3767.3 3790.1 3508.0 3706.4 3711.1 3714.1 3725.4 3712.2 3713.2 3714.0 3724.5 3727.2 3293.1 3307.5 3307.7 3309.3 3314.7 4581.0 4661.5 4665.4 4700.1 4714.9 4224.4 4231.9 4270.4 4270.7 4298.4 4979.9 4997.7 5000.2 5011.2 5016.4 3517.7 3615.0 3616.3 3621.4 3626.9 5183.6 5195.2 5215.5 5224.5 5238.1 3631.1 3662.0 3662.3 3664.8 3698.9 3738.5 3743.6 3747.5 3755.3 3755.5 3906.5 4052.6 4062.3 4288.1 4291.1 4956.2 4965.1 4969.0 4972.2 4985.4 1152.7 1176.4 1187.2 1195.6 1198.3 811.5 826.8 827.5 831.6 840.1 4235.3 4256.9 4268.8 4275.5 4277.2 4884.9 4929.2 4936.5 4946.7 4953.1 4104.7 4264.5 4273.3 4284.1 4289.7 2636.8 2638.5 2638.6 2650.4 2652.2 4650.0 4650.3 4658.5 4671.9 4682.4 4922.9 4959.3 5044.9 5052.6 5074.6 4607.6 4621.6 4623.2 4629.1 4637.9 4895.5 4938.8 4952.9 4975.4 4983.5 4821.5 4844.3 4865.8 5151.5 5184.9 4883.8 4888.5 4890.2 4894.4 4912.4 3618.5 3628.3 3633.5 3633.9 3651.7 5074.8 5181.7 5235.6 5239.4 5269.5 5017.1 5019.2 5039.7 5042.4 5073.7 5018.6 5046.1 5065.7 5074.0 5128.3 4718.0 4767.1 4964.3 4998.6 5027.9 4995.8 4996.5 5048.8 5108.2 5116.3 4928.2 4969.5 4979.3 5003.2 5009.4 10:42:34 INFO - PROCESS | 15686 | 10:42:34 INFO - PROCESS | 15686 | [#5] yui.html Cycles:180 Average:14789.94 Median:47287.26 stddev:19306.29 (40.8%) stddev-sans-first:19330.82 10:42:34 INFO - PROCESS | 15686 | Values: 546.1 591.7 597.2 613.2 617.6 11164.6 11798.6 12071.8 12152.4 12299.0 11262.1 11360.9 11374.5 11382.4 11386.8 610.4 739.4 756.7 759.6 760.4 622.9 736.9 750.8 763.0 765.4 798.6 801.7 802.8 803.5 803.5 634.3 796.9 798.5 798.6 799.2 477.3 561.2 575.7 579.5 581.9 12275.9 12309.3 12316.8 12328.8 12345.6 12738.2 13060.6 13392.0 13422.1 13453.5 8831.8 9035.9 9390.9 12033.0 12174.8 3119.3 3131.8 3225.9 3333.0 4141.8 64827.4 65809.3 67094.3 67438.3 67934.0 9363.8 9455.6 9776.4 9827.2 9871.9 15062.9 15110.8 15247.8 15621.5 15696.3 5969.8 6189.1 6240.0 6240.3 6267.7 3382.6 3401.6 3407.2 3486.1 3592.0 2553.2 2593.4 2602.5 2618.2 2630.6 440.9 441.2 447.8 449.9 450.1 456.7 501.5 513.0 527.3 527.7 6859.3 7317.8 7351.9 7439.7 7575.2 492.9 502.4 503.5 511.0 540.0 3372.5 3404.6 3423.3 3555.2 3579.9 65535.3 68484.0 69864.3 69963.0 73189.0 57423.4 58302.4 59119.4 60136.0 63475.4 53923.5 54776.5 54864.5 54973.5 55172.4 6573.3 6704.3 6732.4 6752.8 6803.2 32186.0 32255.4 34194.7 36244.3 40292.8 39070.6 45132.3 46158.0 47137.0 47437.5 10941.2 11094.8 11220.2 11333.5 12194.5 13107.6 13110.3 13675.5 13866.4 14161.0 11277.6 11301.8 11476.7 11624.9 12073.0 11725.9 11796.4 11799.9 11876.9 12142.5 10465.7 10649.6 10692.5 10850.4 11043.4 9965.3 10156.2 10213.5 10341.8 10680.9 9543.9 9745.5 9760.7 9861.4 9884.9 10:42:34 INFO - PROCESS | 15686 | -------- Summary: end -------- 10:42:34 INFO - PROCESS | 15686 | 10:42:35 INFO - TEST-INFO | 15686: exit 0 10:42:35 INFO - TEST-OK | dromaeo_css | took 1122426ms 10:42:35 INFO - TEST-START | kraken 10:42:35 INFO - operating with platform_type : linux_ 10:42:35 INFO - Initialising browser for kraken test... 10:42:35 INFO - TEST-INFO | started process 15994 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_qfqUl/profile http://localhost:53453/getInfo.html) 10:42:39 INFO - PROCESS | 15994 | __metrics Screen width/height:1600/1200 10:42:39 INFO - PROCESS | 15994 | colorDepth:24 10:42:39 INFO - PROCESS | 15994 | Browser inner width/height: 1024/697 10:42:39 INFO - PROCESS | 15994 | __metrics 10:42:40 INFO - PROCESS | 15994 | 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" 10:42:40 INFO - PROCESS | 15994 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:42:40 INFO - PROCESS | 15994 | console.error: PushService: 10:42:40 INFO - PROCESS | 15994 | stateChangeProcessEnqueue: Error transitioning state 10:42:40 INFO - PROCESS | 15994 | UnknownError 10:42:41 INFO - TEST-INFO | 15994: exit 0 10:42:41 INFO - Browser initialized. 10:42:41 INFO - Running cycle 1/1 for kraken test... 10:42:41 INFO - TEST-INFO | started process 16078 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_qfqUl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:53 INFO - PROCESS | 16078 | RSS: Main: 174993408 10:42:53 INFO - PROCESS | 16078 | 10:42:56 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53453/tests/kraken/driver.html?testName=audio-beat-detection) 10:42:57 INFO - PROCESS | 16078 | RSS: Main: 239837184 10:42:57 INFO - PROCESS | 16078 | 10:43:00 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53453/tests/kraken/driver.html?testName=audio-dft) 10:43:00 INFO - PROCESS | 16078 | RSS: Main: 218411008 10:43:00 INFO - PROCESS | 16078 | 10:43:03 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53453/tests/kraken/driver.html?testName=audio-fft) 10:43:04 INFO - PROCESS | 16078 | RSS: Main: 300441600 10:43:04 INFO - PROCESS | 16078 | 10:43:06 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53453/tests/kraken/driver.html?testName=audio-oscillator) 10:43:07 INFO - PROCESS | 16078 | RSS: Main: 210808832 10:43:07 INFO - PROCESS | 16078 | 10:43:09 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53453/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:43:10 INFO - PROCESS | 16078 | RSS: Main: 214958080 10:43:10 INFO - PROCESS | 16078 | 10:43:13 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53453/tests/kraken/driver.html?testName=imaging-darkroom) 10:43:13 INFO - PROCESS | 16078 | RSS: Main: 254611456 10:43:14 INFO - PROCESS | 16078 | 10:43:17 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53453/tests/kraken/driver.html?testName=imaging-desaturate) 10:43:18 INFO - PROCESS | 16078 | RSS: Main: 248643584 10:43:18 INFO - PROCESS | 16078 | 10:43:20 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53453/tests/kraken/driver.html?testName=json-parse-financial) 10:43:21 INFO - PROCESS | 16078 | RSS: Main: 249982976 10:43:21 INFO - PROCESS | 16078 | 10:43:23 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53453/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:43:23 INFO - PROCESS | 16078 | RSS: Main: 213110784 10:43:23 INFO - PROCESS | 16078 | 10:43:25 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53453/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:43:26 INFO - PROCESS | 16078 | RSS: Main: 221065216 10:43:26 INFO - PROCESS | 16078 | 10:43:28 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53453/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:43:29 INFO - PROCESS | 16078 | RSS: Main: 229113856 10:43:29 INFO - PROCESS | 16078 | 10:43:32 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53453/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:43:33 INFO - PROCESS | 16078 | RSS: Main: 237715456 10:43:33 INFO - PROCESS | 16078 | 10:43:36 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53453/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:43:36 INFO - PROCESS | 16078 | RSS: Main: 235577344 10:43:36 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | Cycle 1(1): loaded http://localhost:53453/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53453/tests/kraken/driver.html?testName=ai-astar) 10:43:38 INFO - PROCESS | 16078 | RSS: Main: 286957568 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | __start_tp_report 10:43:38 INFO - PROCESS | 16078 | _x_x_mozilla_page_load 10:43:38 INFO - PROCESS | 16078 | _x_x_mozilla_page_load_details 10:43:38 INFO - PROCESS | 16078 | |i|pagename|runs| 10:43:38 INFO - PROCESS | 16078 | |0;ai-astar;109;117;117;117;116;117;118;118;116;116 10:43:38 INFO - PROCESS | 16078 | |1;audio-beat-detection;159;128;135;167;136;134;128;139;154;131 10:43:38 INFO - PROCESS | 16078 | |2;audio-dft;161;162;162;162;161;164;163;163;162;162 10:43:38 INFO - PROCESS | 16078 | |3;audio-fft;77;76;79;107;77;78;78;78;91;78 10:43:38 INFO - PROCESS | 16078 | |4;audio-oscillator;84;83;119;84;83;84;100;83;84;111 10:43:38 INFO - PROCESS | 16078 | |5;imaging-gaussian-blur;118;117;117;117;116;118;117;117;117;117 10:43:38 INFO - PROCESS | 16078 | |6;imaging-darkroom;146;147;146;146;146;147;146;146;146;146 10:43:38 INFO - PROCESS | 16078 | |7;imaging-desaturate;96;95;96;96;95;95;96;95;95;95 10:43:38 INFO - PROCESS | 16078 | |8;json-parse-financial;66;64;64;64;65;68;64;65;66;66 10:43:38 INFO - PROCESS | 16078 | |9;json-stringify-tinderbox;65;66;65;67;66;66;67;71;66;66 10:43:38 INFO - PROCESS | 16078 | |10;stanford-crypto-aes;96;90;86;86;95;85;86;85;86;86 10:43:38 INFO - PROCESS | 16078 | |11;stanford-crypto-ccm;165;143;157;148;150;143;154;155;193;143 10:43:38 INFO - PROCESS | 16078 | |12;stanford-crypto-pbkdf2;189;198;186;190;182;184;185;184;188;184 10:43:38 INFO - PROCESS | 16078 | |13;stanford-crypto-sha256-iterative;72;74;72;71;72;72;71;74;71;72 10:43:38 INFO - PROCESS | 16078 | __end_tp_report 10:43:38 INFO - PROCESS | 16078 | __start_cc_report 10:43:38 INFO - PROCESS | 16078 | _x_x_mozilla_cycle_collect,1991 10:43:38 INFO - PROCESS | 16078 | __end_cc_report 10:43:38 INFO - PROCESS | 16078 | __startTimestamp1480531418918__endTimestamp 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | ------- Summary: start ------- 10:43:38 INFO - PROCESS | 16078 | Number of tests: 14 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#0] ai-astar Cycles:10 Average:116.10 Median:117.00 stddev:2.60 (2.2%) stddev-sans-first:0.78 10:43:38 INFO - PROCESS | 16078 | Values: 109.0 117.0 117.0 117.0 116.0 117.0 118.0 118.0 116.0 116.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:137.50 stddev:13.83 (10.1%) stddev-sans-first:13.06 10:43:38 INFO - PROCESS | 16078 | Values: 159.0 128.0 135.0 167.0 136.0 134.0 128.0 139.0 154.0 131.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 10:43:38 INFO - PROCESS | 16078 | Values: 161.0 162.0 162.0 162.0 161.0 164.0 163.0 163.0 162.0 162.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#3] audio-fft Cycles:10 Average:81.90 Median:78.00 stddev:9.80 (12.6%) stddev-sans-first:10.24 10:43:38 INFO - PROCESS | 16078 | Values: 77.0 76.0 79.0 107.0 77.0 78.0 78.0 78.0 91.0 78.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:83.50 stddev:13.54 (16.2%) stddev-sans-first:14.09 10:43:38 INFO - PROCESS | 16078 | Values: 84.0 83.0 119.0 84.0 83.0 84.0 100.0 83.0 84.0 111.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 10:43:38 INFO - PROCESS | 16078 | Values: 118.0 117.0 117.0 117.0 116.0 118.0 117.0 117.0 117.0 117.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 10:43:38 INFO - PROCESS | 16078 | Values: 146.0 147.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 10:43:38 INFO - PROCESS | 16078 | Values: 96.0 95.0 96.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:65.50 stddev:1.32 (2.0%) stddev-sans-first:1.36 10:43:38 INFO - PROCESS | 16078 | Values: 66.0 64.0 64.0 64.0 65.0 68.0 64.0 65.0 66.0 66.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#9] json-stringify-tinderbox Cycles:10 Average:66.50 Median:66.00 stddev:1.72 (2.6%) stddev-sans-first:1.73 10:43:38 INFO - PROCESS | 16078 | Values: 65.0 66.0 65.0 67.0 66.0 66.0 67.0 71.0 66.0 66.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:86.00 stddev:4.15 (4.8%) stddev-sans-first:3.27 10:43:38 INFO - PROCESS | 16078 | Values: 96.0 90.0 86.0 86.0 95.0 85.0 86.0 85.0 86.0 86.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#11] stanford-crypto-ccm Cycles:10 Average:155.10 Median:154.50 stddev:15.11 (9.8%) stddev-sans-first:15.60 10:43:38 INFO - PROCESS | 16078 | Values: 165.0 143.0 157.0 148.0 150.0 143.0 154.0 155.0 193.0 143.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.00 Median:187.00 stddev:4.62 (2.5%) stddev-sans-first:4.84 10:43:38 INFO - PROCESS | 16078 | Values: 189.0 198.0 186.0 190.0 182.0 184.0 185.0 184.0 188.0 184.0 10:43:38 INFO - PROCESS | 16078 | 10:43:38 INFO - PROCESS | 16078 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:72.00 stddev:1.10 (1.5%) stddev-sans-first:1.17 10:43:38 INFO - PROCESS | 16078 | Values: 72.0 74.0 72.0 71.0 72.0 72.0 71.0 74.0 71.0 72.0 10:43:38 INFO - PROCESS | 16078 | -------- Summary: end -------- 10:43:38 INFO - PROCESS | 16078 | 10:43:40 INFO - TEST-INFO | 16078: exit 0 10:43:40 INFO - TEST-OK | kraken | took 64843ms 10:43:40 INFO - SUITE-END | took 1187s 10:43:40 INFO - Completed test suite (00:19:47) 10:43:40 INFO - javascript benchmark 10:43:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2563.410415604411, 2587.1979686297236, 2600.7789337906283, 2620.7772339351154, 2626.8161228714, 4490.57339552742, 4581.61056310214, 4606.953930460699, 4640.675152739305, 4643.396358473398, 4575.222212223922, 4598.126355992359, 4599.448066232048, 4601.33280674302, 4617.769111544426, 4215.262329092412, 4232.17472592843, 4236.301010333292, 4276.401303817468, 4286.335617979218, 4266.1254442839145, 4289.05640759034, 4302.569743025703, 4323.156984388045, 4328.805203765823, 4226.344916537942, 4257.127288905767, 4263.4031235627035, 4269.2955662315835, 4325.632321252698, 4320.1143765527995, 4320.956790432118, 4323.913521729579, 4325.307950727868, 4335.024619460628, 4162.583741625844, 4174.645155161815, 4187.43469631602, 4252.128313695685, 4287.485501739811, 4516.4806047304655, 4544.909101817978, 4549.727016379028, 4549.954500454986, 4570.360149579028, 4905.460399356068, 4911.607071434277, 4918.950810491885, 4928.088303663833, 4973.875653108665, 4792.880177995543, 4831.565159135695, 4931.186354251544, 4947.554720075211, 4956.628252881048, 4527.524609915911, 4541.795619197144, 4560.771961401917, 4571.222892108316, 4609.723416594948, 4994.975025124923, 4996.325496057985, 4998.775055122528, 4999.275105109738, 5042.294078828967, 4786.473487916326, 4787.712737235777, 4799.28010798383, 4833.154198015333, 4841.201201801734, 4778.37881075458, 4803.903921921542, 4807.254875494304, 4809.711417314972, 4857.101436234238, 4775.498572649891, 4840.080384726891, 4843.782029808667, 4849.830255941025, 4853.02939412119, 4796.952030479721, 4804.375431193923, 4806.975965120222, 4813.7352445615825, 4854.199057155601, 4116.629503344653, 4147.419361289422, 4147.958520414818, 4180.540140584533, 4188.4136220929095, 3679.025058359537, 3683.576388715332, 3698.2418604186078, 3708.9258214835554, 3709.2581483703366, 4236.76697781625, 4248.830046798155, 4290.570942905684, 4300.311950087971, 4302.870913872589, 4939.086269040239, 4945.9366236259375, 4947.777350019258, 4950.2822090796635, 4991.001799640014, 4159.9376009359885, 4181.749095054306, 4182.121754431542, 4251.362295655615, 4260.360945858146, 4453.376527286183, 4459.464864216315, 4471.8658440246845, 4507.954920450754, 4548.840790572381, 4656.580907718321, 4656.62746980234, 4662.184117779308, 4663.230566956414, 4705.670603057753, 4786.61188255416, 4963.627727920492, 4964.4539100698175, 4966.428860681068, 5013.097642424399, 4536.6824322297125, 4608.608268297232, 4649.0, 4673.065386922697, 4735.644826638085, 4890.633202509897, 4901.705897646153, 4909.263610458317, 4911.287863259879, 4950.529699678525, 4898.0, 4900.044491324157, 4948.035133149001, 4960.23116416956, 4966.826161084489, 4591.6556258281435, 4616.399868017136, 4652.046330502307, 4662.300654901792, 4756.857294281177, 4766.666333356632, 4769.189237829508, 4776.8566942991765, 4812.422509298906, 4817.397825271841, 4686.718796872062, 4714.292856071616, 4724.858254252241, 4726.054789042137, 4780.0, 4690.601298889496, 4739.3838800955655, 4775.856724298276, 4785.617150628011, 4795.904081918412, 4736.3842700450095, 4736.478987311462, 4772.5227477252, 4784.904301914012, 4789.568938795525], "value": 4514.6653953765845, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.5527705474148, 571.3372487914021, 577.0824389221137, 593.1666009256995, 593.4243783529975, 27839.582406263922, 31022.84488577554, 31026.069217923497, 31043.37913241734, 31066.223344416372, 39491.210175796536, 41089.178216435655, 41129.383059254134, 44918.32622510657, 44940.77529612347, 7556.659950302236, 7685.154632990367, 7686.308232259095, 7687.423443241779, 7689.923100769005, 7458.254174582553, 7536.886946695804, 7540.0197974263265, 7545.434092443062, 7595.848083038336, 7579.242075792405, 9657.4205547667, 9888.604455821758, 9900.801983960317, 10234.488275586227, 9744.658936937209, 9805.558749856273, 9807.06832850878, 9814.312998090136, 9819.459929703899, 9352.953235233848, 9414.905850941472, 9436.622535098588, 9437.528123593862, 9437.952810235973, 37137.0, 37570.812145939366, 37631.81184094089, 37641.24717505662, 37650.62349376526, 2931.3551018776047, 2938.6914373990635, 2992.132281638322, 3139.544766008939, 3236.773425860191, 27919.86040069807, 28179.859100704365, 28257.293567660974, 28379.14862554106, 29892.252693682614, 3474.8957531274104, 3502.6847583717586, 3529.911752206215, 3542.291541691646, 3550.378683730337, 234620.0, 235143.0, 235639.0, 235647.0, 237041.0, 51275.48724512782, 51945.48054519482, 52022.47977520176, 52052.0, 52101.0, 54074.72962635162, 54097.72951135219, 54183.729081354344, 54203.45796541984, 54334.0, 14091.295435228198, 14637.92681036588, 14645.0, 14648.85351146475, 14668.119912805267, 11406.942965285287, 11465.54137834494, 11471.885281147248, 11489.942550287195, 11503.942480287546, 14470.204138772258, 14896.62758431048, 16206.75689864653, 16432.83567164313, 16773.664526709643, 5790.36306006339, 6241.251049874044, 6243.250809902841, 6383.585066970632, 6487.124238227873, 793.5714714054346, 865.3855762408634, 910.108094067804, 928.6719990413638, 955.5795450001976, 26350.0, 26415.339616509358, 26879.865600672263, 27015.729842701323, 27294.0, 946.1059298962552, 978.8923218445965, 1015.4262841494532, 1019.3832731197667, 1032.9741756456149, 13730.176189428666, 14567.125972441687, 14611.78082328766, 14721.11673299584, 14830.40678372853, 341693.0, 630337.0, 633921.0, 641165.0, 644539.0, 42583.787081064394, 43192.0, 43489.347659785126, 43878.0, 43923.34114988278, 1130.3952385473897, 1193.4152265389896, 1199.622119032502, 1206.4329765010432, 1206.8129439936824, 27287.590686139327, 28174.71825281721, 28716.0, 29043.0, 29391.706082938897, 41446.171076577695, 41847.58152418559, 41916.58083419127, 41931.161376771684, 41966.370504441846, 259770.0, 260342.0, 371158.0, 381160.0, 381981.0, 23323.766762332158, 23337.0, 23475.530489390458, 23656.645150322416, 23693.05227790869, 37437.0, 37706.62293377031, 37862.242755144194, 38390.616093838704, 39195.41206881842, 32384.676153238164, 32752.50871236981, 32915.50626740553, 32992.1751956203, 33345.0, 32119.678803211667, 32276.515852261764, 33292.167695807795, 33625.327493449506, 33756.49365259474, 32948.0, 33495.16262093467, 33528.1617959553, 33697.494537582446, 33712.831435842665, 28138.0, 30223.244418889703, 30355.232505823824, 30427.239319016317, 30943.381132376777, 22900.4274893129, 23433.531329373658, 23549.411264717834, 23641.52716945617, 23767.286981390585], "value": 17378.46091565344, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.4203381032477, 340.7665748961963, 342.9537012503312, 344.39042894077477, 364.21511642410616, 12404.565840195595, 12763.936180319097, 13335.0, 15024.849751502481, 16061.759073613905, 14875.479358222437, 14925.477608283712, 14995.175265360402, 15400.998935069241, 15733.763993540106, 4751.6436267280005, 4939.555440010398, 4960.97519512402, 4961.0, 4980.477049909761, 3634.7637403568815, 3804.1250512382167, 4895.730734809584, 5549.500544950954, 5800.9129863052085, 5878.647281163123, 5883.588148829584, 5885.7351419186025, 5888.0, 5890.469857712805, 5678.290213723285, 5848.766049358021, 5857.886478543511, 5892.852678683024, 5926.762929482837, 5106.489351064901, 5107.565856902168, 5110.2590124431745, 5110.386753589593, 5112.0, 12248.571300004547, 17796.199171037333, 18358.816411835844, 19216.423507294803, 19320.4203873884, 1760.6390689908537, 1789.6689112514227, 1807.6023274879506, 1811.6467288878673, 1873.1289950173098, 8941.46351218929, 8996.77508062297, 9053.773655658595, 9296.442213467213, 9534.570944307523, 1401.873831355183, 1421.8720315171684, 1431.7852322151657, 1489.9255037248095, 1501.1368463133742, 64894.3510564893, 67641.32358676399, 68368.31631683669, 68429.0, 70497.2950270496, 9401.0, 9461.905380946102, 9551.283653726, 9672.177864881563, 9819.852702209473, 12931.094823362275, 12967.0, 13019.283939383426, 13027.674308142183, 13038.80441793374, 5440.183972404171, 5669.348024977098, 5697.743601537941, 5991.340952495222, 6980.267071957372, 6571.342865713486, 6757.425618822357, 7047.859042819115, 7052.823679408056, 7121.074260346226, 3949.980250098731, 4058.452108965311, 4095.877123686294, 4174.08170202561, 4184.66522678185, 1712.5889786451412, 1756.9912150439166, 1762.3038899634828, 1770.9822901770817, 1815.6005678750653, 837.3970741066422, 851.2423942691009, 860.1592591853439, 879.89881163667, 922.3174850610634, 16006.839931600767, 16617.667646646998, 16750.0, 16770.161491925355, 17532.824671753373, 806.7418426103591, 824.0, 836.1763662792144, 839.47532792005, 842.5576572299592, 8912.703861806809, 9559.088182363555, 9587.664431744855, 9608.66369677058, 9620.71137865865, 339807.0, 345121.0, 346306.0, 346705.0, 349361.0, 70790.2920970794, 73366.63316683382, 74927.62536187284, 75317.24682753212, 75609.62195188989, 847.4195176304252, 867.8568036273944, 868.3400615532115, 869.6347534035721, 873.0265753684723, 14296.0, 14770.261486925825, 14811.0, 14889.032212906126, 15393.30730117125, 34971.825140874134, 35223.47164792479, 35339.64660353467, 35596.466053009746, 37069.4439583401, 48234.517654824405, 48606.75696621494, 48676.75661621669, 48824.511754882, 49081.2637810426, 6654.800355989328, 6823.761168359084, 6853.828654283683, 6919.100516932766, 7558.357539609195, 7483.738069167553, 8585.785355366166, 9657.855132173163, 9679.70960871175, 10005.299629025898, 8223.95888020556, 8361.16388361159, 8451.788705282417, 8617.0521242662, 8644.178803013703, 8035.638396272065, 8204.466709663853, 8336.666533338599, 8338.499690018618, 8660.35047371437, 8372.706955256537, 8671.046184919527, 8709.346798989975, 8977.37158398932, 8988.43843993875, 6914.585124892523, 7017.0, 7073.0, 7147.749828755968, 7313.817154570966, 8018.158093400109, 8042.316403105801, 8309.626066827128, 8395.32837373021, 8483.490990540588], "value": 7580.129219586055, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1225.9754804903896, 1321.8876395506381, 1369.3153423288356, 1392.5056604905258, 1625.902445853248, 6063.969680151598, 6688.732450701972, 6949.339812717796, 6963.860722785541, 7129.714811407537, 7034.085568876052, 7089.574625522472, 7100.112485939258, 7108.289171082902, 7111.7866464006165, 1792.059168936306, 1852.0369407907947, 2007.307478919774, 2043.9284625038126, 2046.0383619698719, 2003.1887085730302, 2054.537729010968, 2056.8354531637433, 2059.7940205979357, 2062.783407742188, 2010.2562052040767, 2031.3195079648262, 2033.247698351612, 2033.7864524224965, 2035.6844689073218, 1987.5229944813286, 2025.635385630586, 2025.8075482829256, 2026.3616960657419, 2026.9493262668404, 1999.800019998003, 2013.0941076515626, 2028.1481777653273, 2029.9594008119902, 2033.2680235115347, 6892.069570607954, 6940.375366217014, 6947.826304342381, 6949.0, 6956.060931774247, 3937.448757173998, 4065.7967101645095, 4065.7967101645095, 4076.32740597801, 4083.3466645336603, 2474.1340530814073, 2608.491344187884, 2673.4653069386004, 2688.2876037849987, 2712.8643567821728, 810.1938571121713, 868.1405408645459, 884.1335491218641, 885.3846576629245, 905.248643625789, 9059.320550958639, 9063.728088157366, 9067.909320906772, 9069.954650226773, 9097.590608422637, 3478.711580919931, 3576.302620988882, 3741.607131251234, 3766.133789228438, 3775.5091838060875, 8002.159773223844, 8004.5197288161735, 8015.879761803578, 8020.35837133028, 8022.679092836222, 4089.4070359798257, 4105.815238314223, 4116.814743336557, 4120.423140760296, 4120.7321524100835, 3272.574565306495, 3384.0, 3676.0809797550614, 3717.3308804415465, 3730.6642402183934, 3049.5883055787185, 3143.528470729409, 3164.651888292286, 3240.3519296140867, 3290.9177270568425, 1062.665260442958, 1074.6346242277507, 1087.331291255872, 1092.8688557373166, 1106.336198281025, 665.3114026982147, 757.4054367321626, 762.9504082234637, 765.4832987733258, 798.0662624729081, 4609.5620916013595, 4688.062387522509, 4759.904801904012, 4793.161196790547, 4822.662413631012, 380.43695330910407, 396.6747267240835, 398.27911480220894, 399.93800960851115, 400.20359484625385, 3043.436964161637, 3111.284404586958, 3120.2511397264493, 3125.46867032605, 3208.759343049234, 7786.26030527099, 7954.920450795534, 8171.42800003994, 8171.7548473545885, 8306.626201820933, 8273.503589784632, 8289.378296627778, 8290.544020078833, 8292.502449853027, 8299.83802267683, 6307.873842523124, 6347.587406818542, 6424.518161137933, 6429.51778616615, 6470.579412338183, 4493.303537951622, 4597.885052873571, 4615.953840461687, 4700.788464519173, 4742.288656701522, 7413.925860741324, 7416.555006699616, 7505.4746167767735, 7519.624018798929, 7584.2794934481135, 7584.12782529994, 7595.620218988918, 7613.6573854177805, 7638.770836874903, 7671.002769639911, 5778.624389414675, 5852.853678658068, 5875.177475153568, 5878.529717622666, 5889.882202356014, 5737.483626473638, 5740.483356497935, 5964.0, 6002.309734380602, 6325.96837015812, 5437.34751829783, 5444.128939369682, 5491.65454463659, 5542.307211598551, 5569.248151499496, 5455.8363249102595, 5487.259220005249, 5542.556595472433, 5553.0837411828215, 5575.024370735024, 5004.949950500594, 5056.418511871182, 5068.29043933857, 5069.822556210515, 5072.4420313766195, 3827.4641550183555, 3827.693784497178, 4149.7095203336685, 4169.332906734908, 4211.663066954575, 3961.8811435656976, 4105.712600117963, 4111.321631930818, 4124.752514849119, 4126.66986641062], "value": 3575.5293735207742, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.3562385897705, 278.3167324219042, 278.6433365292425, 283.66102507503535, 283.8268656119768, 4657.720536767796, 4776.8805779855475, 4784.162771514988, 4831.637627177963, 4842.491538388467, 4652.7906244219, 4710.481846996828, 4731.580525842251, 4741.336212930192, 4756.357891684622, 3770.7744982880536, 3771.5662698789715, 3774.0186402138893, 3776.8866933992026, 3784.6026167252453, 3575.284943011395, 3750.9624903751023, 3760.9811950940207, 3767.340715374812, 3790.128270497787, 3507.9649203507893, 3706.3884459064357, 3711.1278849470355, 3714.145746478298, 3725.3853114236117, 3712.238991006864, 3713.2016616427463, 3713.9628603713886, 3724.4599533067826, 3727.1986522897437, 3293.077938177314, 3307.5038744188323, 3307.68576985186, 3309.305045940331, 3314.7182489488423, 4581.0, 4661.46393164787, 4665.440147182326, 4700.083483720676, 4714.929276060861, 4224.39473000261, 4231.936520952188, 4270.359446083081, 4270.701050926436, 4298.355246712987, 4979.850604481871, 4997.675151115166, 5000.174971129759, 5011.24606387765, 5016.3980322361185, 3517.66582174693, 3615.0, 3616.3490571697084, 3621.4386770050683, 3626.909327266813, 5183.611229157753, 5195.246689230041, 5215.504527069922, 5224.544464101904, 5238.109521381336, 3631.074076110575, 3661.9816900915325, 3662.304162209172, 3664.798436086041, 3698.8705395311035, 3738.532683414573, 3743.6256374362347, 3747.494088298045, 3755.3052685253315, 3755.4742336072973, 3906.5312162540677, 4052.581596441222, 4062.309407400749, 4288.078063216423, 4291.141771645684, 4956.157453232959, 4965.106280869406, 4968.975155124273, 4972.154733695281, 4985.401751789808, 1152.6599653102355, 1176.4294317256124, 1187.228301603947, 1195.623378635727, 1198.2989950878834, 811.5293129984594, 826.7685048186397, 827.5283088639538, 831.6465502161557, 840.1052878684181, 4235.30117530604, 4256.914861702749, 4268.829246830154, 4275.508316543637, 4277.187334406515, 4884.853454396374, 4929.2359684248995, 4936.5190442867, 4946.653734238569, 4953.1332016896895, 4104.651104656078, 4264.488261408589, 4273.252180868336, 4284.100338928797, 4289.699721019499, 2636.762691357787, 2638.4723055389, 2638.6437830892582, 2650.443406884537, 2652.1910817200555, 4649.976750116295, 4650.255959046536, 4658.510856360102, 4671.929921051119, 4682.41469816273, 4922.8769280768265, 4959.256111583291, 5044.924326135111, 5052.646314758005, 5074.568661663726, 4607.585317321457, 4621.556940079662, 4623.190941585276, 4629.125204292267, 4637.860864174013, 4895.46149923501, 4938.802447902188, 4952.900941981068, 4975.392840190832, 4983.508245877061, 4821.5178482151505, 4844.2699121676815, 4865.756712164449, 5151.5363617274625, 5184.94088487794, 4883.780229889592, 4888.535589119028, 4890.217565189695, 4894.412670479423, 4912.435069966999, 3618.5295911531334, 3628.292482965902, 3633.473146393705, 3633.927321453609, 3651.726120541023, 5074.797008119782, 5181.689098654093, 5235.554977826775, 5239.44985776488, 5269.4730526947, 5017.12200364942, 5019.222020586815, 5039.748012599282, 5042.369703787027, 5073.746312684277, 5018.573421259088, 5046.142155833591, 5065.670731402447, 5073.980981316111, 5128.307678463509, 4717.952820471752, 4767.118083154627, 4964.280179374043, 4998.60011199096, 5027.924581131213, 4995.775190116527, 4996.475370086234, 5048.798048078183, 5108.182690769459, 5116.258142569381, 4928.211486162197, 4969.4782047884455, 4979.261406226633, 5003.249512573143, 5009.373828271466], "value": 3728.0773896838373, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.0716781471498, 591.7307625030611, 597.2415032908207, 613.2303958532042, 617.5893031134295, 11164.553417863286, 11798.587049453261, 12071.818922716166, 12152.392380380968, 12299.0, 11262.099032077434, 11360.943195284011, 11374.545018199262, 11382.3739694317, 11386.772264554726, 610.3652201710216, 739.3826155160448, 756.735142700056, 759.5670467833337, 760.3764912771529, 622.8847663182303, 736.9484136110474, 750.7897788619196, 762.9923700762977, 765.406809722464, 798.5727635714894, 801.707376807464, 802.8153524689325, 803.4817542684965, 803.5219044668413, 634.2674211285972, 796.8844517544952, 798.4849771897123, 798.6446031515977, 799.1768478467188, 477.34365247784297, 561.2142999800271, 575.6948817126929, 579.4611011759063, 581.8516278349036, 12275.877241227563, 12309.322987235699, 12316.81524777129, 12328.81506777399, 12345.62963111099, 12738.172018818746, 13060.608181754562, 13392.0, 13422.127561708556, 13453.461861525528, 8831.77920551985, 9035.909640903572, 9390.906090939072, 12033.0, 12174.756504869942, 3119.3293441909873, 3131.7651176161653, 3225.9354812903844, 3333.0, 4141.772202528859, 64827.3517264826, 65809.34190658032, 67094.32905670929, 67438.32561674419, 67934.0, 9363.765905852408, 9455.621775129055, 9776.41341519497, 9827.164691001202, 9871.851922221173, 15062.924685376503, 15110.848891511165, 15247.847521524865, 15621.453249136226, 15696.293666785023, 5969.791057312973, 6189.102580125947, 6239.96880015597, 6240.31356550779, 6267.71795269214, 3382.6279109297957, 3401.6258211596705, 3407.1993081625797, 3486.1284678830293, 3591.964080359163, 2553.1957433408415, 2593.4164812916943, 2602.49251395976, 2618.1752747884357, 2630.6185603087447, 440.8677396780953, 441.24547024587673, 447.76268577653894, 449.94600647921806, 450.1402321565794, 456.66633574903074, 501.52104739973964, 512.9743512824344, 527.3276572370182, 527.7282199667175, 6859.348361905611, 7317.785555517018, 7351.926480735231, 7439.702411903571, 7575.204603516661, 492.894027784021, 502.4222144533815, 503.49147361165484, 510.9514596113363, 539.9956081688057, 3372.544706464645, 3404.608470025979, 3423.295596478754, 3555.2000799820353, 3579.8926032219074, 65535.34464655293, 68484.0, 69864.30135698679, 69963.0, 73189.0, 57423.42576574264, 58302.4169758297, 59119.40880591225, 60136.0, 63475.36524634695, 53923.460765393414, 54776.45223547793, 54864.45135548673, 54973.45026549763, 55172.448275517534, 6573.3426657333885, 6704.262531121671, 6732.360425759545, 6752.763653272112, 6803.1836179656775, 32186.0, 32255.35489290154, 34194.65805342014, 36244.27511449809, 40292.79853600713, 39070.60929390784, 45132.32301515545, 46158.0, 47137.0, 47437.52562474331, 10941.23411361197, 11094.778104437704, 11220.15848811326, 11333.546658133582, 12194.51221951112, 13107.606771796862, 13110.344482776014, 13675.521356752466, 13866.376013079234, 14161.0, 11277.605283815026, 11301.830472542753, 11476.655700329004, 11624.877077208115, 12073.0, 11725.88274117248, 11796.377875672948, 11799.941000294944, 11876.881231187577, 12142.514299427923, 10465.68602941913, 10649.627263045757, 10692.518836652214, 10850.403227822546, 11043.39261340634, 9965.302428829913, 10156.238282128723, 10213.510919245906, 10341.84487232677, 10680.89319106799, 9543.856842147234, 9745.512724363894, 9760.658376956773, 9861.359011664219, 9884.851727223953], "value": 5940.9428981101355, "unit": "score"}], "name": "dromaeo_css", "value": 6009.546159630637, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 117.0, 117.0, 117.0, 116.0, 117.0, 118.0, 118.0, 116.0, 116.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 128.0, 135.0, 167.0, 136.0, 134.0, 128.0, 139.0, 154.0, 131.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 162.0, 161.0, 164.0, 163.0, 163.0, 162.0, 162.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 79.0, 107.0, 77.0, 78.0, 78.0, 78.0, 91.0, 78.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 119.0, 84.0, 83.0, 84.0, 100.0, 83.0, 84.0, 111.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 116.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 64.0, 65.0, 68.0, 64.0, 65.0, 66.0, 66.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 67.0, 66.0, 66.0, 67.0, 71.0, 66.0, 66.0], "value": 66.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 90.0, 86.0, 86.0, 95.0, 85.0, 86.0, 85.0, 86.0, 86.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 143.0, 157.0, 148.0, 150.0, 143.0, 154.0, 155.0, 193.0, 143.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 198.0, 186.0, 190.0, 182.0, 184.0, 185.0, 184.0, 188.0, 184.0], "value": 187.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 72.0, 71.0, 72.0, 72.0, 71.0, 74.0, 71.0, 72.0], "value": 72.1, "unit": "score"}], "name": "kraken", "value": 1585.4999999999995, "alertThreshold": 2.0}]} 10:43:40 INFO - Return code: 0 10:43:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:43:40 INFO - # TBPL SUCCESS # 10:43:40 INFO - Running post-action listener: _resource_record_post_action 10:43:40 INFO - [mozharness: 2016-11-30 18:43:40.739633Z] Finished run-tests step (success) 10:43:40 INFO - Running post-run listener: _resource_record_post_run 10:43:40 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 3321856; Write bytes: 392617984; Read time: 508; Write time: 260388 10:43:40 INFO - TinderboxPrint: CPU usage
13.1% 10:43:40 INFO - TinderboxPrint: I/O read bytes / time
3,321,856 / 508 10:43:40 INFO - TinderboxPrint: I/O write bytes / time
392,617,984 / 260,388 10:43:40 INFO - TinderboxPrint: CPU idle
8,348.7 (86.9%) 10:43:40 INFO - TinderboxPrint: CPU user
1,209.4 (12.6%) 10:43:40 INFO - TinderboxPrint: Swap in / out
0 / 0 10:43:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99856384; Read time: 0; Write time: 116792 10:43:40 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 3317760; Write bytes: 292761600; Read time: 500; Write time: 143596 10:43:41 INFO - Running post-run listener: _upload_blobber_files 10:43:41 INFO - Blob upload gear active. 10:43:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:43:41 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 10:43:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:43:41 INFO - (blobuploader) - INFO - Open directory for files ... 10:43:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:43:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:43:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:46 INFO - (blobuploader) - INFO - Done attempting. 10:43:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:43:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:43:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:48 INFO - (blobuploader) - INFO - Done attempting. 10:43:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:43:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:43:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:43:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:43:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:43:49 INFO - (blobuploader) - INFO - Done attempting. 10:43:49 INFO - (blobuploader) - INFO - Iteration through files over. 10:43:49 INFO - Return code: 0 10:43:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:43:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:43:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb0e5d65c8267140217d29ccde4ac7f273a47683b80f139111483092c3af90c23e5424c94999704c7bbd628cb6090addcf933a76c8f5d7c8cfd6ac8160727532", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/02d539725ae15739aa0a497cb2651ff4a4866faa6815597740e63bb63cdfd3e8a6500fe14085af82176675a5776215378552ec01f059bf16e10dbc2d5d84e510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/740d8edd3f00442160c813920d121f73f3df0aaa957cdf600c7266cb617777b76d9e1b4e64f710089a43b618ea97150684ebb07e2fa84b991fb90714e866b06d"} 10:43:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:43:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:43:49 INFO - Contents: 10:43:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb0e5d65c8267140217d29ccde4ac7f273a47683b80f139111483092c3af90c23e5424c94999704c7bbd628cb6090addcf933a76c8f5d7c8cfd6ac8160727532", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/02d539725ae15739aa0a497cb2651ff4a4866faa6815597740e63bb63cdfd3e8a6500fe14085af82176675a5776215378552ec01f059bf16e10dbc2d5d84e510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/740d8edd3f00442160c813920d121f73f3df0aaa957cdf600c7266cb617777b76d9e1b4e64f710089a43b618ea97150684ebb07e2fa84b991fb90714e866b06d"} 10:43:49 INFO - Running post-run listener: copy_logs_to_upload_dir 10:43:49 INFO - Copying logs to upload dir... 10:43:49 INFO - mkdir: /builds/slave/test/build/upload/logs 10:43:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.770996 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-11-30 10:43:49.246064) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 10:43:49.253391) ========= 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=3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb0e5d65c8267140217d29ccde4ac7f273a47683b80f139111483092c3af90c23e5424c94999704c7bbd628cb6090addcf933a76c8f5d7c8cfd6ac8160727532", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/02d539725ae15739aa0a497cb2651ff4a4866faa6815597740e63bb63cdfd3e8a6500fe14085af82176675a5776215378552ec01f059bf16e10dbc2d5d84e510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/740d8edd3f00442160c813920d121f73f3df0aaa957cdf600c7266cb617777b76d9e1b4e64f710089a43b618ea97150684ebb07e2fa84b991fb90714e866b06d"} build_url:https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011322 build_url: 'https://queue.taskcluster.net/v1/task/DDVisZRoSYaF9vF6PQSxCg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb0e5d65c8267140217d29ccde4ac7f273a47683b80f139111483092c3af90c23e5424c94999704c7bbd628cb6090addcf933a76c8f5d7c8cfd6ac8160727532", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/02d539725ae15739aa0a497cb2651ff4a4866faa6815597740e63bb63cdfd3e8a6500fe14085af82176675a5776215378552ec01f059bf16e10dbc2d5d84e510", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/740d8edd3f00442160c813920d121f73f3df0aaa957cdf600c7266cb617777b76d9e1b4e64f710089a43b618ea97150684ebb07e2fa84b991fb90714e866b06d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 10:43:49.281533) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 10:43:49.281950) ========= 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=3a3620fb28aea1604ab609ef000001d2-1480530093.175174-467336575 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004462 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 10:43:49.331848) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 10:43:49.335229) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 10:43:49.335549) ========= ========= Total master_lag: 0.30 =========