builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1474375348.63 results: success (0) buildid: 20160920024116 builduid: 70f2daedbcfc4625b1493831cd0d2313 revision: f16332940adb7f2b14de7379a1e8fe32a95a826a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:28.633720) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:28.634215) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:28.634543) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:28.651340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:28.651677) ========= 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=00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 05:42:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-09-20 05:42:28 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119537 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:28.785425) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:28.788822) ========= 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=00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018536 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:28.838897) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:28.839302) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f16332940adb7f2b14de7379a1e8fe32a95a826a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f16332940adb7f2b14de7379a1e8fe32a95a826a --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=00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 05:42:28,839 truncating revision to first 12 chars 2016-09-20 05:42:28,839 Setting DEBUG logging. 2016-09-20 05:42:28,839 attempt 1/10 2016-09-20 05:42:28,839 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f16332940adb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 05:42:29,140 unpacking tar archive at: mozilla-aurora-f16332940adb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.636217 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:29.493177) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:29.493543) ========= script_repo_revision: f16332940adb7f2b14de7379a1e8fe32a95a826a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:29.494008) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:29.494408) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 05:42:29.504113) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 56 secs) (at 2016-09-20 05:42:29.504458) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:42:29 INFO - MultiFileLogger online at 20160920 05:42:29 in /builds/slave/test 05:42:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:42:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:42:29 INFO - {'append_to_log': False, 05:42:29 INFO - 'base_work_dir': '/builds/slave/test', 05:42:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:42:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:42:29 INFO - 'branch': 'Mozilla-Aurora', 05:42:29 INFO - 'buildbot_json_path': 'buildprops.json', 05:42:29 INFO - 'config_files': ('talos/linux_config.py',), 05:42:29 INFO - 'default_actions': ('clobber', 05:42:29 INFO - 'read-buildbot-config', 05:42:29 INFO - 'download-and-extract', 05:42:29 INFO - 'populate-webroot', 05:42:29 INFO - 'create-virtualenv', 05:42:29 INFO - 'install', 05:42:29 INFO - 'run-tests'), 05:42:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:42:29 INFO - 'download_minidump_stackwalk': True, 05:42:29 INFO - 'download_symbols': 'ondemand', 05:42:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:42:29 INFO - 'tooltool.py': '/tools/tooltool.py', 05:42:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:42:29 INFO - '/tools/misc-python/virtualenv.py')}, 05:42:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:42:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:42:29 INFO - 'installer_path': 'installer.exe', 05:42:29 INFO - 'log_level': 'info', 05:42:29 INFO - 'log_name': 'talos', 05:42:29 INFO - 'log_to_console': True, 05:42:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:42:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:42:29 INFO - 'opt_config_files': (), 05:42:29 INFO - 'pip_index': False, 05:42:29 INFO - 'sps_profile': False, 05:42:29 INFO - 'sps_profile_interval': 0, 05:42:29 INFO - 'suite': 'dromaeojs', 05:42:29 INFO - 'system_bits': '32', 05:42:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:42:29 INFO - 'title': 'talos-linux64-ix-016', 05:42:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:42:29 INFO - 'use_talos_json': True, 05:42:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:42:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:42:29 INFO - 'work_dir': 'build'} 05:42:29 INFO - [mozharness: 2016-09-20 12:42:29.595983Z] Running clobber step. 05:42:29 INFO - Running pre-action listener: _resource_record_pre_action 05:42:29 INFO - Running main action method: clobber 05:42:29 INFO - rmtree: /builds/slave/test/build 05:42:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:42:30 INFO - Running post-action listener: _resource_record_post_action 05:42:30 INFO - [mozharness: 2016-09-20 12:42:30.065260Z] Finished clobber step (success) 05:42:30 INFO - [mozharness: 2016-09-20 12:42:30.065361Z] Running read-buildbot-config step. 05:42:30 INFO - Running pre-action listener: _resource_record_pre_action 05:42:30 INFO - Running main action method: read_buildbot_config 05:42:30 INFO - Using buildbot properties: 05:42:30 INFO - { 05:42:30 INFO - "project": "", 05:42:30 INFO - "product": "firefox", 05:42:30 INFO - "script_repo_revision": "production", 05:42:30 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 05:42:30 INFO - "repository": "", 05:42:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 05:42:30 INFO - "buildid": "20160920024116", 05:42:30 INFO - "pgo_build": "True", 05:42:30 INFO - "basedir": "/builds/slave/test", 05:42:30 INFO - "buildnumber": 768, 05:42:30 INFO - "slavename": "talos-linux64-ix-016", 05:42:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:42:30 INFO - "platform": "ubuntu64_hw", 05:42:30 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 05:42:30 INFO - "branch": "mozilla-aurora", 05:42:30 INFO - "repo_path": "releases/mozilla-aurora", 05:42:30 INFO - "revision": "f16332940adb7f2b14de7379a1e8fe32a95a826a", 05:42:30 INFO - "stage_platform": "linux64-pgo", 05:42:30 INFO - "builduid": "70f2daedbcfc4625b1493831cd0d2313", 05:42:30 INFO - "slavebuilddir": "test" 05:42:30 INFO - } 05:42:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 05:42:30 INFO - Running post-action listener: _resource_record_post_action 05:42:30 INFO - [mozharness: 2016-09-20 12:42:30.067818Z] Finished read-buildbot-config step (success) 05:42:30 INFO - [mozharness: 2016-09-20 12:42:30.067909Z] Running download-and-extract step. 05:42:30 INFO - Running pre-action listener: _resource_record_pre_action 05:42:30 INFO - Running main action method: download_and_extract 05:42:30 INFO - mkdir: /builds/slave/test/build/tests 05:42:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:30 INFO - trying https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 05:42:30 INFO - Downloading https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 05:42:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:42:30 INFO - Downloaded 1361 bytes. 05:42:30 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 05:42:30 INFO - Using the following test package requirements: 05:42:30 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 05:42:30 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:42:30 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:42:30 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:42:30 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 05:42:30 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:42:30 INFO - u'jsshell-linux-x86_64.zip'], 05:42:30 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:42:30 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:42:30 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 05:42:30 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:42:30 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:42:30 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:42:30 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:42:30 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:42:30 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:42:30 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 05:42:30 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:42:30 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:42:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 05:42:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:42:30 INFO - Fetch https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 05:42:31 INFO - Expected file size: 21893645 05:42:35 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:42:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 05:42:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:42:35 INFO - Fetch https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 05:42:36 INFO - Expected file size: 21893645 05:42:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 05:42:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:42:41 INFO - Fetch https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 05:42:41 INFO - Expected file size: 14414059 05:42:42 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')]} 05:42:42 INFO - trying https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:42:42 INFO - Downloading https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:42:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:42:43 INFO - Downloaded 63950377 bytes. 05:42:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:42:43 INFO - mkdir: /builds/slave/test/properties 05:42:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:42:43 INFO - Writing to file /builds/slave/test/properties/build_url 05:42:43 INFO - Contents: 05:42:43 INFO - build_url:https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 05:42:44 INFO - Running post-action listener: _resource_record_post_action 05:42:44 INFO - Running post-action listener: set_extra_try_arguments 05:42:44 INFO - [mozharness: 2016-09-20 12:42:44.384927Z] Finished download-and-extract step (success) 05:42:44 INFO - [mozharness: 2016-09-20 12:42:44.385200Z] Running populate-webroot step. 05:42:44 INFO - Running pre-action listener: _resource_record_pre_action 05:42:44 INFO - Running main action method: populate_webroot 05:42:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:42:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:42:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:42:44 INFO - '--tppagecycles', 05:42:44 INFO - '7'], 05:42:44 INFO - 'tests': ['tsvgm']}}, 05:42:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:42:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:42:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:42:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:42:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:42:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:42:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:44 INFO - 'talos_options': ['--disable-e10s'], 05:42:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:42:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:42:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:44 INFO - 'talos_options': ['--disable-e10s'], 05:42:44 INFO - 'tests': ['damp', 'tps']}, 05:42:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:44 INFO - 'tests': ['damp', 'tps']}, 05:42:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:42:44 INFO - 'tests': ['dromaeo_dom']}, 05:42:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:42:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:42:44 INFO - 'tests': ['basic_compositor_video']}, 05:42:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:42:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:42:44 INFO - 'tests': ['a11yr', 05:42:44 INFO - 'ts_paint', 05:42:44 INFO - 'tpaint', 05:42:44 INFO - 'sessionrestore', 05:42:44 INFO - 'sessionrestore_no_auto_restore', 05:42:44 INFO - 'tabpaint']}, 05:42:44 INFO - 'other-e10s': {'tests': ['a11yr', 05:42:44 INFO - 'ts_paint', 05:42:44 INFO - 'tpaint', 05:42:44 INFO - 'sessionrestore', 05:42:44 INFO - 'sessionrestore_no_auto_restore', 05:42:44 INFO - 'tabpaint']}, 05:42:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:42:44 INFO - 'ts_paint', 05:42:44 INFO - 'tpaint', 05:42:44 INFO - 'sessionrestore', 05:42:44 INFO - 'sessionrestore_no_auto_restore', 05:42:44 INFO - 'tabpaint']}, 05:42:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:42:44 INFO - 'ts_paint', 05:42:44 INFO - 'tpaint', 05:42:44 INFO - 'sessionrestore', 05:42:44 INFO - 'sessionrestore_no_auto_restore', 05:42:44 INFO - 'tabpaint']}, 05:42:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:42:44 INFO - 'tests': ['a11yr', 05:42:44 INFO - 'ts_paint', 05:42:44 INFO - 'tpaint', 05:42:44 INFO - 'sessionrestore', 05:42:44 INFO - 'sessionrestore_no_auto_restore', 05:42:44 INFO - 'tabpaint']}, 05:42:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:42:44 INFO - 'tests': ['a11yr', 05:42:44 INFO - 'ts_paint', 05:42:44 INFO - 'tpaint', 05:42:44 INFO - 'sessionrestore', 05:42:44 INFO - 'sessionrestore_no_auto_restore', 05:42:44 INFO - 'tabpaint']}, 05:42:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:42:44 INFO - 'tests': ['tsvgx', 05:42:44 INFO - 'tsvgr_opacity', 05:42:44 INFO - 'tart', 05:42:44 INFO - 'tscrollx', 05:42:44 INFO - 'cart']}, 05:42:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:42:44 INFO - 'tsvgr_opacity', 05:42:44 INFO - 'tart', 05:42:44 INFO - 'tscrollx', 05:42:44 INFO - 'cart']}, 05:42:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:44 INFO - 'talos_options': ['--disable-e10s'], 05:42:44 INFO - 'tests': ['tp5o']}, 05:42:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:44 INFO - 'tests': ['tp5o']}, 05:42:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:44 INFO - 'talos_options': ['--disable-e10s', 05:42:44 INFO - '--xperf_path', 05:42:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:42:44 INFO - 'tests': ['tp5n']}, 05:42:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:44 INFO - 'talos_options': ['--xperf_path', 05:42:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:42:44 INFO - 'tests': ['tp5n']}}, 05:42:44 INFO - 'talos.zip': {'path': '', 05:42:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:42:44 INFO - Running post-action listener: _resource_record_post_action 05:42:44 INFO - [mozharness: 2016-09-20 12:42:44.401183Z] Finished populate-webroot step (success) 05:42:44 INFO - [mozharness: 2016-09-20 12:42:44.401272Z] Running create-virtualenv step. 05:42:44 INFO - Running pre-action listener: _resource_record_pre_action 05:42:44 INFO - Running main action method: create_virtualenv 05:42:44 INFO - Creating virtualenv /builds/slave/test/build/venv 05:42:44 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 05:42:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:42:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:42:44 INFO - Using real prefix '/usr' 05:42:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:42:45 INFO - Installing distribute.............................................................................................................................................................................................done. 05:42:46 INFO - Installing pip.................done. 05:42:46 INFO - Return code: 0 05:42:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:42:46 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')]} 05:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:46 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')]} 05:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:46 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 0x7f2c59a94f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2954ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951f60>, '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 0x2952d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29531d0>, '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': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', '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 05:42:47 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 05:42:47 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 05:42:47 INFO - Using env: {'DISPLAY': ':0', 05:42:47 INFO - 'HOME': '/home/cltbld', 05:42:47 INFO - 'LANG': 'en_US.UTF-8', 05:42:47 INFO - 'LANGUAGE': 'en_US:en', 05:42:47 INFO - 'LOGNAME': 'cltbld', 05:42:47 INFO - 'MAIL': '/var/mail/cltbld', 05:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:47 INFO - 'MOZ_NO_REMOTE': '1', 05:42:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:47 INFO - 'NO_EM_RESTART': '1', 05:42:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:47 INFO - 'PWD': '/builds/slave/test', 05:42:47 INFO - 'SHELL': '/bin/bash', 05:42:47 INFO - 'SHLVL': '1', 05:42:47 INFO - 'TERM': 'linux', 05:42:47 INFO - 'TMOUT': '86400', 05:42:47 INFO - 'USER': 'cltbld', 05:42:47 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', 05:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:47 INFO - '_': '/tools/buildbot/bin/python'} 05:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:47 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 05:42:47 INFO - Cleaning up... 05:42:47 INFO - Return code: 0 05:42:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:42: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')]} 05:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42: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')]} 05:42:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:47 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 0x7f2c59a94f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2954ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951f60>, '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 0x2952d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29531d0>, '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': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', '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 05:42:47 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 05:42:47 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 05:42:47 INFO - Using env: {'DISPLAY': ':0', 05:42:47 INFO - 'HOME': '/home/cltbld', 05:42:47 INFO - 'LANG': 'en_US.UTF-8', 05:42:47 INFO - 'LANGUAGE': 'en_US:en', 05:42:47 INFO - 'LOGNAME': 'cltbld', 05:42:47 INFO - 'MAIL': '/var/mail/cltbld', 05:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:47 INFO - 'MOZ_NO_REMOTE': '1', 05:42:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:47 INFO - 'NO_EM_RESTART': '1', 05:42:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:47 INFO - 'PWD': '/builds/slave/test', 05:42:47 INFO - 'SHELL': '/bin/bash', 05:42:47 INFO - 'SHLVL': '1', 05:42:47 INFO - 'TERM': 'linux', 05:42:47 INFO - 'TMOUT': '86400', 05:42:47 INFO - 'USER': 'cltbld', 05:42:47 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', 05:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:47 INFO - '_': '/tools/buildbot/bin/python'} 05:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:47 INFO - Downloading/unpacking psutil>=3.1.1 05:42:47 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 05:42:47 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 05:42:47 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 05:42:47 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 05:42:47 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 05:42:47 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 05:42:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:42:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:42:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:42:49 INFO - Installing collected packages: psutil 05:42:49 INFO - Running setup.py install for psutil 05:42:49 INFO - building 'psutil._psutil_linux' extension 05:42:49 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 05:42:49 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 05:42:49 INFO - building 'psutil._psutil_posix' extension 05:42:49 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 05:42:49 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 05:42:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:42:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:42:49 INFO - Successfully installed psutil 05:42:49 INFO - Cleaning up... 05:42:49 INFO - Return code: 0 05:42:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2c59a94f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2954ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951f60>, '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 0x2952d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29531d0>, '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': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', '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 05:42:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:42:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:42:49 INFO - Using env: {'DISPLAY': ':0', 05:42:49 INFO - 'HOME': '/home/cltbld', 05:42:49 INFO - 'LANG': 'en_US.UTF-8', 05:42:49 INFO - 'LANGUAGE': 'en_US:en', 05:42:49 INFO - 'LOGNAME': 'cltbld', 05:42:49 INFO - 'MAIL': '/var/mail/cltbld', 05:42:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:49 INFO - 'MOZ_NO_REMOTE': '1', 05:42:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:49 INFO - 'NO_EM_RESTART': '1', 05:42:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:49 INFO - 'PWD': '/builds/slave/test', 05:42:49 INFO - 'SHELL': '/bin/bash', 05:42:49 INFO - 'SHLVL': '1', 05:42:49 INFO - 'TERM': 'linux', 05:42:49 INFO - 'TMOUT': '86400', 05:42:49 INFO - 'USER': 'cltbld', 05:42:49 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', 05:42:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:49 INFO - '_': '/tools/buildbot/bin/python'} 05:42:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:42:50 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 05:42:50 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 05:42:50 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 05:42:50 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 05:42:50 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 05:42:50 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 05:42:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:42:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:42:53 INFO - Installing collected packages: mozsystemmonitor 05:42:53 INFO - Running setup.py install for mozsystemmonitor 05:42:53 INFO - Successfully installed mozsystemmonitor 05:42:53 INFO - Cleaning up... 05:42:53 INFO - Return code: 0 05:42:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:42:53 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')]} 05:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:53 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')]} 05:42:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:53 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 0x7f2c59a94f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2954ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951f60>, '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 0x2952d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29531d0>, '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': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', '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 05:42:53 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 05:42:53 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 05:42:53 INFO - Using env: {'DISPLAY': ':0', 05:42:53 INFO - 'HOME': '/home/cltbld', 05:42:53 INFO - 'LANG': 'en_US.UTF-8', 05:42:53 INFO - 'LANGUAGE': 'en_US:en', 05:42:53 INFO - 'LOGNAME': 'cltbld', 05:42:53 INFO - 'MAIL': '/var/mail/cltbld', 05:42:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:53 INFO - 'MOZ_NO_REMOTE': '1', 05:42:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:53 INFO - 'NO_EM_RESTART': '1', 05:42:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:53 INFO - 'PWD': '/builds/slave/test', 05:42:53 INFO - 'SHELL': '/bin/bash', 05:42:53 INFO - 'SHLVL': '1', 05:42:53 INFO - 'TERM': 'linux', 05:42:53 INFO - 'TMOUT': '86400', 05:42:53 INFO - 'USER': 'cltbld', 05:42:53 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', 05:42:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:53 INFO - '_': '/tools/buildbot/bin/python'} 05:42:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:53 INFO - Downloading/unpacking jsonschema==2.5.1 05:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:42:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:42:55 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 05:42:55 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 05:42:55 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 05:42:55 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 05:42:55 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 05:42:55 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 05:42:55 INFO - Downloading functools32-3.2.3-2.tar.gz 05:42:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:42:55 INFO - warning: no files found matching '*.txt' 05:42:55 INFO - no previously-included directories found matching 'build' 05:42:55 INFO - no previously-included directories found matching 'dist' 05:42:55 INFO - no previously-included directories found matching '.git*' 05:42:55 INFO - Installing collected packages: jsonschema, functools32 05:42:55 INFO - Running setup.py install for functools32 05:42:55 INFO - warning: no files found matching '*.txt' 05:42:55 INFO - no previously-included directories found matching 'build' 05:42:55 INFO - no previously-included directories found matching 'dist' 05:42:55 INFO - no previously-included directories found matching '.git*' 05:42:55 INFO - Successfully installed jsonschema functools32 05:42:55 INFO - Cleaning up... 05:42:55 INFO - Return code: 0 05:42:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:55 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 0x7f2c59a94f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2954ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951f60>, '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 0x2952d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29531d0>, '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': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', '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 05:42:55 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 05:42:55 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 05:42:55 INFO - Using env: {'DISPLAY': ':0', 05:42:55 INFO - 'HOME': '/home/cltbld', 05:42:55 INFO - 'LANG': 'en_US.UTF-8', 05:42:55 INFO - 'LANGUAGE': 'en_US:en', 05:42:55 INFO - 'LOGNAME': 'cltbld', 05:42:55 INFO - 'MAIL': '/var/mail/cltbld', 05:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:55 INFO - 'MOZ_NO_REMOTE': '1', 05:42:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:55 INFO - 'NO_EM_RESTART': '1', 05:42:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:55 INFO - 'PWD': '/builds/slave/test', 05:42:55 INFO - 'SHELL': '/bin/bash', 05:42:55 INFO - 'SHLVL': '1', 05:42:55 INFO - 'TERM': 'linux', 05:42:55 INFO - 'TMOUT': '86400', 05:42:55 INFO - 'USER': 'cltbld', 05:42:55 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', 05:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:55 INFO - '_': '/tools/buildbot/bin/python'} 05:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:42:55 INFO - Cleaning up... 05:42:56 INFO - Return code: 0 05:42:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:42:56 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')]} 05:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:56 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')]} 05:42:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:56 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 0x7f2c59a94f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2954ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951f60>, '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 0x2952d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29531d0>, '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': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', '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 05:42:56 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 05:42:56 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 05:42:56 INFO - Using env: {'DISPLAY': ':0', 05:42:56 INFO - 'HOME': '/home/cltbld', 05:42:56 INFO - 'LANG': 'en_US.UTF-8', 05:42:56 INFO - 'LANGUAGE': 'en_US:en', 05:42:56 INFO - 'LOGNAME': 'cltbld', 05:42:56 INFO - 'MAIL': '/var/mail/cltbld', 05:42:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:56 INFO - 'MOZ_NO_REMOTE': '1', 05:42:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:56 INFO - 'NO_EM_RESTART': '1', 05:42:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:56 INFO - 'PWD': '/builds/slave/test', 05:42:56 INFO - 'SHELL': '/bin/bash', 05:42:56 INFO - 'SHLVL': '1', 05:42:56 INFO - 'TERM': 'linux', 05:42:56 INFO - 'TMOUT': '86400', 05:42:56 INFO - 'USER': 'cltbld', 05:42:56 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', 05:42:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:56 INFO - '_': '/tools/buildbot/bin/python'} 05:42:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:56 INFO - Downloading/unpacking blobuploader==1.2.4 05:42:56 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 05:42:56 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 05:42:56 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 05:42:56 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 05:42:56 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 05:42:56 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 05:42:58 INFO - Downloading blobuploader-1.2.4.tar.gz 05:42:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:42:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:42:58 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 05:42:58 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 05:42:58 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 05:42:58 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 05:42:58 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 05:42:58 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 05:42:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:42:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:42:58 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 05:42:58 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 05:42:58 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 05:42:58 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 05:42:58 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 05:42:58 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 05:42:58 INFO - Downloading docopt-0.6.1.tar.gz 05:42:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:42:58 INFO - Installing collected packages: blobuploader, requests, docopt 05:42:58 INFO - Running setup.py install for blobuploader 05:42:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:42:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:42:58 INFO - Running setup.py install for requests 05:42:59 INFO - Running setup.py install for docopt 05:42:59 INFO - Successfully installed blobuploader requests docopt 05:42:59 INFO - Cleaning up... 05:42:59 INFO - Return code: 0 05:42:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:42: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')]} 05:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42: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')]} 05:42:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:59 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 0x7f2c59a94f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2954ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951f60>, '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 0x2952d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29531d0>, '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': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', '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 05:42:59 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 05:42:59 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 05:42:59 INFO - Using env: {'DISPLAY': ':0', 05:42:59 INFO - 'HOME': '/home/cltbld', 05:42:59 INFO - 'LANG': 'en_US.UTF-8', 05:42:59 INFO - 'LANGUAGE': 'en_US:en', 05:42:59 INFO - 'LOGNAME': 'cltbld', 05:42:59 INFO - 'MAIL': '/var/mail/cltbld', 05:42:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:59 INFO - 'MOZ_NO_REMOTE': '1', 05:42:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:42:59 INFO - 'NO_EM_RESTART': '1', 05:42:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:42:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:59 INFO - 'PWD': '/builds/slave/test', 05:42:59 INFO - 'SHELL': '/bin/bash', 05:42:59 INFO - 'SHLVL': '1', 05:42:59 INFO - 'TERM': 'linux', 05:42:59 INFO - 'TMOUT': '86400', 05:42:59 INFO - 'USER': 'cltbld', 05:42:59 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', 05:42:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:59 INFO - '_': '/tools/buildbot/bin/python'} 05:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:42:59 INFO - Running setup.py (path:/tmp/pip-8OWXf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:42:59 INFO - Running setup.py (path:/tmp/pip-xezvVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:42:59 INFO - Running setup.py (path:/tmp/pip-r7su7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:42:59 INFO - Running setup.py (path:/tmp/pip-MdoKjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:42:59 INFO - Running setup.py (path:/tmp/pip-jPnhC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:43:00 INFO - Running setup.py (path:/tmp/pip-wBG7HJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:43:00 INFO - Running setup.py (path:/tmp/pip-MqbaiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:43:00 INFO - Running setup.py (path:/tmp/pip-PIv38j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:43:00 INFO - Running setup.py (path:/tmp/pip-EdZaMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:43:00 INFO - Running setup.py (path:/tmp/pip-8G9ALz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:43:00 INFO - Running setup.py (path:/tmp/pip-xFe85O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:43:00 INFO - Running setup.py (path:/tmp/pip-Z51PG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:43:00 INFO - Running setup.py (path:/tmp/pip-Elgq4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:43:00 INFO - Running setup.py (path:/tmp/pip-k5yGZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:00 INFO - Running setup.py (path:/tmp/pip-XCEyMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:43:00 INFO - Running setup.py (path:/tmp/pip-muTIDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:43:00 INFO - Running setup.py (path:/tmp/pip-qxNQr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:43:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:43:00 INFO - Running setup.py install for manifestparser 05:43:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:43:01 INFO - Running setup.py install for mozcrash 05:43:01 INFO - Running setup.py install for mozdebug 05:43:01 INFO - Running setup.py install for mozdevice 05:43:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:43:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:43:01 INFO - Running setup.py install for mozfile 05:43:01 INFO - Running setup.py install for mozhttpd 05:43:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:43:01 INFO - Running setup.py install for mozinfo 05:43:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:43:01 INFO - Running setup.py install for mozInstall 05:43:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:43:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:43:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:43:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:43:01 INFO - Running setup.py install for mozleak 05:43:01 INFO - Running setup.py install for mozlog 05:43:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:43:01 INFO - Running setup.py install for moznetwork 05:43:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:43:02 INFO - Running setup.py install for mozprocess 05:43:02 INFO - Running setup.py install for mozprofile 05:43:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:43:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:43:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:43:02 INFO - Running setup.py install for mozrunner 05:43:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:43:02 INFO - Running setup.py install for mozscreenshot 05:43:02 INFO - Running setup.py install for moztest 05:43:02 INFO - Running setup.py install for mozversion 05:43:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:43:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:43:02 INFO - Cleaning up... 05:43:02 INFO - Return code: 0 05:43:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:02 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 0x7f2c59a94f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2954ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951f60>, '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 0x2952d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29531d0>, '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': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', '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 05:43:02 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 05:43:02 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 05:43:02 INFO - Using env: {'DISPLAY': ':0', 05:43:02 INFO - 'HOME': '/home/cltbld', 05:43:02 INFO - 'LANG': 'en_US.UTF-8', 05:43:02 INFO - 'LANGUAGE': 'en_US:en', 05:43:02 INFO - 'LOGNAME': 'cltbld', 05:43:02 INFO - 'MAIL': '/var/mail/cltbld', 05:43:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:02 INFO - 'MOZ_NO_REMOTE': '1', 05:43:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:02 INFO - 'NO_EM_RESTART': '1', 05:43:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:02 INFO - 'PWD': '/builds/slave/test', 05:43:02 INFO - 'SHELL': '/bin/bash', 05:43:02 INFO - 'SHLVL': '1', 05:43:02 INFO - 'TERM': 'linux', 05:43:02 INFO - 'TMOUT': '86400', 05:43:02 INFO - 'USER': 'cltbld', 05:43:02 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', 05:43:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:02 INFO - '_': '/tools/buildbot/bin/python'} 05:43:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:43:02 INFO - Running setup.py (path:/tmp/pip-XcJvZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:43:03 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)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:43:03 INFO - Running setup.py (path:/tmp/pip-FFxQef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:43:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:43:03 INFO - Running setup.py (path:/tmp/pip-wrAehl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:43:03 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)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:43:03 INFO - Running setup.py (path:/tmp/pip-5wMEvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:43:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:43:03 INFO - Running setup.py (path:/tmp/pip-jq_XJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:43:03 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)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:43:03 INFO - Running setup.py (path:/tmp/pip-asfRtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:43:03 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)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:43:03 INFO - Running setup.py (path:/tmp/pip-M7wMnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:43:03 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)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:43:03 INFO - Running setup.py (path:/tmp/pip-7PzKko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:43:03 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)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:43:03 INFO - Running setup.py (path:/tmp/pip-7MRczF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:43:03 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)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:43:03 INFO - Running setup.py (path:/tmp/pip-ZOWskc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:43:03 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)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:43:03 INFO - Running setup.py (path:/tmp/pip-nnvNxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:43:03 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)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:43:03 INFO - Running setup.py (path:/tmp/pip-PuixXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:43:03 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)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:43:03 INFO - Running setup.py (path:/tmp/pip-lTs9Pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:43:03 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)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:43:03 INFO - Running setup.py (path:/tmp/pip-YHBEQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:43:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:03 INFO - Running setup.py (path:/tmp/pip-zARzMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:04 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)) 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:43:04 INFO - Running setup.py (path:/tmp/pip-fMiJ_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:43:04 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)) 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:43:04 INFO - Running setup.py (path:/tmp/pip-St73Bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:43:04 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)) 05:43:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:43:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:43:04 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)) 05:43:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:43:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:43:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:43:04 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 05:43:04 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 05:43:04 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 05:43:04 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 05:43:04 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 05:43:04 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 05:43:06 INFO - Downloading blessings-1.6.tar.gz 05:43:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:43:06 INFO - Installing collected packages: blessings 05:43:06 INFO - Running setup.py install for blessings 05:43:06 INFO - Successfully installed blessings 05:43:06 INFO - Cleaning up... 05:43:06 INFO - Return code: 0 05:43:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:43:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:43:06 INFO - Reading from file tmpfile_stdout 05:43:06 INFO - Current package versions: 05:43:06 INFO - argparse == 1.2.1 05:43:06 INFO - blessings == 1.6 05:43:06 INFO - blobuploader == 1.2.4 05:43:06 INFO - docopt == 0.6.1 05:43:06 INFO - functools32 == 3.2.3-2 05:43:06 INFO - jsonschema == 2.5.1 05:43:06 INFO - manifestparser == 1.1 05:43:06 INFO - mozInstall == 1.12 05:43:06 INFO - mozcrash == 0.17 05:43:06 INFO - mozdebug == 0.1 05:43:06 INFO - mozdevice == 0.48 05:43:06 INFO - mozfile == 1.2 05:43:06 INFO - mozhttpd == 0.7 05:43:06 INFO - mozinfo == 0.9 05:43:06 INFO - mozleak == 0.1 05:43:06 INFO - mozlog == 3.4 05:43:06 INFO - moznetwork == 0.27 05:43:06 INFO - mozprocess == 0.23 05:43:06 INFO - mozprofile == 0.28 05:43:06 INFO - mozrunner == 6.12 05:43:06 INFO - mozscreenshot == 0.1 05:43:06 INFO - mozsystemmonitor == 0.3 05:43:06 INFO - moztest == 0.7 05:43:06 INFO - mozversion == 1.4 05:43:06 INFO - psutil == 3.1.1 05:43:06 INFO - requests == 1.2.3 05:43:06 INFO - wsgiref == 0.1.2 05:43:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:06 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 0x7f2c59a94f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2954ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951f60>, '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 0x2952d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29531d0>, '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': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', '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 05:43:06 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 05:43:06 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 05:43:06 INFO - Using env: {'DISPLAY': ':0', 05:43:06 INFO - 'HOME': '/home/cltbld', 05:43:06 INFO - 'LANG': 'en_US.UTF-8', 05:43:06 INFO - 'LANGUAGE': 'en_US:en', 05:43:06 INFO - 'LOGNAME': 'cltbld', 05:43:06 INFO - 'MAIL': '/var/mail/cltbld', 05:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:06 INFO - 'MOZ_NO_REMOTE': '1', 05:43:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:06 INFO - 'NO_EM_RESTART': '1', 05:43:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:06 INFO - 'PWD': '/builds/slave/test', 05:43:06 INFO - 'SHELL': '/bin/bash', 05:43:06 INFO - 'SHLVL': '1', 05:43:06 INFO - 'TERM': 'linux', 05:43:06 INFO - 'TMOUT': '86400', 05:43:06 INFO - 'USER': 'cltbld', 05:43:06 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', 05:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:06 INFO - '_': '/tools/buildbot/bin/python'} 05:43:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:06 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)) 05:43:06 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)) 05:43:06 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)) 05:43:06 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)) 05:43:06 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)) 05:43:06 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)) 05:43:06 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)) 05:43:06 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)) 05:43:06 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)) 05:43:06 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)) 05:43:06 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)) 05:43:06 INFO - Cleaning up... 05:43:06 INFO - Return code: 0 05:43:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2c59a94f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2954ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2951f60>, '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 0x2952d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29531d0>, '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': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', '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 05:43: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', 'jsonschema'] in /builds/slave/test/build 05:43: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 jsonschema 05:43:06 INFO - Using env: {'DISPLAY': ':0', 05:43:06 INFO - 'HOME': '/home/cltbld', 05:43:06 INFO - 'LANG': 'en_US.UTF-8', 05:43:06 INFO - 'LANGUAGE': 'en_US:en', 05:43:06 INFO - 'LOGNAME': 'cltbld', 05:43:06 INFO - 'MAIL': '/var/mail/cltbld', 05:43:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:06 INFO - 'MOZ_NO_REMOTE': '1', 05:43:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:06 INFO - 'NO_EM_RESTART': '1', 05:43:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:06 INFO - 'PWD': '/builds/slave/test', 05:43:06 INFO - 'SHELL': '/bin/bash', 05:43:06 INFO - 'SHLVL': '1', 05:43:06 INFO - 'TERM': 'linux', 05:43:06 INFO - 'TMOUT': '86400', 05:43:06 INFO - 'USER': 'cltbld', 05:43:06 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', 05:43:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:06 INFO - '_': '/tools/buildbot/bin/python'} 05:43:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:43:07 INFO - Cleaning up... 05:43:07 INFO - Return code: 0 05:43:07 INFO - Running post-action listener: _resource_record_post_action 05:43:07 INFO - Running post-action listener: _start_resource_monitoring 05:43:07 INFO - Starting resource monitoring. 05:43:07 INFO - [mozharness: 2016-09-20 12:43:07.050332Z] Finished create-virtualenv step (success) 05:43:07 INFO - [mozharness: 2016-09-20 12:43:07.050772Z] Running install step. 05:43:07 INFO - Running pre-action listener: _resource_record_pre_action 05:43:07 INFO - Running main action method: install 05:43:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:43:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:43:07 INFO - Reading from file tmpfile_stdout 05:43:07 INFO - Detecting whether we're running mozinstall >=1.0... 05:43:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:43:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:43:07 INFO - Reading from file tmpfile_stdout 05:43:07 INFO - Output received: 05:43:07 INFO - Usage: mozinstall [options] installer 05:43:07 INFO - Options: 05:43:07 INFO - -h, --help show this help message and exit 05:43:07 INFO - -d DEST, --destination=DEST 05:43:07 INFO - Directory to install application into. [default: 05:43:07 INFO - "/builds/slave/test"] 05:43:07 INFO - --app=APP Application being installed. [default: firefox] 05:43:07 INFO - mkdir: /builds/slave/test/build/application 05:43:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:43:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:43:23 INFO - Reading from file tmpfile_stdout 05:43:23 INFO - Output received: 05:43:23 INFO - /builds/slave/test/build/application/firefox/firefox 05:43:23 INFO - Running post-action listener: _resource_record_post_action 05:43:23 INFO - [mozharness: 2016-09-20 12:43:23.308151Z] Finished install step (success) 05:43:23 INFO - [mozharness: 2016-09-20 12:43:23.308396Z] Running run-tests step. 05:43:23 INFO - Running pre-action listener: _resource_record_pre_action 05:43:23 INFO - Running main action method: run_tests 05:43:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:43:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:43:23 INFO - Python 2.7.3 05:43:23 INFO - Return code: 0 05:43:23 INFO - grabbing minidump binary from tooltool 05:43:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:43:23 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 0x2951f60>, '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 0x2952d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29531d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:43:23 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 05:43:23 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 05:43:23 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 05:43:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:43:23 INFO - Return code: 0 05:43:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:43:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:43:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:43:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:43:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:43:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 05:43:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --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 05:43:23 INFO - Using env: {'DISPLAY': ':0', 05:43:23 INFO - 'HOME': '/home/cltbld', 05:43:23 INFO - 'LANG': 'en_US.UTF-8', 05:43:23 INFO - 'LANGUAGE': 'en_US:en', 05:43:23 INFO - 'LOGNAME': 'cltbld', 05:43:23 INFO - 'MAIL': '/var/mail/cltbld', 05:43:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:43:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:43:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:23 INFO - 'MOZ_NO_REMOTE': '1', 05:43:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:43:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:23 INFO - 'NO_EM_RESTART': '1', 05:43:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:23 INFO - 'PWD': '/builds/slave/test', 05:43:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:43:23 INFO - 'SHELL': '/bin/bash', 05:43:23 INFO - 'SHLVL': '1', 05:43:23 INFO - 'TERM': 'linux', 05:43:23 INFO - 'TMOUT': '86400', 05:43:23 INFO - 'USER': 'cltbld', 05:43:23 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783', 05:43:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:23 INFO - '_': '/tools/buildbot/bin/python'} 05:43:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 05:43:23 INFO - mozversion application_buildid: 20160920024116 05:43:23 INFO - mozversion application_changeset: f16332940adb7f2b14de7379a1e8fe32a95a826a 05:43:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:43:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:43:23 INFO - mozversion application_name: Firefox 05:43:23 INFO - mozversion application_remotingname: firefox-dev 05:43:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:43:23 INFO - mozversion application_vendor: Mozilla 05:43:23 INFO - mozversion application_version: 51.0a2 05:43:23 INFO - mozversion platform_buildid: 20160920024116 05:43:23 INFO - mozversion platform_changeset: f16332940adb7f2b14de7379a1e8fe32a95a826a 05:43:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:43:23 INFO - mozversion platform_version: 51.0a2 05:43:23 INFO - using testdate: 1474375403 05:43:23 INFO - actual date: 1474375403 05:43:23 INFO - starting webserver on 'localhost:40385' 05:43:23 INFO - SUITE-START | Running 2 tests 05:43:23 INFO - TEST-START | dromaeo_css 05:43:23 INFO - operating with platform_type : linux_ 05:43:23 INFO - Initialising browser for dromaeo_css test... 05:43:23 INFO - TEST-INFO | started process 6471 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbpkNd8/profile http://localhost:40385/getInfo.html) 05:43:27 INFO - PROCESS | 6471 | __metrics Screen width/height:1600/1200 05:43:27 INFO - PROCESS | 6471 | colorDepth:24 05:43:27 INFO - PROCESS | 6471 | Browser inner width/height: 1024/697 05:43:27 INFO - PROCESS | 6471 | __metrics 05:43:28 INFO - PROCESS | 6471 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:43:28 INFO - PROCESS | 6471 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:43:28 INFO - PROCESS | 6471 | console.error: PushService: 05:43:28 INFO - PROCESS | 6471 | stateChangeProcessEnqueue: Error transitioning state 05:43:28 INFO - PROCESS | 6471 | UnknownError 05:43:29 INFO - TEST-INFO | 6471: exit 0 05:43:29 INFO - Browser initialized. 05:43:29 INFO - Running cycle 1/1 for dromaeo_css test... 05:43:29 INFO - TEST-INFO | started process 6559 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbpkNd8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:41 INFO - PROCESS | 6559 | RSS: Main: 144351232 05:43:41 INFO - PROCESS | 6559 | 05:43:44 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.13325 seconds. 05:43:51 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 2.00042 seconds. 05:43:51 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 2.00042 seconds. 05:43:58 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00029 seconds. 05:44:04 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 05:44:10 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00076 seconds. 05:44:27 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.0499 seconds. 05:44:32 INFO - PROCESS | 6559 | 1474375472883 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] 05:44:44 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.03415 seconds. 05:45:05 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.03325 seconds. 05:45:12 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.08366 seconds. 05:45:17 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.04976 seconds. 05:45:33 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.0329 seconds. 05:45:41 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.0164 seconds. 05:45:46 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.18288 seconds. 05:45:51 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.03304 seconds. 05:45:57 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.03379 seconds. 05:46:00 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:46:25 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 05:46:27 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01642 seconds. 05:46:29 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.04938 seconds. 05:46:34 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.0164 seconds. 05:46:36 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00024 seconds. 05:46:37 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.16645 seconds. 05:46:41 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:40385/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40385/tests/dromaeo/cssquery-ext.html) 05:46:42 INFO - PROCESS | 6559 | RSS: Main: 246677504 05:46:42 INFO - PROCESS | 6559 | 05:46:45 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 05:47:00 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 05:47:25 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 05:47:31 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00017 seconds. 05:47:51 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:47:54 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 05:48:13 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00019 seconds. 05:48:17 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:48:33 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 05:48:37 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00086 seconds. 05:48:40 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00025 seconds. 05:49:07 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01604 seconds. 05:49:09 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 05:49:12 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:49:14 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00079 seconds. 05:49:16 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01624 seconds. 05:49:44 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:40385/tests/dromaeo/cssquery-ext.html (next: http://localhost:40385/tests/dromaeo/cssquery-jquery.html) 05:49:45 INFO - PROCESS | 6559 | RSS: Main: 230412288 05:49:45 INFO - PROCESS | 6559 | 05:49:51 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00023 seconds. 05:49:57 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00057 seconds. 05:50:07 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00033 seconds. 05:50:56 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01663 seconds. 05:51:04 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01713 seconds. 05:51:18 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 05:51:20 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 05:51:56 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01736 seconds. 05:52:10 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 05:52:12 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00062 seconds. 05:52:22 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.0002 seconds. 05:52:42 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 05:52:47 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:40385/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40385/tests/dromaeo/cssquery-mootools.html) 05:52:47 INFO - PROCESS | 6559 | RSS: Main: 224079872 05:52:47 INFO - PROCESS | 6559 | 05:52:51 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01717 seconds. 05:52:53 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.03364 seconds. 05:53:12 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 05:53:28 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 05:53:36 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 05:53:38 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:54:31 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00022 seconds. 05:54:57 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00022 seconds. 05:55:01 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 05:55:31 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00039 seconds. 05:55:49 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01646 seconds. 05:55:49 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:40385/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40385/tests/dromaeo/cssquery-prototype.html) 05:55:50 INFO - PROCESS | 6559 | RSS: Main: 201125888 05:55:50 INFO - PROCESS | 6559 | 05:56:01 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01725 seconds. 05:56:23 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00013 seconds. 05:56:35 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00045 seconds. 05:56:39 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 05:56:49 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.0004 seconds. 05:56:51 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00043 seconds. 05:57:01 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 05:57:37 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01616 seconds. 05:57:44 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.0005 seconds. 05:57:46 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 05:57:53 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00012 seconds. 05:57:54 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01631 seconds. 05:58:00 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00086 seconds. 05:58:08 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00021 seconds. 05:58:10 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:58:20 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00068 seconds. 05:58:33 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 05:58:38 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00019 seconds. 05:58:40 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.0168 seconds. 05:58:53 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01644 seconds. 05:58:53 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:40385/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40385/tests/dromaeo/cssquery-yui.html) 05:58:53 INFO - PROCESS | 6559 | RSS: Main: 173789184 05:58:53 INFO - PROCESS | 6559 | 05:58:56 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01658 seconds. 05:59:08 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00032 seconds. 05:59:20 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00053 seconds. 05:59:34 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:59:42 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 05:59:54 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00056 seconds. 06:00:00 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00038 seconds. 06:00:20 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00039 seconds. 06:00:26 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00033 seconds. 06:00:43 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:00:47 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00041 seconds. 06:00:57 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00037 seconds. 06:01:01 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01738 seconds. 06:01:11 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00044 seconds. 06:01:27 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.00016 seconds. 06:01:51 INFO - PROCESS | 6559 | [GFX1-]: Refresh driver waiting for the compositor for 1.01662 seconds. 06:01:56 INFO - PROCESS | 6559 | Cycle 1(1): loaded http://localhost:40385/tests/dromaeo/cssquery-yui.html (next: http://localhost:40385/tests/dromaeo/cssquery-dojo.html) 06:01:56 INFO - PROCESS | 6559 | RSS: Main: 268779520 06:01:56 INFO - PROCESS | 6559 | 06:01:56 INFO - PROCESS | 6559 | __start_tp_report 06:01:56 INFO - PROCESS | 6559 | _x_x_mozilla_page_load 06:01:56 INFO - PROCESS | 6559 | _x_x_mozilla_page_load_details 06:01:56 INFO - PROCESS | 6559 | |i|pagename|runs| 06:01:56 INFO - PROCESS | 6559 | |0;dojo.html;3079.2301924518865;3123.5158550424685;3159.3681263747244;3185.601799775028;3201.519772034196;5630.971845140773;5702.629329093606;5706.914396284059;5708.514776244019;5732.54139668826;5738.56960727945;5802.477777000069;5809.651420914748;5810.215620891178;5815.360310365867;5178.249153872686;5199.064168449678;5219;5221.477852214786;5244.973775131119;5035.521625445577;5040.344755181821;5137.229415587654;5293.735313234342;5340.332458442695;5171;5189.506996835314;5256.316678831766;5277.551408130314;5283.471652834705;5273.709945952971;5278.656887302313;5281.339832520935;5283.207518872162;5286.920696189561;4910.459849416561;4999.400071991385;5169.327987361657;5176.534111929945;5232.502912223333;5536;5569.498745112959;5592.552595792326;5593.161025846115;5595.664260144405;6045.939540604581;6064.332923378425;6095.695215239265;6138.47822935051;6142.170806941051;5871.1193321001765;6151.907721384183;6174.073888916699;6174.351693072208;6188.8762224755255;5537.556995440395;5590.888182236373;5648.830535083954;5650.971745141289;5677.37548869624;6213.689315534205;6232.750689972441;6242.812715618538;6245.6252624842655;6246.562740608205;5635.915461268084;5987.520998320124;5993.730282137227;5994.670293133832;6000.0999850022845;5699.031164702011;6108.335197752735;6130.856783744145;6133.977404287557;6165.198524191826;6119.389560159527;6130.941095466627;6131.87526527353;6132.146101400496;6138.457541419318;6118.497354392036;6136.304638670889;6139.439125307311;6139.74593465464;6142.713648198282;4501.932471012938;4536.228841097021;4536.432945881765;4544.840930567414;4565.452145742465;4074.959250407458;4080.9183816323507;4092.5498195198506;4113.321301985199;4118.361653943643;5690.829275121753;5724.141378793214;5729.312482502127;5734.168545560954;5758.006230529595;5891.263592050994;5941.702914854326;6039.818805435844;6090.116933044684;6104.908426373608;5841.240638716939;5850.1224816277045;5856.707164641751;5864.179014937912;5879.265091863517;5143.819966301162;5148.33071700684;5157.0975079361715;5164.457731938168;5172.146595811724;5979.5814292999075;6014.639121652627;6185.319614842454;6363.395477429637;6369.044643303541;6063.636181829104;6148.938510614837;6159.84600384994;6163.9383606163365;6172.753089876354;6270.12218289449;6326.905096423466;6337.8732425352155;6338.366163383625;6340.524460665376;5750.856228594319;5829.679367634821;5987.730552125252;6050.788222412194;6055.939440605537;6002.519798416029;6029.57792954489;6041.6375017499095;6109.586862300737;6141.201643786279;5890.1164825276555;5926.348101708895;5937.406259374028;5937.524998000236;5954.136650185612;5692.430756924275;5935.228420305332;5935.821925342247;5940.316863560746;5953.553483488842;5757.8560535986935;5896.439838215363;5897.5576831736935;5953.255843019622;6046.516278697605;6087.66517841523;6089.238845144357;6093.817185484442;6100.603460775035;6106.816795496142;5583;5650.039493286069;5883.646981181143;5901.822945311647;5906.202662640489 06:01:56 INFO - PROCESS | 6559 | |1;ext.html;591.0602142593278;622.7602373086361;644.6228956060708;656.2223764838667;659.297847792101;33591.160220994454;45763.31355029673;46444;46512.302315465255;50148.24777628329;48769.51230487694;49061.754691226495;49100.50899491013;49216.26175607369;49235.50764492354;8448.83102337953;9320.627174912996;9457;9465;9479.526023698822;8217.876731849026;11064.2808217466;11329.697084835227;11339.37633430161;11789.233699809527;11469.254498457616;11526.884731152664;11532.308061516336;11535.307881527135;11538.826917596201;11216.158788090885;11244.381559014251;11291.322520648788;11300;11301.886981130167;11241.437928103562;11281.605143819927;11295.152863535268;11307.773844523064;11313.547458101666;45676.77161614204;46111.30833037507;46154.307685384745;46224.3066354005;46248;3774.1508160663907;3837.9424308635394;3843.4811300474357;3844.2888065707652;3894.7468414552964;34926.476102858476;35462.468062979075;35755.82122089399;36791.63208367909;37030.62969370326;4778.1638213312535;4876.292937524075;4884.975575122102;4906.288588154697;5062.0888240116765;278698;279682;280485;283120;284108;67180;67193;67400.6629966847;67401;67527.32472675211;43664.781676091414;44604;44636.10727785491;44654.77672611681;44819.32771008438;19452.22191112368;19558.21767129299;19587.80412195888;19608.411747647595;19628.803711962697;13674.111182773087;13947.093438926437;14610.26948652563;14901.552953411416;15010.47463338778;20659.380218593167;21669.133234670753;21857.234996775038;21887.67168492474;21900.671489927663;7389.482736208413;7476.738314158978;7522.749646449738;7773.18381569927;7789.415793815373;1009.7728011197569;1043.9582416703247;1103.7461383881753;1147.9827802582968;1158.7566611011614;34506.309873802384;35432.291354172776;35547.64452355443;35639.555517787805;35715.464268036;1128.1482480727163;1145.3643228008368;1146.4898120336536;1149.3391300002531;1182.9231099978474;18349;18350.908245458955;18491;18634.906825466056;18663.346782862536;592652;592887;595282;598460;599473;65727;66397;66456.6677166611;66463.66768166129;66483.33516664869;1299.4867027524265;1356.85752995936;1381.9861801382058;1416.7874818777266;1421.587739555538;31644.5253321205;36049.63950360568;36488;36669.08327291839;36800;56163.438365617454;56404.43595563992;56695.433045669015;56831.715841422185;56869.71565142148;328628;332522;333018;333030;333340;31317.373652527276;32345.19137021499;32487.83756081299;33977.49033764446;33992.83003584966;52906.470935290155;53428;53471.465285348204;53672.46327536675;53771.46228537665;43725.067815541675;45012.08214868744;45353;45994;47125.29312060391;44736.77631611821;45482.54517454783;45594.316085258084;45597.77201113973;46763;45295.32057019213;46272.7686361566;46327.76836115798;46360.07279854451;46959.06081878411;37553.6244637561;41034.38448423216;41307.7934610325;41381.37927931023;41758;29514.557281640362;30444.847775761864;30626.540601890545;31789.68210317867;32501 06:01:56 INFO - PROCESS | 6559 | |2;jquery.html;396.79366729300745;414.4694790667944;428.7256156060121;430.8965848196433;443.64286749166905;15361.53915382539;19571.02144892755;20468.181272749072;20478.2832600859;20596.691049634268;19764.505887352825;20426.182952681876;20999.47501312468;21002;21049.894750526226;5940.19807326012;5959.344472108076;6189.257289125311;6195.31851496335;6202.565820392574;5995.100734889779;6053.606515576495;6096.634201947897;6097;6105.32841387447;5840.3575606683235;5972.432618901197;6074.878502429949;6075.483583895374;6087.756489740405;5993.670348130851;6021.909671354933;6057.787977420813;6061.575689701723;6062.272527296708;5314.548263397616;5408.188771684239;5433.483819037185;5441.401445840955;5452.4274951129955;22705.432364190863;23455.4136146596;23601.763982360128;24120.155794547107;24302.756972430227;2337.5558643857785;2422.818288628342;2479.1199124310792;2489.8381605195605;2579.5227882841546;11142.832857507145;11223.887761122447;12793.29636869981;15032.473863414838;15557.92221038899;1961.117497126299;1968.8031196880197;2019.0611365715013;2029.4621925189836;2066.8036536529007;84410.57794711049;87577;88498;88687;90536.54731726366;9953.25350598708;10125.797484050277;10256.282060255711;10687.519061642244;10730.624428144978;13948.2328471933;14317.85682143186;14871.33079011447;15770.68458630844;15952.76070858938;8728;9033.277337812959;9064.728058158265;9113.81772364549;9141.451512909247;9087.591423329395;9110.908890911138;9128.771780705536;9199.770005749777;9363.625454981724;5108.2848401223855;5200.947990520046;5224.346956630421;5249.265102885599;5325.121354976385;2071.78246284141;2129.3572034109734;2131.5736852629534;2164.0910817456706;2243.743503637968;906.4425378392241;966.463612694947;967.6274634265858;1000.6697789729371;1053.167997282154;20517.281895133598;20689.58620827575;20817.271395501084;20819.791802082087;20825.271115511187;1015.2182819229153;1028.388109075099;1052.2791887557048;1079.875814281352;1081.8431327457474;10578.682639520826;11419.771604567863;11499;11624.186306958432;11648.825267620992;519353;521700;523637;529738;533791;97099;99556.50221748845;99691.50154249182;101070.4946475263;101905.49047254716;1030.314840630972;1054.4674939155677;1059.6344261229863;1060.2366296266675;1071.9571217151226;17510.474685759447;17940.372086976895;18068.90965545164;18506;18614.720779188054;32830.507542387364;46680;47148;47291.763541182074;48127.518724812304;63706.681466592374;64132;64398.35601643923;65317.67341163264;65980.6700966492;8909.509976951329;9086.182243598108;9156.496392698466;9183.403078799856;9379.624815007597;10136.44249566281;13085.738285234433;13416.39626216842;13775.586732398044;13995.860041399455;11858.822117668069;11920.225185362924;12192.695182620506;12259.632211033684;12347.19743216688;11813;11951.820722689341;12074.335911524951;12133;12150.939245303716;11815.704607384883;11980.64058078259;12011.279323240633;12101.576444824388;12887.6778080545;7964.800879978047;8566.57337065179;8904.287656987295;9322.114399132071;9639.71080867575;9673.177779888789;11480.344470137961;11552.133589980616;11712.824307635221;11900.821487677866 06:01:56 INFO - PROCESS | 6559 | |3;mootools.html;1427.4932398998362;1536.677297767469;1586.0800735573368;1729.2391347806963;1872.7378167056615;8057.395695322845;8867.512286824222;9101;9109;9432.434053956767;9197.678081267157;9275.768105797359;9276.53617319133;9295.90704092959;9297.628094876196;2425.1390756281485;2461.10169788027;2462.039804476247;2463.7043554773363;2463.7659422354845;2414.9758502414925;2465.716442609105;2468.1978357034036;2468.3705655058025;2471.060996821205;2413.79482743967;2429.7813196812285;2459.0655550890638;2462.8768561571937;2462.901483940649;2372.0630351011255;2388.9641655375185;2393.3179043972386;2398.424378149249;2400.4238982644215;2266.3880752196815;2328.860268383902;2332.4635333873302;2334.8949297281665;2338.8362814602983;9466.952665236699;9595.952020239854;9831;9831.115199631997;9835.114839664393;5429.8099566515375;5594.608377413582;5603.327600687943;5605.187247849039;5606.579506537026;3639.181184233553;3669.981650091759;3684.1342284563107;3756.154865155318;3776;1261.5521489871146;1264.6522206393224;1279.6864768131775;1292.9741405171937;1316.2760481735008;13598.38807253676;13711.794323085162;13717.931410342984;13735.45058197681;13763.587092387244;4730.172219861511;4788.401449818773;4795.040991801654;4856.587190088811;5042.243663450438;10697.732556686145;10702.304350217211;10714.142868570652;10735.94632026835;10742.462876856125;5584.5811564132855;5591.4688104629995;5594.804181853697;5597.188407680864;5597.720113994284;4400.471943366816;4543.795529201194;5126.5898728101665;5209.4009188943;5251;4171.058126354702;4204.957950420518;4242.469691288589;4295.3771703102875;4335.1329734053315;1196.126827415991;1206.5354838387284;1219.4451524556305;1220.334917469981;1228.103484456352;920.40173886974;996.1433167475965;1017.6183931025865;1059.8410238464292;1068.3376306689902;6102.084687296941;6393.104965304861;6431.292557818636;6590.275069742324;6662.400383965466;512.8102602037195;519.828456609318;525.4561528817655;1073.6939972107912;1077.9622713205001;4079.73481723687;4217.493900731871;4284.143171365739;4335.328024156261;4359.215341238608;12137.453814578364;12371.13402061865;12462;12474.625761227178;12503.062270329761;12347.073969452327;12353.197042192229;12361.07291953107;12378.133530652767;12379.071569632313;8598.097199794056;8934.24058954983;8937.329700272376;9047.140521650565;9131.497767622845;6162.383761623802;6276.466500347339;6772.11962444879;6797.0823938767635;6829.93170068293;11078.335299882034;11214.55141794319;11359.54561817518;11376.43117844088;11449.255798373079;11327.660170194908;11327.71680707956;11585.536578536765;11611.477483513094;11645.708857278636;7679.731209407644;7857.607119643881;8561.957190214009;8712.825743485222;8869.866951995855;9278.443293402148;9405.482698451377;9581.233501320017;9608.27937904646;9616.182624476998;8340.374471914509;8395.202455766692;8430.915690843012;8546.957265213634;8832.5583720815;8287.95856020716;8348.66605335804;8383.916160838313;8389.203025712548;8516.914830851612;7327.194008658937;7358.0434543509;7426.665800038903;7431;7435.92564074374;5467.20725494809;5469.72651367438;5483.917741233964;5577.498025177753;5683.71581420919;5363.597730170175;5462.672239665632;5516.393196748435;5517.586181036358;5579.163125531203 06:01:56 INFO - PROCESS | 6559 | |4;prototype.html;344.4454428370323;354.5993027878497;357.31217406492493;358.57687928244667;361.5300109857183;5302.840914772553;5439.864003399917;5486.780528778854;5487.9451205487985;5502.2296878437;5279.947200527993;5442.374126975399;5474.698891560963;5484.780608775654;5484.945150548493;4357.106793107421;4371.486395322686;4373.059792144688;4374.934375984363;4387.758673272969;4196.412502249687;4323.978380108095;4350.390945267665;4368.563143685637;4370.956290437102;4277.187334406468;4283.657307415399;4295.226859165349;4320.783960801963;4325.82696692132;4285.228658841407;4292.613664770154;4298.548652391516;4322.805473753689;4326.286162783168;3667.541557305337;3778.414345776409;3798.3163030654473;3799.202167544819;3809.3524100902914;5313.893722125575;5319.867003324948;5327.786888524493;5329.987302412568;5387.73061346935;4948.228991764623;4948.505149485059;4949.158643030693;4949.876253093666;4955.628327875388;5566.499015088621;5645.661260324353;5707.828765137053;5724.141378793172;5725.398833122504;4165.729452516981;4174.478190226222;4175.436316097318;4176.310908700061;4182.519010313792;5839.562032847511;5916.792912248051;5949.375315591887;5956.22569066023;5986.2217911671205;4218.156368726267;4235.23765722173;4235.97882010588;4248.660107191417;4250.107477429774;4295.591918767712;4309.267424537836;4309.978450107792;4314.158739045919;4329.956700432956;4898.926516102261;4908.214685650279;4910.631702622318;4926.162552366106;4926.768307923019;5476.67139971603;5547.278853748987;5728.570357223225;5752.654840709571;5773.422657734193;1417.9578010162522;1445.9878085340304;1476.645605054779;1484.940602375893;1491.903450963541;993.6373223773271;1014.4268488304084;1025.1491262252312;1027.7276521721749;1031.118393773328;5498.835034948958;5548.667079975215;5565.354692676304;5566.638168519034;5581.469760372839;5865.325487568899;5871.853203669943;5884.617499862496;5885.352611212848;5897.378220989176;5368.651037682538;5542.473465020817;5570.387257401682;5636.746346414421;5661.461586508432;3115.8442077896016;3118.3607360491046;3130.154858188276;3131.3111115555;3132.436161490908;5862.8534286642325;6076.392360763888;6106.694665266719;6118.541109416811;6133.202683651097;6034.969825150934;6085.908711369333;6089.786857460056;6090.786822461193;6091.725872335755;6122.908156377658;6128.17269668598;6144.385561443909;6156.33778879691;6162.414570615695;5636.689982051026;5897.989367230324;5969.432903874125;5977.312609049841;5982.192404025401;5893.882122357614;5908.586398952032;5917.419960923579;5927.170196172453;5927.2590926134235;5874.412558744091;6115.602485838406;6171.9074213887725;6178.1350610913605;6195.256569211723;4300.204462174508;4308.48298204205;4312.180685669713;4322.832835134559;4329.7835108243835;5697.914531282117;5845.912311315248;5976.163337132892;5980.671063091571;6019.638821670714;5620.971895140498;5666.320041595035;5720.313562372542;5782.913256301074;5826.854328641818;5773.451522105393;5814.912776308274;5816.243888294494;5823.4467725566;5827.24245848037;5576.498115169654;5710.885782284248;5768.711564421678;5775.27809023872;5776.277965254343;5834.241548598825;5841.474267315962;5847.239858818326;5854.268216472941;5874.324452687995;5422.322209723785;5658.405867383865;5667.319921609268;5667.4332566743;5669.461401166883 06:01:56 INFO - PROCESS | 6559 | |5;yui.html;833.2167762303434;844.6283635200512;888.5024386343647;899.304676682102;925.0980294213143;15104;16024.43914462994;16132.83867161328;16199.595010124738;16250.106244156552;14621.049631773927;14627.561173164795;14632.195229262386;14633.707325853477;14649.414023439049;724.4457989637931;738.3170567225322;851.4848516647417;854.2738672128703;868.3574155125193;861.7673228228375;864.5245115186653;864.6238886084542;864.6368525219392;869.0961400143843;905.4341036851968;913.1370854542427;914.4970266353512;915.199200699388;916.3631276263;760.2207737069492;781.2851241114402;911.5761171055425;913.310450609792;915.5239275576696;681.58082779091;686.327399148832;686.6669665212368;686.7596341280562;687.5805758487319;15455.53633391;15466.690666186612;15508.689826203525;15514.844851551454;15528.068315901082;15101.5469535913;15362.308696108701;15397.923010384988;15536.689266214726;16026.839731602651;14221.431142754278;14278.785818212735;14374.496892608708;14453.638659033502;14475.565733028026;4676.883077923079;4687.601553867926;4691.319758635013;4741.668083234175;4788.832390866338;86361.56819215864;86441;87586;88927;89578.5521072397;11788.88211117895;11980.760384792255;12191.390430478441;12215.694607634703;12538.623841284776;23690.933907974184;23720.288391348287;23749.762502375102;24126.638100428507;24387;8739.825203496022;8740.475571465733;8791.472511649321;8815.867761983576;9027.347640590871;4756.619470442356;4779.545943135397;4782.402199725035;4912.4350699669285;4956.405231372259;3690.501782259414;3789.9242015160094;3800.4489349044234;3801.657850793442;3802.752821066622;594.4085634793408;598.9847208980735;602.2592211579781;603.7826382502295;617.6633734614646;644.9806505804834;681.8261343357412;722.887952367384;772.6638912073266;785.1089013969186;9350.251979841596;9829;9897.950510247547;9915.900840991642;9932.205423566098;710.74057968841;753.4688044928313;755.9659815308214;763.5151678684076;788.5781107107784;4422.75674837887;4593.701409408378;4705.95294047062;4709.434867815884;4777.355057067321;125945.37027314988;133746;134253;137801;138058;112618.43690781658;114015;114704;114849;119416;97479;98694;99168.50415747875;99243.00756992483;102195;9351.766205844908;9421.81156376855;9446;9447.858282125637;9459.195968342769;54215.18677219924;54914.72542637261;55440.72279638576;55783.44216557945;56558;82869.17130828614;84954;85007;85748.57125714331;88120;13461.730765384833;13663.521776737767;13743;13747.587572372844;13942.372593233125;13175.802362964754;13206.339683016004;13556.59330220095;13638.318084095954;13848.653783655489;13524;13745.175289482662;13905.304734763424;13922.860771392561;14299.714005720036;13658.795118073038;13996.230207338695;14687.706245875237;14715.337809798371;14885.627859303604;12501.124921255425;12771.872281277068;13377.866221337663;13402.865971340552;13445.058845881085;10589.258751887293;10922.12622990143;10938.289011214245;11415.657530274106;11430.428478575872;9753.853692194481;10040.497975101362;10581.576736930436;10660.307080039773;10972.670819875417 06:01:56 INFO - PROCESS | 6559 | __end_tp_report 06:01:56 INFO - PROCESS | 6559 | __start_cc_report 06:01:56 INFO - PROCESS | 6559 | _x_x_mozilla_cycle_collect,907 06:01:56 INFO - PROCESS | 6559 | __end_cc_report 06:01:56 INFO - PROCESS | 6559 | __startTimestamp1474376516477__endTimestamp 06:01:56 INFO - PROCESS | 6559 | 06:01:56 INFO - PROCESS | 6559 | ------- Summary: start ------- 06:01:56 INFO - PROCESS | 6559 | Number of tests: 6 06:01:56 INFO - PROCESS | 6559 | 06:01:56 INFO - PROCESS | 6559 | [#0] dojo.html Cycles:165 Average:5641.36 Median:5871.12 stddev:669.51 (11.4%) stddev-sans-first:640.68 06:01:56 INFO - PROCESS | 6559 | Values: 3079.2 3123.5 3159.4 3185.6 3201.5 5631.0 5702.6 5706.9 5708.5 5732.5 5738.6 5802.5 5809.7 5810.2 5815.4 5178.2 5199.1 5219.0 5221.5 5245.0 5035.5 5040.3 5137.2 5293.7 5340.3 5171.0 5189.5 5256.3 5277.6 5283.5 5273.7 5278.7 5281.3 5283.2 5286.9 4910.5 4999.4 5169.3 5176.5 5232.5 5536.0 5569.5 5592.6 5593.2 5595.7 6045.9 6064.3 6095.7 6138.5 6142.2 5871.1 6151.9 6174.1 6174.4 6188.9 5537.6 5590.9 5648.8 5651.0 5677.4 6213.7 6232.8 6242.8 6245.6 6246.6 5635.9 5987.5 5993.7 5994.7 6000.1 5699.0 6108.3 6130.9 6134.0 6165.2 6119.4 6130.9 6131.9 6132.1 6138.5 6118.5 6136.3 6139.4 6139.7 6142.7 4501.9 4536.2 4536.4 4544.8 4565.5 4075.0 4080.9 4092.5 4113.3 4118.4 5690.8 5724.1 5729.3 5734.2 5758.0 5891.3 5941.7 6039.8 6090.1 6104.9 5841.2 5850.1 5856.7 5864.2 5879.3 5143.8 5148.3 5157.1 5164.5 5172.1 5979.6 6014.6 6185.3 6363.4 6369.0 6063.6 6148.9 6159.8 6163.9 6172.8 6270.1 6326.9 6337.9 6338.4 6340.5 5750.9 5829.7 5987.7 6050.8 6055.9 6002.5 6029.6 6041.6 6109.6 6141.2 5890.1 5926.3 5937.4 5937.5 5954.1 5692.4 5935.2 5935.8 5940.3 5953.6 5757.9 5896.4 5897.6 5953.3 6046.5 6087.7 6089.2 6093.8 6100.6 6106.8 5583.0 5650.0 5883.6 5901.8 5906.2 06:01:56 INFO - PROCESS | 6559 | 06:01:56 INFO - PROCESS | 6559 | [#1] ext.html Cycles:180 Average:59284.59 Median:20663.89 stddev:113006.37 (546.9%) stddev-sans-first:113237.46 06:01:56 INFO - PROCESS | 6559 | Values: 591.1 622.8 644.6 656.2 659.3 33591.2 45763.3 46444.0 46512.3 50148.2 48769.5 49061.8 49100.5 49216.3 49235.5 8448.8 9320.6 9457.0 9465.0 9479.5 8217.9 11064.3 11329.7 11339.4 11789.2 11469.3 11526.9 11532.3 11535.3 11538.8 11216.2 11244.4 11291.3 11300.0 11301.9 11241.4 11281.6 11295.2 11307.8 11313.5 45676.8 46111.3 46154.3 46224.3 46248.0 3774.2 3837.9 3843.5 3844.3 3894.7 34926.5 35462.5 35755.8 36791.6 37030.6 4778.2 4876.3 4885.0 4906.3 5062.1 278698.0 279682.0 280485.0 283120.0 284108.0 67180.0 67193.0 67400.7 67401.0 67527.3 43664.8 44604.0 44636.1 44654.8 44819.3 19452.2 19558.2 19587.8 19608.4 19628.8 13674.1 13947.1 14610.3 14901.6 15010.5 20659.4 21669.1 21857.2 21887.7 21900.7 7389.5 7476.7 7522.7 7773.2 7789.4 1009.8 1044.0 1103.7 1148.0 1158.8 34506.3 35432.3 35547.6 35639.6 35715.5 1128.1 1145.4 1146.5 1149.3 1182.9 18349.0 18350.9 18491.0 18634.9 18663.3 592652.0 592887.0 595282.0 598460.0 599473.0 65727.0 66397.0 66456.7 66463.7 66483.3 1299.5 1356.9 1382.0 1416.8 1421.6 31644.5 36049.6 36488.0 36669.1 36800.0 56163.4 56404.4 56695.4 56831.7 56869.7 328628.0 332522.0 333018.0 333030.0 333340.0 31317.4 32345.2 32487.8 33977.5 33992.8 52906.5 53428.0 53471.5 53672.5 53771.5 43725.1 45012.1 45353.0 45994.0 47125.3 44736.8 45482.5 45594.3 45597.8 46763.0 45295.3 46272.8 46327.8 46360.1 46959.1 37553.6 41034.4 41307.8 41381.4 41758.0 29514.6 30444.8 30626.5 31789.7 32501.0 06:01:56 INFO - PROCESS | 6559 | 06:01:56 INFO - PROCESS | 6559 | [#2] jquery.html Cycles:180 Average:31013.35 Median:23860.96 stddev:86788.49 (363.7%) stddev-sans-first:87001.51 06:01:56 INFO - PROCESS | 6559 | Values: 396.8 414.5 428.7 430.9 443.6 15361.5 19571.0 20468.2 20478.3 20596.7 19764.5 20426.2 20999.5 21002.0 21049.9 5940.2 5959.3 6189.3 6195.3 6202.6 5995.1 6053.6 6096.6 6097.0 6105.3 5840.4 5972.4 6074.9 6075.5 6087.8 5993.7 6021.9 6057.8 6061.6 6062.3 5314.5 5408.2 5433.5 5441.4 5452.4 22705.4 23455.4 23601.8 24120.2 24302.8 2337.6 2422.8 2479.1 2489.8 2579.5 11142.8 11223.9 12793.3 15032.5 15557.9 1961.1 1968.8 2019.1 2029.5 2066.8 84410.6 87577.0 88498.0 88687.0 90536.5 9953.3 10125.8 10256.3 10687.5 10730.6 13948.2 14317.9 14871.3 15770.7 15952.8 8728.0 9033.3 9064.7 9113.8 9141.5 9087.6 9110.9 9128.8 9199.8 9363.6 5108.3 5200.9 5224.3 5249.3 5325.1 2071.8 2129.4 2131.6 2164.1 2243.7 906.4 966.5 967.6 1000.7 1053.2 20517.3 20689.6 20817.3 20819.8 20825.3 1015.2 1028.4 1052.3 1079.9 1081.8 10578.7 11419.8 11499.0 11624.2 11648.8 519353.0 521700.0 523637.0 529738.0 533791.0 97099.0 99556.5 99691.5 101070.5 101905.5 1030.3 1054.5 1059.6 1060.2 1072.0 17510.5 17940.4 18068.9 18506.0 18614.7 32830.5 46680.0 47148.0 47291.8 48127.5 63706.7 64132.0 64398.4 65317.7 65980.7 8909.5 9086.2 9156.5 9183.4 9379.6 10136.4 13085.7 13416.4 13775.6 13995.9 11858.8 11920.2 12192.7 12259.6 12347.2 11813.0 11951.8 12074.3 12133.0 12150.9 11815.7 11980.6 12011.3 12101.6 12887.7 7964.8 8566.6 8904.3 9322.1 9639.7 9673.2 11480.3 11552.1 11712.8 11900.8 06:01:56 INFO - PROCESS | 6559 | 06:01:56 INFO - PROCESS | 6559 | [#3] mootools.html Cycles:180 Average:6278.33 Median:4472.13 stddev:3695.75 (82.6%) stddev-sans-first:3688.14 06:01:56 INFO - PROCESS | 6559 | Values: 1427.5 1536.7 1586.1 1729.2 1872.7 8057.4 8867.5 9101.0 9109.0 9432.4 9197.7 9275.8 9276.5 9295.9 9297.6 2425.1 2461.1 2462.0 2463.7 2463.8 2415.0 2465.7 2468.2 2468.4 2471.1 2413.8 2429.8 2459.1 2462.9 2462.9 2372.1 2389.0 2393.3 2398.4 2400.4 2266.4 2328.9 2332.5 2334.9 2338.8 9467.0 9596.0 9831.0 9831.1 9835.1 5429.8 5594.6 5603.3 5605.2 5606.6 3639.2 3670.0 3684.1 3756.2 3776.0 1261.6 1264.7 1279.7 1293.0 1316.3 13598.4 13711.8 13717.9 13735.5 13763.6 4730.2 4788.4 4795.0 4856.6 5042.2 10697.7 10702.3 10714.1 10735.9 10742.5 5584.6 5591.5 5594.8 5597.2 5597.7 4400.5 4543.8 5126.6 5209.4 5251.0 4171.1 4205.0 4242.5 4295.4 4335.1 1196.1 1206.5 1219.4 1220.3 1228.1 920.4 996.1 1017.6 1059.8 1068.3 6102.1 6393.1 6431.3 6590.3 6662.4 512.8 519.8 525.5 1073.7 1078.0 4079.7 4217.5 4284.1 4335.3 4359.2 12137.5 12371.1 12462.0 12474.6 12503.1 12347.1 12353.2 12361.1 12378.1 12379.1 8598.1 8934.2 8937.3 9047.1 9131.5 6162.4 6276.5 6772.1 6797.1 6829.9 11078.3 11214.6 11359.5 11376.4 11449.3 11327.7 11327.7 11585.5 11611.5 11645.7 7679.7 7857.6 8562.0 8712.8 8869.9 9278.4 9405.5 9581.2 9608.3 9616.2 8340.4 8395.2 8430.9 8547.0 8832.6 8288.0 8348.7 8383.9 8389.2 8516.9 7327.2 7358.0 7426.7 7431.0 7435.9 5467.2 5469.7 5483.9 5577.5 5683.7 5363.6 5462.7 5516.4 5517.6 5579.2 06:01:56 INFO - PROCESS | 6559 | 06:01:56 INFO - PROCESS | 6559 | [#4] prototype.html Cycles:180 Average:4867.27 Median:5493.39 stddev:1420.19 (25.9%) stddev-sans-first:1383.01 06:01:56 INFO - PROCESS | 6559 | Values: 344.4 354.6 357.3 358.6 361.5 5302.8 5439.9 5486.8 5487.9 5502.2 5279.9 5442.4 5474.7 5484.8 5484.9 4357.1 4371.5 4373.1 4374.9 4387.8 4196.4 4324.0 4350.4 4368.6 4371.0 4277.2 4283.7 4295.2 4320.8 4325.8 4285.2 4292.6 4298.5 4322.8 4326.3 3667.5 3778.4 3798.3 3799.2 3809.4 5313.9 5319.9 5327.8 5330.0 5387.7 4948.2 4948.5 4949.2 4949.9 4955.6 5566.5 5645.7 5707.8 5724.1 5725.4 4165.7 4174.5 4175.4 4176.3 4182.5 5839.6 5916.8 5949.4 5956.2 5986.2 4218.2 4235.2 4236.0 4248.7 4250.1 4295.6 4309.3 4310.0 4314.2 4330.0 4898.9 4908.2 4910.6 4926.2 4926.8 5476.7 5547.3 5728.6 5752.7 5773.4 1418.0 1446.0 1476.6 1484.9 1491.9 993.6 1014.4 1025.1 1027.7 1031.1 5498.8 5548.7 5565.4 5566.6 5581.5 5865.3 5871.9 5884.6 5885.4 5897.4 5368.7 5542.5 5570.4 5636.7 5661.5 3115.8 3118.4 3130.2 3131.3 3132.4 5862.9 6076.4 6106.7 6118.5 6133.2 6035.0 6085.9 6089.8 6090.8 6091.7 6122.9 6128.2 6144.4 6156.3 6162.4 5636.7 5898.0 5969.4 5977.3 5982.2 5893.9 5908.6 5917.4 5927.2 5927.3 5874.4 6115.6 6171.9 6178.1 6195.3 4300.2 4308.5 4312.2 4322.8 4329.8 5697.9 5845.9 5976.2 5980.7 6019.6 5621.0 5666.3 5720.3 5782.9 5826.9 5773.5 5814.9 5816.2 5823.4 5827.2 5576.5 5710.9 5768.7 5775.3 5776.3 5834.2 5841.5 5847.2 5854.3 5874.3 5422.3 5658.4 5667.3 5667.4 5669.5 06:01:56 INFO - PROCESS | 6559 | 06:01:56 INFO - PROCESS | 6559 | [#5] yui.html Cycles:180 Average:23112.48 Median:4707.69 stddev:34787.27 (738.9%) stddev-sans-first:34844.64 06:01:56 INFO - PROCESS | 6559 | Values: 833.2 844.6 888.5 899.3 925.1 15104.0 16024.4 16132.8 16199.6 16250.1 14621.0 14627.6 14632.2 14633.7 14649.4 724.4 738.3 851.5 854.3 868.4 861.8 864.5 864.6 864.6 869.1 905.4 913.1 914.5 915.2 916.4 760.2 781.3 911.6 913.3 915.5 681.6 686.3 686.7 686.8 687.6 15455.5 15466.7 15508.7 15514.8 15528.1 15101.5 15362.3 15397.9 15536.7 16026.8 14221.4 14278.8 14374.5 14453.6 14475.6 4676.9 4687.6 4691.3 4741.7 4788.8 86361.6 86441.0 87586.0 88927.0 89578.6 11788.9 11980.8 12191.4 12215.7 12538.6 23690.9 23720.3 23749.8 24126.6 24387.0 8739.8 8740.5 8791.5 8815.9 9027.3 4756.6 4779.5 4782.4 4912.4 4956.4 3690.5 3789.9 3800.4 3801.7 3802.8 594.4 599.0 602.3 603.8 617.7 645.0 681.8 722.9 772.7 785.1 9350.3 9829.0 9898.0 9915.9 9932.2 710.7 753.5 756.0 763.5 788.6 4422.8 4593.7 4706.0 4709.4 4777.4 125945.4 133746.0 134253.0 137801.0 138058.0 112618.4 114015.0 114704.0 114849.0 119416.0 97479.0 98694.0 99168.5 99243.0 102195.0 9351.8 9421.8 9446.0 9447.9 9459.2 54215.2 54914.7 55440.7 55783.4 56558.0 82869.2 84954.0 85007.0 85748.6 88120.0 13461.7 13663.5 13743.0 13747.6 13942.4 13175.8 13206.3 13556.6 13638.3 13848.7 13524.0 13745.2 13905.3 13922.9 14299.7 13658.8 13996.2 14687.7 14715.3 14885.6 12501.1 12771.9 13377.9 13402.9 13445.1 10589.3 10922.1 10938.3 11415.7 11430.4 9753.9 10040.5 10581.6 10660.3 10972.7 06:01:56 INFO - PROCESS | 6559 | -------- Summary: end -------- 06:01:56 INFO - PROCESS | 6559 | 06:01:57 INFO - TEST-INFO | 6559: exit 0 06:01:57 INFO - TEST-OK | dromaeo_css | took 1113555ms 06:01:57 INFO - TEST-START | kraken 06:01:57 INFO - operating with platform_type : linux_ 06:01:57 INFO - Initialising browser for kraken test... 06:01:57 INFO - TEST-INFO | started process 6878 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRAN37G/profile http://localhost:40385/getInfo.html) 06:02:01 INFO - PROCESS | 6878 | __metrics Screen width/height:1600/1200 06:02:01 INFO - PROCESS | 6878 | colorDepth:24 06:02:01 INFO - PROCESS | 6878 | Browser inner width/height: 1024/697 06:02:01 INFO - PROCESS | 6878 | __metrics 06:02:01 INFO - PROCESS | 6878 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:02:01 INFO - PROCESS | 6878 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:02:02 INFO - PROCESS | 6878 | console.error: PushService: 06:02:02 INFO - PROCESS | 6878 | stateChangeProcessEnqueue: Error transitioning state 06:02:02 INFO - PROCESS | 6878 | UnknownError 06:02:02 INFO - TEST-INFO | 6878: exit 0 06:02:02 INFO - Browser initialized. 06:02:02 INFO - Running cycle 1/1 for kraken test... 06:02:02 INFO - TEST-INFO | started process 6971 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRAN37G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:02:14 INFO - PROCESS | 6971 | RSS: Main: 144269312 06:02:14 INFO - PROCESS | 6971 | 06:02:17 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40385/tests/kraken/driver.html?testName=audio-beat-detection) 06:02:17 INFO - PROCESS | 6971 | RSS: Main: 186458112 06:02:17 INFO - PROCESS | 6971 | 06:02:20 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40385/tests/kraken/driver.html?testName=audio-dft) 06:02:21 INFO - PROCESS | 6971 | RSS: Main: 174907392 06:02:21 INFO - PROCESS | 6971 | 06:02:23 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40385/tests/kraken/driver.html?testName=audio-fft) 06:02:24 INFO - PROCESS | 6971 | RSS: Main: 266047488 06:02:24 INFO - PROCESS | 6971 | 06:02:26 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40385/tests/kraken/driver.html?testName=audio-oscillator) 06:02:27 INFO - PROCESS | 6971 | RSS: Main: 172646400 06:02:27 INFO - PROCESS | 6971 | 06:02:29 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40385/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:02:29 INFO - PROCESS | 6971 | RSS: Main: 173535232 06:02:29 INFO - PROCESS | 6971 | 06:02:32 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40385/tests/kraken/driver.html?testName=imaging-darkroom) 06:02:33 INFO - PROCESS | 6971 | RSS: Main: 209760256 06:02:33 INFO - PROCESS | 6971 | 06:02:36 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40385/tests/kraken/driver.html?testName=imaging-desaturate) 06:02:37 INFO - PROCESS | 6971 | RSS: Main: 209035264 06:02:37 INFO - PROCESS | 6971 | 06:02:40 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40385/tests/kraken/driver.html?testName=json-parse-financial) 06:02:40 INFO - PROCESS | 6971 | RSS: Main: 208334848 06:02:40 INFO - PROCESS | 6971 | 06:02:42 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40385/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:02:42 INFO - PROCESS | 6971 | RSS: Main: 171274240 06:02:42 INFO - PROCESS | 6971 | 06:02:44 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40385/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:02:45 INFO - PROCESS | 6971 | RSS: Main: 178712576 06:02:45 INFO - PROCESS | 6971 | 06:02:47 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40385/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:02:47 INFO - PROCESS | 6971 | RSS: Main: 184086528 06:02:47 INFO - PROCESS | 6971 | 06:02:50 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40385/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:02:50 INFO - PROCESS | 6971 | RSS: Main: 184201216 06:02:50 INFO - PROCESS | 6971 | 06:02:53 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40385/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:02:54 INFO - PROCESS | 6971 | RSS: Main: 182349824 06:02:54 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | Cycle 1(1): loaded http://localhost:40385/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40385/tests/kraken/driver.html?testName=ai-astar) 06:02:55 INFO - PROCESS | 6971 | RSS: Main: 233996288 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | __start_tp_report 06:02:55 INFO - PROCESS | 6971 | _x_x_mozilla_page_load 06:02:55 INFO - PROCESS | 6971 | _x_x_mozilla_page_load_details 06:02:55 INFO - PROCESS | 6971 | |i|pagename|runs| 06:02:55 INFO - PROCESS | 6971 | |0;ai-astar;110;112;111;112;111;111;111;111;111;112 06:02:55 INFO - PROCESS | 6971 | |1;audio-beat-detection;138;117;115;156;116;116;117;122;159;118 06:02:55 INFO - PROCESS | 6971 | |2;audio-dft;160;161;160;159;160;164;160;161;163;159 06:02:55 INFO - PROCESS | 6971 | |3;audio-fft;75;75;75;100;75;76;76;76;98;74 06:02:55 INFO - PROCESS | 6971 | |4;audio-oscillator;82;81;125;82;83;82;99;82;82;101 06:02:55 INFO - PROCESS | 6971 | |5;imaging-gaussian-blur;117;117;118;117;117;116;117;117;116;118 06:02:55 INFO - PROCESS | 6971 | |6;imaging-darkroom;146;147;146;145;146;146;146;146;146;146 06:02:55 INFO - PROCESS | 6971 | |7;imaging-desaturate;95;96;95;95;96;96;96;95;95;96 06:02:55 INFO - PROCESS | 6971 | |8;json-parse-financial;57;50;48;50;48;50;51;48;49;48 06:02:55 INFO - PROCESS | 6971 | |9;json-stringify-tinderbox;58;59;59;67;59;60;58;58;58;58 06:02:55 INFO - PROCESS | 6971 | |10;stanford-crypto-aes;75;73;72;74;77;79;73;73;83;73 06:02:55 INFO - PROCESS | 6971 | |11;stanford-crypto-ccm;120;120;118;127;126;133;119;122;153;127 06:02:55 INFO - PROCESS | 6971 | |12;stanford-crypto-pbkdf2;153;153;153;155;157;153;151;152;153;154 06:02:55 INFO - PROCESS | 6971 | |13;stanford-crypto-sha256-iterative;65;63;62;61;61;60;69;61;63;60 06:02:55 INFO - PROCESS | 6971 | __end_tp_report 06:02:55 INFO - PROCESS | 6971 | __start_cc_report 06:02:55 INFO - PROCESS | 6971 | _x_x_mozilla_cycle_collect,1534 06:02:55 INFO - PROCESS | 6971 | __end_cc_report 06:02:55 INFO - PROCESS | 6971 | __startTimestamp1474376575946__endTimestamp 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | ------- Summary: start ------- 06:02:55 INFO - PROCESS | 6971 | Number of tests: 14 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:0.63 (0.6%) stddev-sans-first:0.50 06:02:55 INFO - PROCESS | 6971 | Values: 110.0 112.0 111.0 112.0 111.0 111.0 111.0 111.0 111.0 112.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:120.00 stddev:17.24 (14.4%) stddev-sans-first:17.86 06:02:55 INFO - PROCESS | 6971 | Values: 138.0 117.0 115.0 156.0 116.0 116.0 117.0 122.0 159.0 118.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#2] audio-dft Cycles:10 Average:160.70 Median:160.50 stddev:1.64 (1.0%) stddev-sans-first:1.72 06:02:55 INFO - PROCESS | 6971 | Values: 160.0 161.0 160.0 159.0 160.0 164.0 160.0 161.0 163.0 159.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#3] audio-fft Cycles:10 Average:80.00 Median:75.50 stddev:10.04 (13.3%) stddev-sans-first:10.49 06:02:55 INFO - PROCESS | 6971 | Values: 75.0 75.0 75.0 100.0 75.0 76.0 76.0 76.0 98.0 74.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:82.00 stddev:14.44 (17.6%) stddev-sans-first:15.03 06:02:55 INFO - PROCESS | 6971 | Values: 82.0 81.0 125.0 82.0 83.0 82.0 99.0 82.0 82.0 101.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 06:02:55 INFO - PROCESS | 6971 | Values: 117.0 117.0 118.0 117.0 117.0 116.0 117.0 117.0 116.0 118.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 06:02:55 INFO - PROCESS | 6971 | Values: 146.0 147.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 06:02:55 INFO - PROCESS | 6971 | Values: 95.0 96.0 95.0 95.0 96.0 96.0 96.0 95.0 95.0 96.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.00 stddev:2.73 (5.5%) stddev-sans-first:1.17 06:02:55 INFO - PROCESS | 6971 | Values: 57.0 50.0 48.0 50.0 48.0 50.0 51.0 48.0 49.0 48.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.00 stddev:2.76 (4.7%) stddev-sans-first:2.88 06:02:55 INFO - PROCESS | 6971 | Values: 58.0 59.0 59.0 67.0 59.0 60.0 58.0 58.0 58.0 58.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:74.50 stddev:3.49 (4.7%) stddev-sans-first:3.70 06:02:55 INFO - PROCESS | 6971 | Values: 75.0 73.0 72.0 74.0 77.0 79.0 73.0 73.0 83.0 73.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:126.50 stddev:10.43 (8.2%) stddev-sans-first:10.79 06:02:55 INFO - PROCESS | 6971 | Values: 120.0 120.0 118.0 127.0 126.0 133.0 119.0 122.0 153.0 127.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:153.00 stddev:1.65 (1.1%) stddev-sans-first:1.74 06:02:55 INFO - PROCESS | 6971 | Values: 153.0 153.0 153.0 155.0 157.0 153.0 151.0 152.0 153.0 154.0 06:02:55 INFO - PROCESS | 6971 | 06:02:55 INFO - PROCESS | 6971 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.50 stddev:2.76 (4.4%) stddev-sans-first:2.77 06:02:55 INFO - PROCESS | 6971 | Values: 65.0 63.0 62.0 61.0 61.0 60.0 69.0 61.0 63.0 60.0 06:02:55 INFO - PROCESS | 6971 | -------- Summary: end -------- 06:02:55 INFO - PROCESS | 6971 | 06:02:56 INFO - TEST-INFO | 6971: exit 0 06:02:56 INFO - TEST-OK | kraken | took 59822ms 06:02:56 INFO - SUITE-END | took 1173s 06:02:57 INFO - Completed test suite (00:19:33) 06:02:57 INFO - javascript benchmark 06:02:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3079.2301924518865, 3123.5158550424685, 3159.3681263747244, 3185.601799775028, 3201.519772034196, 5630.971845140773, 5702.629329093606, 5706.914396284059, 5708.514776244019, 5732.54139668826, 5738.56960727945, 5802.477777000069, 5809.651420914748, 5810.215620891178, 5815.360310365867, 5178.249153872686, 5199.064168449678, 5219.0, 5221.477852214786, 5244.973775131119, 5035.521625445577, 5040.344755181821, 5137.229415587654, 5293.735313234342, 5340.332458442695, 5171.0, 5189.506996835314, 5256.316678831766, 5277.551408130314, 5283.471652834705, 5273.709945952971, 5278.656887302313, 5281.339832520935, 5283.207518872162, 5286.920696189561, 4910.459849416561, 4999.400071991385, 5169.327987361657, 5176.534111929945, 5232.502912223333, 5536.0, 5569.498745112959, 5592.552595792326, 5593.161025846115, 5595.664260144405, 6045.939540604581, 6064.332923378425, 6095.695215239265, 6138.47822935051, 6142.170806941051, 5871.1193321001765, 6151.907721384183, 6174.073888916699, 6174.351693072208, 6188.8762224755255, 5537.556995440395, 5590.888182236373, 5648.830535083954, 5650.971745141289, 5677.37548869624, 6213.689315534205, 6232.750689972441, 6242.812715618538, 6245.6252624842655, 6246.562740608205, 5635.915461268084, 5987.520998320124, 5993.730282137227, 5994.670293133832, 6000.0999850022845, 5699.031164702011, 6108.335197752735, 6130.856783744145, 6133.977404287557, 6165.198524191826, 6119.389560159527, 6130.941095466627, 6131.87526527353, 6132.146101400496, 6138.457541419318, 6118.497354392036, 6136.304638670889, 6139.439125307311, 6139.74593465464, 6142.713648198282, 4501.932471012938, 4536.228841097021, 4536.432945881765, 4544.840930567414, 4565.452145742465, 4074.959250407458, 4080.9183816323507, 4092.5498195198506, 4113.321301985199, 4118.361653943643, 5690.829275121753, 5724.141378793214, 5729.312482502127, 5734.168545560954, 5758.006230529595, 5891.263592050994, 5941.702914854326, 6039.818805435844, 6090.116933044684, 6104.908426373608, 5841.240638716939, 5850.1224816277045, 5856.707164641751, 5864.179014937912, 5879.265091863517, 5143.819966301162, 5148.33071700684, 5157.0975079361715, 5164.457731938168, 5172.146595811724, 5979.5814292999075, 6014.639121652627, 6185.319614842454, 6363.395477429637, 6369.044643303541, 6063.636181829104, 6148.938510614837, 6159.84600384994, 6163.9383606163365, 6172.753089876354, 6270.12218289449, 6326.905096423466, 6337.8732425352155, 6338.366163383625, 6340.524460665376, 5750.856228594319, 5829.679367634821, 5987.730552125252, 6050.788222412194, 6055.939440605537, 6002.519798416029, 6029.57792954489, 6041.6375017499095, 6109.586862300737, 6141.201643786279, 5890.1164825276555, 5926.348101708895, 5937.406259374028, 5937.524998000236, 5954.136650185612, 5692.430756924275, 5935.228420305332, 5935.821925342247, 5940.316863560746, 5953.553483488842, 5757.8560535986935, 5896.439838215363, 5897.5576831736935, 5953.255843019622, 6046.516278697605, 6087.66517841523, 6089.238845144357, 6093.817185484442, 6100.603460775035, 6106.816795496142, 5583.0, 5650.039493286069, 5883.646981181143, 5901.822945311647, 5906.202662640489], "value": 5593.501740020229, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.0602142593278, 622.7602373086361, 644.6228956060708, 656.2223764838667, 659.297847792101, 33591.160220994454, 45763.31355029673, 46444.0, 46512.302315465255, 50148.24777628329, 48769.51230487694, 49061.754691226495, 49100.50899491013, 49216.26175607369, 49235.50764492354, 8448.83102337953, 9320.627174912996, 9457.0, 9465.0, 9479.526023698822, 8217.876731849026, 11064.2808217466, 11329.697084835227, 11339.37633430161, 11789.233699809527, 11469.254498457616, 11526.884731152664, 11532.308061516336, 11535.307881527135, 11538.826917596201, 11216.158788090885, 11244.381559014251, 11291.322520648788, 11300.0, 11301.886981130167, 11241.437928103562, 11281.605143819927, 11295.152863535268, 11307.773844523064, 11313.547458101666, 45676.77161614204, 46111.30833037507, 46154.307685384745, 46224.3066354005, 46248.0, 3774.1508160663907, 3837.9424308635394, 3843.4811300474357, 3844.2888065707652, 3894.7468414552964, 34926.476102858476, 35462.468062979075, 35755.82122089399, 36791.63208367909, 37030.62969370326, 4778.1638213312535, 4876.292937524075, 4884.975575122102, 4906.288588154697, 5062.0888240116765, 278698.0, 279682.0, 280485.0, 283120.0, 284108.0, 67180.0, 67193.0, 67400.6629966847, 67401.0, 67527.32472675211, 43664.781676091414, 44604.0, 44636.10727785491, 44654.77672611681, 44819.32771008438, 19452.22191112368, 19558.21767129299, 19587.80412195888, 19608.411747647595, 19628.803711962697, 13674.111182773087, 13947.093438926437, 14610.26948652563, 14901.552953411416, 15010.47463338778, 20659.380218593167, 21669.133234670753, 21857.234996775038, 21887.67168492474, 21900.671489927663, 7389.482736208413, 7476.738314158978, 7522.749646449738, 7773.18381569927, 7789.415793815373, 1009.7728011197569, 1043.9582416703247, 1103.7461383881753, 1147.9827802582968, 1158.7566611011614, 34506.309873802384, 35432.291354172776, 35547.64452355443, 35639.555517787805, 35715.464268036, 1128.1482480727163, 1145.3643228008368, 1146.4898120336536, 1149.3391300002531, 1182.9231099978474, 18349.0, 18350.908245458955, 18491.0, 18634.906825466056, 18663.346782862536, 592652.0, 592887.0, 595282.0, 598460.0, 599473.0, 65727.0, 66397.0, 66456.6677166611, 66463.66768166129, 66483.33516664869, 1299.4867027524265, 1356.85752995936, 1381.9861801382058, 1416.7874818777266, 1421.587739555538, 31644.5253321205, 36049.63950360568, 36488.0, 36669.08327291839, 36800.0, 56163.438365617454, 56404.43595563992, 56695.433045669015, 56831.715841422185, 56869.71565142148, 328628.0, 332522.0, 333018.0, 333030.0, 333340.0, 31317.373652527276, 32345.19137021499, 32487.83756081299, 33977.49033764446, 33992.83003584966, 52906.470935290155, 53428.0, 53471.465285348204, 53672.46327536675, 53771.46228537665, 43725.067815541675, 45012.08214868744, 45353.0, 45994.0, 47125.29312060391, 44736.77631611821, 45482.54517454783, 45594.316085258084, 45597.77201113973, 46763.0, 45295.32057019213, 46272.7686361566, 46327.76836115798, 46360.07279854451, 46959.06081878411, 37553.6244637561, 41034.38448423216, 41307.7934610325, 41381.37927931023, 41758.0, 29514.557281640362, 30444.847775761864, 30626.540601890545, 31789.68210317867, 32501.0], "value": 21810.32791864121, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.79366729300745, 414.4694790667944, 428.7256156060121, 430.8965848196433, 443.64286749166905, 15361.53915382539, 19571.02144892755, 20468.181272749072, 20478.2832600859, 20596.691049634268, 19764.505887352825, 20426.182952681876, 20999.47501312468, 21002.0, 21049.894750526226, 5940.19807326012, 5959.344472108076, 6189.257289125311, 6195.31851496335, 6202.565820392574, 5995.100734889779, 6053.606515576495, 6096.634201947897, 6097.0, 6105.32841387447, 5840.3575606683235, 5972.432618901197, 6074.878502429949, 6075.483583895374, 6087.756489740405, 5993.670348130851, 6021.909671354933, 6057.787977420813, 6061.575689701723, 6062.272527296708, 5314.548263397616, 5408.188771684239, 5433.483819037185, 5441.401445840955, 5452.4274951129955, 22705.432364190863, 23455.4136146596, 23601.763982360128, 24120.155794547107, 24302.756972430227, 2337.5558643857785, 2422.818288628342, 2479.1199124310792, 2489.8381605195605, 2579.5227882841546, 11142.832857507145, 11223.887761122447, 12793.29636869981, 15032.473863414838, 15557.92221038899, 1961.117497126299, 1968.8031196880197, 2019.0611365715013, 2029.4621925189836, 2066.8036536529007, 84410.57794711049, 87577.0, 88498.0, 88687.0, 90536.54731726366, 9953.25350598708, 10125.797484050277, 10256.282060255711, 10687.519061642244, 10730.624428144978, 13948.2328471933, 14317.85682143186, 14871.33079011447, 15770.68458630844, 15952.76070858938, 8728.0, 9033.277337812959, 9064.728058158265, 9113.81772364549, 9141.451512909247, 9087.591423329395, 9110.908890911138, 9128.771780705536, 9199.770005749777, 9363.625454981724, 5108.2848401223855, 5200.947990520046, 5224.346956630421, 5249.265102885599, 5325.121354976385, 2071.78246284141, 2129.3572034109734, 2131.5736852629534, 2164.0910817456706, 2243.743503637968, 906.4425378392241, 966.463612694947, 967.6274634265858, 1000.6697789729371, 1053.167997282154, 20517.281895133598, 20689.58620827575, 20817.271395501084, 20819.791802082087, 20825.271115511187, 1015.2182819229153, 1028.388109075099, 1052.2791887557048, 1079.875814281352, 1081.8431327457474, 10578.682639520826, 11419.771604567863, 11499.0, 11624.186306958432, 11648.825267620992, 519353.0, 521700.0, 523637.0, 529738.0, 533791.0, 97099.0, 99556.50221748845, 99691.50154249182, 101070.4946475263, 101905.49047254716, 1030.314840630972, 1054.4674939155677, 1059.6344261229863, 1060.2366296266675, 1071.9571217151226, 17510.474685759447, 17940.372086976895, 18068.90965545164, 18506.0, 18614.720779188054, 32830.507542387364, 46680.0, 47148.0, 47291.763541182074, 48127.518724812304, 63706.681466592374, 64132.0, 64398.35601643923, 65317.67341163264, 65980.6700966492, 8909.509976951329, 9086.182243598108, 9156.496392698466, 9183.403078799856, 9379.624815007597, 10136.44249566281, 13085.738285234433, 13416.39626216842, 13775.586732398044, 13995.860041399455, 11858.822117668069, 11920.225185362924, 12192.695182620506, 12259.632211033684, 12347.19743216688, 11813.0, 11951.820722689341, 12074.335911524951, 12133.0, 12150.939245303716, 11815.704607384883, 11980.64058078259, 12011.279323240633, 12101.576444824388, 12887.6778080545, 7964.800879978047, 8566.57337065179, 8904.287656987295, 9322.114399132071, 9639.71080867575, 9673.177779888789, 11480.344470137961, 11552.133589980616, 11712.824307635221, 11900.821487677866], "value": 9712.159478078947, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.4932398998362, 1536.677297767469, 1586.0800735573368, 1729.2391347806963, 1872.7378167056615, 8057.395695322845, 8867.512286824222, 9101.0, 9109.0, 9432.434053956767, 9197.678081267157, 9275.768105797359, 9276.53617319133, 9295.90704092959, 9297.628094876196, 2425.1390756281485, 2461.10169788027, 2462.039804476247, 2463.7043554773363, 2463.7659422354845, 2414.9758502414925, 2465.716442609105, 2468.1978357034036, 2468.3705655058025, 2471.060996821205, 2413.79482743967, 2429.7813196812285, 2459.0655550890638, 2462.8768561571937, 2462.901483940649, 2372.0630351011255, 2388.9641655375185, 2393.3179043972386, 2398.424378149249, 2400.4238982644215, 2266.3880752196815, 2328.860268383902, 2332.4635333873302, 2334.8949297281665, 2338.8362814602983, 9466.952665236699, 9595.952020239854, 9831.0, 9831.115199631997, 9835.114839664393, 5429.8099566515375, 5594.608377413582, 5603.327600687943, 5605.187247849039, 5606.579506537026, 3639.181184233553, 3669.981650091759, 3684.1342284563107, 3756.154865155318, 3776.0, 1261.5521489871146, 1264.6522206393224, 1279.6864768131775, 1292.9741405171937, 1316.2760481735008, 13598.38807253676, 13711.794323085162, 13717.931410342984, 13735.45058197681, 13763.587092387244, 4730.172219861511, 4788.401449818773, 4795.040991801654, 4856.587190088811, 5042.243663450438, 10697.732556686145, 10702.304350217211, 10714.142868570652, 10735.94632026835, 10742.462876856125, 5584.5811564132855, 5591.4688104629995, 5594.804181853697, 5597.188407680864, 5597.720113994284, 4400.471943366816, 4543.795529201194, 5126.5898728101665, 5209.4009188943, 5251.0, 4171.058126354702, 4204.957950420518, 4242.469691288589, 4295.3771703102875, 4335.1329734053315, 1196.126827415991, 1206.5354838387284, 1219.4451524556305, 1220.334917469981, 1228.103484456352, 920.40173886974, 996.1433167475965, 1017.6183931025865, 1059.8410238464292, 1068.3376306689902, 6102.084687296941, 6393.104965304861, 6431.292557818636, 6590.275069742324, 6662.400383965466, 512.8102602037195, 519.828456609318, 525.4561528817655, 1073.6939972107912, 1077.9622713205001, 4079.73481723687, 4217.493900731871, 4284.143171365739, 4335.328024156261, 4359.215341238608, 12137.453814578364, 12371.13402061865, 12462.0, 12474.625761227178, 12503.062270329761, 12347.073969452327, 12353.197042192229, 12361.07291953107, 12378.133530652767, 12379.071569632313, 8598.097199794056, 8934.24058954983, 8937.329700272376, 9047.140521650565, 9131.497767622845, 6162.383761623802, 6276.466500347339, 6772.11962444879, 6797.0823938767635, 6829.93170068293, 11078.335299882034, 11214.55141794319, 11359.54561817518, 11376.43117844088, 11449.255798373079, 11327.660170194908, 11327.71680707956, 11585.536578536765, 11611.477483513094, 11645.708857278636, 7679.731209407644, 7857.607119643881, 8561.957190214009, 8712.825743485222, 8869.866951995855, 9278.443293402148, 9405.482698451377, 9581.233501320017, 9608.27937904646, 9616.182624476998, 8340.374471914509, 8395.202455766692, 8430.915690843012, 8546.957265213634, 8832.5583720815, 8287.95856020716, 8348.66605335804, 8383.916160838313, 8389.203025712548, 8516.914830851612, 7327.194008658937, 7358.0434543509, 7426.665800038903, 7431.0, 7435.92564074374, 5467.20725494809, 5469.72651367438, 5483.917741233964, 5577.498025177753, 5683.71581420919, 5363.597730170175, 5462.672239665632, 5516.393196748435, 5517.586181036358, 5579.163125531203], "value": 4929.488476334074, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.4454428370323, 354.5993027878497, 357.31217406492493, 358.57687928244667, 361.5300109857183, 5302.840914772553, 5439.864003399917, 5486.780528778854, 5487.9451205487985, 5502.2296878437, 5279.947200527993, 5442.374126975399, 5474.698891560963, 5484.780608775654, 5484.945150548493, 4357.106793107421, 4371.486395322686, 4373.059792144688, 4374.934375984363, 4387.758673272969, 4196.412502249687, 4323.978380108095, 4350.390945267665, 4368.563143685637, 4370.956290437102, 4277.187334406468, 4283.657307415399, 4295.226859165349, 4320.783960801963, 4325.82696692132, 4285.228658841407, 4292.613664770154, 4298.548652391516, 4322.805473753689, 4326.286162783168, 3667.541557305337, 3778.414345776409, 3798.3163030654473, 3799.202167544819, 3809.3524100902914, 5313.893722125575, 5319.867003324948, 5327.786888524493, 5329.987302412568, 5387.73061346935, 4948.228991764623, 4948.505149485059, 4949.158643030693, 4949.876253093666, 4955.628327875388, 5566.499015088621, 5645.661260324353, 5707.828765137053, 5724.141378793172, 5725.398833122504, 4165.729452516981, 4174.478190226222, 4175.436316097318, 4176.310908700061, 4182.519010313792, 5839.562032847511, 5916.792912248051, 5949.375315591887, 5956.22569066023, 5986.2217911671205, 4218.156368726267, 4235.23765722173, 4235.97882010588, 4248.660107191417, 4250.107477429774, 4295.591918767712, 4309.267424537836, 4309.978450107792, 4314.158739045919, 4329.956700432956, 4898.926516102261, 4908.214685650279, 4910.631702622318, 4926.162552366106, 4926.768307923019, 5476.67139971603, 5547.278853748987, 5728.570357223225, 5752.654840709571, 5773.422657734193, 1417.9578010162522, 1445.9878085340304, 1476.645605054779, 1484.940602375893, 1491.903450963541, 993.6373223773271, 1014.4268488304084, 1025.1491262252312, 1027.7276521721749, 1031.118393773328, 5498.835034948958, 5548.667079975215, 5565.354692676304, 5566.638168519034, 5581.469760372839, 5865.325487568899, 5871.853203669943, 5884.617499862496, 5885.352611212848, 5897.378220989176, 5368.651037682538, 5542.473465020817, 5570.387257401682, 5636.746346414421, 5661.461586508432, 3115.8442077896016, 3118.3607360491046, 3130.154858188276, 3131.3111115555, 3132.436161490908, 5862.8534286642325, 6076.392360763888, 6106.694665266719, 6118.541109416811, 6133.202683651097, 6034.969825150934, 6085.908711369333, 6089.786857460056, 6090.786822461193, 6091.725872335755, 6122.908156377658, 6128.17269668598, 6144.385561443909, 6156.33778879691, 6162.414570615695, 5636.689982051026, 5897.989367230324, 5969.432903874125, 5977.312609049841, 5982.192404025401, 5893.882122357614, 5908.586398952032, 5917.419960923579, 5927.170196172453, 5927.2590926134235, 5874.412558744091, 6115.602485838406, 6171.9074213887725, 6178.1350610913605, 6195.256569211723, 4300.204462174508, 4308.48298204205, 4312.180685669713, 4322.832835134559, 4329.7835108243835, 5697.914531282117, 5845.912311315248, 5976.163337132892, 5980.671063091571, 6019.638821670714, 5620.971895140498, 5666.320041595035, 5720.313562372542, 5782.913256301074, 5826.854328641818, 5773.451522105393, 5814.912776308274, 5816.243888294494, 5823.4467725566, 5827.24245848037, 5576.498115169654, 5710.885782284248, 5768.711564421678, 5775.27809023872, 5776.277965254343, 5834.241548598825, 5841.474267315962, 5847.239858818326, 5854.268216472941, 5874.324452687995, 5422.322209723785, 5658.405867383865, 5667.319921609268, 5667.4332566743, 5669.461401166883], "value": 4418.558531907691, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [833.2167762303434, 844.6283635200512, 888.5024386343647, 899.304676682102, 925.0980294213143, 15104.0, 16024.43914462994, 16132.83867161328, 16199.595010124738, 16250.106244156552, 14621.049631773927, 14627.561173164795, 14632.195229262386, 14633.707325853477, 14649.414023439049, 724.4457989637931, 738.3170567225322, 851.4848516647417, 854.2738672128703, 868.3574155125193, 861.7673228228375, 864.5245115186653, 864.6238886084542, 864.6368525219392, 869.0961400143843, 905.4341036851968, 913.1370854542427, 914.4970266353512, 915.199200699388, 916.3631276263, 760.2207737069492, 781.2851241114402, 911.5761171055425, 913.310450609792, 915.5239275576696, 681.58082779091, 686.327399148832, 686.6669665212368, 686.7596341280562, 687.5805758487319, 15455.53633391, 15466.690666186612, 15508.689826203525, 15514.844851551454, 15528.068315901082, 15101.5469535913, 15362.308696108701, 15397.923010384988, 15536.689266214726, 16026.839731602651, 14221.431142754278, 14278.785818212735, 14374.496892608708, 14453.638659033502, 14475.565733028026, 4676.883077923079, 4687.601553867926, 4691.319758635013, 4741.668083234175, 4788.832390866338, 86361.56819215864, 86441.0, 87586.0, 88927.0, 89578.5521072397, 11788.88211117895, 11980.760384792255, 12191.390430478441, 12215.694607634703, 12538.623841284776, 23690.933907974184, 23720.288391348287, 23749.762502375102, 24126.638100428507, 24387.0, 8739.825203496022, 8740.475571465733, 8791.472511649321, 8815.867761983576, 9027.347640590871, 4756.619470442356, 4779.545943135397, 4782.402199725035, 4912.4350699669285, 4956.405231372259, 3690.501782259414, 3789.9242015160094, 3800.4489349044234, 3801.657850793442, 3802.752821066622, 594.4085634793408, 598.9847208980735, 602.2592211579781, 603.7826382502295, 617.6633734614646, 644.9806505804834, 681.8261343357412, 722.887952367384, 772.6638912073266, 785.1089013969186, 9350.251979841596, 9829.0, 9897.950510247547, 9915.900840991642, 9932.205423566098, 710.74057968841, 753.4688044928313, 755.9659815308214, 763.5151678684076, 788.5781107107784, 4422.75674837887, 4593.701409408378, 4705.95294047062, 4709.434867815884, 4777.355057067321, 125945.37027314988, 133746.0, 134253.0, 137801.0, 138058.0, 112618.43690781658, 114015.0, 114704.0, 114849.0, 119416.0, 97479.0, 98694.0, 99168.50415747875, 99243.00756992483, 102195.0, 9351.766205844908, 9421.81156376855, 9446.0, 9447.858282125637, 9459.195968342769, 54215.18677219924, 54914.72542637261, 55440.72279638576, 55783.44216557945, 56558.0, 82869.17130828614, 84954.0, 85007.0, 85748.57125714331, 88120.0, 13461.730765384833, 13663.521776737767, 13743.0, 13747.587572372844, 13942.372593233125, 13175.802362964754, 13206.339683016004, 13556.59330220095, 13638.318084095954, 13848.653783655489, 13524.0, 13745.175289482662, 13905.304734763424, 13922.860771392561, 14299.714005720036, 13658.795118073038, 13996.230207338695, 14687.706245875237, 14715.337809798371, 14885.627859303604, 12501.124921255425, 12771.872281277068, 13377.866221337663, 13402.865971340552, 13445.058845881085, 10589.258751887293, 10922.12622990143, 10938.289011214245, 11415.657530274106, 11430.428478575872, 9753.853692194481, 10040.497975101362, 10581.576736930436, 10660.307080039773, 10972.670819875417], "value": 7977.543459107606, "unit": "score"}], "name": "dromaeo_css", "value": 7684.393029512736, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 112.0, 111.0, 112.0, 111.0, 111.0, 111.0, 111.0, 111.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 117.0, 115.0, 156.0, 116.0, 116.0, 117.0, 122.0, 159.0, 118.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 159.0, 160.0, 164.0, 160.0, 161.0, 163.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 100.0, 75.0, 76.0, 76.0, 76.0, 98.0, 74.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 125.0, 82.0, 83.0, 82.0, 99.0, 82.0, 82.0, 101.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 118.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 50.0, 48.0, 50.0, 48.0, 50.0, 51.0, 48.0, 49.0, 48.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 67.0, 59.0, 60.0, 58.0, 58.0, 58.0, 58.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 72.0, 74.0, 77.0, 79.0, 73.0, 73.0, 83.0, 73.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 120.0, 118.0, 127.0, 126.0, 133.0, 119.0, 122.0, 153.0, 127.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 153.0, 155.0, 157.0, 153.0, 151.0, 152.0, 153.0, 154.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 62.0, 61.0, 61.0, 60.0, 69.0, 61.0, 63.0, 60.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1454.6000000000001, "alertThreshold": 2.0}]} 06:02:57 INFO - Return code: 0 06:02:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:02:57 INFO - # TBPL SUCCESS # 06:02:57 INFO - Running post-action listener: _resource_record_post_action 06:02:57 INFO - [mozharness: 2016-09-20 13:02:57.259258Z] Finished run-tests step (success) 06:02:57 INFO - Running post-run listener: _resource_record_post_run 06:02:57 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 454656; Write bytes: 352497664; Read time: 64; Write time: 224384 06:02:57 INFO - TinderboxPrint: CPU usage
12.9% 06:02:57 INFO - TinderboxPrint: I/O read bytes / time
454,656 / 64 06:02:57 INFO - TinderboxPrint: I/O write bytes / time
352,497,664 / 224,384 06:02:57 INFO - TinderboxPrint: CPU idle
8,268.7 (87.1%) 06:02:57 INFO - TinderboxPrint: CPU user
1,182.8 (12.5%) 06:02:57 INFO - TinderboxPrint: Swap in / out
0 / 0 06:02:57 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 108052480; Read time: 0; Write time: 84788 06:02:57 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 454656; Write bytes: 244097024; Read time: 64; Write time: 139596 06:02:57 INFO - Running post-run listener: _upload_blobber_files 06:02:57 INFO - Blob upload gear active. 06:02:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:02:57 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 06:02:57 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:02:57 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:02:58 INFO - (blobuploader) - INFO - Open directory for files ... 06:02:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:02:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:02:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:02:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:02:59 INFO - (blobuploader) - INFO - Done attempting. 06:02:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:02:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:03:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:03:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:03:02 INFO - (blobuploader) - INFO - Done attempting. 06:03:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:03:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:03:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:03:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:03:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:03:03 INFO - (blobuploader) - INFO - Done attempting. 06:03:03 INFO - (blobuploader) - INFO - Iteration through files over. 06:03:03 INFO - Return code: 0 06:03:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:03:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:03:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/846d849331ad4db5ed3dbd2c75c44b675c2ae83747fbecc6cdd9047c54e467a3ae8033c5f39ff16c7f45a587c43671ee7fd15bdc4435b79af26b7af55db97c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b18487ef69e3a806cf819efe8206fbc43d3b34dbe4241a77ad40eea7215d36ec850b489df55f406c05d15d4bec59e56157d95621dfe1e951e2df8d17aae5c5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/802562ccfccdd211764211c0449da0facde3f3b929da8e06b243401ff742f90b13f2542bbf6f6b0d23408fee973aa05efe50a48299984c5426623812cc310f17"} 06:03:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:03:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:03:03 INFO - Contents: 06:03:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/846d849331ad4db5ed3dbd2c75c44b675c2ae83747fbecc6cdd9047c54e467a3ae8033c5f39ff16c7f45a587c43671ee7fd15bdc4435b79af26b7af55db97c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b18487ef69e3a806cf819efe8206fbc43d3b34dbe4241a77ad40eea7215d36ec850b489df55f406c05d15d4bec59e56157d95621dfe1e951e2df8d17aae5c5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/802562ccfccdd211764211c0449da0facde3f3b929da8e06b243401ff742f90b13f2542bbf6f6b0d23408fee973aa05efe50a48299984c5426623812cc310f17"} 06:03:03 INFO - Running post-run listener: copy_logs_to_upload_dir 06:03:03 INFO - Copying logs to upload dir... 06:03:03 INFO - mkdir: /builds/slave/test/build/upload/logs 06:03:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.633724 ========= master_lag: 83.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 56 secs) (at 2016-09-20 06:04:26.337399) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-20 06:04:26.346413) ========= 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=00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/846d849331ad4db5ed3dbd2c75c44b675c2ae83747fbecc6cdd9047c54e467a3ae8033c5f39ff16c7f45a587c43671ee7fd15bdc4435b79af26b7af55db97c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b18487ef69e3a806cf819efe8206fbc43d3b34dbe4241a77ad40eea7215d36ec850b489df55f406c05d15d4bec59e56157d95621dfe1e951e2df8d17aae5c5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/802562ccfccdd211764211c0449da0facde3f3b929da8e06b243401ff742f90b13f2542bbf6f6b0d23408fee973aa05efe50a48299984c5426623812cc310f17"} build_url:https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011340 build_url: 'https://queue.taskcluster.net/v1/task/FAy-f7CeQmOpmmHYpm5UFQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/846d849331ad4db5ed3dbd2c75c44b675c2ae83747fbecc6cdd9047c54e467a3ae8033c5f39ff16c7f45a587c43671ee7fd15bdc4435b79af26b7af55db97c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b18487ef69e3a806cf819efe8206fbc43d3b34dbe4241a77ad40eea7215d36ec850b489df55f406c05d15d4bec59e56157d95621dfe1e951e2df8d17aae5c5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/802562ccfccdd211764211c0449da0facde3f3b929da8e06b243401ff742f90b13f2542bbf6f6b0d23408fee973aa05efe50a48299984c5426623812cc310f17"}' ========= master_lag: 1.27 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-20 06:04:27.627965) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 06:04:27.628305) ========= 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=00204705a9daa57ed339a427000001b9-1474375347.765536-1833635783 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.13 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 06:04:27.767359) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 06:04:27.770710) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 06:04:28.736510) ========= ========= Total master_lag: 84.67 =========