builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1480957724.64 results: success (0) buildid: 20161205073106 builduid: 8f2cdac550ec4f0894462838db1a5622 revision: 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:44.639430) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:44.639884) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:44.640167) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:44.661785) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:44.662134) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-05 09:08:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 29.9M=0s 2016-12-05 09:08:44 (29.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107604 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:44.783310) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:44.785999) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015215 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:44.839019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:44.839463) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-05 09:08:45,121 truncating revision to first 12 chars 2016-12-05 09:08:45,122 Setting DEBUG logging. 2016-12-05 09:08:45,122 attempt 1/10 2016-12-05 09:08:45,122 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8103c612b79c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-05 09:08:45,639 unpacking tar archive at: mozilla-central-8103c612b79c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.854306 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:45.711786) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:45.712102) ========= script_repo_revision: 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:45.712493) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:45.712777) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-05 09:08:45.721651) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-05 09:08:45.722035) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:08:46 INFO - MultiFileLogger online at 20161205 09:08:46 in /builds/slave/test 09:08:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:08:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:08:46 INFO - {'append_to_log': False, 09:08:46 INFO - 'base_work_dir': '/builds/slave/test', 09:08:46 INFO - 'blob_upload_branch': 'Firefox', 09:08:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:08:46 INFO - 'branch': 'Firefox', 09:08:46 INFO - 'buildbot_json_path': 'buildprops.json', 09:08:46 INFO - 'config_files': ('talos/linux_config.py',), 09:08:46 INFO - 'default_actions': ('clobber', 09:08:46 INFO - 'read-buildbot-config', 09:08:46 INFO - 'download-and-extract', 09:08:46 INFO - 'populate-webroot', 09:08:46 INFO - 'create-virtualenv', 09:08:46 INFO - 'install', 09:08:46 INFO - 'run-tests'), 09:08:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:08:46 INFO - 'download_minidump_stackwalk': True, 09:08:46 INFO - 'download_symbols': 'ondemand', 09:08:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:08:46 INFO - 'tooltool.py': '/tools/tooltool.py', 09:08:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:08:46 INFO - '/tools/misc-python/virtualenv.py')}, 09:08:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:08:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:08:46 INFO - 'installer_path': 'installer.exe', 09:08:46 INFO - 'log_level': 'info', 09:08:46 INFO - 'log_name': 'talos', 09:08:46 INFO - 'log_to_console': True, 09:08:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:08:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:08:46 INFO - 'opt_config_files': (), 09:08:46 INFO - 'pip_index': False, 09:08:46 INFO - 'sps_profile': False, 09:08:46 INFO - 'sps_profile_interval': 0, 09:08:46 INFO - 'suite': 'dromaeojs', 09:08:46 INFO - 'system_bits': '32', 09:08:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:08:46 INFO - 'title': 'talos-linux64-ix-013', 09:08:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:08:46 INFO - 'use_talos_json': True, 09:08:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:08:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:08:46 INFO - 'work_dir': 'build'} 09:08:46 INFO - [mozharness: 2016-12-05 17:08:46.092981Z] Running clobber step. 09:08:46 INFO - Running pre-action listener: _resource_record_pre_action 09:08:46 INFO - Running main action method: clobber 09:08:46 INFO - rmtree: /builds/slave/test/build 09:08:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:08:47 INFO - Running post-action listener: _resource_record_post_action 09:08:47 INFO - [mozharness: 2016-12-05 17:08:47.071046Z] Finished clobber step (success) 09:08:47 INFO - [mozharness: 2016-12-05 17:08:47.071144Z] Running read-buildbot-config step. 09:08:47 INFO - Running pre-action listener: _resource_record_pre_action 09:08:47 INFO - Running main action method: read_buildbot_config 09:08:47 INFO - Using buildbot properties: 09:08:47 INFO - { 09:08:47 INFO - "project": "", 09:08:47 INFO - "product": "firefox", 09:08:47 INFO - "script_repo_revision": "production", 09:08:47 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 09:08:47 INFO - "repository": "", 09:08:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 09:08:47 INFO - "buildid": "20161205073106", 09:08:47 INFO - "pgo_build": "True", 09:08:47 INFO - "basedir": "/builds/slave/test", 09:08:47 INFO - "buildnumber": 97, 09:08:47 INFO - "slavename": "talos-linux64-ix-013", 09:08:47 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:08:47 INFO - "platform": "ubuntu64_hw", 09:08:47 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:08:47 INFO - "branch": "mozilla-central", 09:08:47 INFO - "repo_path": "mozilla-central", 09:08:47 INFO - "revision": "8103c612b79c2587ea4ca1b0a9f9f82db4b185b8", 09:08:47 INFO - "stage_platform": "linux64-pgo", 09:08:47 INFO - "builduid": "8f2cdac550ec4f0894462838db1a5622", 09:08:47 INFO - "slavebuilddir": "test" 09:08:47 INFO - } 09:08:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:08:47 INFO - Running post-action listener: _resource_record_post_action 09:08:47 INFO - [mozharness: 2016-12-05 17:08:47.073587Z] Finished read-buildbot-config step (success) 09:08:47 INFO - [mozharness: 2016-12-05 17:08:47.073676Z] Running download-and-extract step. 09:08:47 INFO - Running pre-action listener: _resource_record_pre_action 09:08:47 INFO - Running main action method: download_and_extract 09:08:47 INFO - mkdir: /builds/slave/test/build/tests 09:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:47 INFO - trying https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:08:47 INFO - Downloading https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/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 09:08:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/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 09:08:47 INFO - Downloaded 1361 bytes. 09:08:47 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:08:47 INFO - Using the following test package requirements: 09:08:47 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:08:47 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:08:47 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:08:47 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:47 INFO - u'jsshell-linux-x86_64.zip'], 09:08:47 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:08:47 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:08:47 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:08:47 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:08:47 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:08:47 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:08:47 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:08:47 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:08:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:08:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:08:47 INFO - Fetch https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:08:48 INFO - Expected file size: 21780627 09:08:48 INFO - Obtained file size: 21780627 09:08:52 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 09:08:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:08:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:08:52 INFO - Fetch https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:08:53 INFO - Expected file size: 21780627 09:08:53 INFO - Obtained file size: 21780627 09:08:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:08:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:08:57 INFO - Fetch https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:08:59 INFO - Expected file size: 14409734 09:08:59 INFO - Obtained file size: 14409734 09:08:59 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')]} 09:08:59 INFO - trying https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:08:59 INFO - Downloading https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/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 09:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/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 09:09:02 INFO - Downloaded 59839775 bytes. 09:09:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:09:02 INFO - mkdir: /builds/slave/test/properties 09:09:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:09:02 INFO - Writing to file /builds/slave/test/properties/build_url 09:09:02 INFO - Contents: 09:09:02 INFO - build_url:https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:09:03 INFO - Running post-action listener: _resource_record_post_action 09:09:03 INFO - Running post-action listener: set_extra_try_arguments 09:09:03 INFO - [mozharness: 2016-12-05 17:09:03.391338Z] Finished download-and-extract step (success) 09:09:03 INFO - [mozharness: 2016-12-05 17:09:03.391565Z] Running populate-webroot step. 09:09:03 INFO - Running pre-action listener: _resource_record_pre_action 09:09:03 INFO - Running main action method: populate_webroot 09:09:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:09:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:09:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:09:03 INFO - '--tppagecycles', 09:09:03 INFO - '7'], 09:09:03 INFO - 'tests': ['tsvgm']}}, 09:09:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:09:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:09:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:09:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:09:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:09:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:09:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:09:03 INFO - 'talos_options': ['--disable-e10s'], 09:09:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:09:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:09:03 INFO - 'talos_options': ['--disable-e10s'], 09:09:03 INFO - 'tests': ['damp', 'tps']}, 09:09:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:09:03 INFO - 'tests': ['damp', 'tps']}, 09:09:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:09:03 INFO - 'tests': ['dromaeo_dom']}, 09:09:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:09:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:09:03 INFO - 'tests': ['basic_compositor_video']}, 09:09:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:09:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:09:03 INFO - 'tests': ['a11yr', 09:09:03 INFO - 'ts_paint', 09:09:03 INFO - 'tpaint', 09:09:03 INFO - 'sessionrestore', 09:09:03 INFO - 'sessionrestore_no_auto_restore', 09:09:03 INFO - 'tabpaint']}, 09:09:03 INFO - 'other-e10s': {'tests': ['a11yr', 09:09:03 INFO - 'ts_paint', 09:09:03 INFO - 'tpaint', 09:09:03 INFO - 'sessionrestore', 09:09:03 INFO - 'sessionrestore_no_auto_restore', 09:09:03 INFO - 'tabpaint']}, 09:09:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:09:03 INFO - 'ts_paint', 09:09:03 INFO - 'tpaint', 09:09:03 INFO - 'sessionrestore', 09:09:03 INFO - 'sessionrestore_no_auto_restore', 09:09:03 INFO - 'tabpaint']}, 09:09:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:09:03 INFO - 'ts_paint', 09:09:03 INFO - 'tpaint', 09:09:03 INFO - 'sessionrestore', 09:09:03 INFO - 'sessionrestore_no_auto_restore', 09:09:03 INFO - 'tabpaint']}, 09:09:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:09:03 INFO - 'tests': ['a11yr', 09:09:03 INFO - 'ts_paint', 09:09:03 INFO - 'tpaint', 09:09:03 INFO - 'sessionrestore', 09:09:03 INFO - 'sessionrestore_no_auto_restore', 09:09:03 INFO - 'tabpaint']}, 09:09:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:09:03 INFO - 'tests': ['a11yr', 09:09:03 INFO - 'ts_paint', 09:09:03 INFO - 'tpaint', 09:09:03 INFO - 'sessionrestore', 09:09:03 INFO - 'sessionrestore_no_auto_restore', 09:09:03 INFO - 'tabpaint']}, 09:09:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:09:03 INFO - 'tests': ['tsvgx', 09:09:03 INFO - 'tsvgr_opacity', 09:09:03 INFO - 'tart', 09:09:03 INFO - 'tscrollx', 09:09:03 INFO - 'cart', 09:09:03 INFO - 'tsvg_static']}, 09:09:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:09:03 INFO - 'tsvgr_opacity', 09:09:03 INFO - 'tart', 09:09:03 INFO - 'tscrollx', 09:09:03 INFO - 'cart', 09:09:03 INFO - 'tsvg_static']}, 09:09:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:09:03 INFO - 'talos_options': ['--disable-e10s'], 09:09:03 INFO - 'tests': ['tp5o']}, 09:09:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:09:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:09:03 INFO - 'talos_options': ['--disable-e10s', 09:09:03 INFO - '--xperf_path', 09:09:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:03 INFO - 'tests': ['tp5n']}, 09:09:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:09:03 INFO - 'talos_options': ['--xperf_path', 09:09:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:03 INFO - 'tests': ['tp5n']}}, 09:09:03 INFO - 'talos.zip': {'path': '', 09:09:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:09:03 INFO - Running post-action listener: _resource_record_post_action 09:09:03 INFO - [mozharness: 2016-12-05 17:09:03.412852Z] Finished populate-webroot step (success) 09:09:03 INFO - [mozharness: 2016-12-05 17:09:03.412939Z] Running create-virtualenv step. 09:09:03 INFO - Running pre-action listener: _resource_record_pre_action 09:09:03 INFO - Running main action method: create_virtualenv 09:09:03 INFO - Creating virtualenv /builds/slave/test/build/venv 09:09:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:09:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:09:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:09:03 INFO - Using real prefix '/usr' 09:09:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:09:04 INFO - Installing distribute.............................................................................................................................................................................................done. 09:09:06 INFO - Installing pip.................done. 09:09:06 INFO - Return code: 0 09:09:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:09:06 INFO - Reading from file tmpfile_stdout 09:09:06 INFO - Output received: 09:09:06 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) 09:09:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:09:06 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')]} 09:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:06 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')]} 09:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:06 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 0x7fa243c33e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ba120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294af20>, '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 0x28b7c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:09:06 INFO - Using env: {'DISPLAY': ':0', 09:09:06 INFO - 'HOME': '/home/cltbld', 09:09:06 INFO - 'LANG': 'en_US.UTF-8', 09:09:06 INFO - 'LANGUAGE': 'en_US:en', 09:09:06 INFO - 'LOGNAME': 'cltbld', 09:09:06 INFO - 'MAIL': '/var/mail/cltbld', 09:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:06 INFO - 'MOZ_NO_REMOTE': '1', 09:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:06 INFO - 'NO_EM_RESTART': '1', 09:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:06 INFO - 'PWD': '/builds/slave/test', 09:09:06 INFO - 'SHELL': '/bin/bash', 09:09:06 INFO - 'SHLVL': '1', 09:09:06 INFO - 'TERM': 'linux', 09:09:06 INFO - 'TMOUT': '86400', 09:09:06 INFO - 'USER': 'cltbld', 09:09:06 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 09:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:06 INFO - '_': '/tools/buildbot/bin/python'} 09:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:09:06 INFO - Cleaning up... 09:09:06 INFO - Return code: 0 09:09:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:09:06 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')]} 09:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:06 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')]} 09:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:06 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 0x7fa243c33e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ba120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294af20>, '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 0x28b7c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:09:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:09:06 INFO - Using env: {'DISPLAY': ':0', 09:09:06 INFO - 'HOME': '/home/cltbld', 09:09:06 INFO - 'LANG': 'en_US.UTF-8', 09:09:06 INFO - 'LANGUAGE': 'en_US:en', 09:09:06 INFO - 'LOGNAME': 'cltbld', 09:09:06 INFO - 'MAIL': '/var/mail/cltbld', 09:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:06 INFO - 'MOZ_NO_REMOTE': '1', 09:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:06 INFO - 'NO_EM_RESTART': '1', 09:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:06 INFO - 'PWD': '/builds/slave/test', 09:09:06 INFO - 'SHELL': '/bin/bash', 09:09:06 INFO - 'SHLVL': '1', 09:09:06 INFO - 'TERM': 'linux', 09:09:06 INFO - 'TMOUT': '86400', 09:09:06 INFO - 'USER': 'cltbld', 09:09:06 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 09:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:06 INFO - '_': '/tools/buildbot/bin/python'} 09:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:06 INFO - Downloading/unpacking psutil>=3.1.1 09:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:09:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:08 INFO - Installing collected packages: psutil 09:09:08 INFO - Running setup.py install for psutil 09:09:08 INFO - building 'psutil._psutil_linux' extension 09:09:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:09:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:09:08 INFO - building 'psutil._psutil_posix' extension 09:09:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:09:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:09:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:09 INFO - Successfully installed psutil 09:09:09 INFO - Cleaning up... 09:09:09 INFO - Return code: 0 09:09:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:09:09 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')]} 09:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:09 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')]} 09:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:09 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 0x7fa243c33e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ba120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294af20>, '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 0x28b7c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:09:09 INFO - Using env: {'DISPLAY': ':0', 09:09:09 INFO - 'HOME': '/home/cltbld', 09:09:09 INFO - 'LANG': 'en_US.UTF-8', 09:09:09 INFO - 'LANGUAGE': 'en_US:en', 09:09:09 INFO - 'LOGNAME': 'cltbld', 09:09:09 INFO - 'MAIL': '/var/mail/cltbld', 09:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:09 INFO - 'MOZ_NO_REMOTE': '1', 09:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:09 INFO - 'NO_EM_RESTART': '1', 09:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:09 INFO - 'PWD': '/builds/slave/test', 09:09:09 INFO - 'SHELL': '/bin/bash', 09:09:09 INFO - 'SHLVL': '1', 09:09:09 INFO - 'TERM': 'linux', 09:09:09 INFO - 'TMOUT': '86400', 09:09:09 INFO - 'USER': 'cltbld', 09:09:09 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 09:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:09 INFO - '_': '/tools/buildbot/bin/python'} 09:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:09:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:09:11 INFO - Installing collected packages: mozsystemmonitor 09:09:11 INFO - Running setup.py install for mozsystemmonitor 09:09:11 INFO - Successfully installed mozsystemmonitor 09:09:11 INFO - Cleaning up... 09:09:11 INFO - Return code: 0 09:09:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:11 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 0x7fa243c33e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ba120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294af20>, '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 0x28b7c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:09:11 INFO - Using env: {'DISPLAY': ':0', 09:09:11 INFO - 'HOME': '/home/cltbld', 09:09:11 INFO - 'LANG': 'en_US.UTF-8', 09:09:11 INFO - 'LANGUAGE': 'en_US:en', 09:09:11 INFO - 'LOGNAME': 'cltbld', 09:09:11 INFO - 'MAIL': '/var/mail/cltbld', 09:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:11 INFO - 'MOZ_NO_REMOTE': '1', 09:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:11 INFO - 'NO_EM_RESTART': '1', 09:09:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:11 INFO - 'PWD': '/builds/slave/test', 09:09:11 INFO - 'SHELL': '/bin/bash', 09:09:11 INFO - 'SHLVL': '1', 09:09:11 INFO - 'TERM': 'linux', 09:09:11 INFO - 'TMOUT': '86400', 09:09:11 INFO - 'USER': 'cltbld', 09:09:11 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 09:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:11 INFO - '_': '/tools/buildbot/bin/python'} 09:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:11 INFO - Downloading/unpacking jsonschema==2.5.1 09:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:09:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:14 INFO - Downloading functools32-3.2.3-2.tar.gz 09:09:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:09:14 INFO - warning: no files found matching '*.txt' 09:09:14 INFO - no previously-included directories found matching 'build' 09:09:14 INFO - no previously-included directories found matching 'dist' 09:09:14 INFO - no previously-included directories found matching '.git*' 09:09:14 INFO - Installing collected packages: jsonschema, functools32 09:09:14 INFO - Running setup.py install for functools32 09:09:14 INFO - warning: no files found matching '*.txt' 09:09:14 INFO - no previously-included directories found matching 'build' 09:09:14 INFO - no previously-included directories found matching 'dist' 09:09:14 INFO - no previously-included directories found matching '.git*' 09:09:14 INFO - Successfully installed jsonschema functools32 09:09:14 INFO - Cleaning up... 09:09:14 INFO - Return code: 0 09:09:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:14 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 0x7fa243c33e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ba120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294af20>, '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 0x28b7c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:14 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 09:09:14 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 09:09:14 INFO - Using env: {'DISPLAY': ':0', 09:09:14 INFO - 'HOME': '/home/cltbld', 09:09:14 INFO - 'LANG': 'en_US.UTF-8', 09:09:14 INFO - 'LANGUAGE': 'en_US:en', 09:09:14 INFO - 'LOGNAME': 'cltbld', 09:09:14 INFO - 'MAIL': '/var/mail/cltbld', 09:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:14 INFO - 'MOZ_NO_REMOTE': '1', 09:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:14 INFO - 'NO_EM_RESTART': '1', 09:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:14 INFO - 'PWD': '/builds/slave/test', 09:09:14 INFO - 'SHELL': '/bin/bash', 09:09:14 INFO - 'SHLVL': '1', 09:09:14 INFO - 'TERM': 'linux', 09:09:14 INFO - 'TMOUT': '86400', 09:09:14 INFO - 'USER': 'cltbld', 09:09:14 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 09:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:14 INFO - '_': '/tools/buildbot/bin/python'} 09:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:09:14 INFO - Cleaning up... 09:09:14 INFO - Return code: 0 09:09:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:14 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 0x7fa243c33e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ba120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294af20>, '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 0x28b7c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:09:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:09:14 INFO - Using env: {'DISPLAY': ':0', 09:09:14 INFO - 'HOME': '/home/cltbld', 09:09:14 INFO - 'LANG': 'en_US.UTF-8', 09:09:14 INFO - 'LANGUAGE': 'en_US:en', 09:09:14 INFO - 'LOGNAME': 'cltbld', 09:09:14 INFO - 'MAIL': '/var/mail/cltbld', 09:09:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:14 INFO - 'MOZ_NO_REMOTE': '1', 09:09:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:14 INFO - 'NO_EM_RESTART': '1', 09:09:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:14 INFO - 'PWD': '/builds/slave/test', 09:09:14 INFO - 'SHELL': '/bin/bash', 09:09:14 INFO - 'SHLVL': '1', 09:09:14 INFO - 'TERM': 'linux', 09:09:14 INFO - 'TMOUT': '86400', 09:09:14 INFO - 'USER': 'cltbld', 09:09:14 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 09:09:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:14 INFO - '_': '/tools/buildbot/bin/python'} 09:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:14 INFO - Downloading/unpacking blobuploader==1.2.4 09:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:16 INFO - Downloading blobuploader-1.2.4.tar.gz 09:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:09:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:09:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:17 INFO - Downloading docopt-0.6.1.tar.gz 09:09:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:09:17 INFO - Installing collected packages: blobuploader, requests, docopt 09:09:17 INFO - Running setup.py install for blobuploader 09:09:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:09:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:09:17 INFO - Running setup.py install for requests 09:09:17 INFO - Running setup.py install for docopt 09:09:18 INFO - Successfully installed blobuploader requests docopt 09:09:18 INFO - Cleaning up... 09:09:18 INFO - Return code: 0 09:09:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:18 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 0x7fa243c33e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ba120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294af20>, '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 0x28b7c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:09:18 INFO - Using env: {'DISPLAY': ':0', 09:09:18 INFO - 'HOME': '/home/cltbld', 09:09:18 INFO - 'LANG': 'en_US.UTF-8', 09:09:18 INFO - 'LANGUAGE': 'en_US:en', 09:09:18 INFO - 'LOGNAME': 'cltbld', 09:09:18 INFO - 'MAIL': '/var/mail/cltbld', 09:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:18 INFO - 'MOZ_NO_REMOTE': '1', 09:09:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:18 INFO - 'NO_EM_RESTART': '1', 09:09:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:18 INFO - 'PWD': '/builds/slave/test', 09:09:18 INFO - 'SHELL': '/bin/bash', 09:09:18 INFO - 'SHLVL': '1', 09:09:18 INFO - 'TERM': 'linux', 09:09:18 INFO - 'TMOUT': '86400', 09:09:18 INFO - 'USER': 'cltbld', 09:09:18 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 09:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:18 INFO - '_': '/tools/buildbot/bin/python'} 09:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:18 INFO - Running setup.py (path:/tmp/pip-_Bp5rN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:18 INFO - Running setup.py (path:/tmp/pip-5pWlMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:18 INFO - Running setup.py (path:/tmp/pip-ObKQNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:18 INFO - Running setup.py (path:/tmp/pip-ASy0SK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:18 INFO - Running setup.py (path:/tmp/pip-TKxYN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:18 INFO - Running setup.py (path:/tmp/pip-U6QLU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:18 INFO - Running setup.py (path:/tmp/pip-J49cyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:18 INFO - Running setup.py (path:/tmp/pip-NCEwom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:18 INFO - Running setup.py (path:/tmp/pip-mu0Fu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:18 INFO - Running setup.py (path:/tmp/pip-DvHbpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:19 INFO - Running setup.py (path:/tmp/pip-GRgKdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:19 INFO - Running setup.py (path:/tmp/pip-2qvgS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:19 INFO - Running setup.py (path:/tmp/pip-8xdsBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:19 INFO - Running setup.py (path:/tmp/pip-yXKmqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:19 INFO - Running setup.py (path:/tmp/pip-2FKlQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:19 INFO - Running setup.py (path:/tmp/pip-ja_pal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:19 INFO - Running setup.py (path:/tmp/pip-isCii_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:09:19 INFO - Running setup.py install for manifestparser 09:09:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:09:19 INFO - Running setup.py install for mozcrash 09:09:19 INFO - Running setup.py install for mozdebug 09:09:19 INFO - Running setup.py install for mozdevice 09:09:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:09:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:09:19 INFO - Running setup.py install for mozfile 09:09:20 INFO - Running setup.py install for mozhttpd 09:09:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:09:20 INFO - Running setup.py install for mozinfo 09:09:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:09:20 INFO - Running setup.py install for mozInstall 09:09:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:09:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:09:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:09:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:09:20 INFO - Running setup.py install for mozleak 09:09:20 INFO - Running setup.py install for mozlog 09:09:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:09:20 INFO - Running setup.py install for moznetwork 09:09:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:09:20 INFO - Running setup.py install for mozprocess 09:09:20 INFO - Running setup.py install for mozprofile 09:09:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:09:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:09:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:09:20 INFO - Running setup.py install for mozrunner 09:09:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:09:21 INFO - Running setup.py install for mozscreenshot 09:09:21 INFO - Running setup.py install for moztest 09:09:21 INFO - Running setup.py install for mozversion 09:09:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:09:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:09:21 INFO - Cleaning up... 09:09:21 INFO - Return code: 0 09:09:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:21 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 0x7fa243c33e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ba120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294af20>, '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 0x28b7c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:09:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:09:21 INFO - Using env: {'DISPLAY': ':0', 09:09:21 INFO - 'HOME': '/home/cltbld', 09:09:21 INFO - 'LANG': 'en_US.UTF-8', 09:09:21 INFO - 'LANGUAGE': 'en_US:en', 09:09:21 INFO - 'LOGNAME': 'cltbld', 09:09:21 INFO - 'MAIL': '/var/mail/cltbld', 09:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:21 INFO - 'MOZ_NO_REMOTE': '1', 09:09:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:21 INFO - 'NO_EM_RESTART': '1', 09:09:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:21 INFO - 'PWD': '/builds/slave/test', 09:09:21 INFO - 'SHELL': '/bin/bash', 09:09:21 INFO - 'SHLVL': '1', 09:09:21 INFO - 'TERM': 'linux', 09:09:21 INFO - 'TMOUT': '86400', 09:09:21 INFO - 'USER': 'cltbld', 09:09:21 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 09:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:21 INFO - '_': '/tools/buildbot/bin/python'} 09:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:21 INFO - Running setup.py (path:/tmp/pip-LL9bwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:21 INFO - Running setup.py (path:/tmp/pip-iOf7tI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:21 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)) 09:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:21 INFO - Running setup.py (path:/tmp/pip-H32qI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:21 INFO - Running setup.py (path:/tmp/pip-9kjLTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:21 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)) 09:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:21 INFO - Running setup.py (path:/tmp/pip-ZBiXd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:21 INFO - Running setup.py (path:/tmp/pip-YMtxIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:22 INFO - Running setup.py (path:/tmp/pip-93eSKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:22 INFO - Running setup.py (path:/tmp/pip-_QPXcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:22 INFO - Running setup.py (path:/tmp/pip-HrOyem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:22 INFO - Running setup.py (path:/tmp/pip-I64dS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:22 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)) 09:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:22 INFO - Running setup.py (path:/tmp/pip-QveJmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:22 INFO - Running setup.py (path:/tmp/pip-j940iG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:22 INFO - Running setup.py (path:/tmp/pip-aOnxN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:22 INFO - Running setup.py (path:/tmp/pip-DJTOQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:22 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)) 09:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:22 INFO - Running setup.py (path:/tmp/pip-4ICxiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:22 INFO - Running setup.py (path:/tmp/pip-2u0gox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:22 INFO - Running setup.py (path:/tmp/pip-BgBh8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:09:22 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)) 09:09:22 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)) 09:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:09:22 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)) 09:09:22 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)) 09:09:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09:24 INFO - Downloading blessings-1.6.tar.gz 09:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:09:24 INFO - Installing collected packages: blessings 09:09:24 INFO - Running setup.py install for blessings 09:09:25 INFO - Successfully installed blessings 09:09:25 INFO - Cleaning up... 09:09:25 INFO - Return code: 0 09:09:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:09:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:09:25 INFO - Reading from file tmpfile_stdout 09:09:25 INFO - Current package versions: 09:09:25 INFO - argparse == 1.2.1 09:09:25 INFO - blessings == 1.6 09:09:25 INFO - blobuploader == 1.2.4 09:09:25 INFO - docopt == 0.6.1 09:09:25 INFO - functools32 == 3.2.3-2 09:09:25 INFO - jsonschema == 2.5.1 09:09:25 INFO - manifestparser == 1.1 09:09:25 INFO - mozInstall == 1.12 09:09:25 INFO - mozcrash == 1.0 09:09:25 INFO - mozdebug == 0.1 09:09:25 INFO - mozdevice == 0.49 09:09:25 INFO - mozfile == 1.2 09:09:25 INFO - mozhttpd == 0.7 09:09:25 INFO - mozinfo == 0.9 09:09:25 INFO - mozleak == 0.1 09:09:25 INFO - mozlog == 3.4 09:09:25 INFO - moznetwork == 0.27 09:09:25 INFO - mozprocess == 0.23 09:09:25 INFO - mozprofile == 0.28 09:09:25 INFO - mozrunner == 6.13 09:09:25 INFO - mozscreenshot == 0.1 09:09:25 INFO - mozsystemmonitor == 0.3 09:09:25 INFO - moztest == 0.7 09:09:25 INFO - mozversion == 1.4 09:09:25 INFO - psutil == 3.1.1 09:09:25 INFO - requests == 1.2.3 09:09:25 INFO - wsgiref == 0.1.2 09:09:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09: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')]} 09:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09: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')]} 09:09:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:25 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 0x7fa243c33e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ba120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294af20>, '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 0x28b7c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:09:25 INFO - Using env: {'DISPLAY': ':0', 09:09:25 INFO - 'HOME': '/home/cltbld', 09:09:25 INFO - 'LANG': 'en_US.UTF-8', 09:09:25 INFO - 'LANGUAGE': 'en_US:en', 09:09:25 INFO - 'LOGNAME': 'cltbld', 09:09:25 INFO - 'MAIL': '/var/mail/cltbld', 09:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:25 INFO - 'MOZ_NO_REMOTE': '1', 09:09:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:25 INFO - 'NO_EM_RESTART': '1', 09:09:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:25 INFO - 'PWD': '/builds/slave/test', 09:09:25 INFO - 'SHELL': '/bin/bash', 09:09:25 INFO - 'SHLVL': '1', 09:09:25 INFO - 'TERM': 'linux', 09:09:25 INFO - 'TMOUT': '86400', 09:09:25 INFO - 'USER': 'cltbld', 09:09:25 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 09:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:25 INFO - '_': '/tools/buildbot/bin/python'} 09:09:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:09:25 INFO - Cleaning up... 09:09:25 INFO - Return code: 0 09:09:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:09: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')]} 09:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09: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')]} 09:09:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa243c33e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x271ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ba120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x294af20>, '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 0x28b7c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:09: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', 'jsonschema'] in /builds/slave/test/build 09:09: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 jsonschema 09:09:25 INFO - Using env: {'DISPLAY': ':0', 09:09:25 INFO - 'HOME': '/home/cltbld', 09:09:25 INFO - 'LANG': 'en_US.UTF-8', 09:09:25 INFO - 'LANGUAGE': 'en_US:en', 09:09:25 INFO - 'LOGNAME': 'cltbld', 09:09:25 INFO - 'MAIL': '/var/mail/cltbld', 09:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:25 INFO - 'MOZ_NO_REMOTE': '1', 09:09:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:25 INFO - 'NO_EM_RESTART': '1', 09:09:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:25 INFO - 'PWD': '/builds/slave/test', 09:09:25 INFO - 'SHELL': '/bin/bash', 09:09:25 INFO - 'SHLVL': '1', 09:09:25 INFO - 'TERM': 'linux', 09:09:25 INFO - 'TMOUT': '86400', 09:09:25 INFO - 'USER': 'cltbld', 09:09:25 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 09:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:25 INFO - '_': '/tools/buildbot/bin/python'} 09:09:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:09:25 INFO - Cleaning up... 09:09:25 INFO - Return code: 0 09:09:25 INFO - Running post-action listener: _resource_record_post_action 09:09:25 INFO - Running post-action listener: _start_resource_monitoring 09:09:25 INFO - Starting resource monitoring. 09:09:25 INFO - [mozharness: 2016-12-05 17:09:25.872427Z] Finished create-virtualenv step (success) 09:09:25 INFO - [mozharness: 2016-12-05 17:09:25.873000Z] Running install step. 09:09:25 INFO - Running pre-action listener: _resource_record_pre_action 09:09:25 INFO - Running main action method: install 09:09:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:09:26 INFO - Reading from file tmpfile_stdout 09:09:26 INFO - Detecting whether we're running mozinstall >=1.0... 09:09:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:09:26 INFO - Reading from file tmpfile_stdout 09:09:26 INFO - Output received: 09:09:26 INFO - Usage: mozinstall [options] installer 09:09:26 INFO - Options: 09:09:26 INFO - -h, --help show this help message and exit 09:09:26 INFO - -d DEST, --destination=DEST 09:09:26 INFO - Directory to install application into. [default: 09:09:26 INFO - "/builds/slave/test"] 09:09:26 INFO - --app=APP Application being installed. [default: firefox] 09:09:26 INFO - mkdir: /builds/slave/test/build/application 09:09:26 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'] 09:09:26 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 09:09:43 INFO - Reading from file tmpfile_stdout 09:09:43 INFO - Output received: 09:09:43 INFO - /builds/slave/test/build/application/firefox/firefox 09:09:43 INFO - Running post-action listener: _resource_record_post_action 09:09:43 INFO - [mozharness: 2016-12-05 17:09:43.757647Z] Finished install step (success) 09:09:43 INFO - [mozharness: 2016-12-05 17:09:43.757889Z] Running run-tests step. 09:09:43 INFO - Running pre-action listener: _resource_record_pre_action 09:09:43 INFO - Running main action method: run_tests 09:09:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:09:43 INFO - Python 2.7.3 09:09:43 INFO - Return code: 0 09:09:43 INFO - grabbing minidump binary from tooltool 09:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:43 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 0x294af20>, '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 0x28b7c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28b7f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:09:43 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:09:43 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:09:43 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:09:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:09:43 INFO - Return code: 0 09:09:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:09:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:09:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:09:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:09:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:09:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:09:43 INFO - Using env: {'DISPLAY': ':0', 09:09:43 INFO - 'HOME': '/home/cltbld', 09:09:43 INFO - 'LANG': 'en_US.UTF-8', 09:09:43 INFO - 'LANGUAGE': 'en_US:en', 09:09:43 INFO - 'LOGNAME': 'cltbld', 09:09:43 INFO - 'MAIL': '/var/mail/cltbld', 09:09:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:09:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:43 INFO - 'MOZ_NO_REMOTE': '1', 09:09:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:09:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:43 INFO - 'NO_EM_RESTART': '1', 09:09:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:43 INFO - 'PWD': '/builds/slave/test', 09:09:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:09:43 INFO - 'SHELL': '/bin/bash', 09:09:43 INFO - 'SHLVL': '1', 09:09:43 INFO - 'TERM': 'linux', 09:09:43 INFO - 'TMOUT': '86400', 09:09:43 INFO - 'USER': 'cltbld', 09:09:43 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503', 09:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:43 INFO - '_': '/tools/buildbot/bin/python'} 09:09:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:09:44 INFO - mozversion application_buildid: 20161205073106 09:09:44 INFO - mozversion application_changeset: 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 09:09:44 INFO - mozversion application_display_name: Nightly 09:09:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:09:44 INFO - mozversion application_name: Firefox 09:09:44 INFO - mozversion application_remotingname: firefox 09:09:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:09:44 INFO - mozversion application_vendor: Mozilla 09:09:44 INFO - mozversion application_version: 53.0a1 09:09:44 INFO - mozversion platform_buildid: 20161205073106 09:09:44 INFO - mozversion platform_changeset: 8103c612b79c2587ea4ca1b0a9f9f82db4b185b8 09:09:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:09:44 INFO - mozversion platform_version: 53.0a1 09:09:44 INFO - using testdate: 1480957784 09:09:44 INFO - actual date: 1480957784 09:09:44 INFO - starting webserver on 'localhost:41210' 09:09:44 INFO - SUITE-START | Running 2 tests 09:09:44 INFO - TEST-START | dromaeo_css 09:09:44 INFO - operating with platform_type : linux_ 09:09:44 INFO - Initialising browser for dromaeo_css test... 09:09:44 INFO - TEST-INFO | started process 3942 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ1fmwE/profile http://localhost:41210/getInfo.html) 09:09:48 INFO - PROCESS | 3942 | __metrics Screen width/height:1600/1200 09:09:48 INFO - PROCESS | 3942 | colorDepth:24 09:09:48 INFO - PROCESS | 3942 | Browser inner width/height: 1024/697 09:09:48 INFO - PROCESS | 3942 | __metrics 09:09:48 INFO - PROCESS | 3942 | 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" 09:09:48 INFO - PROCESS | 3942 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:09:49 INFO - PROCESS | 3942 | console.error: PushService: 09:09:49 INFO - PROCESS | 3942 | stateChangeProcessEnqueue: Error transitioning state 09:09:49 INFO - PROCESS | 3942 | UnknownError 09:09:50 INFO - TEST-INFO | 3942: exit 0 09:09:50 INFO - Browser initialized. 09:09:50 INFO - Running cycle 1/1 for dromaeo_css test... 09:09:50 INFO - TEST-INFO | started process 4026 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ1fmwE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:02 INFO - PROCESS | 4026 | RSS: Main: 171995136 09:10:02 INFO - PROCESS | 4026 | 09:10:53 INFO - PROCESS | 4026 | 1480957853169 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:13:03 INFO - PROCESS | 4026 | Cycle 1(1): loaded http://localhost:41210/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41210/tests/dromaeo/cssquery-ext.html) 09:13:05 INFO - PROCESS | 4026 | RSS: Main: 363229184 09:13:05 INFO - PROCESS | 4026 | 09:16:08 INFO - PROCESS | 4026 | Cycle 1(1): loaded http://localhost:41210/tests/dromaeo/cssquery-ext.html (next: http://localhost:41210/tests/dromaeo/cssquery-jquery.html) 09:16:08 INFO - PROCESS | 4026 | RSS: Main: 273764352 09:16:08 INFO - PROCESS | 4026 | 09:19:11 INFO - PROCESS | 4026 | Cycle 1(1): loaded http://localhost:41210/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41210/tests/dromaeo/cssquery-mootools.html) 09:19:11 INFO - PROCESS | 4026 | RSS: Main: 220364800 09:19:11 INFO - PROCESS | 4026 | 09:22:13 INFO - PROCESS | 4026 | Cycle 1(1): loaded http://localhost:41210/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41210/tests/dromaeo/cssquery-prototype.html) 09:22:14 INFO - PROCESS | 4026 | RSS: Main: 245239808 09:22:14 INFO - PROCESS | 4026 | 09:25:17 INFO - PROCESS | 4026 | Cycle 1(1): loaded http://localhost:41210/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41210/tests/dromaeo/cssquery-yui.html) 09:25:17 INFO - PROCESS | 4026 | RSS: Main: 224284672 09:25:17 INFO - PROCESS | 4026 | 09:28:20 INFO - PROCESS | 4026 | Cycle 1(1): loaded http://localhost:41210/tests/dromaeo/cssquery-yui.html (next: http://localhost:41210/tests/dromaeo/cssquery-dojo.html) 09:28:20 INFO - PROCESS | 4026 | RSS: Main: 303927296 09:28:20 INFO - PROCESS | 4026 | 09:28:20 INFO - PROCESS | 4026 | __start_tp_report 09:28:20 INFO - PROCESS | 4026 | _x_x_mozilla_page_load 09:28:20 INFO - PROCESS | 4026 | _x_x_mozilla_page_load_details 09:28:20 INFO - PROCESS | 4026 | |i|pagename|runs| 09:28:20 INFO - PROCESS | 4026 | |0;dojo.html;3235.3044095519467;3288.4080865444225;3294.456414691576;3317.5023746438033;3320.850561724722;5202.089634313989;5261.210818377245;5304.310439642846;5374.758135883885;5380.7309634518215;5297.814576489824;5310.893782124355;5315.654482458637;5316.840494785153;5323.8136665216725;4809.639277054217;4830.033993201374;4843.66094373394;4851.854444366674;4857.587105096053;4850.07848508784;4917.3607431033915;4923.335349727777;4924.089043526941;4930.80276788928;4869.488703686097;4931.5315045070665;4935.235038569028;4942.382202224722;4953.380827396575;4966.602671786267;4980.078685443204;4991.07665081961;4992.051510213048;5029.497050294941;4777.856664300077;4813.638977076734;4826.517348265181;4894.339264199323;4894.461609222983;5278.9208161877605;5298.390685071227;5298.6555873868465;5300.814471493479;5307.65500242483;5665.971670141623;5724.627899186539;5728.3126024876865;5732.799352022659;5751.137329400582;5532.834014979557;5605.411431799643;5709.143628455723;5764.51001664859;5764.625299355528;5361.705106219157;5366.463353664681;5370.865728356823;5414.91877621836;5426.83719488412;5844.620099693507;5851.093080572518;5852.151438041546;5858.677772722456;5862.14998825168;5390.137577987503;5446.046941785172;5658.773649054074;5665.830025099253;5669.206311116447;5694.800681976111;5730.971345143331;5765.106408506688;5803.941960580424;5813.331466881363;5704.600677952587;5736.42635736423;5744.885102298014;5749.022666146765;5753;5497.642653227527;5757.913631295534;5762.9135562966585;5763;5769.624974376736;4342.131573685276;4379.5182529921685;4381.9342709859375;4397.450318710161;4403.735775853395;3975.383815508601;3982.2234664240195;4002.479677641888;4011.6991225657607;4017.5178978522763;5364.463553644604;5369.731513424314;5370.597205209625;5372.91940620891;5413.566914646819;5588.748506317225;5644.181593668895;5696.943030569641;5698.601097923189;5708.372079071299;5394.64934779246;5422.40353561108;5430.348358197041;5478.616496845262;5486.067368547277;4966.652334336562;4995.0259699359;5012;5013.323201367842;5052.7726252318735;5721.599488035798;5780.508656764222;5896.587238893237;5953.22608060958;5957.910631340534;5636.3799982002765;5667.85830354228;5670.8015219467125;5701.657900525982;5742.885142297047;5847.766089356378;5850.561207909339;5855.795047173328;5858.414158584107;5865.266841644794;5464.562834973113;5602.719864006865;5605.607607467438;5607.691576963306;5607.691576963306;5624.775008999594;5666.405027472056;5733.111367738007;5744.023516002373;5809.477147056785;5574.86062848432;5612.803551875828;5617.045102332531;5618.044932361427;5628.0432326505415;5552.3892371839875;5607.243022191952;5610.0743377343915;5612.635178713371;5616.494515493625;5347.946520534745;5397.541208997279;5400.945990540202;5427.267318911896;5450.754716037709;5548.140038294071;5557.888842223214;5558.082916318762;5559.444055594408;5561.499465048165;5424.457554244544;5427.023135835588;5427.972860135674;5442.374126975448;5443.319585051869 09:28:20 INFO - PROCESS | 4026 | |1;ext.html;532.3321752773729;592.5496622566849;600.8708127752534;617.9227596550431;643.2184895352146;30472.695273047262;39522.40716389253;40313.596864031424;40325.7983710081;43933;41440;41807.163856722924;41871;41905;41929.7903510482;7680.769576912701;7969.800754981114;8385.66457341707;8533.232009119178;8538.70114545991;9916.801663966717;10165.491725413736;10168.13570846479;10450.581976720921;10473.476326183698;10130.240231982594;10151.441670708107;10160.644377446792;10166;10174.389536627788;10066.697999060003;10110.898891011142;10122.089011988883;10126.594936202542;10128.949355253177;9941.701748947471;10021.699349019542;10065.547050382751;10083.798324033552;10093.646722364681;39490.407643885366;39718.20563588741;39840.60159398398;39865.40201896974;39901.40147897784;3477.565304336958;3865.43951127085;3873.8837834865;3915.647591716731;3927.175293188434;29122;29628.259293517833;29939.25151871199;30002.24994375136;30010.84994575035;4079.2453396121514;4100.835966561334;4162.875113746562;4187;4319.784010799479;299820;302336.9999999978;303551;303580;304911;52964.73517632387;53507;53573.196402054;53657;53664.195037074474;54688.17967730487;54934;55026.44973550213;55308.44691553033;55340;14591.708165836624;14942.477013304482;14958.476453324081;15062.096274223582;15062.397504099714;10709.250352475408;11006.339619622848;11344;11643;11663.300201987908;15330.693386132214;15386.153761543223;15407.537773866801;15426.537203883678;15562.688746225012;5460;5726.713664316768;5806.245188125601;5828.142716569821;5880.411958804171;849.4818160921833;913.3150137396952;955.7323949294208;1057.5141925594553;1076.69852441315;29851;30246.243843903638;30347.241318967204;30672.233194170323;30707.692923070485;1017.5115944346607;1030.5517100061497;1121.8317252412203;1125.487903004131;1127.8026345389524;15123.329575758238;16007.199640017952;16152.192390380435;16155.273012714457;16330.265138068815;407751;412046;412521;412766;412777;54349.45650543523;54431;54438.18342724862;54461.72769136129;54630.35641970477;1173.096715529055;1220.4812954494305;1247.594531777194;1290.4773566705499;1314.3625341709264;27722.02972895939;30486;30793.384132317675;30835.691643083283;31392.686073138975;46055.309170361805;46623.30065048959;46799.53200467952;46807;46809.2978605328;328828;331481;331570;331700;331826;24228.63657045181;24748.628770568095;25069.74930250674;26355.604665929644;38051.23897522089;42637.5736242646;42702.359464607434;42869.142617146856;42935.77981651326;43330.350044748724;36310.45534316934;36608.26783464369;37125.903635467155;37320.253594928494;37795.433068503444;35930.64069359273;36154.63845361512;36414.08964775902;36417.81791091028;36575;35627.46558801672;36219.27561448704;36597.268054638225;36890.44664329984;37440.251194975404;31531.21171970719;33573.32853342968;33867.15332116717;33882;34216.486752699224;25369.619455708547;25505;25530.74469255284;26105.477890442464;26138.607920880822 09:28:20 INFO - PROCESS | 4026 | |2;jquery.html;372.7055626055416;398.50983290552637;400.49337587951237;406.6401234907106;423.24873349804096;13182.274974876378;14971.101733895961;17885.642287154264;18217.635647287047;18515.2593896244;17416.21627026787;17839;18583.25666973323;19085.236590536362;19097.427077187673;5522.171674248864;5752.597318187729;5757.424257574251;5759.020966435716;5759.308882934054;4992.77532511036;6299.748010079615;6329.683515824214;6411.326810684881;6425.453836423886;6187.845303867394;6309.179806625132;6375.266844312917;6399.456046236076;6399.968000159993;6346.016367463028;6378.107065010902;6385.936140638581;6387.872242555123;6388.1695379600815;5426.810061647863;5556.944430555724;5593.664380137205;5594.049011668027;5669.376368599451;21942.89028554863;22087.226947056777;22179.66730499044;22221.777802220873;22269.554608907732;2298.494331247123;2332.54515369503;2521.3948652323493;2630.842149471038;2747.409306999005;13735.381907814173;13788.172709637454;14052;14079.155250684991;14612.269386530737;1832.5418645338666;1901.4865986183654;1905.9332923347615;1927.7493925789697;1948.5713143108635;71801.6409917947;75804.24195758082;76162.61918690427;77753.22246777517;78751.21248787496;10330.694177863163;10549.84175237372;10663.466826658636;10856.2400631955;11043.392613406178;15012.849871501145;15018.324175412132;15020.699586008219;15026.248687565578;15040.699186016438;7508.685979953486;7815.3356964657505;7853.489523180975;7953.920460795433;8044.718434854752;7381;7384.852302953911;7579.696812127563;7601.923980760122;7614.334982755991;6488.67556622167;6509.934900651027;6537.738490460329;6595.604263744095;6635.303293154246;1862.8695991280752;1871.139275933086;1884.2180495094383;1924.6246981838688;1943;844.50174397106;978.848278516831;984.3404918704485;987.2102318145598;1041.0214398465419;17703.645927081645;18653.813461865208;19022.239110435705;19045.428637140907;19501.902490487457;968.0619320362665;972.0619602084023;973.0950216298769;1010.6311196413403;1043.2280112716535;9846.852297215548;10288.382697038052;10971.615993440191;10980.780384392267;11042.558297668164;435730;445760;455150;462301;465778;88444.5577772107;88598.55700721455;89447;90671.54664226636;94876.52561737147;1008.4050410257836;1071.8552995345528;1082.5128692088592;1086.32104934416;1093.436880006797;15843.841561584697;17058.914705426392;17440.82559174392;17444.563885902953;17466.253374662432;38280.42579361368;38726.225475489766;40731;40827.7958610205;40985;52742.73628631832;54246;55490.445095550145;56111.719441404166;56540.43459565516;6043.7884674036195;8113.634886430243;8198.098209197104;8248.46384984974;8562.871556926775;10584.523696433525;12445.502179912703;12701.87298127007;12736.426860791102;13165.868341316847;10847;10938.56245750161;10985.176111791488;11052.281601695864;11448.542058317575;10883.782324353628;10969.780604388026;11092.667219983414;11195.832062519232;11247.325160490396;10962.451877405938;11098.667039988813;11266.211365204357;11647.359395233343;11715.297082174757;8217.424780265324;8288.171182881664;8371.455855369382;8501.617427215913;8680.435771675073;9524.904750952403;11162.928857864123;11490.138239631893;11625.24435911663;11719.296842189497 09:28:20 INFO - PROCESS | 4026 | |3;mootools.html;1218.4212499062942;1406.9015168938174;1422.608782584789;1536.6772977674689;1712.777338945937;7291.161516425613;8053.476524025934;8213.7125200618;8224.465409748362;8443.113473085314;8259.174082591739;8321.500709957407;8340.040895297043;8341.749747507569;8342.249197572217;1835.174171622775;1890.2061134323549;2255.469964558323;2290.335802617239;2290.839641225115;2224.4883676754357;2281.6691579721014;2291.1522736587485;2291.301153148289;2296.908123675051;2201.6587428948537;2208.3154222191174;2217.966730499044;2221.433534448713;2223.1329781385193;2230.8549944253655;2243.741969673492;2245.3937436891947;2247.0337754765446;2250.167438047916;2207.0399376271375;2208.8343374246833;2239.585676649825;2244.046280330853;2246.7191601049867;8418.78953026173;8491.532965686885;8497.320214382835;8502.06477287498;8512.829743405096;4943.950560494421;5068.239764035387;5080.13637681595;5085.186370180753;5086.084504789136;3026.530887712408;3159.810411375302;3249.2039450334582;3256.47896336585;3291.8683252670103;955.5652178258875;985.8816941966938;1011.7976404719012;1032.6179313653995;1048.6172547020358;11249.381284029376;11380.829287560693;11385.601503947322;11388.886111138865;11393;4176.519700234512;4474.932876006863;4504.594586487232;4564.6348292136545;4568.885777855514;9553.713388598353;9586.760330991781;9601;9608.13526782593;9609.951950240204;5096.464871188546;5102.795888164506;5103.234514822806;5109.20807274873;5114.948850511447;4594.104149690845;4716.787744551503;4723.551262630044;4728.858134255909;4770.833020844254;3795.3168429682923;3855.1711382052995;3910.159315747128;3918.8628398006504;3932.6264004919485;1301.069735139361;1303.2376060005004;1311.9737605247842;1315.414640484975;1334.5329134802935;791.6358475101403;879.9296056315608;906.954652267384;912.9501073765223;930.0467021303218;5519.448055194529;5584.748686309126;5673.489385955201;5706.80026199081;5729.914051289234;418.96438802701505;423.7478700173392;426.44349124392966;429.23810236829877;432.6473923752132;3899.805009749501;3931.4495970564144;3933.704972127102;4027.335489644176;4123.319652257344;9439;9774.902250977399;9946.35348702332;10034.24743144267;10037.849432258521;9830.852537211947;10004.899951000543;10005.699829005142;10024;10026;7411.2588741125455;7652.234776522303;7664.08031036268;7672.616369181518;7675.961620191975;5375.81184658535;5430.484104010113;5440.156775699773;5495.148252020943;5559.44405559457;8870.822583548164;8955.029893423436;8970.461772293684;9007.954960225157;9057.954710226406;9056.411333263317;9127.954360228156;9204.677836275698;9210.677626283048;9245.537723113952;6665.400113989765;6881.311868813279;6890.44876409876;7057.294270572902;7059.223485416703;7716.151223365538;7949.165337639464;7986.081600616004;8044.959775201087;8186.304164146114;7325.157606875065;7347.926520734938;7388.630568471663;7398.371138453292;7453.850922981402;7242.239564845826;7306.2693730626515;7311.488195826241;7343.742968996059;7357.926420735724;6581.736730530725;6629.204495460575;6664.8000559983275;6678.298778628174;6688.264290928091;4893.09477746618;4995.850124496271;5079;5109.335786347751;5153.484651534816;5070.594352451869;5080.171138390103;5110.48895110486;5125.974370128126;5289.6032797539565 09:28:20 INFO - PROCESS | 4026 | |4;prototype.html;332.7969938337615;334.3013102615534;341.8717980757217;342.28291728828094;344.0469898381485;5127;5263.684178949261;5269.920951185731;5276.548949039011;5286.497782710646;5103.23451482277;5163.974180129094;5201.323827902377;5222.242774797662;5222.973885130569;4150.377443383486;4160.625543701066;4166.187593419292;4170.728902621335;4193.916121677565;3920.6275403836735;3966.3455529837543;4191.266528357541;4194.266003449399;4222.429971953778;4088.2232375848644;4089.7546147231114;4122.855700050499;4131.595257612411;4152.771597562133;4064.3903414487913;4099.754014759124;4100.91798164038;4110.321796903508;4123.8556650517085;3684.6683798458266;3713.0717320669833;3713.4615480755137;3715.721320900916;3725.8137093145233;5088.567471764867;5099.311592934981;5128.179506267855;5137.743112844343;5143.5113664201845;4747.620190384761;4754.786034628415;4759.666823322369;4764.642651801094;4766.475687674353;5370.248165256867;5451.454854514556;5481.643693159932;5538.556915446755;5540.2243685884005;3988.8603898863403;4007.2185923744883;4028.8187031583648;4056.9188616227807;4072.2873497137884;5595.300587426572;5613.764971706218;5619.522340601054;5638.436156384329;5639.830805075854;4114.403411505315;4136.524299705512;4140.585941405896;4142.606452387018;4148.336266197394;3906.90232744178;4072.1675246139357;4102.364133559303;4105.97947010269;4193.1404062167185;4642.210824159901;4663.044075964419;4666.836660716858;4670.696404733681;4679.642903557999;5459.5359394451125;5460.726963651801;5464.644798088111;5483.616146869681;5485.561155107662;1357.497725841445;1382.7303675783328;1383.9930800345933;1396.9022168448314;1409.8942579306593;950.7924935332089;964.5273815830333;975.9024097590326;977.4965892565334;980.7352014955922;5123.205903085028;5140.530142154031;5160.303359046481;5183.948160518422;5194.740262986836;5417.29355253094;5459.153831156177;5482.588805839578;5485;5490.69801160932;4882.681675947545;5095.184770436712;5140.203268493389;5160.41419113902;5226.764795584208;2870.5407134858324;2876;2878.330568270398;2880.1935458071775;2892.739653431201;5591.707399965994;5601.131824567198;5604.355499117572;5615.298087739033;5626.381098079208;5422.322209723785;5512.751926163332;5745.712714364348;5756.079027355603;5764.855878602984;5797.985352563284;5835.35811060783;5839.9124013139835;5871.236739223959;5885.55858310637;5534.91697624544;5664.660120392789;5699.715014249354;5738.651416916938;5742.506948193416;5625.874825034927;5635.88728225425;5638.774449021993;5643.633163844337;5655.88688226225;5834.15404766315;5848.385919478564;5866;5867.471927526543;5878.529717622494;3867.5165604299464;3964.742291751027;4066.1867626475414;4073;4098.467199264077;5510.476504732044;5524.972375138099;5667.4332566743;5675.290588676416;5685.630434021776;5515.338159420895;5543.750531226024;5544.417836127149;5548.472895074961;5568;5287.894242115213;5474.972625136849;5512.228288039598;5520.337559492887;5526.91709624364;5396.622236443412;5432.18517222404;5438.864028399322;5450.863728406822;5466.34403871538;5523.944760552343;5553.694546799803;5574.689947862168;5584.944150558443;5607.43925607436;5305.363356397257;5453.2365468833605;5460.726963651722;5466.699331536804;5471.9179212311055 09:28:20 INFO - PROCESS | 4026 | |5;yui.html;761.5582961882108;785.112822510563;788.1448628238364;793.6706266899237;805.4321703199245;15299.92350038251;16187.676246475094;16212.189390530471;16220.188990550485;16284.51146465605;15677;15709.135997520132;15789.131597762118;15823.920880395583;15908.52274431766;670.1355251725269;779.3336697123963;798.8442253760519;799.8120441696196;799.9720009799659;679.3919442099318;686.3376841348098;686.4782765098536;686.8557602903396;783.8393129408457;828.9875651865227;830.904445988713;833.0711256948489;836.7238811192323;842.7219017724136;827.2430725885807;828.9958550207241;830.1366578758084;830.2112992657;834.5201509132273;514.8764296568834;521.0984995957016;611.966341851198;612.0788213738296;612.7089632424608;12665.620031399072;13362.465501379933;13484.528041518499;13758.518451854237;13772.724545509034;14222.359993800304;14362.784558231635;14515.85484145166;14521.636959076002;14567.344469498898;11797.88202117985;11802.94098529502;11836.230645008132;11951.282923024646;12524.185927914748;3572.821358932043;3693.907652308687;3732.085639018431;3773.471713960048;3853.0367408147963;79058.60470697668;80972.99999999942;82915.5854220725;84320;84506.57746711228;9888.258380621483;10421.5831366746;10494.842577361345;10555.155587552978;10559;20993.58012839765;21022.579548408947;21038.47403814917;21080.78919210819;21137.78862211358;7532.435067369969;7629.0845098588525;7671.462997590115;7735.3424958878;7902.68389264423;4011.338129208648;4171.249175148502;4230.407742915994;4264.552222016706;4292.506361768436;3440.2775417162675;3506.5967413747235;3540.929181416409;3544.8050357230086;3581.0510214793103;577.3706659740902;589.4665327878191;589.5725598940734;598.1267349669445;605.3764622438895;610.7373829253374;693.5873155472486;710.346481237263;724.3661795928563;735.7057177129084;8819.29445644347;8988.955055224682;9187.310951678652;9238.168564829064;9417.90582094184;561.8202175303863;637.8437282865727;638.084348959245;659.8086554899121;705.6365971524668;4496.527864574238;4605.8848528786375;4613.5847773700525;4666.603338716247;4727.172744769651;69151.30848691448;77909.61045194737;79983;80555.59722201352;81209;68796.96804547936;72867;73713;73807;73897;64216.35783642104;65218;65910.34089659138;66472;68163;8766.649334026568;8811.60347784364;8853.468791872509;8863.290936725176;9131.680391186277;44799.32801008053;45249.03161314507;46783.53216467928;46877.53122468868;47356;57132.71433642805;59698.70150649219;60351.698241508515;60440;61355.386446134966;10792.406417646791;11375.146863985068;11463.369514676438;11473.942630286796;11552.249103808226;12417.029636170442;12726.554570589984;12816.743665126833;12948;13184.208947463183;11691.415429228675;12129.878701212874;12134.635960921187;12190.695232619255;12508.124431289722;11374.886251137714;11435.085193184359;11479.885201148216;11742.17804753659;12274;11577.6526704199;12539.623811285675;13001.349932503526;13007.414666339848;13033;9752.804943901225;9767.365121267096;10204.693859184237;10289.897101029193;10329.9483502585;8982.371234013557;9192.49441280736;10009.749756256151;10031.749206269902;10511.474426278808 09:28:20 INFO - PROCESS | 4026 | __end_tp_report 09:28:20 INFO - PROCESS | 4026 | __start_cc_report 09:28:20 INFO - PROCESS | 4026 | _x_x_mozilla_cycle_collect,1709 09:28:20 INFO - PROCESS | 4026 | __end_cc_report 09:28:20 INFO - PROCESS | 4026 | __startTimestamp1480958900850__endTimestamp 09:28:20 INFO - PROCESS | 4026 | 09:28:20 INFO - PROCESS | 4026 | ------- Summary: start ------- 09:28:20 INFO - PROCESS | 4026 | Number of tests: 6 09:28:20 INFO - PROCESS | 4026 | 09:28:20 INFO - PROCESS | 4026 | [#0] dojo.html Cycles:165 Average:5312.14 Median:5443.32 stddev:559.83 (10.3%) stddev-sans-first:537.31 09:28:20 INFO - PROCESS | 4026 | Values: 3235.3 3288.4 3294.5 3317.5 3320.9 5202.1 5261.2 5304.3 5374.8 5380.7 5297.8 5310.9 5315.7 5316.8 5323.8 4809.6 4830.0 4843.7 4851.9 4857.6 4850.1 4917.4 4923.3 4924.1 4930.8 4869.5 4931.5 4935.2 4942.4 4953.4 4966.6 4980.1 4991.1 4992.1 5029.5 4777.9 4813.6 4826.5 4894.3 4894.5 5278.9 5298.4 5298.7 5300.8 5307.7 5666.0 5724.6 5728.3 5732.8 5751.1 5532.8 5605.4 5709.1 5764.5 5764.6 5361.7 5366.5 5370.9 5414.9 5426.8 5844.6 5851.1 5852.2 5858.7 5862.1 5390.1 5446.0 5658.8 5665.8 5669.2 5694.8 5731.0 5765.1 5803.9 5813.3 5704.6 5736.4 5744.9 5749.0 5753.0 5497.6 5757.9 5762.9 5763.0 5769.6 4342.1 4379.5 4381.9 4397.5 4403.7 3975.4 3982.2 4002.5 4011.7 4017.5 5364.5 5369.7 5370.6 5372.9 5413.6 5588.7 5644.2 5696.9 5698.6 5708.4 5394.6 5422.4 5430.3 5478.6 5486.1 4966.7 4995.0 5012.0 5013.3 5052.8 5721.6 5780.5 5896.6 5953.2 5957.9 5636.4 5667.9 5670.8 5701.7 5742.9 5847.8 5850.6 5855.8 5858.4 5865.3 5464.6 5602.7 5605.6 5607.7 5607.7 5624.8 5666.4 5733.1 5744.0 5809.5 5574.9 5612.8 5617.0 5618.0 5628.0 5552.4 5607.2 5610.1 5612.6 5616.5 5347.9 5397.5 5400.9 5427.3 5450.8 5548.1 5557.9 5558.1 5559.4 5561.5 5424.5 5427.0 5428.0 5442.4 5443.3 09:28:20 INFO - PROCESS | 4026 | 09:28:20 INFO - PROCESS | 4026 | [#1] ext.html Cycles:180 Average:50819.52 Median:19552.52 stddev:92482.44 (473.0%) stddev-sans-first:92664.80 09:28:20 INFO - PROCESS | 4026 | Values: 532.3 592.5 600.9 617.9 643.2 30472.7 39522.4 40313.6 40325.8 43933.0 41440.0 41807.2 41871.0 41905.0 41929.8 7680.8 7969.8 8385.7 8533.2 8538.7 9916.8 10165.5 10168.1 10450.6 10473.5 10130.2 10151.4 10160.6 10166.0 10174.4 10066.7 10110.9 10122.1 10126.6 10128.9 9941.7 10021.7 10065.5 10083.8 10093.6 39490.4 39718.2 39840.6 39865.4 39901.4 3477.6 3865.4 3873.9 3915.6 3927.2 29122.0 29628.3 29939.3 30002.2 30010.8 4079.2 4100.8 4162.9 4187.0 4319.8 299820.0 302337.0 303551.0 303580.0 304911.0 52964.7 53507.0 53573.2 53657.0 53664.2 54688.2 54934.0 55026.4 55308.4 55340.0 14591.7 14942.5 14958.5 15062.1 15062.4 10709.3 11006.3 11344.0 11643.0 11663.3 15330.7 15386.2 15407.5 15426.5 15562.7 5460.0 5726.7 5806.2 5828.1 5880.4 849.5 913.3 955.7 1057.5 1076.7 29851.0 30246.2 30347.2 30672.2 30707.7 1017.5 1030.6 1121.8 1125.5 1127.8 15123.3 16007.2 16152.2 16155.3 16330.3 407751.0 412046.0 412521.0 412766.0 412777.0 54349.5 54431.0 54438.2 54461.7 54630.4 1173.1 1220.5 1247.6 1290.5 1314.4 27722.0 30486.0 30793.4 30835.7 31392.7 46055.3 46623.3 46799.5 46807.0 46809.3 328828.0 331481.0 331570.0 331700.0 331826.0 24228.6 24748.6 25069.7 26355.6 38051.2 42637.6 42702.4 42869.1 42935.8 43330.4 36310.5 36608.3 37125.9 37320.3 37795.4 35930.6 36154.6 36414.1 36417.8 36575.0 35627.5 36219.3 36597.3 36890.4 37440.3 31531.2 33573.3 33867.2 33882.0 34216.5 25369.6 25505.0 25530.7 26105.5 26138.6 09:28:20 INFO - PROCESS | 4026 | 09:28:20 INFO - PROCESS | 4026 | [#2] jquery.html Cycles:180 Average:27570.41 Median:1560.06 stddev:74753.47 (4791.7%) stddev-sans-first:74935.28 09:28:20 INFO - PROCESS | 4026 | Values: 372.7 398.5 400.5 406.6 423.2 13182.3 14971.1 17885.6 18217.6 18515.3 17416.2 17839.0 18583.3 19085.2 19097.4 5522.2 5752.6 5757.4 5759.0 5759.3 4992.8 6299.7 6329.7 6411.3 6425.5 6187.8 6309.2 6375.3 6399.5 6400.0 6346.0 6378.1 6385.9 6387.9 6388.2 5426.8 5556.9 5593.7 5594.0 5669.4 21942.9 22087.2 22179.7 22221.8 22269.6 2298.5 2332.5 2521.4 2630.8 2747.4 13735.4 13788.2 14052.0 14079.2 14612.3 1832.5 1901.5 1905.9 1927.7 1948.6 71801.6 75804.2 76162.6 77753.2 78751.2 10330.7 10549.8 10663.5 10856.2 11043.4 15012.8 15018.3 15020.7 15026.2 15040.7 7508.7 7815.3 7853.5 7953.9 8044.7 7381.0 7384.9 7579.7 7601.9 7614.3 6488.7 6509.9 6537.7 6595.6 6635.3 1862.9 1871.1 1884.2 1924.6 1943.0 844.5 978.8 984.3 987.2 1041.0 17703.6 18653.8 19022.2 19045.4 19501.9 968.1 972.1 973.1 1010.6 1043.2 9846.9 10288.4 10971.6 10980.8 11042.6 435730.0 445760.0 455150.0 462301.0 465778.0 88444.6 88598.6 89447.0 90671.5 94876.5 1008.4 1071.9 1082.5 1086.3 1093.4 15843.8 17058.9 17440.8 17444.6 17466.3 38280.4 38726.2 40731.0 40827.8 40985.0 52742.7 54246.0 55490.4 56111.7 56540.4 6043.8 8113.6 8198.1 8248.5 8562.9 10584.5 12445.5 12701.9 12736.4 13165.9 10847.0 10938.6 10985.2 11052.3 11448.5 10883.8 10969.8 11092.7 11195.8 11247.3 10962.5 11098.7 11266.2 11647.4 11715.3 8217.4 8288.2 8371.5 8501.6 8680.4 9524.9 11162.9 11490.1 11625.2 11719.3 09:28:20 INFO - PROCESS | 4026 | 09:28:20 INFO - PROCESS | 4026 | [#3] mootools.html Cycles:180 Average:5429.19 Median:5103.02 stddev:3024.99 (59.3%) stddev-sans-first:3016.92 09:28:20 INFO - PROCESS | 4026 | Values: 1218.4 1406.9 1422.6 1536.7 1712.8 7291.2 8053.5 8213.7 8224.5 8443.1 8259.2 8321.5 8340.0 8341.7 8342.2 1835.2 1890.2 2255.5 2290.3 2290.8 2224.5 2281.7 2291.2 2291.3 2296.9 2201.7 2208.3 2218.0 2221.4 2223.1 2230.9 2243.7 2245.4 2247.0 2250.2 2207.0 2208.8 2239.6 2244.0 2246.7 8418.8 8491.5 8497.3 8502.1 8512.8 4944.0 5068.2 5080.1 5085.2 5086.1 3026.5 3159.8 3249.2 3256.5 3291.9 955.6 985.9 1011.8 1032.6 1048.6 11249.4 11380.8 11385.6 11388.9 11393.0 4176.5 4474.9 4504.6 4564.6 4568.9 9553.7 9586.8 9601.0 9608.1 9610.0 5096.5 5102.8 5103.2 5109.2 5114.9 4594.1 4716.8 4723.6 4728.9 4770.8 3795.3 3855.2 3910.2 3918.9 3932.6 1301.1 1303.2 1312.0 1315.4 1334.5 791.6 879.9 907.0 913.0 930.0 5519.4 5584.7 5673.5 5706.8 5729.9 419.0 423.7 426.4 429.2 432.6 3899.8 3931.4 3933.7 4027.3 4123.3 9439.0 9774.9 9946.4 10034.2 10037.8 9830.9 10004.9 10005.7 10024.0 10026.0 7411.3 7652.2 7664.1 7672.6 7676.0 5375.8 5430.5 5440.2 5495.1 5559.4 8870.8 8955.0 8970.5 9008.0 9058.0 9056.4 9128.0 9204.7 9210.7 9245.5 6665.4 6881.3 6890.4 7057.3 7059.2 7716.2 7949.2 7986.1 8045.0 8186.3 7325.2 7347.9 7388.6 7398.4 7453.9 7242.2 7306.3 7311.5 7343.7 7357.9 6581.7 6629.2 6664.8 6678.3 6688.3 4893.1 4995.9 5079.0 5109.3 5153.5 5070.6 5080.2 5110.5 5126.0 5289.6 09:28:20 INFO - PROCESS | 4026 | 09:28:20 INFO - PROCESS | 4026 | [#4] prototype.html Cycles:180 Average:4628.39 Median:5245.22 stddev:1347.26 (25.7%) stddev-sans-first:1311.90 09:28:20 INFO - PROCESS | 4026 | Values: 332.8 334.3 341.9 342.3 344.0 5127.0 5263.7 5269.9 5276.5 5286.5 5103.2 5164.0 5201.3 5222.2 5223.0 4150.4 4160.6 4166.2 4170.7 4193.9 3920.6 3966.3 4191.3 4194.3 4222.4 4088.2 4089.8 4122.9 4131.6 4152.8 4064.4 4099.8 4100.9 4110.3 4123.9 3684.7 3713.1 3713.5 3715.7 3725.8 5088.6 5099.3 5128.2 5137.7 5143.5 4747.6 4754.8 4759.7 4764.6 4766.5 5370.2 5451.5 5481.6 5538.6 5540.2 3988.9 4007.2 4028.8 4056.9 4072.3 5595.3 5613.8 5619.5 5638.4 5639.8 4114.4 4136.5 4140.6 4142.6 4148.3 3906.9 4072.2 4102.4 4106.0 4193.1 4642.2 4663.0 4666.8 4670.7 4679.6 5459.5 5460.7 5464.6 5483.6 5485.6 1357.5 1382.7 1384.0 1396.9 1409.9 950.8 964.5 975.9 977.5 980.7 5123.2 5140.5 5160.3 5183.9 5194.7 5417.3 5459.2 5482.6 5485.0 5490.7 4882.7 5095.2 5140.2 5160.4 5226.8 2870.5 2876.0 2878.3 2880.2 2892.7 5591.7 5601.1 5604.4 5615.3 5626.4 5422.3 5512.8 5745.7 5756.1 5764.9 5798.0 5835.4 5839.9 5871.2 5885.6 5534.9 5664.7 5699.7 5738.7 5742.5 5625.9 5635.9 5638.8 5643.6 5655.9 5834.2 5848.4 5866.0 5867.5 5878.5 3867.5 3964.7 4066.2 4073.0 4098.5 5510.5 5525.0 5667.4 5675.3 5685.6 5515.3 5543.8 5544.4 5548.5 5568.0 5287.9 5475.0 5512.2 5520.3 5526.9 5396.6 5432.2 5438.9 5450.9 5466.3 5523.9 5553.7 5574.7 5584.9 5607.4 5305.4 5453.2 5460.7 5466.7 5471.9 09:28:20 INFO - PROCESS | 4026 | 09:28:20 INFO - PROCESS | 4026 | [#5] yui.html Cycles:180 Average:17678.26 Median:517.99 stddev:23496.67 (4536.1%) stddev-sans-first:23528.25 09:28:20 INFO - PROCESS | 4026 | Values: 761.6 785.1 788.1 793.7 805.4 15299.9 16187.7 16212.2 16220.2 16284.5 15677.0 15709.1 15789.1 15823.9 15908.5 670.1 779.3 798.8 799.8 800.0 679.4 686.3 686.5 686.9 783.8 829.0 830.9 833.1 836.7 842.7 827.2 829.0 830.1 830.2 834.5 514.9 521.1 612.0 612.1 612.7 12665.6 13362.5 13484.5 13758.5 13772.7 14222.4 14362.8 14515.9 14521.6 14567.3 11797.9 11802.9 11836.2 11951.3 12524.2 3572.8 3693.9 3732.1 3773.5 3853.0 79058.6 80973.0 82915.6 84320.0 84506.6 9888.3 10421.6 10494.8 10555.2 10559.0 20993.6 21022.6 21038.5 21080.8 21137.8 7532.4 7629.1 7671.5 7735.3 7902.7 4011.3 4171.2 4230.4 4264.6 4292.5 3440.3 3506.6 3540.9 3544.8 3581.1 577.4 589.5 589.6 598.1 605.4 610.7 693.6 710.3 724.4 735.7 8819.3 8989.0 9187.3 9238.2 9417.9 561.8 637.8 638.1 659.8 705.6 4496.5 4605.9 4613.6 4666.6 4727.2 69151.3 77909.6 79983.0 80555.6 81209.0 68797.0 72867.0 73713.0 73807.0 73897.0 64216.4 65218.0 65910.3 66472.0 68163.0 8766.6 8811.6 8853.5 8863.3 9131.7 44799.3 45249.0 46783.5 46877.5 47356.0 57132.7 59698.7 60351.7 60440.0 61355.4 10792.4 11375.1 11463.4 11473.9 11552.2 12417.0 12726.6 12816.7 12948.0 13184.2 11691.4 12129.9 12134.6 12190.7 12508.1 11374.9 11435.1 11479.9 11742.2 12274.0 11577.7 12539.6 13001.3 13007.4 13033.0 9752.8 9767.4 10204.7 10289.9 10329.9 8982.4 9192.5 10009.7 10031.7 10511.5 09:28:20 INFO - PROCESS | 4026 | -------- Summary: end -------- 09:28:20 INFO - PROCESS | 4026 | 09:28:21 INFO - TEST-INFO | 4026: exit 0 09:28:21 INFO - TEST-OK | dromaeo_css | took 1117710ms 09:28:21 INFO - TEST-START | kraken 09:28:21 INFO - operating with platform_type : linux_ 09:28:21 INFO - Initialising browser for kraken test... 09:28:21 INFO - TEST-INFO | started process 4329 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfbNfWX/profile http://localhost:41210/getInfo.html) 09:28:25 INFO - PROCESS | 4329 | __metrics Screen width/height:1600/1200 09:28:25 INFO - PROCESS | 4329 | colorDepth:24 09:28:25 INFO - PROCESS | 4329 | Browser inner width/height: 1024/697 09:28:25 INFO - PROCESS | 4329 | __metrics 09:28:26 INFO - PROCESS | 4329 | 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" 09:28:26 INFO - PROCESS | 4329 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:28:27 INFO - PROCESS | 4329 | console.error: PushService: 09:28:27 INFO - PROCESS | 4329 | stateChangeProcessEnqueue: Error transitioning state 09:28:27 INFO - PROCESS | 4329 | UnknownError 09:28:27 INFO - TEST-INFO | 4329: exit 0 09:28:27 INFO - Browser initialized. 09:28:27 INFO - Running cycle 1/1 for kraken test... 09:28:27 INFO - TEST-INFO | started process 4414 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfbNfWX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:40 INFO - PROCESS | 4414 | RSS: Main: 172105728 09:28:40 INFO - PROCESS | 4414 | 09:28:42 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41210/tests/kraken/driver.html?testName=audio-beat-detection) 09:28:43 INFO - PROCESS | 4414 | RSS: Main: 238854144 09:28:43 INFO - PROCESS | 4414 | 09:28:46 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41210/tests/kraken/driver.html?testName=audio-dft) 09:28:46 INFO - PROCESS | 4414 | RSS: Main: 216727552 09:28:46 INFO - PROCESS | 4414 | 09:28:49 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41210/tests/kraken/driver.html?testName=audio-fft) 09:28:50 INFO - PROCESS | 4414 | RSS: Main: 298549248 09:28:50 INFO - PROCESS | 4414 | 09:28:52 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41210/tests/kraken/driver.html?testName=audio-oscillator) 09:28:53 INFO - PROCESS | 4414 | RSS: Main: 210223104 09:28:53 INFO - PROCESS | 4414 | 09:28:55 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41210/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:28:55 INFO - PROCESS | 4414 | RSS: Main: 213336064 09:28:55 INFO - PROCESS | 4414 | 09:28:58 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41210/tests/kraken/driver.html?testName=imaging-darkroom) 09:28:59 INFO - PROCESS | 4414 | RSS: Main: 252940288 09:28:59 INFO - PROCESS | 4414 | 09:29:02 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41210/tests/kraken/driver.html?testName=imaging-desaturate) 09:29:03 INFO - PROCESS | 4414 | RSS: Main: 253267968 09:29:03 INFO - PROCESS | 4414 | 09:29:06 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41210/tests/kraken/driver.html?testName=json-parse-financial) 09:29:06 INFO - PROCESS | 4414 | RSS: Main: 251346944 09:29:06 INFO - PROCESS | 4414 | 09:29:08 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41210/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:29:08 INFO - PROCESS | 4414 | RSS: Main: 213905408 09:29:08 INFO - PROCESS | 4414 | 09:29:10 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41210/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:29:11 INFO - PROCESS | 4414 | RSS: Main: 220958720 09:29:11 INFO - PROCESS | 4414 | 09:29:13 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41210/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:29:13 INFO - PROCESS | 4414 | RSS: Main: 229228544 09:29:13 INFO - PROCESS | 4414 | 09:29:16 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41210/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:29:17 INFO - PROCESS | 4414 | RSS: Main: 238911488 09:29:17 INFO - PROCESS | 4414 | 09:29:20 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41210/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:29:20 INFO - PROCESS | 4414 | RSS: Main: 235003904 09:29:20 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | Cycle 1(1): loaded http://localhost:41210/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41210/tests/kraken/driver.html?testName=ai-astar) 09:29:22 INFO - PROCESS | 4414 | RSS: Main: 285900800 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | __start_tp_report 09:29:22 INFO - PROCESS | 4414 | _x_x_mozilla_page_load 09:29:22 INFO - PROCESS | 4414 | _x_x_mozilla_page_load_details 09:29:22 INFO - PROCESS | 4414 | |i|pagename|runs| 09:29:22 INFO - PROCESS | 4414 | |0;ai-astar;109;111;111;111;111;111;111;111;110;111 09:29:22 INFO - PROCESS | 4414 | |1;audio-beat-detection;155;128;127;166;125;126;122;122;152;124 09:29:22 INFO - PROCESS | 4414 | |2;audio-dft;160;160;163;160;161;162;160;160;160;160 09:29:22 INFO - PROCESS | 4414 | |3;audio-fft;74;75;75;107;76;76;76;76;91;76 09:29:22 INFO - PROCESS | 4414 | |4;audio-oscillator;84;82;111;82;82;82;97;84;82;110 09:29:22 INFO - PROCESS | 4414 | |5;imaging-gaussian-blur;118;118;117;117;118;117;117;117;117;117 09:29:22 INFO - PROCESS | 4414 | |6;imaging-darkroom;146;147;146;146;146;145;145;146;145;147 09:29:22 INFO - PROCESS | 4414 | |7;imaging-desaturate;96;95;96;95;95;95;95;96;96;96 09:29:22 INFO - PROCESS | 4414 | |8;json-parse-financial;55;52;51;54;53;55;52;53;53;53 09:29:22 INFO - PROCESS | 4414 | |9;json-stringify-tinderbox;63;64;63;64;65;63;64;64;63;65 09:29:22 INFO - PROCESS | 4414 | |10;stanford-crypto-aes;81;80;80;83;81;81;83;87;81;81 09:29:22 INFO - PROCESS | 4414 | |11;stanford-crypto-ccm;146;146;154;130;136;143;144;140;176;161 09:29:22 INFO - PROCESS | 4414 | |12;stanford-crypto-pbkdf2;169;169;168;168;169;169;168;167;170;169 09:29:22 INFO - PROCESS | 4414 | |13;stanford-crypto-sha256-iterative;67;66;67;67;66;67;66;67;66;65 09:29:22 INFO - PROCESS | 4414 | __end_tp_report 09:29:22 INFO - PROCESS | 4414 | __start_cc_report 09:29:22 INFO - PROCESS | 4414 | _x_x_mozilla_cycle_collect,1686 09:29:22 INFO - PROCESS | 4414 | __end_cc_report 09:29:22 INFO - PROCESS | 4414 | __startTimestamp1480958962700__endTimestamp 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | ------- Summary: start ------- 09:29:22 INFO - PROCESS | 4414 | Number of tests: 14 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:0.67 (0.6%) stddev-sans-first:0.33 09:29:22 INFO - PROCESS | 4414 | Values: 109.0 111.0 111.0 111.0 111.0 111.0 111.0 111.0 110.0 111.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#1] audio-beat-detection Cycles:10 Average:134.70 Median:127.50 stddev:16.34 (12.8%) stddev-sans-first:15.59 09:29:22 INFO - PROCESS | 4414 | Values: 155.0 128.0 127.0 166.0 125.0 126.0 122.0 122.0 152.0 124.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 09:29:22 INFO - PROCESS | 4414 | Values: 160.0 160.0 163.0 160.0 161.0 162.0 160.0 160.0 160.0 160.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#3] audio-fft Cycles:10 Average:80.20 Median:76.00 stddev:10.62 (14.0%) stddev-sans-first:11.03 09:29:22 INFO - PROCESS | 4414 | Values: 74.0 75.0 75.0 107.0 76.0 76.0 76.0 76.0 91.0 76.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:82.00 stddev:11.93 (14.5%) stddev-sans-first:12.48 09:29:22 INFO - PROCESS | 4414 | Values: 84.0 82.0 111.0 82.0 82.0 82.0 97.0 84.0 82.0 110.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 09:29:22 INFO - PROCESS | 4414 | Values: 118.0 118.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 09:29:22 INFO - PROCESS | 4414 | Values: 146.0 147.0 146.0 146.0 146.0 145.0 145.0 146.0 145.0 147.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:29:22 INFO - PROCESS | 4414 | Values: 96.0 95.0 96.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:53.00 stddev:1.29 (2.4%) stddev-sans-first:1.17 09:29:22 INFO - PROCESS | 4414 | Values: 55.0 52.0 51.0 54.0 53.0 55.0 52.0 53.0 53.0 53.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:64.00 stddev:0.79 (1.2%) stddev-sans-first:0.78 09:29:22 INFO - PROCESS | 4414 | Values: 63.0 64.0 63.0 64.0 65.0 63.0 64.0 64.0 63.0 65.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:81.00 stddev:2.10 (2.6%) stddev-sans-first:2.20 09:29:22 INFO - PROCESS | 4414 | Values: 81.0 80.0 80.0 83.0 81.0 81.0 83.0 87.0 81.0 81.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:146.00 stddev:13.20 (9.0%) stddev-sans-first:13.99 09:29:22 INFO - PROCESS | 4414 | Values: 146.0 146.0 154.0 130.0 136.0 143.0 144.0 140.0 176.0 161.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:169.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 09:29:22 INFO - PROCESS | 4414 | Values: 169.0 169.0 168.0 168.0 169.0 169.0 168.0 167.0 170.0 169.0 09:29:22 INFO - PROCESS | 4414 | 09:29:22 INFO - PROCESS | 4414 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 09:29:22 INFO - PROCESS | 4414 | Values: 67.0 66.0 67.0 67.0 66.0 67.0 66.0 67.0 66.0 65.0 09:29:22 INFO - PROCESS | 4414 | -------- Summary: end -------- 09:29:22 INFO - PROCESS | 4414 | 09:29:23 INFO - TEST-INFO | 4414: exit 0 09:29:24 INFO - TEST-OK | kraken | took 62264ms 09:29:24 INFO - SUITE-END | took 1179s 09:29:24 INFO - Completed test suite (00:19:40) 09:29:24 INFO - javascript benchmark 09:29:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3235.3044095519467, 3288.4080865444225, 3294.456414691576, 3317.5023746438033, 3320.850561724722, 5202.089634313989, 5261.210818377245, 5304.310439642846, 5374.758135883885, 5380.7309634518215, 5297.814576489824, 5310.893782124355, 5315.654482458637, 5316.840494785153, 5323.8136665216725, 4809.639277054217, 4830.033993201374, 4843.66094373394, 4851.854444366674, 4857.587105096053, 4850.07848508784, 4917.3607431033915, 4923.335349727777, 4924.089043526941, 4930.80276788928, 4869.488703686097, 4931.5315045070665, 4935.235038569028, 4942.382202224722, 4953.380827396575, 4966.602671786267, 4980.078685443204, 4991.07665081961, 4992.051510213048, 5029.497050294941, 4777.856664300077, 4813.638977076734, 4826.517348265181, 4894.339264199323, 4894.461609222983, 5278.9208161877605, 5298.390685071227, 5298.6555873868465, 5300.814471493479, 5307.65500242483, 5665.971670141623, 5724.627899186539, 5728.3126024876865, 5732.799352022659, 5751.137329400582, 5532.834014979557, 5605.411431799643, 5709.143628455723, 5764.51001664859, 5764.625299355528, 5361.705106219157, 5366.463353664681, 5370.865728356823, 5414.91877621836, 5426.83719488412, 5844.620099693507, 5851.093080572518, 5852.151438041546, 5858.677772722456, 5862.14998825168, 5390.137577987503, 5446.046941785172, 5658.773649054074, 5665.830025099253, 5669.206311116447, 5694.800681976111, 5730.971345143331, 5765.106408506688, 5803.941960580424, 5813.331466881363, 5704.600677952587, 5736.42635736423, 5744.885102298014, 5749.022666146765, 5753.0, 5497.642653227527, 5757.913631295534, 5762.9135562966585, 5763.0, 5769.624974376736, 4342.131573685276, 4379.5182529921685, 4381.9342709859375, 4397.450318710161, 4403.735775853395, 3975.383815508601, 3982.2234664240195, 4002.479677641888, 4011.6991225657607, 4017.5178978522763, 5364.463553644604, 5369.731513424314, 5370.597205209625, 5372.91940620891, 5413.566914646819, 5588.748506317225, 5644.181593668895, 5696.943030569641, 5698.601097923189, 5708.372079071299, 5394.64934779246, 5422.40353561108, 5430.348358197041, 5478.616496845262, 5486.067368547277, 4966.652334336562, 4995.0259699359, 5012.0, 5013.323201367842, 5052.7726252318735, 5721.599488035798, 5780.508656764222, 5896.587238893237, 5953.22608060958, 5957.910631340534, 5636.3799982002765, 5667.85830354228, 5670.8015219467125, 5701.657900525982, 5742.885142297047, 5847.766089356378, 5850.561207909339, 5855.795047173328, 5858.414158584107, 5865.266841644794, 5464.562834973113, 5602.719864006865, 5605.607607467438, 5607.691576963306, 5607.691576963306, 5624.775008999594, 5666.405027472056, 5733.111367738007, 5744.023516002373, 5809.477147056785, 5574.86062848432, 5612.803551875828, 5617.045102332531, 5618.044932361427, 5628.0432326505415, 5552.3892371839875, 5607.243022191952, 5610.0743377343915, 5612.635178713371, 5616.494515493625, 5347.946520534745, 5397.541208997279, 5400.945990540202, 5427.267318911896, 5450.754716037709, 5548.140038294071, 5557.888842223214, 5558.082916318762, 5559.444055594408, 5561.499465048165, 5424.457554244544, 5427.023135835588, 5427.972860135674, 5442.374126975448, 5443.319585051869], "value": 5277.861789591863, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.3321752773729, 592.5496622566849, 600.8708127752534, 617.9227596550431, 643.2184895352146, 30472.695273047262, 39522.40716389253, 40313.596864031424, 40325.7983710081, 43933.0, 41440.0, 41807.163856722924, 41871.0, 41905.0, 41929.7903510482, 7680.769576912701, 7969.800754981114, 8385.66457341707, 8533.232009119178, 8538.70114545991, 9916.801663966717, 10165.491725413736, 10168.13570846479, 10450.581976720921, 10473.476326183698, 10130.240231982594, 10151.441670708107, 10160.644377446792, 10166.0, 10174.389536627788, 10066.697999060003, 10110.898891011142, 10122.089011988883, 10126.594936202542, 10128.949355253177, 9941.701748947471, 10021.699349019542, 10065.547050382751, 10083.798324033552, 10093.646722364681, 39490.407643885366, 39718.20563588741, 39840.60159398398, 39865.40201896974, 39901.40147897784, 3477.565304336958, 3865.43951127085, 3873.8837834865, 3915.647591716731, 3927.175293188434, 29122.0, 29628.259293517833, 29939.25151871199, 30002.24994375136, 30010.84994575035, 4079.2453396121514, 4100.835966561334, 4162.875113746562, 4187.0, 4319.784010799479, 299820.0, 302336.9999999978, 303551.0, 303580.0, 304911.0, 52964.73517632387, 53507.0, 53573.196402054, 53657.0, 53664.195037074474, 54688.17967730487, 54934.0, 55026.44973550213, 55308.44691553033, 55340.0, 14591.708165836624, 14942.477013304482, 14958.476453324081, 15062.096274223582, 15062.397504099714, 10709.250352475408, 11006.339619622848, 11344.0, 11643.0, 11663.300201987908, 15330.693386132214, 15386.153761543223, 15407.537773866801, 15426.537203883678, 15562.688746225012, 5460.0, 5726.713664316768, 5806.245188125601, 5828.142716569821, 5880.411958804171, 849.4818160921833, 913.3150137396952, 955.7323949294208, 1057.5141925594553, 1076.69852441315, 29851.0, 30246.243843903638, 30347.241318967204, 30672.233194170323, 30707.692923070485, 1017.5115944346607, 1030.5517100061497, 1121.8317252412203, 1125.487903004131, 1127.8026345389524, 15123.329575758238, 16007.199640017952, 16152.192390380435, 16155.273012714457, 16330.265138068815, 407751.0, 412046.0, 412521.0, 412766.0, 412777.0, 54349.45650543523, 54431.0, 54438.18342724862, 54461.72769136129, 54630.35641970477, 1173.096715529055, 1220.4812954494305, 1247.594531777194, 1290.4773566705499, 1314.3625341709264, 27722.02972895939, 30486.0, 30793.384132317675, 30835.691643083283, 31392.686073138975, 46055.309170361805, 46623.30065048959, 46799.53200467952, 46807.0, 46809.2978605328, 328828.0, 331481.0, 331570.0, 331700.0, 331826.0, 24228.63657045181, 24748.628770568095, 25069.74930250674, 26355.604665929644, 38051.23897522089, 42637.5736242646, 42702.359464607434, 42869.142617146856, 42935.77981651326, 43330.350044748724, 36310.45534316934, 36608.26783464369, 37125.903635467155, 37320.253594928494, 37795.433068503444, 35930.64069359273, 36154.63845361512, 36414.08964775902, 36417.81791091028, 36575.0, 35627.46558801672, 36219.27561448704, 36597.268054638225, 36890.44664329984, 37440.251194975404, 31531.21171970719, 33573.32853342968, 33867.15332116717, 33882.0, 34216.486752699224, 25369.619455708547, 25505.0, 25530.74469255284, 26105.477890442464, 26138.607920880822], "value": 18775.65694313022, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.7055626055416, 398.50983290552637, 400.49337587951237, 406.6401234907106, 423.24873349804096, 13182.274974876378, 14971.101733895961, 17885.642287154264, 18217.635647287047, 18515.2593896244, 17416.21627026787, 17839.0, 18583.25666973323, 19085.236590536362, 19097.427077187673, 5522.171674248864, 5752.597318187729, 5757.424257574251, 5759.020966435716, 5759.308882934054, 4992.77532511036, 6299.748010079615, 6329.683515824214, 6411.326810684881, 6425.453836423886, 6187.845303867394, 6309.179806625132, 6375.266844312917, 6399.456046236076, 6399.968000159993, 6346.016367463028, 6378.107065010902, 6385.936140638581, 6387.872242555123, 6388.1695379600815, 5426.810061647863, 5556.944430555724, 5593.664380137205, 5594.049011668027, 5669.376368599451, 21942.89028554863, 22087.226947056777, 22179.66730499044, 22221.777802220873, 22269.554608907732, 2298.494331247123, 2332.54515369503, 2521.3948652323493, 2630.842149471038, 2747.409306999005, 13735.381907814173, 13788.172709637454, 14052.0, 14079.155250684991, 14612.269386530737, 1832.5418645338666, 1901.4865986183654, 1905.9332923347615, 1927.7493925789697, 1948.5713143108635, 71801.6409917947, 75804.24195758082, 76162.61918690427, 77753.22246777517, 78751.21248787496, 10330.694177863163, 10549.84175237372, 10663.466826658636, 10856.2400631955, 11043.392613406178, 15012.849871501145, 15018.324175412132, 15020.699586008219, 15026.248687565578, 15040.699186016438, 7508.685979953486, 7815.3356964657505, 7853.489523180975, 7953.920460795433, 8044.718434854752, 7381.0, 7384.852302953911, 7579.696812127563, 7601.923980760122, 7614.334982755991, 6488.67556622167, 6509.934900651027, 6537.738490460329, 6595.604263744095, 6635.303293154246, 1862.8695991280752, 1871.139275933086, 1884.2180495094383, 1924.6246981838688, 1943.0, 844.50174397106, 978.848278516831, 984.3404918704485, 987.2102318145598, 1041.0214398465419, 17703.645927081645, 18653.813461865208, 19022.239110435705, 19045.428637140907, 19501.902490487457, 968.0619320362665, 972.0619602084023, 973.0950216298769, 1010.6311196413403, 1043.2280112716535, 9846.852297215548, 10288.382697038052, 10971.615993440191, 10980.780384392267, 11042.558297668164, 435730.0, 445760.0, 455150.0, 462301.0, 465778.0, 88444.5577772107, 88598.55700721455, 89447.0, 90671.54664226636, 94876.52561737147, 1008.4050410257836, 1071.8552995345528, 1082.5128692088592, 1086.32104934416, 1093.436880006797, 15843.841561584697, 17058.914705426392, 17440.82559174392, 17444.563885902953, 17466.253374662432, 38280.42579361368, 38726.225475489766, 40731.0, 40827.7958610205, 40985.0, 52742.73628631832, 54246.0, 55490.445095550145, 56111.719441404166, 56540.43459565516, 6043.7884674036195, 8113.634886430243, 8198.098209197104, 8248.46384984974, 8562.871556926775, 10584.523696433525, 12445.502179912703, 12701.87298127007, 12736.426860791102, 13165.868341316847, 10847.0, 10938.56245750161, 10985.176111791488, 11052.281601695864, 11448.542058317575, 10883.782324353628, 10969.780604388026, 11092.667219983414, 11195.832062519232, 11247.325160490396, 10962.451877405938, 11098.667039988813, 11266.211365204357, 11647.359395233343, 11715.297082174757, 8217.424780265324, 8288.171182881664, 8371.455855369382, 8501.617427215913, 8680.435771675073, 9524.904750952403, 11162.928857864123, 11490.138239631893, 11625.24435911663, 11719.296842189497], "value": 9202.012258809269, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1218.4212499062942, 1406.9015168938174, 1422.608782584789, 1536.6772977674689, 1712.777338945937, 7291.161516425613, 8053.476524025934, 8213.7125200618, 8224.465409748362, 8443.113473085314, 8259.174082591739, 8321.500709957407, 8340.040895297043, 8341.749747507569, 8342.249197572217, 1835.174171622775, 1890.2061134323549, 2255.469964558323, 2290.335802617239, 2290.839641225115, 2224.4883676754357, 2281.6691579721014, 2291.1522736587485, 2291.301153148289, 2296.908123675051, 2201.6587428948537, 2208.3154222191174, 2217.966730499044, 2221.433534448713, 2223.1329781385193, 2230.8549944253655, 2243.741969673492, 2245.3937436891947, 2247.0337754765446, 2250.167438047916, 2207.0399376271375, 2208.8343374246833, 2239.585676649825, 2244.046280330853, 2246.7191601049867, 8418.78953026173, 8491.532965686885, 8497.320214382835, 8502.06477287498, 8512.829743405096, 4943.950560494421, 5068.239764035387, 5080.13637681595, 5085.186370180753, 5086.084504789136, 3026.530887712408, 3159.810411375302, 3249.2039450334582, 3256.47896336585, 3291.8683252670103, 955.5652178258875, 985.8816941966938, 1011.7976404719012, 1032.6179313653995, 1048.6172547020358, 11249.381284029376, 11380.829287560693, 11385.601503947322, 11388.886111138865, 11393.0, 4176.519700234512, 4474.932876006863, 4504.594586487232, 4564.6348292136545, 4568.885777855514, 9553.713388598353, 9586.760330991781, 9601.0, 9608.13526782593, 9609.951950240204, 5096.464871188546, 5102.795888164506, 5103.234514822806, 5109.20807274873, 5114.948850511447, 4594.104149690845, 4716.787744551503, 4723.551262630044, 4728.858134255909, 4770.833020844254, 3795.3168429682923, 3855.1711382052995, 3910.159315747128, 3918.8628398006504, 3932.6264004919485, 1301.069735139361, 1303.2376060005004, 1311.9737605247842, 1315.414640484975, 1334.5329134802935, 791.6358475101403, 879.9296056315608, 906.954652267384, 912.9501073765223, 930.0467021303218, 5519.448055194529, 5584.748686309126, 5673.489385955201, 5706.80026199081, 5729.914051289234, 418.96438802701505, 423.7478700173392, 426.44349124392966, 429.23810236829877, 432.6473923752132, 3899.805009749501, 3931.4495970564144, 3933.704972127102, 4027.335489644176, 4123.319652257344, 9439.0, 9774.902250977399, 9946.35348702332, 10034.24743144267, 10037.849432258521, 9830.852537211947, 10004.899951000543, 10005.699829005142, 10024.0, 10026.0, 7411.2588741125455, 7652.234776522303, 7664.08031036268, 7672.616369181518, 7675.961620191975, 5375.81184658535, 5430.484104010113, 5440.156775699773, 5495.148252020943, 5559.44405559457, 8870.822583548164, 8955.029893423436, 8970.461772293684, 9007.954960225157, 9057.954710226406, 9056.411333263317, 9127.954360228156, 9204.677836275698, 9210.677626283048, 9245.537723113952, 6665.400113989765, 6881.311868813279, 6890.44876409876, 7057.294270572902, 7059.223485416703, 7716.151223365538, 7949.165337639464, 7986.081600616004, 8044.959775201087, 8186.304164146114, 7325.157606875065, 7347.926520734938, 7388.630568471663, 7398.371138453292, 7453.850922981402, 7242.239564845826, 7306.2693730626515, 7311.488195826241, 7343.742968996059, 7357.926420735724, 6581.736730530725, 6629.204495460575, 6664.8000559983275, 6678.298778628174, 6688.264290928091, 4893.09477746618, 4995.850124496271, 5079.0, 5109.335786347751, 5153.484651534816, 5070.594352451869, 5080.171138390103, 5110.48895110486, 5125.974370128126, 5289.6032797539565], "value": 4297.804351497182, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.7969938337615, 334.3013102615534, 341.8717980757217, 342.28291728828094, 344.0469898381485, 5127.0, 5263.684178949261, 5269.920951185731, 5276.548949039011, 5286.497782710646, 5103.23451482277, 5163.974180129094, 5201.323827902377, 5222.242774797662, 5222.973885130569, 4150.377443383486, 4160.625543701066, 4166.187593419292, 4170.728902621335, 4193.916121677565, 3920.6275403836735, 3966.3455529837543, 4191.266528357541, 4194.266003449399, 4222.429971953778, 4088.2232375848644, 4089.7546147231114, 4122.855700050499, 4131.595257612411, 4152.771597562133, 4064.3903414487913, 4099.754014759124, 4100.91798164038, 4110.321796903508, 4123.8556650517085, 3684.6683798458266, 3713.0717320669833, 3713.4615480755137, 3715.721320900916, 3725.8137093145233, 5088.567471764867, 5099.311592934981, 5128.179506267855, 5137.743112844343, 5143.5113664201845, 4747.620190384761, 4754.786034628415, 4759.666823322369, 4764.642651801094, 4766.475687674353, 5370.248165256867, 5451.454854514556, 5481.643693159932, 5538.556915446755, 5540.2243685884005, 3988.8603898863403, 4007.2185923744883, 4028.8187031583648, 4056.9188616227807, 4072.2873497137884, 5595.300587426572, 5613.764971706218, 5619.522340601054, 5638.436156384329, 5639.830805075854, 4114.403411505315, 4136.524299705512, 4140.585941405896, 4142.606452387018, 4148.336266197394, 3906.90232744178, 4072.1675246139357, 4102.364133559303, 4105.97947010269, 4193.1404062167185, 4642.210824159901, 4663.044075964419, 4666.836660716858, 4670.696404733681, 4679.642903557999, 5459.5359394451125, 5460.726963651801, 5464.644798088111, 5483.616146869681, 5485.561155107662, 1357.497725841445, 1382.7303675783328, 1383.9930800345933, 1396.9022168448314, 1409.8942579306593, 950.7924935332089, 964.5273815830333, 975.9024097590326, 977.4965892565334, 980.7352014955922, 5123.205903085028, 5140.530142154031, 5160.303359046481, 5183.948160518422, 5194.740262986836, 5417.29355253094, 5459.153831156177, 5482.588805839578, 5485.0, 5490.69801160932, 4882.681675947545, 5095.184770436712, 5140.203268493389, 5160.41419113902, 5226.764795584208, 2870.5407134858324, 2876.0, 2878.330568270398, 2880.1935458071775, 2892.739653431201, 5591.707399965994, 5601.131824567198, 5604.355499117572, 5615.298087739033, 5626.381098079208, 5422.322209723785, 5512.751926163332, 5745.712714364348, 5756.079027355603, 5764.855878602984, 5797.985352563284, 5835.35811060783, 5839.9124013139835, 5871.236739223959, 5885.55858310637, 5534.91697624544, 5664.660120392789, 5699.715014249354, 5738.651416916938, 5742.506948193416, 5625.874825034927, 5635.88728225425, 5638.774449021993, 5643.633163844337, 5655.88688226225, 5834.15404766315, 5848.385919478564, 5866.0, 5867.471927526543, 5878.529717622494, 3867.5165604299464, 3964.742291751027, 4066.1867626475414, 4073.0, 4098.467199264077, 5510.476504732044, 5524.972375138099, 5667.4332566743, 5675.290588676416, 5685.630434021776, 5515.338159420895, 5543.750531226024, 5544.417836127149, 5548.472895074961, 5568.0, 5287.894242115213, 5474.972625136849, 5512.228288039598, 5520.337559492887, 5526.91709624364, 5396.622236443412, 5432.18517222404, 5438.864028399322, 5450.863728406822, 5466.34403871538, 5523.944760552343, 5553.694546799803, 5574.689947862168, 5584.944150558443, 5607.43925607436, 5305.363356397257, 5453.2365468833605, 5460.726963651722, 5466.699331536804, 5471.9179212311055], "value": 4202.347700598342, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.5582961882108, 785.112822510563, 788.1448628238364, 793.6706266899237, 805.4321703199245, 15299.92350038251, 16187.676246475094, 16212.189390530471, 16220.188990550485, 16284.51146465605, 15677.0, 15709.135997520132, 15789.131597762118, 15823.920880395583, 15908.52274431766, 670.1355251725269, 779.3336697123963, 798.8442253760519, 799.8120441696196, 799.9720009799659, 679.3919442099318, 686.3376841348098, 686.4782765098536, 686.8557602903396, 783.8393129408457, 828.9875651865227, 830.904445988713, 833.0711256948489, 836.7238811192323, 842.7219017724136, 827.2430725885807, 828.9958550207241, 830.1366578758084, 830.2112992657, 834.5201509132273, 514.8764296568834, 521.0984995957016, 611.966341851198, 612.0788213738296, 612.7089632424608, 12665.620031399072, 13362.465501379933, 13484.528041518499, 13758.518451854237, 13772.724545509034, 14222.359993800304, 14362.784558231635, 14515.85484145166, 14521.636959076002, 14567.344469498898, 11797.88202117985, 11802.94098529502, 11836.230645008132, 11951.282923024646, 12524.185927914748, 3572.821358932043, 3693.907652308687, 3732.085639018431, 3773.471713960048, 3853.0367408147963, 79058.60470697668, 80972.99999999942, 82915.5854220725, 84320.0, 84506.57746711228, 9888.258380621483, 10421.5831366746, 10494.842577361345, 10555.155587552978, 10559.0, 20993.58012839765, 21022.579548408947, 21038.47403814917, 21080.78919210819, 21137.78862211358, 7532.435067369969, 7629.0845098588525, 7671.462997590115, 7735.3424958878, 7902.68389264423, 4011.338129208648, 4171.249175148502, 4230.407742915994, 4264.552222016706, 4292.506361768436, 3440.2775417162675, 3506.5967413747235, 3540.929181416409, 3544.8050357230086, 3581.0510214793103, 577.3706659740902, 589.4665327878191, 589.5725598940734, 598.1267349669445, 605.3764622438895, 610.7373829253374, 693.5873155472486, 710.346481237263, 724.3661795928563, 735.7057177129084, 8819.29445644347, 8988.955055224682, 9187.310951678652, 9238.168564829064, 9417.90582094184, 561.8202175303863, 637.8437282865727, 638.084348959245, 659.8086554899121, 705.6365971524668, 4496.527864574238, 4605.8848528786375, 4613.5847773700525, 4666.603338716247, 4727.172744769651, 69151.30848691448, 77909.61045194737, 79983.0, 80555.59722201352, 81209.0, 68796.96804547936, 72867.0, 73713.0, 73807.0, 73897.0, 64216.35783642104, 65218.0, 65910.34089659138, 66472.0, 68163.0, 8766.649334026568, 8811.60347784364, 8853.468791872509, 8863.290936725176, 9131.680391186277, 44799.32801008053, 45249.03161314507, 46783.53216467928, 46877.53122468868, 47356.0, 57132.71433642805, 59698.70150649219, 60351.698241508515, 60440.0, 61355.386446134966, 10792.406417646791, 11375.146863985068, 11463.369514676438, 11473.942630286796, 11552.249103808226, 12417.029636170442, 12726.554570589984, 12816.743665126833, 12948.0, 13184.208947463183, 11691.415429228675, 12129.878701212874, 12134.635960921187, 12190.695232619255, 12508.124431289722, 11374.886251137714, 11435.085193184359, 11479.885201148216, 11742.17804753659, 12274.0, 11577.6526704199, 12539.623811285675, 13001.349932503526, 13007.414666339848, 13033.0, 9752.804943901225, 9767.365121267096, 10204.693859184237, 10289.897101029193, 10329.9483502585, 8982.371234013557, 9192.49441280736, 10009.749756256151, 10031.749206269902, 10511.474426278808], "value": 6944.498868960037, "unit": "score"}], "name": "dromaeo_css", "value": 6967.230382069387, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 110.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 128.0, 127.0, 166.0, 125.0, 126.0, 122.0, 122.0, 152.0, 124.0], "value": 134.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 163.0, 160.0, 161.0, 162.0, 160.0, 160.0, 160.0, 160.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 107.0, 76.0, 76.0, 76.0, 76.0, 91.0, 76.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 111.0, 82.0, 82.0, 82.0, 97.0, 84.0, 82.0, 110.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 147.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 51.0, 54.0, 53.0, 55.0, 52.0, 53.0, 53.0, 53.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 64.0, 65.0, 63.0, 64.0, 64.0, 63.0, 65.0], "value": 63.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 80.0, 83.0, 81.0, 81.0, 83.0, 87.0, 81.0, 81.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 154.0, 130.0, 136.0, 143.0, 144.0, 140.0, 176.0, 161.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 168.0, 168.0, 169.0, 169.0, 168.0, 167.0, 170.0, 169.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 66.0, 67.0, 66.0, 67.0, 66.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1515.7999999999997, "alertThreshold": 2.0}]} 09:29:24 INFO - Return code: 0 09:29:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:29:24 INFO - # TBPL SUCCESS # 09:29:24 INFO - Running post-action listener: _resource_record_post_action 09:29:24 INFO - [mozharness: 2016-12-05 17:29:24.494939Z] Finished run-tests step (success) 09:29:24 INFO - Running post-run listener: _resource_record_post_run 09:29:24 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392298496; Read time: 12; Write time: 273644 09:29:24 INFO - TinderboxPrint: CPU usage
13.1% 09:29:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:29:24 INFO - TinderboxPrint: I/O write bytes / time
392,298,496 / 273,644 09:29:24 INFO - TinderboxPrint: CPU idle
8,305.5 (86.9%) 09:29:24 INFO - TinderboxPrint: CPU user
1,201.5 (12.6%) 09:29:24 INFO - TinderboxPrint: Swap in / out
0 / 0 09:29:24 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 103915520; Read time: 0; Write time: 67940 09:29:24 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288382976; Read time: 12; Write time: 205704 09:29:25 INFO - Running post-run listener: _upload_blobber_files 09:29:25 INFO - Blob upload gear active. 09:29:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:29:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:29:25 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:29:25 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:29:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:29:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:29:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:29:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:26 INFO - (blobuploader) - INFO - Done attempting. 09:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:29:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:29 INFO - (blobuploader) - INFO - Done attempting. 09:29:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:29:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:29:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:30 INFO - (blobuploader) - INFO - Done attempting. 09:29:30 INFO - (blobuploader) - INFO - Iteration through files over. 09:29:30 INFO - Return code: 0 09:29:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:29:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:29:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2ecc1577ddac592f8c67f9338cab4d2c2b96b371faf53b5c90a96acbde12f42f205e6cf52b9497c0eb1ff19ec0b1bc7a21eac5a5f5b5a486e2ab93ab3e5cc0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73a14726951d08729ae94b4d7f65c17c3d26740d3f00607927b86251349b84d6a615590e74b5a3e4bde2fdbd355ca3061518c444f369415805099c208cde7474", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8bc24406f447fc6b0e913d824749a49de5eb599e1496421fdf5b218b0225397eb81b8d9f3a788f6ad105ab1e2215a36c90c15d0776f36cb3349dfad3e0f105e"} 09:29:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:29:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:29:30 INFO - Contents: 09:29:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2ecc1577ddac592f8c67f9338cab4d2c2b96b371faf53b5c90a96acbde12f42f205e6cf52b9497c0eb1ff19ec0b1bc7a21eac5a5f5b5a486e2ab93ab3e5cc0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73a14726951d08729ae94b4d7f65c17c3d26740d3f00607927b86251349b84d6a615590e74b5a3e4bde2fdbd355ca3061518c444f369415805099c208cde7474", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8bc24406f447fc6b0e913d824749a49de5eb599e1496421fdf5b218b0225397eb81b8d9f3a788f6ad105ab1e2215a36c90c15d0776f36cb3349dfad3e0f105e"} 09:29:30 INFO - Running post-run listener: copy_logs_to_upload_dir 09:29:30 INFO - Copying logs to upload dir... 09:29:30 INFO - mkdir: /builds/slave/test/build/upload/logs 09:29:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.342701 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-12-05 09:29:30.082106) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-05 09:29:30.085958) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e2ecc1577ddac592f8c67f9338cab4d2c2b96b371faf53b5c90a96acbde12f42f205e6cf52b9497c0eb1ff19ec0b1bc7a21eac5a5f5b5a486e2ab93ab3e5cc0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73a14726951d08729ae94b4d7f65c17c3d26740d3f00607927b86251349b84d6a615590e74b5a3e4bde2fdbd355ca3061518c444f369415805099c208cde7474", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8bc24406f447fc6b0e913d824749a49de5eb599e1496421fdf5b218b0225397eb81b8d9f3a788f6ad105ab1e2215a36c90c15d0776f36cb3349dfad3e0f105e"} build_url:https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011535 build_url: 'https://queue.taskcluster.net/v1/task/GV_rKTp4Tf2LkWuenHdAkQ/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/Firefox/sha512/e2ecc1577ddac592f8c67f9338cab4d2c2b96b371faf53b5c90a96acbde12f42f205e6cf52b9497c0eb1ff19ec0b1bc7a21eac5a5f5b5a486e2ab93ab3e5cc0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73a14726951d08729ae94b4d7f65c17c3d26740d3f00607927b86251349b84d6a615590e74b5a3e4bde2fdbd355ca3061518c444f369415805099c208cde7474", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8bc24406f447fc6b0e913d824749a49de5eb599e1496421fdf5b218b0225397eb81b8d9f3a788f6ad105ab1e2215a36c90c15d0776f36cb3349dfad3e0f105e"}' ========= master_lag: 0.55 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-05 09:29:30.649187) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:29:30.649511) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1480957194.310590-1391025503 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004532 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-05 09:29:30.666035) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-05 09:29:30.666345) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-05 09:29:30.669679) ========= ========= Total master_lag: 0.65 =========