builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-047 starttime: 1482965564.38 results: success (0) buildid: 20161228140637 builduid: 8c84f433d8d24f08ba803fe3f2164293 revision: 2fa9856e4ccf1f0cfe423c82f39df67b275d3b77 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:44.378416) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:44.378855) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:44.379347) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:44.725142) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:44.725427) ========= 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=03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-28 14:52:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 64.7M=0s 2016-12-28 14:52:45 (64.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117481 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:44.869677) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:44.870066) ========= 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=03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016703 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:44.923909) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 14:52:44.924226) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 2fa9856e4ccf1f0cfe423c82f39df67b275d3b77 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 2fa9856e4ccf1f0cfe423c82f39df67b275d3b77 --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=03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-28 14:52:45,937 truncating revision to first 12 chars 2016-12-28 14:52:45,937 Setting DEBUG logging. 2016-12-28 14:52:45,937 attempt 1/10 2016-12-28 14:52:45,937 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/2fa9856e4ccf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 14:52:46,904 unpacking tar archive at: graphics-2fa9856e4ccf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.287238 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 14:52:46.233891) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:46.234184) ========= script_repo_revision: 2fa9856e4ccf1f0cfe423c82f39df67b275d3b77 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:46.234618) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:46.234912) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 14:52:46.245355) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-28 14:52:46.245623) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:52:47 INFO - MultiFileLogger online at 20161228 14:52:47 in /builds/slave/test 14:52:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 14:52:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:52:47 INFO - {'append_to_log': False, 14:52:47 INFO - 'base_work_dir': '/builds/slave/test', 14:52:47 INFO - 'blob_upload_branch': 'Graphics', 14:52:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:52:47 INFO - 'branch': 'Graphics', 14:52:47 INFO - 'buildbot_json_path': 'buildprops.json', 14:52:47 INFO - 'config_files': ('talos/linux_config.py',), 14:52:47 INFO - 'default_actions': ('clobber', 14:52:47 INFO - 'read-buildbot-config', 14:52:47 INFO - 'download-and-extract', 14:52:47 INFO - 'populate-webroot', 14:52:47 INFO - 'create-virtualenv', 14:52:47 INFO - 'install', 14:52:47 INFO - 'run-tests'), 14:52:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:52:47 INFO - 'download_minidump_stackwalk': True, 14:52:47 INFO - 'download_symbols': 'ondemand', 14:52:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:52:47 INFO - 'tooltool.py': '/tools/tooltool.py', 14:52:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:52:47 INFO - '/tools/misc-python/virtualenv.py')}, 14:52:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:52:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:52:47 INFO - 'installer_path': 'installer.exe', 14:52:47 INFO - 'log_level': 'info', 14:52:47 INFO - 'log_name': 'talos', 14:52:47 INFO - 'log_to_console': True, 14:52:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:52:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:52:47 INFO - 'opt_config_files': (), 14:52:47 INFO - 'pip_index': False, 14:52:47 INFO - 'sps_profile': False, 14:52:47 INFO - 'sps_profile_interval': 0, 14:52:47 INFO - 'suite': 'dromaeojs', 14:52:47 INFO - 'system_bits': '32', 14:52:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:52:47 INFO - 'title': 'talos-linux64-ix-047', 14:52:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:52:47 INFO - 'use_talos_json': True, 14:52:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:52:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:52:47 INFO - 'work_dir': 'build'} 14:52:47 INFO - [mozharness: 2016-12-28 22:52:47.348166Z] Running clobber step. 14:52:47 INFO - Running pre-action listener: _resource_record_pre_action 14:52:47 INFO - Running main action method: clobber 14:52:47 INFO - rmtree: /builds/slave/test/build 14:52:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:52:47 INFO - Running post-action listener: _resource_record_post_action 14:52:47 INFO - [mozharness: 2016-12-28 22:52:47.815985Z] Finished clobber step (success) 14:52:47 INFO - [mozharness: 2016-12-28 22:52:47.816087Z] Running read-buildbot-config step. 14:52:47 INFO - Running pre-action listener: _resource_record_pre_action 14:52:47 INFO - Running main action method: read_buildbot_config 14:52:47 INFO - Using buildbot properties: 14:52:47 INFO - { 14:52:47 INFO - "project": "", 14:52:47 INFO - "product": "firefox", 14:52:47 INFO - "script_repo_revision": "default", 14:52:47 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 14:52:47 INFO - "repository": "", 14:52:47 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 14:52:47 INFO - "buildid": "20161228140637", 14:52:47 INFO - "pgo_build": "False", 14:52:47 INFO - "basedir": "/builds/slave/test", 14:52:47 INFO - "buildnumber": 3, 14:52:47 INFO - "slavename": "talos-linux64-ix-047", 14:52:47 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:52:47 INFO - "platform": "ubuntu64_hw", 14:52:47 INFO - "scheduler": "tests-graphics-linux64-talos", 14:52:47 INFO - "branch": "graphics", 14:52:47 INFO - "repo_path": "projects/graphics", 14:52:47 INFO - "revision": "2fa9856e4ccf1f0cfe423c82f39df67b275d3b77", 14:52:47 INFO - "stage_platform": "linux64", 14:52:47 INFO - "builduid": "8c84f433d8d24f08ba803fe3f2164293", 14:52:47 INFO - "slavebuilddir": "test" 14:52:47 INFO - } 14:52:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 14:52:47 INFO - Running post-action listener: _resource_record_post_action 14:52:47 INFO - [mozharness: 2016-12-28 22:52:47.818550Z] Finished read-buildbot-config step (success) 14:52:47 INFO - [mozharness: 2016-12-28 22:52:47.818639Z] Running download-and-extract step. 14:52:47 INFO - Running pre-action listener: _resource_record_pre_action 14:52:47 INFO - Running main action method: download_and_extract 14:52:47 INFO - mkdir: /builds/slave/test/build/tests 14:52: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')]} 14:52:47 INFO - trying https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:52:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:52:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:52:48 INFO - Downloaded 1361 bytes. 14:52:48 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:52:48 INFO - Using the following test package requirements: 14:52:48 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:52:48 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:52:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:52:48 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:52:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:52:48 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:52:48 INFO - u'jsshell-linux-x86_64.zip'], 14:52:48 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:52:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:52:48 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:52:48 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:52:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:52:48 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:52:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:52:48 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:52:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:52:48 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:52:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:52:48 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:52:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:52:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:52:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:52:49 INFO - Expected file size: 20241358 14:52:49 INFO - Obtained file size: 20241358 14:52:53 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:52:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:52:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:52:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:52:53 INFO - Expected file size: 20241358 14:52:53 INFO - Obtained file size: 20241358 14:52:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 14:52:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:52:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 14:52:59 INFO - Expected file size: 14409776 14:52:59 INFO - Obtained file size: 14409776 14:52: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')]} 14:52:59 INFO - trying https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:52:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:52:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:53:00 INFO - Downloaded 55917953 bytes. 14:53:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:53:00 INFO - mkdir: /builds/slave/test/properties 14:53:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:53:00 INFO - Writing to file /builds/slave/test/properties/build_url 14:53:00 INFO - Contents: 14:53:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:53:01 INFO - Running post-action listener: _resource_record_post_action 14:53:01 INFO - Running post-action listener: set_extra_try_arguments 14:53:01 INFO - [mozharness: 2016-12-28 22:53:01.161258Z] Finished download-and-extract step (success) 14:53:01 INFO - [mozharness: 2016-12-28 22:53:01.161484Z] Running populate-webroot step. 14:53:01 INFO - Running pre-action listener: _resource_record_pre_action 14:53:01 INFO - Running main action method: populate_webroot 14:53:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:53:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:53:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:53:01 INFO - '--tppagecycles', 14:53:01 INFO - '7'], 14:53:01 INFO - 'tests': ['tsvgm']}}, 14:53:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:53:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:53:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:53:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:53:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:53:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:53:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:53:01 INFO - 'talos_options': ['--disable-e10s'], 14:53:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:53:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:53:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:53:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:53:01 INFO - 'talos_options': ['--disable-e10s'], 14:53:01 INFO - 'tests': ['damp', 'tps']}, 14:53:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:53:01 INFO - 'tests': ['damp', 'tps']}, 14:53:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:53:01 INFO - 'tests': ['dromaeo_dom']}, 14:53:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:53:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:53:01 INFO - 'tests': ['basic_compositor_video']}, 14:53:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:53:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:53:01 INFO - 'tests': ['a11yr', 14:53:01 INFO - 'ts_paint', 14:53:01 INFO - 'tpaint', 14:53:01 INFO - 'sessionrestore', 14:53:01 INFO - 'sessionrestore_no_auto_restore', 14:53:01 INFO - 'tabpaint']}, 14:53:01 INFO - 'other-e10s': {'tests': ['a11yr', 14:53:01 INFO - 'ts_paint', 14:53:01 INFO - 'tpaint', 14:53:01 INFO - 'sessionrestore', 14:53:01 INFO - 'sessionrestore_no_auto_restore', 14:53:01 INFO - 'tabpaint']}, 14:53:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:53:01 INFO - 'ts_paint', 14:53:01 INFO - 'tpaint', 14:53:01 INFO - 'sessionrestore', 14:53:01 INFO - 'sessionrestore_no_auto_restore', 14:53:01 INFO - 'tabpaint']}, 14:53:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:53:01 INFO - 'ts_paint', 14:53:01 INFO - 'tpaint', 14:53:01 INFO - 'sessionrestore', 14:53:01 INFO - 'sessionrestore_no_auto_restore', 14:53:01 INFO - 'tabpaint']}, 14:53:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:53:01 INFO - 'tests': ['a11yr', 14:53:01 INFO - 'ts_paint', 14:53:01 INFO - 'tpaint', 14:53:01 INFO - 'sessionrestore', 14:53:01 INFO - 'sessionrestore_no_auto_restore', 14:53:01 INFO - 'tabpaint']}, 14:53:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:53:01 INFO - 'tests': ['a11yr', 14:53:01 INFO - 'ts_paint', 14:53:01 INFO - 'tpaint', 14:53:01 INFO - 'sessionrestore', 14:53:01 INFO - 'sessionrestore_no_auto_restore', 14:53:01 INFO - 'tabpaint']}, 14:53:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:53:01 INFO - 'tests': ['tsvgx', 14:53:01 INFO - 'tsvgr_opacity', 14:53:01 INFO - 'tart', 14:53:01 INFO - 'tscrollx', 14:53:01 INFO - 'cart', 14:53:01 INFO - 'tsvg_static']}, 14:53:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:53:01 INFO - 'tsvgr_opacity', 14:53:01 INFO - 'tart', 14:53:01 INFO - 'tscrollx', 14:53:01 INFO - 'cart', 14:53:01 INFO - 'tsvg_static']}, 14:53:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:53:01 INFO - 'talos_options': ['--disable-e10s'], 14:53:01 INFO - 'tests': ['tp5o']}, 14:53:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:53:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:53:01 INFO - 'talos_options': ['--disable-e10s', 14:53:01 INFO - '--xperf_path', 14:53:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:53:01 INFO - 'tests': ['tp5n']}, 14:53:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:53:01 INFO - 'talos_options': ['--xperf_path', 14:53:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:53:01 INFO - 'tests': ['tp5n']}}, 14:53:01 INFO - 'talos.zip': {'path': '', 14:53:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:53:01 INFO - Running post-action listener: _resource_record_post_action 14:53:01 INFO - [mozharness: 2016-12-28 22:53:01.178553Z] Finished populate-webroot step (success) 14:53:01 INFO - [mozharness: 2016-12-28 22:53:01.178641Z] Running create-virtualenv step. 14:53:01 INFO - Running pre-action listener: _resource_record_pre_action 14:53:01 INFO - Running main action method: create_virtualenv 14:53:01 INFO - Creating virtualenv /builds/slave/test/build/venv 14:53:01 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 14:53:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:53:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:53:01 INFO - Using real prefix '/usr' 14:53:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:53:01 INFO - Installing distribute.............................................................................................................................................................................................done. 14:53:03 INFO - Installing pip.................done. 14:53:03 INFO - Return code: 0 14:53:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:53:03 INFO - Reading from file tmpfile_stdout 14:53:03 INFO - Output received: 14:53:03 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:53:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:53:03 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')]} 14:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:03 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')]} 14:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:03 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 0x1c211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1deda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f92c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f903f0>, '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 0x1f90cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f911a0>, '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': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', '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 14:53:03 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 14:53:03 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 14:53:03 INFO - Using env: {'DISPLAY': ':0', 14:53:03 INFO - 'HOME': '/home/cltbld', 14:53:03 INFO - 'LANG': 'en_US.UTF-8', 14:53:03 INFO - 'LANGUAGE': 'en_US:en', 14:53:03 INFO - 'LOGNAME': 'cltbld', 14:53:03 INFO - 'MAIL': '/var/mail/cltbld', 14:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:03 INFO - 'MOZ_NO_REMOTE': '1', 14:53:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:03 INFO - 'NO_EM_RESTART': '1', 14:53:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:03 INFO - 'PWD': '/builds/slave/test', 14:53:03 INFO - 'SHELL': '/bin/bash', 14:53:03 INFO - 'SHLVL': '1', 14:53:03 INFO - 'TERM': 'linux', 14:53:03 INFO - 'TMOUT': '86400', 14:53:03 INFO - 'USER': 'cltbld', 14:53:03 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', 14:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:03 INFO - '_': '/tools/buildbot/bin/python'} 14:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:04 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 14:53:04 INFO - Cleaning up... 14:53:04 INFO - Return code: 0 14:53:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:53:04 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')]} 14:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:04 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')]} 14:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:04 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 0x1c211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1deda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f92c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f903f0>, '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 0x1f90cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f911a0>, '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': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', '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 14:53:04 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 14:53:04 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 14:53:04 INFO - Using env: {'DISPLAY': ':0', 14:53:04 INFO - 'HOME': '/home/cltbld', 14:53:04 INFO - 'LANG': 'en_US.UTF-8', 14:53:04 INFO - 'LANGUAGE': 'en_US:en', 14:53:04 INFO - 'LOGNAME': 'cltbld', 14:53:04 INFO - 'MAIL': '/var/mail/cltbld', 14:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:04 INFO - 'MOZ_NO_REMOTE': '1', 14:53:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:04 INFO - 'NO_EM_RESTART': '1', 14:53:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:04 INFO - 'PWD': '/builds/slave/test', 14:53:04 INFO - 'SHELL': '/bin/bash', 14:53:04 INFO - 'SHLVL': '1', 14:53:04 INFO - 'TERM': 'linux', 14:53:04 INFO - 'TMOUT': '86400', 14:53:04 INFO - 'USER': 'cltbld', 14:53:04 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', 14:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:04 INFO - '_': '/tools/buildbot/bin/python'} 14:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:04 INFO - Downloading/unpacking psutil>=3.1.1 14:53: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 14:53: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 14:53: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 14:53: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 14:53: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 14:53: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 14:53:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:53:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:53:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:53:06 INFO - Installing collected packages: psutil 14:53:06 INFO - Running setup.py install for psutil 14:53:06 INFO - building 'psutil._psutil_linux' extension 14:53:06 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 14:53:06 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 14:53:06 INFO - building 'psutil._psutil_posix' extension 14:53:06 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 14:53:06 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 14:53:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:53:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:53:06 INFO - Successfully installed psutil 14:53:06 INFO - Cleaning up... 14:53:07 INFO - Return code: 0 14:53:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:53:07 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')]} 14:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:07 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')]} 14:53:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:07 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 0x1c211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1deda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f92c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f903f0>, '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 0x1f90cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f911a0>, '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': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', '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 14:53:07 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 14:53:07 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 14:53:07 INFO - Using env: {'DISPLAY': ':0', 14:53:07 INFO - 'HOME': '/home/cltbld', 14:53:07 INFO - 'LANG': 'en_US.UTF-8', 14:53:07 INFO - 'LANGUAGE': 'en_US:en', 14:53:07 INFO - 'LOGNAME': 'cltbld', 14:53:07 INFO - 'MAIL': '/var/mail/cltbld', 14:53:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:07 INFO - 'MOZ_NO_REMOTE': '1', 14:53:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:07 INFO - 'NO_EM_RESTART': '1', 14:53:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:07 INFO - 'PWD': '/builds/slave/test', 14:53:07 INFO - 'SHELL': '/bin/bash', 14:53:07 INFO - 'SHLVL': '1', 14:53:07 INFO - 'TERM': 'linux', 14:53:07 INFO - 'TMOUT': '86400', 14:53:07 INFO - 'USER': 'cltbld', 14:53:07 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', 14:53:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:07 INFO - '_': '/tools/buildbot/bin/python'} 14:53:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:53:07 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 14:53:07 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 14:53:07 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 14:53:07 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 14:53:07 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 14:53:07 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 14:53:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:53:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:53:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:53:09 INFO - Installing collected packages: mozsystemmonitor 14:53:09 INFO - Running setup.py install for mozsystemmonitor 14:53:09 INFO - Successfully installed mozsystemmonitor 14:53:09 INFO - Cleaning up... 14:53:09 INFO - Return code: 0 14:53:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1deda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f92c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f903f0>, '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 0x1f90cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f911a0>, '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': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', '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 14:53:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:53:09 INFO - Using env: {'DISPLAY': ':0', 14:53:09 INFO - 'HOME': '/home/cltbld', 14:53:09 INFO - 'LANG': 'en_US.UTF-8', 14:53:09 INFO - 'LANGUAGE': 'en_US:en', 14:53:09 INFO - 'LOGNAME': 'cltbld', 14:53:09 INFO - 'MAIL': '/var/mail/cltbld', 14:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:09 INFO - 'MOZ_NO_REMOTE': '1', 14:53:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:09 INFO - 'NO_EM_RESTART': '1', 14:53:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:09 INFO - 'PWD': '/builds/slave/test', 14:53:09 INFO - 'SHELL': '/bin/bash', 14:53:09 INFO - 'SHLVL': '1', 14:53:09 INFO - 'TERM': 'linux', 14:53:09 INFO - 'TMOUT': '86400', 14:53:09 INFO - 'USER': 'cltbld', 14:53:09 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', 14:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:09 INFO - '_': '/tools/buildbot/bin/python'} 14:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:09 INFO - Downloading/unpacking jsonschema==2.5.1 14:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:53:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:11 INFO - Downloading functools32-3.2.3-2.tar.gz 14:53:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:53:11 INFO - warning: no files found matching '*.txt' 14:53:11 INFO - no previously-included directories found matching 'build' 14:53:11 INFO - no previously-included directories found matching 'dist' 14:53:11 INFO - no previously-included directories found matching '.git*' 14:53:11 INFO - Installing collected packages: jsonschema, functools32 14:53:11 INFO - Running setup.py install for functools32 14:53:12 INFO - warning: no files found matching '*.txt' 14:53:12 INFO - no previously-included directories found matching 'build' 14:53:12 INFO - no previously-included directories found matching 'dist' 14:53:12 INFO - no previously-included directories found matching '.git*' 14:53:12 INFO - Successfully installed jsonschema functools32 14:53:12 INFO - Cleaning up... 14:53:12 INFO - Return code: 0 14:53:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:53:12 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')]} 14:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:12 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')]} 14:53:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:12 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 0x1c211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1deda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f92c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f903f0>, '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 0x1f90cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f911a0>, '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': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', '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 14:53:12 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 14:53:12 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 14:53:12 INFO - Using env: {'DISPLAY': ':0', 14:53:12 INFO - 'HOME': '/home/cltbld', 14:53:12 INFO - 'LANG': 'en_US.UTF-8', 14:53:12 INFO - 'LANGUAGE': 'en_US:en', 14:53:12 INFO - 'LOGNAME': 'cltbld', 14:53:12 INFO - 'MAIL': '/var/mail/cltbld', 14:53:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:12 INFO - 'MOZ_NO_REMOTE': '1', 14:53:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:12 INFO - 'NO_EM_RESTART': '1', 14:53:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:12 INFO - 'PWD': '/builds/slave/test', 14:53:12 INFO - 'SHELL': '/bin/bash', 14:53:12 INFO - 'SHLVL': '1', 14:53:12 INFO - 'TERM': 'linux', 14:53:12 INFO - 'TMOUT': '86400', 14:53:12 INFO - 'USER': 'cltbld', 14:53:12 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', 14:53:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:12 INFO - '_': '/tools/buildbot/bin/python'} 14:53:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:53:12 INFO - Cleaning up... 14:53:12 INFO - Return code: 0 14:53:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:53:12 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')]} 14:53:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:12 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')]} 14:53:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:12 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 0x1c211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1deda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f92c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f903f0>, '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 0x1f90cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f911a0>, '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': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', '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 14:53:12 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 14:53:12 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 14:53:12 INFO - Using env: {'DISPLAY': ':0', 14:53:12 INFO - 'HOME': '/home/cltbld', 14:53:12 INFO - 'LANG': 'en_US.UTF-8', 14:53:12 INFO - 'LANGUAGE': 'en_US:en', 14:53:12 INFO - 'LOGNAME': 'cltbld', 14:53:12 INFO - 'MAIL': '/var/mail/cltbld', 14:53:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:12 INFO - 'MOZ_NO_REMOTE': '1', 14:53:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:12 INFO - 'NO_EM_RESTART': '1', 14:53:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:12 INFO - 'PWD': '/builds/slave/test', 14:53:12 INFO - 'SHELL': '/bin/bash', 14:53:12 INFO - 'SHLVL': '1', 14:53:12 INFO - 'TERM': 'linux', 14:53:12 INFO - 'TMOUT': '86400', 14:53:12 INFO - 'USER': 'cltbld', 14:53:12 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', 14:53:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:12 INFO - '_': '/tools/buildbot/bin/python'} 14:53:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:12 INFO - Downloading/unpacking blobuploader==1.2.4 14:53:12 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 14:53:12 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 14:53:12 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 14:53:12 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 14:53:12 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 14:53:12 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 14:53:14 INFO - Downloading blobuploader-1.2.4.tar.gz 14:53:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:53:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:53:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:53:15 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 14:53:15 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 14:53:15 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 14:53:15 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 14:53:15 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 14:53:15 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 14:53:15 INFO - Downloading docopt-0.6.1.tar.gz 14:53:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:53:15 INFO - Installing collected packages: blobuploader, requests, docopt 14:53:15 INFO - Running setup.py install for blobuploader 14:53:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:53:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:53:15 INFO - Running setup.py install for requests 14:53:15 INFO - Running setup.py install for docopt 14:53:15 INFO - Successfully installed blobuploader requests docopt 14:53:15 INFO - Cleaning up... 14:53:16 INFO - Return code: 0 14:53:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:16 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 0x1c211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1deda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f92c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f903f0>, '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 0x1f90cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f911a0>, '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': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', '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 14:53:16 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 14:53:16 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 14:53:16 INFO - Using env: {'DISPLAY': ':0', 14:53:16 INFO - 'HOME': '/home/cltbld', 14:53:16 INFO - 'LANG': 'en_US.UTF-8', 14:53:16 INFO - 'LANGUAGE': 'en_US:en', 14:53:16 INFO - 'LOGNAME': 'cltbld', 14:53:16 INFO - 'MAIL': '/var/mail/cltbld', 14:53:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:16 INFO - 'MOZ_NO_REMOTE': '1', 14:53:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:16 INFO - 'NO_EM_RESTART': '1', 14:53:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:16 INFO - 'PWD': '/builds/slave/test', 14:53:16 INFO - 'SHELL': '/bin/bash', 14:53:16 INFO - 'SHLVL': '1', 14:53:16 INFO - 'TERM': 'linux', 14:53:16 INFO - 'TMOUT': '86400', 14:53:16 INFO - 'USER': 'cltbld', 14:53:16 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', 14:53:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:16 INFO - '_': '/tools/buildbot/bin/python'} 14:53:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:53:16 INFO - Running setup.py (path:/tmp/pip-N4yDt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:53:16 INFO - Running setup.py (path:/tmp/pip-sXl2Ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:53:16 INFO - Running setup.py (path:/tmp/pip-9A6ZyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:53:16 INFO - Running setup.py (path:/tmp/pip-mxU5Xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:53:16 INFO - Running setup.py (path:/tmp/pip-T00XLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:53:16 INFO - Running setup.py (path:/tmp/pip-2UEYEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:53:16 INFO - Running setup.py (path:/tmp/pip-ZDeKiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:53:16 INFO - Running setup.py (path:/tmp/pip-HChnDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:53:16 INFO - Running setup.py (path:/tmp/pip-LKDy_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:53:16 INFO - Running setup.py (path:/tmp/pip-IrXtJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:53:16 INFO - Running setup.py (path:/tmp/pip-OAbnpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:53:17 INFO - Running setup.py (path:/tmp/pip-b6fU7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:53:17 INFO - Running setup.py (path:/tmp/pip-otwP9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:53:17 INFO - Running setup.py (path:/tmp/pip-bThKa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:17 INFO - Running setup.py (path:/tmp/pip-Hble2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:53:17 INFO - Running setup.py (path:/tmp/pip-QPcsd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:53:17 INFO - Running setup.py (path:/tmp/pip-ft5pN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:53:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:53:17 INFO - Running setup.py install for manifestparser 14:53:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:53:17 INFO - Running setup.py install for mozcrash 14:53:17 INFO - Running setup.py install for mozdebug 14:53:17 INFO - Running setup.py install for mozdevice 14:53:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:53:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:53:17 INFO - Running setup.py install for mozfile 14:53:17 INFO - Running setup.py install for mozhttpd 14:53:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Running setup.py install for mozinfo 14:53:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Running setup.py install for mozInstall 14:53:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Running setup.py install for mozleak 14:53:18 INFO - Running setup.py install for mozlog 14:53:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Running setup.py install for moznetwork 14:53:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Running setup.py install for mozprocess 14:53:18 INFO - Running setup.py install for mozprofile 14:53:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Running setup.py install for mozrunner 14:53:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:53:18 INFO - Running setup.py install for mozscreenshot 14:53:19 INFO - Running setup.py install for moztest 14:53:19 INFO - Running setup.py install for mozversion 14:53:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:53:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:53:19 INFO - Cleaning up... 14:53:19 INFO - Return code: 0 14:53:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:19 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 0x1c211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1deda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f92c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f903f0>, '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 0x1f90cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f911a0>, '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': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', '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 14:53:19 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 14:53:19 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 14:53:19 INFO - Using env: {'DISPLAY': ':0', 14:53:19 INFO - 'HOME': '/home/cltbld', 14:53:19 INFO - 'LANG': 'en_US.UTF-8', 14:53:19 INFO - 'LANGUAGE': 'en_US:en', 14:53:19 INFO - 'LOGNAME': 'cltbld', 14:53:19 INFO - 'MAIL': '/var/mail/cltbld', 14:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:19 INFO - 'MOZ_NO_REMOTE': '1', 14:53:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:19 INFO - 'NO_EM_RESTART': '1', 14:53:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:19 INFO - 'PWD': '/builds/slave/test', 14:53:19 INFO - 'SHELL': '/bin/bash', 14:53:19 INFO - 'SHLVL': '1', 14:53:19 INFO - 'TERM': 'linux', 14:53:19 INFO - 'TMOUT': '86400', 14:53:19 INFO - 'USER': 'cltbld', 14:53:19 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', 14:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:19 INFO - '_': '/tools/buildbot/bin/python'} 14:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:53:19 INFO - Running setup.py (path:/tmp/pip-5j9Dmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:53:19 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)) 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:53:19 INFO - Running setup.py (path:/tmp/pip-RF4Ri2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:53:19 INFO - Running setup.py (path:/tmp/pip-ohxqM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:53:19 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)) 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:53:19 INFO - Running setup.py (path:/tmp/pip-ztRvS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:53:19 INFO - Running setup.py (path:/tmp/pip-OElz92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:53:19 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)) 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:53:19 INFO - Running setup.py (path:/tmp/pip-hSVcSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:53:19 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)) 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:53:19 INFO - Running setup.py (path:/tmp/pip-ygXjUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:53:19 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)) 14:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:53:19 INFO - Running setup.py (path:/tmp/pip-NnUg63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:53:20 INFO - Running setup.py (path:/tmp/pip-VA5jqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:53:20 INFO - Running setup.py (path:/tmp/pip-NlGegU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:53:20 INFO - Running setup.py (path:/tmp/pip-mHycbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:53:20 INFO - Running setup.py (path:/tmp/pip-sMoHPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:53:20 INFO - Running setup.py (path:/tmp/pip-wtFMq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:53:20 INFO - Running setup.py (path:/tmp/pip-2BKGiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:20 INFO - Running setup.py (path:/tmp/pip-xZAB0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:53:20 INFO - Running setup.py (path:/tmp/pip-PugI6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:53:20 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)) 14:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:53:20 INFO - Running setup.py (path:/tmp/pip-BjKY8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:53:20 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)) 14:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:53:20 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)) 14:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:53:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:53:20 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 14:53:20 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 14:53:20 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 14:53:20 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 14:53:20 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 14:53:20 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 14:53:22 INFO - Downloading blessings-1.6.tar.gz 14:53:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:53:22 INFO - Installing collected packages: blessings 14:53:22 INFO - Running setup.py install for blessings 14:53:22 INFO - Successfully installed blessings 14:53:22 INFO - Cleaning up... 14:53:22 INFO - Return code: 0 14:53:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:53:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:53:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:53:23 INFO - Reading from file tmpfile_stdout 14:53:23 INFO - Current package versions: 14:53:23 INFO - argparse == 1.2.1 14:53:23 INFO - blessings == 1.6 14:53:23 INFO - blobuploader == 1.2.4 14:53:23 INFO - docopt == 0.6.1 14:53:23 INFO - functools32 == 3.2.3-2 14:53:23 INFO - jsonschema == 2.5.1 14:53:23 INFO - manifestparser == 1.1 14:53:23 INFO - mozInstall == 1.12 14:53:23 INFO - mozcrash == 1.0 14:53:23 INFO - mozdebug == 0.1 14:53:23 INFO - mozdevice == 0.49 14:53:23 INFO - mozfile == 1.2 14:53:23 INFO - mozhttpd == 0.7 14:53:23 INFO - mozinfo == 0.9 14:53:23 INFO - mozleak == 0.1 14:53:23 INFO - mozlog == 3.4 14:53:23 INFO - moznetwork == 0.27 14:53:23 INFO - mozprocess == 0.23 14:53:23 INFO - mozprofile == 0.28 14:53:23 INFO - mozrunner == 6.13 14:53:23 INFO - mozscreenshot == 0.1 14:53:23 INFO - mozsystemmonitor == 0.3 14:53:23 INFO - moztest == 0.7 14:53:23 INFO - mozversion == 1.4 14:53:23 INFO - psutil == 3.1.1 14:53:23 INFO - requests == 1.2.3 14:53:23 INFO - wsgiref == 0.1.2 14:53:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:23 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 0x1c211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1deda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f92c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f903f0>, '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 0x1f90cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f911a0>, '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': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', '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 14:53:23 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 14:53:23 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 14:53:23 INFO - Using env: {'DISPLAY': ':0', 14:53:23 INFO - 'HOME': '/home/cltbld', 14:53:23 INFO - 'LANG': 'en_US.UTF-8', 14:53:23 INFO - 'LANGUAGE': 'en_US:en', 14:53:23 INFO - 'LOGNAME': 'cltbld', 14:53:23 INFO - 'MAIL': '/var/mail/cltbld', 14:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:23 INFO - 'MOZ_NO_REMOTE': '1', 14:53:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:23 INFO - 'NO_EM_RESTART': '1', 14:53:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:23 INFO - 'PWD': '/builds/slave/test', 14:53:23 INFO - 'SHELL': '/bin/bash', 14:53:23 INFO - 'SHLVL': '1', 14:53:23 INFO - 'TERM': 'linux', 14:53:23 INFO - 'TMOUT': '86400', 14:53:23 INFO - 'USER': 'cltbld', 14:53:23 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', 14:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:23 INFO - '_': '/tools/buildbot/bin/python'} 14:53:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 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)) 14:53:23 INFO - Cleaning up... 14:53:23 INFO - Return code: 0 14:53:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1deda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f92c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f903f0>, '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 0x1f90cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f911a0>, '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': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', '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 14:53:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:53:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:53:23 INFO - Using env: {'DISPLAY': ':0', 14:53:23 INFO - 'HOME': '/home/cltbld', 14:53:23 INFO - 'LANG': 'en_US.UTF-8', 14:53:23 INFO - 'LANGUAGE': 'en_US:en', 14:53:23 INFO - 'LOGNAME': 'cltbld', 14:53:23 INFO - 'MAIL': '/var/mail/cltbld', 14:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:23 INFO - 'MOZ_NO_REMOTE': '1', 14:53:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:23 INFO - 'NO_EM_RESTART': '1', 14:53:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:23 INFO - 'PWD': '/builds/slave/test', 14:53:23 INFO - 'SHELL': '/bin/bash', 14:53:23 INFO - 'SHLVL': '1', 14:53:23 INFO - 'TERM': 'linux', 14:53:23 INFO - 'TMOUT': '86400', 14:53:23 INFO - 'USER': 'cltbld', 14:53:23 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', 14:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:23 INFO - '_': '/tools/buildbot/bin/python'} 14:53:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:53:23 INFO - Cleaning up... 14:53:23 INFO - Return code: 0 14:53:23 INFO - Running post-action listener: _resource_record_post_action 14:53:23 INFO - Running post-action listener: _start_resource_monitoring 14:53:23 INFO - Starting resource monitoring. 14:53:23 INFO - [mozharness: 2016-12-28 22:53:23.723296Z] Finished create-virtualenv step (success) 14:53:23 INFO - [mozharness: 2016-12-28 22:53:23.723850Z] Running install step. 14:53:23 INFO - Running pre-action listener: _resource_record_pre_action 14:53:23 INFO - Running main action method: install 14:53:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:53:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:53:23 INFO - Reading from file tmpfile_stdout 14:53:23 INFO - Detecting whether we're running mozinstall >=1.0... 14:53:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:53:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:53:24 INFO - Reading from file tmpfile_stdout 14:53:24 INFO - Output received: 14:53:24 INFO - Usage: mozinstall [options] installer 14:53:24 INFO - Options: 14:53:24 INFO - -h, --help show this help message and exit 14:53:24 INFO - -d DEST, --destination=DEST 14:53:24 INFO - Directory to install application into. [default: 14:53:24 INFO - "/builds/slave/test"] 14:53:24 INFO - --app=APP Application being installed. [default: firefox] 14:53:24 INFO - mkdir: /builds/slave/test/build/application 14:53:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:53:40 INFO - Reading from file tmpfile_stdout 14:53:40 INFO - Output received: 14:53:40 INFO - /builds/slave/test/build/application/firefox/firefox 14:53:40 INFO - Running post-action listener: _resource_record_post_action 14:53:40 INFO - [mozharness: 2016-12-28 22:53:40.121021Z] Finished install step (success) 14:53:40 INFO - [mozharness: 2016-12-28 22:53:40.121266Z] Running run-tests step. 14:53:40 INFO - Running pre-action listener: _resource_record_pre_action 14:53:40 INFO - Running main action method: run_tests 14:53:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:53:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:53:40 INFO - Python 2.7.3 14:53:40 INFO - Return code: 0 14:53:40 INFO - grabbing minidump binary from tooltool 14:53:40 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')]} 14:53:40 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 0x1f903f0>, '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 0x1f90cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f911a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:53:40 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 14:53:40 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 14:53:40 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 14:53:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:53:40 INFO - Return code: 0 14:53:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:53:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:53:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:53:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:53:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:53:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 14:53:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 14:53:40 INFO - Using env: {'DISPLAY': ':0', 14:53:40 INFO - 'HOME': '/home/cltbld', 14:53:40 INFO - 'LANG': 'en_US.UTF-8', 14:53:40 INFO - 'LANGUAGE': 'en_US:en', 14:53:40 INFO - 'LOGNAME': 'cltbld', 14:53:40 INFO - 'MAIL': '/var/mail/cltbld', 14:53:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:53:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:53:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:40 INFO - 'MOZ_NO_REMOTE': '1', 14:53:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:53:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:40 INFO - 'NO_EM_RESTART': '1', 14:53:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:40 INFO - 'PWD': '/builds/slave/test', 14:53:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:53:40 INFO - 'SHELL': '/bin/bash', 14:53:40 INFO - 'SHLVL': '1', 14:53:40 INFO - 'TERM': 'linux', 14:53:40 INFO - 'TMOUT': '86400', 14:53:40 INFO - 'USER': 'cltbld', 14:53:40 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434', 14:53:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:40 INFO - '_': '/tools/buildbot/bin/python'} 14:53:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 14:53:40 INFO - mozversion application_buildid: 20161228140637 14:53:40 INFO - mozversion application_changeset: 2fa9856e4ccf1f0cfe423c82f39df67b275d3b77 14:53:40 INFO - mozversion application_display_name: Nightly 14:53:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:53:40 INFO - mozversion application_name: Firefox 14:53:40 INFO - mozversion application_remotingname: firefox 14:53:40 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 14:53:40 INFO - mozversion application_vendor: Mozilla 14:53:40 INFO - mozversion application_version: 53.0a1 14:53:40 INFO - mozversion platform_buildid: 20161228140637 14:53:40 INFO - mozversion platform_changeset: 2fa9856e4ccf1f0cfe423c82f39df67b275d3b77 14:53:40 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 14:53:40 INFO - mozversion platform_version: 53.0a1 14:53:40 INFO - using testdate: 1482965620 14:53:40 INFO - actual date: 1482965620 14:53:40 INFO - starting webserver on 'localhost:42369' 14:53:40 INFO - SUITE-START | Running 2 tests 14:53:40 INFO - TEST-START | dromaeo_css 14:53:40 INFO - operating with platform_type : linux_ 14:53:40 INFO - Initialising browser for dromaeo_css test... 14:53:40 INFO - TEST-INFO | started process 15853 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU9gM0r/profile http://localhost:42369/getInfo.html) 14:53:40 INFO - PROCESS | 15853 | 1482965620948 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 14:53:44 INFO - PROCESS | 15853 | __metrics Screen width/height:1600/1200 14:53:44 INFO - PROCESS | 15853 | colorDepth:24 14:53:44 INFO - PROCESS | 15853 | Browser inner width/height: 1024/697 14:53:44 INFO - PROCESS | 15853 | __metrics 14:53:45 INFO - PROCESS | 15853 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:53:45 INFO - PROCESS | 15853 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:53:45 INFO - PROCESS | 15853 | console.error: PushService: 14:53:45 INFO - PROCESS | 15853 | stateChangeProcessEnqueue: Error transitioning state 14:53:45 INFO - PROCESS | 15853 | UnknownError 14:53:46 INFO - TEST-INFO | 15853: exit 0 14:53:46 INFO - Browser initialized. 14:53:46 INFO - Running cycle 1/1 for dromaeo_css test... 14:53:46 INFO - TEST-INFO | started process 15933 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU9gM0r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:53:58 INFO - PROCESS | 15933 | RSS: Main: 150937600 14:53:58 INFO - PROCESS | 15933 | 14:57:03 INFO - PROCESS | 15933 | Cycle 1(1): loaded http://localhost:42369/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42369/tests/dromaeo/cssquery-ext.html) 14:57:04 INFO - PROCESS | 15933 | RSS: Main: 254889984 14:57:04 INFO - PROCESS | 15933 | 14:58:47 INFO - PROCESS | 15933 | ************************* 14:58:47 INFO - PROCESS | 15933 | A coding exception was thrown and uncaught in a Task. 14:58:47 INFO - PROCESS | 15933 | 14:58:47 INFO - PROCESS | 15933 | Full message: ReferenceError: fetch is not defined 14:58:47 INFO - PROCESS | 15933 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 14:58:47 INFO - PROCESS | 15933 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 14:58:47 INFO - PROCESS | 15933 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 14:58:47 INFO - PROCESS | 15933 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 14:58:47 INFO - PROCESS | 15933 | TaskImpl@resource://gre/modules/Task.jsm:277:3 14:58:47 INFO - PROCESS | 15933 | asyncFunction@resource://gre/modules/Task.jsm:252:14 14:58:47 INFO - PROCESS | 15933 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 14:58:47 INFO - PROCESS | 15933 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 14:58:47 INFO - PROCESS | 15933 | TaskImpl@resource://gre/modules/Task.jsm:277:3 14:58:47 INFO - PROCESS | 15933 | asyncFunction@resource://gre/modules/Task.jsm:252:14 14:58:47 INFO - PROCESS | 15933 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 14:58:47 INFO - PROCESS | 15933 | 14:58:47 INFO - PROCESS | 15933 | ************************* 15:00:07 INFO - PROCESS | 15933 | Cycle 1(1): loaded http://localhost:42369/tests/dromaeo/cssquery-ext.html (next: http://localhost:42369/tests/dromaeo/cssquery-jquery.html) 15:00:07 INFO - PROCESS | 15933 | RSS: Main: 241180672 15:00:07 INFO - PROCESS | 15933 | 15:03:10 INFO - PROCESS | 15933 | Cycle 1(1): loaded http://localhost:42369/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42369/tests/dromaeo/cssquery-mootools.html) 15:03:10 INFO - PROCESS | 15933 | RSS: Main: 184827904 15:03:10 INFO - PROCESS | 15933 | 15:06:13 INFO - PROCESS | 15933 | Cycle 1(1): loaded http://localhost:42369/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42369/tests/dromaeo/cssquery-prototype.html) 15:06:13 INFO - PROCESS | 15933 | RSS: Main: 190611456 15:06:13 INFO - PROCESS | 15933 | 15:09:16 INFO - PROCESS | 15933 | Cycle 1(1): loaded http://localhost:42369/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42369/tests/dromaeo/cssquery-yui.html) 15:09:17 INFO - PROCESS | 15933 | RSS: Main: 184381440 15:09:17 INFO - PROCESS | 15933 | 15:12:20 INFO - PROCESS | 15933 | Cycle 1(1): loaded http://localhost:42369/tests/dromaeo/cssquery-yui.html (next: http://localhost:42369/tests/dromaeo/cssquery-dojo.html) 15:12:20 INFO - PROCESS | 15933 | RSS: Main: 278265856 15:12:20 INFO - PROCESS | 15933 | 15:12:20 INFO - PROCESS | 15933 | __start_tp_report 15:12:20 INFO - PROCESS | 15933 | _x_x_mozilla_page_load 15:12:20 INFO - PROCESS | 15933 | _x_x_mozilla_page_load_details 15:12:20 INFO - PROCESS | 15933 | |i|pagename|runs| 15:12:20 INFO - PROCESS | 15933 | |0;dojo.html;2541.0852093246426;2603.323135984644;2629.434671545617;2652.4297276085636;2657.096587160366;4765.594924431419;4819.662623616348;4824.903501929968;4847.491013443586;4873.098476781798;4776.737279449638;4797.184478638631;4841.176999910015;4858.902821943559;4890.8777280568;4318.956810431895;4357.17213729392;4391.824327026915;4447.266201076818;4513.052259025592;4053.837846486137;4398.120375924812;4490.528494508078;4511.819527218908;4576.039031803325;4367.563243675639;4397.31841564558;4449.221386257392;4483.910321793578;4526.049529598772;4382.737035777864;4437.312216606399;4447.977760111211;4457.264551349023;4525.140223357552;4447.399601053881;4456.064226512404;4461.420015397977;4466.843660471868;4505.189065968125;4826.831060912851;4841.298011788306;4853.369062021915;4855.660103792736;4864.5621894029355;4994.275830004629;5032.169692000815;5134.101532231882;5157.71632560209;5194.68831870089;5227.738613069369;5229.607779416559;5236.607254455893;5262.894742105136;5280.735963201824;4884.5848102911295;4885.291632713237;4886.462489126193;4886.877828054327;4930;5156.63903526757;5238.711870847063;5242.9213561796605;5341.62608617393;5344.171653393731;4980.477049909779;5035.748212589356;5078.619103567174;5082.237664350377;5090.389153301628;5050.621203409685;5086.491350864958;5098.286239926413;5188.429272779991;5208.8437346879655;4969.428515720666;4978.079055374767;4978.178600530944;5049.469805670352;5088.796448142033;5018.222175562793;5033.748312584356;5039.370078740158;5040.1179793535985;5040.1179793535985;4387.188370151533;4404.66964977628;4408.030233348658;4417.293233082692;4419.447569053868;3811.714121440903;3813.7521061130938;3835.5013848200115;3848.749831260959;3854.0750219947013;4371.497277813029;4372.497162826316;4376.102898905717;4405.493368262627;4415.359772832921;5056.469070747592;5129.9230511542355;5136.255242989746;5141.305923700327;5148.407933087743;4243.724157929786;4330.978345108254;4365.738055716603;4395.1868904252815;4454.28731402974;4666.906661866744;4682.555157260055;4692.225782745877;4696.0842635686395;4734.360861283752;4839.854804355805;4842.539958703917;4843.24929635907;4847.442544107473;4891.070696567641;5017;5084.491550844886;5149.382074150976;5193.272941788228;5230;4879.219324907997;4882.389701287339;4885.633577481632;4926.310316555757;4930.211166213388;5149.562287205629;5155.484451554815;5157.484251574962;5160.793568257378;5163.3545806774155;5016.097102521581;5044.54599086084;5138.434772175133;5179.067767801681;5215.687058776485;5033.798648054037;5034.647574669738;5037.899242015213;5072.974635126949;5087.949120508748;4850.781714822912;4994.700317980933;5031.597472202142;5053.267276244997;5094.337736094284;4841.975790121027;4920.21276595743;4922.360093187863;4926.679765815211;4937.5556199942175;4959.181735013826;5005.174146265826;5011.84964451067;5018.598512118949;5075.847724568269;4873.4882837301575;4895;4896.706197628154;4905.23968784839;4952.059108769322 15:12:20 INFO - PROCESS | 15933 | |1;ext.html;519.311911716975;558.5894367639784;559.2143039030161;560.812127937141;566.2977907394832;28010;30376.544351834684;30388.544171837413;37915.620843791556;39916.40125398114;39579.208415831665;40031.59968400315;40046.79976600113;40068.79965600175;40146.39780403289;7683.270089341504;7846.960765196166;7848.843023139534;7854.214578542129;7854.7250846220395;7476.36450901674;7662.157162712125;7690.15408305086;7778.105517865433;9890.703278901608;5786.56600754943;5865.85335366615;5871.911921321183;5873.4420230078285;5878.470937615613;9726.999999999965;9798.461084640341;9812.264080193978;9813.165880900133;9824.508774561244;9914.107730304235;10001.79996400068;10017.24870634698;10043.849342259871;10047.397156170653;36704.265914681826;37256.813715931516;37279;37329.81335093334;37367;3610.5125808015846;3750.1749615084636;3753.8498460061564;3788.4885540452237;3790.488284081669;28401.431971360456;28433.431331373464;28593.428131437464;28736.425271494452;29799.702002980128;3258.7230085442766;3343.732501399882;3399.9320013599836;3496.4230901901155;3522.8766993155373;253002;253897;254513;254591.99999999814;255490;46407.53592464099;46896;46896.5310346899;46908.061838763715;46910.06179876383;48795;48904.75547622239;48907.51092489101;48926.8197567157;48979.755101224975;12343.876561234452;12509.624711258677;12520.373981300898;12522.436490357955;12522.874771252353;9624.133827955518;9625.855612165824;9630.614775408905;9635.518224088768;9664.37181583209;13882.583522494342;14226.928865355607;14265.78601320981;14717.92641036788;14726.190059546614;5701.971490142523;6258.15514905491;6390.348005577634;6407.231132264158;6513;791.9762407127795;796.3271035974592;796.366888323784;802.2734398718039;827.668932427034;26052.869735651202;27318.863405682845;27352.863235683693;27554;27769.444611107665;880.506916126971;892.2594246775291;894.2443635128307;897.599744398727;1070.0476575847501;14485.565433037025;14784.704305913821;14901.403943842124;14922.179280139479;15137;350387;405647;411578;413074;618204;37953.430698539545;38641.806790965864;42280.788596056824;42998;43156.3526547102;1190.0776897904193;1208.1663652080038;1211.418519110814;1214.5566868093083;1217.9208351457128;24697.2082677258;25227.243182704548;25905.222843314732;25930.814115031924;25999.61000584955;36691.44962825506;37349.25301493901;37435.62564374322;37438.81280593688;37451.81274093612;265655;268897;269270;270469;270771;22524.21165259208;22815.201467948544;22873.656895146254;22874.31377058691;22918.73946932868;34639.13402164865;34932.65067349294;35012;36057.819710901276;36073.0981725459;29639.407211856073;30138.69861301359;30228.395432090794;30257.24356891095;30626.92805751877;29749.553756693233;30113.3977320448;30277.545836812908;30648.846755766077;30841.38317233598;29657;29706.405871882875;29769.404611908074;31516.060492438446;32182;25277;25900.61149082803;26565.60151597689;26856.59715104314;27300.86349568319;22613.43466413353;22993.65509517325;23035.6544651827;23084.192053278053;23158.652620210374 15:12:20 INFO - PROCESS | 15933 | |2;jquery.html;361.36941037888886;363.3840640115006;373.94577786221;377.2210385553832;398.47401430112245;12732.172408793422;15793.28930198141;16137.757933631005;16465.25906334215;17229;16653.91673041633;16739;16768.580785480368;16996.235169417374;17010.914945425287;4932.432770231433;4950.579200767921;5073.036123136592;5099.974500127494;5114.795408183668;3862.9034274143232;4038.6567141792984;5038.924416133761;5102;5102.821401250957;5750.884982300351;5994.700264986755;6011.038233882579;6094.116353128794;6117.847053823645;5810.506106980912;5956.672383018932;5998.790042348497;6084.999999999978;6100.145979562864;5266.394364648077;5268.5521730652945;5270.314859068297;5272.894542109175;5273.4462881397285;18518.814811851844;20295.79704202954;20543;20642.48393790152;20666.5866682667;2333.813294936418;2473.579491486452;2486.4032632168332;2491.53906527293;2522.7603377679093;8937.64249430022;9106.499142547158;9264.907350926473;9595.088466595662;9657.661981830672;1670.7243304854806;1716.3134746101534;1719.9656006879793;1737.3571778442058;1755.6576467588825;61596.384036159514;63092.68453657702;64409.355906440804;64594.677026615034;66353;9456.52717364129;9727.270454715925;9822.361546499455;9956.660184856553;9973.202143828476;13652.590422287347;13656.590302290948;13667.248301343521;13675.24786136772;13682.247476388695;6803.65981700913;6983.441324694012;6988.231294557696;7086.291370862872;7134.500584959107;6118.20463339763;6272.435480806749;6397.296297407282;6510.283868774431;6654.767083152066;4236.2374772540625;4292.12011537634;4305.655547556188;4338.739675619473;4349.369341445473;1752.2640490993813;1780.4658602419222;1798.9370372036915;1819.5451137215696;1820.927162913495;722.5556282886092;737.5279820914619;744.2260049548518;752.5747952406873;758.6927294445758;15661.765073523906;16145.35418583267;16230.51308460748;16306.429274975319;16971.321147154224;755.6939439527;756.5611945071845;759.1080480435577;759.5974133709143;767.5241350362811;8982.595783189772;9510.33427660071;9618.759531011781;9631.807363852824;9704.417734935785;327162;328803;330369;331290;335918;63803;64122.35877641164;65026.349736502976;73923.26076739271;75917.62041189759;943.7923656795494;972.3971137894409;975.7755716185175;980.4705459052175;987.195435719886;15126.697466050397;15127.319270632628;15713.764293535378;15918.7612185815;16001;31875.52186717155;32737.836310818293;33320.16936527957;33762.66237337694;34763;44876;45289.54710452853;45572.54427455683;45778.54221457743;45872;7694.345980591713;7703.730369437043;7743.225677432437;8040.316573091119;8047.718329858427;10736.624218152327;11406.771864562828;11580.942095289469;11626.476808543466;12040.819387709016;10275.126614237874;10285.331453455501;10322.432266225429;10539.262251642618;10611.840822387825;10199.184065274612;10221.28451008452;10367.32612380193;10391.168706503311;10484.21368397358;9727.270454715783;10134.746631333981;10217.795644086927;10481.459775709367;10766.084882785051;7682.539047657158;7768.114206447941;7917.327027202752;8099.392545558989;8168.428210025241;9978.900210998088;10174.796504070026;10197;10271.640492583023;10803.783924321628 15:12:20 INFO - PROCESS | 15933 | |3;mootools.html;1310.842698876135;1313.770090234209;1319.9142055766372;1322.7619028574866;1327.4756471193873;6796.558223715461;7179.676914538845;7182.9281707182845;7185.065941427613;7194.964025179866;6530.6408147551865;6720.495962802785;6806.863862722743;7075.150981882169;7112.933060040997;2106.3154474795715;2174.9891250543806;2180.9345719628436;2184.9344519664437;2185.25701261571;2144.2280778919576;2171.1532502324108;2177.923772667957;2179.9019044142974;2184.3337781976493;1731.7575539424427;1785.8392744652979;2072.5336799220204;2123.352377524854;2139.101577337522;2119.2582596091397;2122.713433686448;2123.3205374280237;2123.9150433982622;2125.5005073807642;2064.5148390128306;2090.2579584247524;2096.1929657081982;2099.2757498662936;2160.503084290622;7458;7509.624518774094;7518.849623007564;7524.623768811538;7554.962225188894;4487.730736155841;4615.446146462413;4616.422947131608;4622.95377046232;4631.837885674019;2715.1990162901793;2887.4513842370093;2913.1697466222026;2917.9708202918123;2992.0724575381482;1047.5233768635328;1099.8515200447916;1105.1269497097283;1121.0695123047851;1138.9088872890231;9573.617055317778;9789.608415663437;9798.804023919482;9817.410955342702;9831.754206144831;3940.330143875548;4063.817128229237;4138.317177665652;4171.541130475645;4216.3464662977285;8595.398322117517;8606.5696715164;8609.268212201907;8622.784430389165;8627.741167764978;4622.19111655459;4623.4451865775645;4626.097910907408;4636.675432719745;4646.883827904329;3728.683061939711;3832.674222691047;4079.143379890197;4191.937120943188;4198.832046718097;3527.7530572859655;3538.6992105670793;3662.7436079474714;3678.2275722098657;3759.49246851677;1178.3754610056683;1206.8913797758244;1209.9654795150195;1210.2375503432957;1210.2980271442543;680.482833046878;691.1386858406203;703.0543918429704;705.5519744961986;723.4176487927269;4500.392447019676;4699.553542413465;4757.50439681639;4787.0904528139545;4844.667716378024;508.51182864449805;512.8384558863946;517.332640893251;517.7023211653315;521.7339157029942;3000.604718805766;3065.632124145117;3119.454095533318;3128.483800172946;3195.3609278144463;8647.956760216159;8901.554922253861;8934.642614295486;8947.955260223658;9029.819403611891;8909.910900890907;8920.24177944882;8923.330750193762;8933.15135062168;8939;6594.901076483857;6769.593824370553;6799.558028728117;6805.761798337035;6821.045053692487;4695.600873925618;4742.78657460422;4761.642876784185;4766.380370551806;4804.567588917014;8051.637676304464;8065.637046332812;8121.878171827309;8161.183881611792;8183.79540511492;7984.600769961595;8142.10436851934;8204.548749818818;8277.337812974827;8280.668773249003;6002.849928751816;6086.1175129606845;6158.353372895782;6168.722407491584;6185.907211391743;6663.666816659051;6720.563163394364;6736.32636736342;6738.49461290415;6749.325067493408;6173.135760993554;6242.094896240111;6247.906281405873;6251.9687401562705;6426.196725409324;5927.792527261525;6108.57239993214;6147.231596050494;6221;6255.499560035278;5207.088759467154;5275.894482110413;5284.471552844685;5297.761600727899;5302.2576839241765;3816.561095473945;3859.9614003860725;3871.8838434847;3954.8615798446917;3966.8016599170505;4037.253108174997;4101.343784994387;4106.260873042881;4117.938230926478;4174.582541745801 15:12:20 INFO - PROCESS | 15933 | |4;prototype.html;348.3068693300333;356.1132779379345;361.49029867886276;370.8553664071011;371.15932413084386;4618.214903466411;4732.479427262998;4777.90444191116;4824.734639594825;4832.275158726188;4604.240300350438;4709.411323584552;4710.764461776915;4715.693479923802;4719.952800471994;3837.827297771607;3841.9807900960454;3847.03824043989;3851.788151651665;3892.9756526606147;3654.945175822365;3720.869769558066;3898.434726964588;3905.3556163233034;3940.921181576367;3689.3543629864666;3792.696584273265;3799.734018618698;3827.291950989062;3836.7506112102765;3818.4463252828464;3818.694504439638;3825.3305671507374;3828.942565861514;3836.9808150959066;3433.5536380270405;3453.4647129694686;3455.51622772812;3462.8787992420143;3466.341395134916;4537.546245375469;4549.203889319356;4611.953880461219;4651.1395391852275;4672.3925889634465;4332.285172946492;4333.39332493448;4339.913201735948;4347.087451407565;4349.630281426083;4962.1812400953795;4972.403311602595;4973.626977976666;4999.525045120708;5004.172642457;3615.5299811024397;3726.366517691999;3740.0649837540614;3750.8687195948282;3758.830852611639;5071.554606937005;5119.46245644202;5142.588592912595;5146.897062058775;5163.896722065538;3841.1741475582885;3844.3464611016193;3851.326017946904;3868.1103346230525;3872.792508270259;3952.110775075585;3952.565217826037;3957.3668213086344;3957.7229593928723;3971.245463361952;4253.659707223414;4296.398504209413;4327.956720432819;4335.219660461147;4338.891527711832;4951;4954.801807927643;4955.166588362257;4957.4794646561595;5027.773750181177;1262.9557965471165;1271.2309053022973;1286.6168868466436;1294.3657607772125;1313.2908229556124;929.7303781903172;951.6478902805868;951.9619215231452;957.1863915671623;962.9855552166723;4205.137946720914;4210.347396153601;4216.325387937915;4216.810243539048;4225.704200705921;4673.558579666633;4866.270059491105;4890.339804126468;4921.630877684188;4948.05986862495;4107.794610269475;4125.401816736557;4143.08852052548;4155.501339839178;4174.791260437027;2602.8568428736603;2603.8047146463714;2617.343396452602;2624.8031397644872;2626.947461050768;4367.934480982788;4558.495696420239;4580.4045474088825;4617.607503362252;4630.536946305342;4981.28145789702;4991.627302491771;5002.224655178453;5014.674046186986;5016.247562865599;4582.862514124582;4602.171609110393;4603.838865639686;4612.469565999953;4623.3758442609815;4743.644226682944;4853.854384368475;4899.4365647950945;4912.410510738734;4919;4823.469418364048;4827.831025914076;4832.806687732451;4845.53967373099;4862.197737373435;4593.242115051113;4607.3549703040935;4617.891705990471;4624.606908412823;4625.375574297562;3650.1422165790486;3703.6111208322736;3752.6059763724415;3765.1528406108405;3783.9432408513344;4866.75897646098;4871.3667223260745;4874.488178741181;4906.705597664151;4907.705537667752;4681.7190968542;4719.7404142772475;4726.1020406122725;4734.839964208791;4756.667033307635;4713.410823647044;4741.952580474152;4748.9050218994735;4785.186518291829;4875.536824001714;4788.832390866303;4871.87820304481;4876.317315575751;4885.780139893643;4887.511248875227;4974.353334066548;4978.551930326288;4987;5029.371328583927;5046.545810876892;4704.176769065331;4842.223066008519;4864.416270047617;4869.829555965524;4880.219164933594 15:12:20 INFO - PROCESS | 15933 | |5;yui.html;634.7968650031992;653.9182602174725;719.1980941250507;729.9197088320284;752.7139686918969;11843.111766617505;12871.871281287184;12947.93526032371;12974.545890893833;12996.61010169694;12845.67885802853;13258.403371848266;13473.461061557526;13479.932600336984;13488.841785314351;805.1706742055677;816.5876232502578;824.7690646618927;825.3685930263354;825.455199568285;814.9388795840335;818.9344852411823;819.2503858969066;819.3813670678629;819.5902048975512;854.1586537260794;857.9871301930476;858.5750053723416;858.8024754306513;858.9269912057451;850.5322072859902;851.0850835352021;851.4508142248277;851.7572491839827;852.3820230333021;627.9089532017878;629.1380808292622;629.1789215074326;629.3549112160027;629.7260691599123;11328.830067548994;11356.977871991561;11389.259698119597;11415.888060708796;11675.416229188591;10918.454077296103;11068.335899845955;11200.215984881062;11269.943650281779;11394.316341019483;9749.220062394992;9784.266180036526;9805.26460515457;9857.063578959987;10075.89924100757;3210.950112412972;3282.901512954615;3287.983560082208;3300.6864347886913;3341.8997430076893;38257.35435192907;50538;58256.708716456145;58368.708156458946;63716.681416593085;9693.22454203662;9697.806043879084;9988.101070903653;10029.247806414549;10669.626563070411;18665.44003679892;18714;18766.34317798898;18888.433346999613;19037.80962190388;6555.278919318871;6601.86796264072;6631.27056023837;6639.966800165968;6867.587944723333;3642.635736426389;3712.535933008374;3719.5536535615897;3720.7023438124884;3735.7198210134347;2811.0723461257735;2892.580575816537;2964.273752930546;3097.643770966323;3098.9380212395627;528.5639347538296;533.9172428273624;535.8767483478744;536.1368197202501;542.0568211312288;597.158007209832;611.5963464113663;621.3755176048043;622.2035794183453;626.145311649595;7481.177070522238;7903.604819758989;8024.879626805603;8107.837843243102;8338.791530211673;479.6786153277313;488.6286422319063;494.83670388771617;496.86087895389346;502.8692539939642;3759.3984962405884;3884.3008258513737;3932.4297976793778;3996.5603783583783;4029.7985100744845;65171.67414162899;67847;68487;71733;72127.63936180285;60717.48206294878;62231;62553.374466255664;62762;64615.353846461876;53200.46799531955;53781.1932821008;53855;53912.19131713027;53998.95007874425;7513.248675132443;7767.417443691858;7775.339096176888;7937.801554961172;7992;37764.43353349647;37975.594759021726;38064.42903356507;38206.235875282895;39905.40141897816;43590.12819743524;47598.52401476079;48427.5157248423;48876;49688.509344719714;9812.55843487059;9831.347778841744;9878.209743220381;10042.949785251027;10455.268131230741;11477.885221147682;11493.712657183638;11583.189176757545;11725.41372931367;11897;10291.176705863363;10295.639652612123;10394.428306442916;10554.366737995744;10908;9818.263630227619;9851.892156324318;9964.850527242243;9992.450415227233;10050.798984020425;10049.447280399649;10174.949125254327;10177.389356638625;10238.334508257238;10282.897171028493;8480.787980300542;8750.343724220837;8754.47473151613;8793.604287806937;8932.86600701003;8302.667893284442;8518.78703032429;8525.573721314033;8664.436811607226;8731.650733970824 15:12:20 INFO - PROCESS | 15933 | __end_tp_report 15:12:20 INFO - PROCESS | 15933 | __start_cc_report 15:12:20 INFO - PROCESS | 15933 | _x_x_mozilla_cycle_collect,1320 15:12:20 INFO - PROCESS | 15933 | __end_cc_report 15:12:20 INFO - PROCESS | 15933 | __startTimestamp1482966740613__endTimestamp 15:12:20 INFO - PROCESS | 15933 | 15:12:20 INFO - PROCESS | 15933 | ------- Summary: start ------- 15:12:20 INFO - PROCESS | 15933 | Number of tests: 6 15:12:20 INFO - PROCESS | 15933 | 15:12:20 INFO - PROCESS | 15933 | [#0] dojo.html Cycles:165 Average:4754.22 Median:4886.88 stddev:504.81 (10.3%) stddev-sans-first:475.57 15:12:20 INFO - PROCESS | 15933 | Values: 2541.1 2603.3 2629.4 2652.4 2657.1 4765.6 4819.7 4824.9 4847.5 4873.1 4776.7 4797.2 4841.2 4858.9 4890.9 4319.0 4357.2 4391.8 4447.3 4513.1 4053.8 4398.1 4490.5 4511.8 4576.0 4367.6 4397.3 4449.2 4483.9 4526.0 4382.7 4437.3 4448.0 4457.3 4525.1 4447.4 4456.1 4461.4 4466.8 4505.2 4826.8 4841.3 4853.4 4855.7 4864.6 4994.3 5032.2 5134.1 5157.7 5194.7 5227.7 5229.6 5236.6 5262.9 5280.7 4884.6 4885.3 4886.5 4886.9 4930.0 5156.6 5238.7 5242.9 5341.6 5344.2 4980.5 5035.7 5078.6 5082.2 5090.4 5050.6 5086.5 5098.3 5188.4 5208.8 4969.4 4978.1 4978.2 5049.5 5088.8 5018.2 5033.7 5039.4 5040.1 5040.1 4387.2 4404.7 4408.0 4417.3 4419.4 3811.7 3813.8 3835.5 3848.7 3854.1 4371.5 4372.5 4376.1 4405.5 4415.4 5056.5 5129.9 5136.3 5141.3 5148.4 4243.7 4331.0 4365.7 4395.2 4454.3 4666.9 4682.6 4692.2 4696.1 4734.4 4839.9 4842.5 4843.2 4847.4 4891.1 5017.0 5084.5 5149.4 5193.3 5230.0 4879.2 4882.4 4885.6 4926.3 4930.2 5149.6 5155.5 5157.5 5160.8 5163.4 5016.1 5044.5 5138.4 5179.1 5215.7 5033.8 5034.6 5037.9 5073.0 5087.9 4850.8 4994.7 5031.6 5053.3 5094.3 4842.0 4920.2 4922.4 4926.7 4937.6 4959.2 5005.2 5011.8 5018.6 5075.8 4873.5 4895.0 4896.7 4905.2 4952.1 15:12:20 INFO - PROCESS | 15933 | 15:12:20 INFO - PROCESS | 15933 | [#1] ext.html Cycles:180 Average:45485.43 Median:176954.94 stddev:89273.73 (50.4%) stddev-sans-first:89460.33 15:12:20 INFO - PROCESS | 15933 | Values: 519.3 558.6 559.2 560.8 566.3 28010.0 30376.5 30388.5 37915.6 39916.4 39579.2 40031.6 40046.8 40068.8 40146.4 7683.3 7847.0 7848.8 7854.2 7854.7 7476.4 7662.2 7690.2 7778.1 9890.7 5786.6 5865.9 5871.9 5873.4 5878.5 9727.0 9798.5 9812.3 9813.2 9824.5 9914.1 10001.8 10017.2 10043.8 10047.4 36704.3 37256.8 37279.0 37329.8 37367.0 3610.5 3750.2 3753.8 3788.5 3790.5 28401.4 28433.4 28593.4 28736.4 29799.7 3258.7 3343.7 3399.9 3496.4 3522.9 253002.0 253897.0 254513.0 254592.0 255490.0 46407.5 46896.0 46896.5 46908.1 46910.1 48795.0 48904.8 48907.5 48926.8 48979.8 12343.9 12509.6 12520.4 12522.4 12522.9 9624.1 9625.9 9630.6 9635.5 9664.4 13882.6 14226.9 14265.8 14717.9 14726.2 5702.0 6258.2 6390.3 6407.2 6513.0 792.0 796.3 796.4 802.3 827.7 26052.9 27318.9 27352.9 27554.0 27769.4 880.5 892.3 894.2 897.6 1070.0 14485.6 14784.7 14901.4 14922.2 15137.0 350387.0 405647.0 411578.0 413074.0 618204.0 37953.4 38641.8 42280.8 42998.0 43156.4 1190.1 1208.2 1211.4 1214.6 1217.9 24697.2 25227.2 25905.2 25930.8 25999.6 36691.4 37349.3 37435.6 37438.8 37451.8 265655.0 268897.0 269270.0 270469.0 270771.0 22524.2 22815.2 22873.7 22874.3 22918.7 34639.1 34932.7 35012.0 36057.8 36073.1 29639.4 30138.7 30228.4 30257.2 30626.9 29749.6 30113.4 30277.5 30648.8 30841.4 29657.0 29706.4 29769.4 31516.1 32182.0 25277.0 25900.6 26565.6 26856.6 27300.9 22613.4 22993.7 23035.7 23084.2 23158.7 15:12:20 INFO - PROCESS | 15933 | 15:12:20 INFO - PROCESS | 15933 | [#2] jquery.html Cycles:180 Average:21965.55 Median:45082.77 stddev:54636.76 (121.2%) stddev-sans-first:54765.96 15:12:20 INFO - PROCESS | 15933 | Values: 361.4 363.4 373.9 377.2 398.5 12732.2 15793.3 16137.8 16465.3 17229.0 16653.9 16739.0 16768.6 16996.2 17010.9 4932.4 4950.6 5073.0 5100.0 5114.8 3862.9 4038.7 5038.9 5102.0 5102.8 5750.9 5994.7 6011.0 6094.1 6117.8 5810.5 5956.7 5998.8 6085.0 6100.1 5266.4 5268.6 5270.3 5272.9 5273.4 18518.8 20295.8 20543.0 20642.5 20666.6 2333.8 2473.6 2486.4 2491.5 2522.8 8937.6 9106.5 9264.9 9595.1 9657.7 1670.7 1716.3 1720.0 1737.4 1755.7 61596.4 63092.7 64409.4 64594.7 66353.0 9456.5 9727.3 9822.4 9956.7 9973.2 13652.6 13656.6 13667.2 13675.2 13682.2 6803.7 6983.4 6988.2 7086.3 7134.5 6118.2 6272.4 6397.3 6510.3 6654.8 4236.2 4292.1 4305.7 4338.7 4349.4 1752.3 1780.5 1798.9 1819.5 1820.9 722.6 737.5 744.2 752.6 758.7 15661.8 16145.4 16230.5 16306.4 16971.3 755.7 756.6 759.1 759.6 767.5 8982.6 9510.3 9618.8 9631.8 9704.4 327162.0 328803.0 330369.0 331290.0 335918.0 63803.0 64122.4 65026.3 73923.3 75917.6 943.8 972.4 975.8 980.5 987.2 15126.7 15127.3 15713.8 15918.8 16001.0 31875.5 32737.8 33320.2 33762.7 34763.0 44876.0 45289.5 45572.5 45778.5 45872.0 7694.3 7703.7 7743.2 8040.3 8047.7 10736.6 11406.8 11580.9 11626.5 12040.8 10275.1 10285.3 10322.4 10539.3 10611.8 10199.2 10221.3 10367.3 10391.2 10484.2 9727.3 10134.7 10217.8 10481.5 10766.1 7682.5 7768.1 7917.3 8099.4 8168.4 9978.9 10174.8 10197.0 10271.6 10803.8 15:12:20 INFO - PROCESS | 15933 | 15:12:20 INFO - PROCESS | 15933 | [#3] mootools.html Cycles:180 Average:4751.05 Median:4764.01 stddev:2623.57 (55.1%) stddev-sans-first:2618.19 15:12:20 INFO - PROCESS | 15933 | Values: 1310.8 1313.8 1319.9 1322.8 1327.5 6796.6 7179.7 7182.9 7185.1 7195.0 6530.6 6720.5 6806.9 7075.2 7112.9 2106.3 2175.0 2180.9 2184.9 2185.3 2144.2 2171.2 2177.9 2179.9 2184.3 1731.8 1785.8 2072.5 2123.4 2139.1 2119.3 2122.7 2123.3 2123.9 2125.5 2064.5 2090.3 2096.2 2099.3 2160.5 7458.0 7509.6 7518.8 7524.6 7555.0 4487.7 4615.4 4616.4 4623.0 4631.8 2715.2 2887.5 2913.2 2918.0 2992.1 1047.5 1099.9 1105.1 1121.1 1138.9 9573.6 9789.6 9798.8 9817.4 9831.8 3940.3 4063.8 4138.3 4171.5 4216.3 8595.4 8606.6 8609.3 8622.8 8627.7 4622.2 4623.4 4626.1 4636.7 4646.9 3728.7 3832.7 4079.1 4191.9 4198.8 3527.8 3538.7 3662.7 3678.2 3759.5 1178.4 1206.9 1210.0 1210.2 1210.3 680.5 691.1 703.1 705.6 723.4 4500.4 4699.6 4757.5 4787.1 4844.7 508.5 512.8 517.3 517.7 521.7 3000.6 3065.6 3119.5 3128.5 3195.4 8648.0 8901.6 8934.6 8948.0 9029.8 8909.9 8920.2 8923.3 8933.2 8939.0 6594.9 6769.6 6799.6 6805.8 6821.0 4695.6 4742.8 4761.6 4766.4 4804.6 8051.6 8065.6 8121.9 8161.2 8183.8 7984.6 8142.1 8204.5 8277.3 8280.7 6002.8 6086.1 6158.4 6168.7 6185.9 6663.7 6720.6 6736.3 6738.5 6749.3 6173.1 6242.1 6247.9 6252.0 6426.2 5927.8 6108.6 6147.2 6221.0 6255.5 5207.1 5275.9 5284.5 5297.8 5302.3 3816.6 3860.0 3871.9 3954.9 3966.8 4037.3 4101.3 4106.3 4117.9 4174.6 15:12:20 INFO - PROCESS | 15933 | 15:12:20 INFO - PROCESS | 15933 | [#4] prototype.html Cycles:180 Average:4104.99 Median:4609.65 stddev:1128.07 (24.5%) stddev-sans-first:1095.43 15:12:20 INFO - PROCESS | 15933 | Values: 348.3 356.1 361.5 370.9 371.2 4618.2 4732.5 4777.9 4824.7 4832.3 4604.2 4709.4 4710.8 4715.7 4720.0 3837.8 3842.0 3847.0 3851.8 3893.0 3654.9 3720.9 3898.4 3905.4 3940.9 3689.4 3792.7 3799.7 3827.3 3836.8 3818.4 3818.7 3825.3 3828.9 3837.0 3433.6 3453.5 3455.5 3462.9 3466.3 4537.5 4549.2 4612.0 4651.1 4672.4 4332.3 4333.4 4339.9 4347.1 4349.6 4962.2 4972.4 4973.6 4999.5 5004.2 3615.5 3726.4 3740.1 3750.9 3758.8 5071.6 5119.5 5142.6 5146.9 5163.9 3841.2 3844.3 3851.3 3868.1 3872.8 3952.1 3952.6 3957.4 3957.7 3971.2 4253.7 4296.4 4328.0 4335.2 4338.9 4951.0 4954.8 4955.2 4957.5 5027.8 1263.0 1271.2 1286.6 1294.4 1313.3 929.7 951.6 952.0 957.2 963.0 4205.1 4210.3 4216.3 4216.8 4225.7 4673.6 4866.3 4890.3 4921.6 4948.1 4107.8 4125.4 4143.1 4155.5 4174.8 2602.9 2603.8 2617.3 2624.8 2626.9 4367.9 4558.5 4580.4 4617.6 4630.5 4981.3 4991.6 5002.2 5014.7 5016.2 4582.9 4602.2 4603.8 4612.5 4623.4 4743.6 4853.9 4899.4 4912.4 4919.0 4823.5 4827.8 4832.8 4845.5 4862.2 4593.2 4607.4 4617.9 4624.6 4625.4 3650.1 3703.6 3752.6 3765.2 3783.9 4866.8 4871.4 4874.5 4906.7 4907.7 4681.7 4719.7 4726.1 4734.8 4756.7 4713.4 4742.0 4748.9 4785.2 4875.5 4788.8 4871.9 4876.3 4885.8 4887.5 4974.4 4978.6 4987.0 5029.4 5046.5 4704.2 4842.2 4864.4 4869.8 4880.2 15:12:20 INFO - PROCESS | 15933 | 15:12:20 INFO - PROCESS | 15933 | [#5] yui.html Cycles:180 Average:14576.08 Median:53955.57 stddev:18853.36 (34.9%) stddev-sans-first:18877.18 15:12:20 INFO - PROCESS | 15933 | Values: 634.8 653.9 719.2 729.9 752.7 11843.1 12871.9 12947.9 12974.5 12996.6 12845.7 13258.4 13473.5 13479.9 13488.8 805.2 816.6 824.8 825.4 825.5 814.9 818.9 819.3 819.4 819.6 854.2 858.0 858.6 858.8 858.9 850.5 851.1 851.5 851.8 852.4 627.9 629.1 629.2 629.4 629.7 11328.8 11357.0 11389.3 11415.9 11675.4 10918.5 11068.3 11200.2 11269.9 11394.3 9749.2 9784.3 9805.3 9857.1 10075.9 3211.0 3282.9 3288.0 3300.7 3341.9 38257.4 50538.0 58256.7 58368.7 63716.7 9693.2 9697.8 9988.1 10029.2 10669.6 18665.4 18714.0 18766.3 18888.4 19037.8 6555.3 6601.9 6631.3 6640.0 6867.6 3642.6 3712.5 3719.6 3720.7 3735.7 2811.1 2892.6 2964.3 3097.6 3098.9 528.6 533.9 535.9 536.1 542.1 597.2 611.6 621.4 622.2 626.1 7481.2 7903.6 8024.9 8107.8 8338.8 479.7 488.6 494.8 496.9 502.9 3759.4 3884.3 3932.4 3996.6 4029.8 65171.7 67847.0 68487.0 71733.0 72127.6 60717.5 62231.0 62553.4 62762.0 64615.4 53200.5 53781.2 53855.0 53912.2 53999.0 7513.2 7767.4 7775.3 7937.8 7992.0 37764.4 37975.6 38064.4 38206.2 39905.4 43590.1 47598.5 48427.5 48876.0 49688.5 9812.6 9831.3 9878.2 10042.9 10455.3 11477.9 11493.7 11583.2 11725.4 11897.0 10291.2 10295.6 10394.4 10554.4 10908.0 9818.3 9851.9 9964.9 9992.5 10050.8 10049.4 10174.9 10177.4 10238.3 10282.9 8480.8 8750.3 8754.5 8793.6 8932.9 8302.7 8518.8 8525.6 8664.4 8731.7 15:12:20 INFO - PROCESS | 15933 | -------- Summary: end -------- 15:12:20 INFO - PROCESS | 15933 | 15:12:21 INFO - TEST-INFO | 15933: exit 0 15:12:21 INFO - TEST-OK | dromaeo_css | took 1121073ms 15:12:21 INFO - TEST-START | kraken 15:12:21 INFO - operating with platform_type : linux_ 15:12:21 INFO - Initialising browser for kraken test... 15:12:21 INFO - TEST-INFO | started process 16233 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIeb7Td/profile http://localhost:42369/getInfo.html) 15:12:22 INFO - PROCESS | 16233 | 1482966741995 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 15:12:25 INFO - PROCESS | 16233 | __metrics Screen width/height:1600/1200 15:12:25 INFO - PROCESS | 16233 | colorDepth:24 15:12:25 INFO - PROCESS | 16233 | Browser inner width/height: 1024/697 15:12:25 INFO - PROCESS | 16233 | __metrics 15:12:26 INFO - PROCESS | 16233 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:12:26 INFO - PROCESS | 16233 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:12:27 INFO - TEST-INFO | 16233: exit 0 15:12:27 INFO - Browser initialized. 15:12:27 INFO - Running cycle 1/1 for kraken test... 15:12:27 INFO - TEST-INFO | started process 16319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIeb7Td/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:12:39 INFO - PROCESS | 16319 | RSS: Main: 151027712 15:12:39 INFO - PROCESS | 16319 | 15:12:42 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42369/tests/kraken/driver.html?testName=audio-beat-detection) 15:12:43 INFO - PROCESS | 16319 | RSS: Main: 204804096 15:12:43 INFO - PROCESS | 16319 | 15:12:45 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42369/tests/kraken/driver.html?testName=audio-dft) 15:12:46 INFO - PROCESS | 16319 | RSS: Main: 184406016 15:12:46 INFO - PROCESS | 16319 | 15:12:49 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42369/tests/kraken/driver.html?testName=audio-fft) 15:12:50 INFO - PROCESS | 16319 | RSS: Main: 266895360 15:12:50 INFO - PROCESS | 16319 | 15:12:52 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42369/tests/kraken/driver.html?testName=audio-oscillator) 15:12:52 INFO - PROCESS | 16319 | RSS: Main: 178528256 15:12:52 INFO - PROCESS | 16319 | 15:12:55 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42369/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:12:55 INFO - PROCESS | 16319 | RSS: Main: 181645312 15:12:55 INFO - PROCESS | 16319 | 15:12:58 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42369/tests/kraken/driver.html?testName=imaging-darkroom) 15:12:59 INFO - PROCESS | 16319 | RSS: Main: 221372416 15:12:59 INFO - PROCESS | 16319 | 15:13:03 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42369/tests/kraken/driver.html?testName=imaging-desaturate) 15:13:03 INFO - PROCESS | 16319 | RSS: Main: 215269376 15:13:03 INFO - PROCESS | 16319 | 15:13:06 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42369/tests/kraken/driver.html?testName=json-parse-financial) 15:13:07 INFO - PROCESS | 16319 | RSS: Main: 216616960 15:13:07 INFO - PROCESS | 16319 | 15:13:08 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42369/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:13:09 INFO - PROCESS | 16319 | RSS: Main: 180617216 15:13:09 INFO - PROCESS | 16319 | 15:13:11 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42369/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:13:12 INFO - PROCESS | 16319 | RSS: Main: 187834368 15:13:12 INFO - PROCESS | 16319 | 15:13:14 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42369/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:13:14 INFO - PROCESS | 16319 | RSS: Main: 196501504 15:13:14 INFO - PROCESS | 16319 | 15:13:17 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42369/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:13:18 INFO - PROCESS | 16319 | RSS: Main: 205172736 15:13:18 INFO - PROCESS | 16319 | 15:13:21 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42369/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:13:22 INFO - PROCESS | 16319 | RSS: Main: 202268672 15:13:22 INFO - PROCESS | 16319 | 15:13:23 INFO - PROCESS | 16319 | Cycle 1(1): loaded http://localhost:42369/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42369/tests/kraken/driver.html?testName=ai-astar) 15:13:24 INFO - PROCESS | 16319 | RSS: Main: 253153280 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | __start_tp_report 15:13:24 INFO - PROCESS | 16319 | _x_x_mozilla_page_load 15:13:24 INFO - PROCESS | 16319 | _x_x_mozilla_page_load_details 15:13:24 INFO - PROCESS | 16319 | |i|pagename|runs| 15:13:24 INFO - PROCESS | 16319 | |0;ai-astar;106;115;118;114;114;115;114;115;114;115 15:13:24 INFO - PROCESS | 16319 | |1;audio-beat-detection;152;124;133;180;129;127;124;126;147;129 15:13:24 INFO - PROCESS | 16319 | |2;audio-dft;162;162;162;164;162;162;163;162;163;163 15:13:24 INFO - PROCESS | 16319 | |3;audio-fft;76;77;77;104;76;77;78;77;107;76 15:13:24 INFO - PROCESS | 16319 | |4;audio-oscillator;85;85;105;85;85;85;108;85;85;109 15:13:24 INFO - PROCESS | 16319 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;117;118 15:13:24 INFO - PROCESS | 16319 | |6;imaging-darkroom;149;149;150;151;150;149;150;150;149;150 15:13:24 INFO - PROCESS | 16319 | |7;imaging-desaturate;95;96;95;96;96;95;95;95;95;95 15:13:24 INFO - PROCESS | 16319 | |8;json-parse-financial;69;65;67;66;67;67;68;67;68;68 15:13:24 INFO - PROCESS | 16319 | |9;json-stringify-tinderbox;68;68;67;68;68;70;68;67;73;68 15:13:24 INFO - PROCESS | 16319 | |10;stanford-crypto-aes;85;84;85;84;84;85;84;85;89;87 15:13:24 INFO - PROCESS | 16319 | |11;stanford-crypto-ccm;149;137;137;133;131;132;133;134;181;139 15:13:24 INFO - PROCESS | 16319 | |12;stanford-crypto-pbkdf2;181;182;176;176;174;174;172;183;175;180 15:13:24 INFO - PROCESS | 16319 | |13;stanford-crypto-sha256-iterative;68;66;68;68;68;68;67;68;67;67 15:13:24 INFO - PROCESS | 16319 | __end_tp_report 15:13:24 INFO - PROCESS | 16319 | __start_cc_report 15:13:24 INFO - PROCESS | 16319 | _x_x_mozilla_cycle_collect,1824 15:13:24 INFO - PROCESS | 16319 | __end_cc_report 15:13:24 INFO - PROCESS | 16319 | __startTimestamp1482966804108__endTimestamp 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | ------- Summary: start ------- 15:13:24 INFO - PROCESS | 16319 | Number of tests: 14 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.00 stddev:3.06 (2.7%) stddev-sans-first:1.27 15:13:24 INFO - PROCESS | 16319 | Values: 106.0 115.0 118.0 114.0 114.0 115.0 114.0 115.0 114.0 115.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:131.00 stddev:17.88 (13.6%) stddev-sans-first:18.13 15:13:24 INFO - PROCESS | 16319 | Values: 152.0 124.0 133.0 180.0 129.0 127.0 124.0 126.0 147.0 129.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:0.71 (0.4%) stddev-sans-first:0.73 15:13:24 INFO - PROCESS | 16319 | Values: 162.0 162.0 162.0 164.0 162.0 162.0 163.0 162.0 163.0 163.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#3] audio-fft Cycles:10 Average:82.50 Median:77.00 stddev:12.16 (15.8%) stddev-sans-first:12.67 15:13:24 INFO - PROCESS | 16319 | Values: 76.0 77.0 77.0 104.0 76.0 77.0 78.0 77.0 107.0 76.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:85.00 stddev:10.83 (12.7%) stddev-sans-first:11.22 15:13:24 INFO - PROCESS | 16319 | Values: 85.0 85.0 105.0 85.0 85.0 85.0 108.0 85.0 85.0 109.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 15:13:24 INFO - PROCESS | 16319 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 118.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#6] imaging-darkroom Cycles:10 Average:149.70 Median:150.00 stddev:0.67 (0.4%) stddev-sans-first:0.67 15:13:24 INFO - PROCESS | 16319 | Values: 149.0 149.0 150.0 151.0 150.0 149.0 150.0 150.0 149.0 150.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 15:13:24 INFO - PROCESS | 16319 | Values: 95.0 96.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:67.50 stddev:1.14 (1.7%) stddev-sans-first:1.00 15:13:24 INFO - PROCESS | 16319 | Values: 69.0 65.0 67.0 66.0 67.0 67.0 68.0 67.0 68.0 68.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:68.00 stddev:1.78 (2.6%) stddev-sans-first:1.88 15:13:24 INFO - PROCESS | 16319 | Values: 68.0 68.0 67.0 68.0 68.0 70.0 68.0 67.0 73.0 68.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:85.00 stddev:1.62 (1.9%) stddev-sans-first:1.72 15:13:24 INFO - PROCESS | 16319 | Values: 85.0 84.0 85.0 84.0 84.0 85.0 84.0 85.0 89.0 87.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#11] stanford-crypto-ccm Cycles:10 Average:140.60 Median:137.00 stddev:15.12 (11.0%) stddev-sans-first:15.72 15:13:24 INFO - PROCESS | 16319 | Values: 149.0 137.0 137.0 133.0 131.0 132.0 133.0 134.0 181.0 139.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:178.00 stddev:3.86 (2.2%) stddev-sans-first:3.86 15:13:24 INFO - PROCESS | 16319 | Values: 181.0 182.0 176.0 176.0 174.0 174.0 172.0 183.0 175.0 180.0 15:13:24 INFO - PROCESS | 16319 | 15:13:24 INFO - PROCESS | 16319 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:0.71 (1.0%) stddev-sans-first:0.73 15:13:24 INFO - PROCESS | 16319 | Values: 68.0 66.0 68.0 68.0 68.0 68.0 67.0 68.0 67.0 67.0 15:13:24 INFO - PROCESS | 16319 | -------- Summary: end -------- 15:13:24 INFO - PROCESS | 16319 | 15:13:25 INFO - TEST-INFO | 16319: exit 0 15:13:25 INFO - TEST-OK | kraken | took 63810ms 15:13:25 INFO - SUITE-END | took 1184s 15:13:25 INFO - Completed test suite (00:19:45) 15:13:25 INFO - javascript benchmark 15:13:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2541.0852093246426, 2603.323135984644, 2629.434671545617, 2652.4297276085636, 2657.096587160366, 4765.594924431419, 4819.662623616348, 4824.903501929968, 4847.491013443586, 4873.098476781798, 4776.737279449638, 4797.184478638631, 4841.176999910015, 4858.902821943559, 4890.8777280568, 4318.956810431895, 4357.17213729392, 4391.824327026915, 4447.266201076818, 4513.052259025592, 4053.837846486137, 4398.120375924812, 4490.528494508078, 4511.819527218908, 4576.039031803325, 4367.563243675639, 4397.31841564558, 4449.221386257392, 4483.910321793578, 4526.049529598772, 4382.737035777864, 4437.312216606399, 4447.977760111211, 4457.264551349023, 4525.140223357552, 4447.399601053881, 4456.064226512404, 4461.420015397977, 4466.843660471868, 4505.189065968125, 4826.831060912851, 4841.298011788306, 4853.369062021915, 4855.660103792736, 4864.5621894029355, 4994.275830004629, 5032.169692000815, 5134.101532231882, 5157.71632560209, 5194.68831870089, 5227.738613069369, 5229.607779416559, 5236.607254455893, 5262.894742105136, 5280.735963201824, 4884.5848102911295, 4885.291632713237, 4886.462489126193, 4886.877828054327, 4930.0, 5156.63903526757, 5238.711870847063, 5242.9213561796605, 5341.62608617393, 5344.171653393731, 4980.477049909779, 5035.748212589356, 5078.619103567174, 5082.237664350377, 5090.389153301628, 5050.621203409685, 5086.491350864958, 5098.286239926413, 5188.429272779991, 5208.8437346879655, 4969.428515720666, 4978.079055374767, 4978.178600530944, 5049.469805670352, 5088.796448142033, 5018.222175562793, 5033.748312584356, 5039.370078740158, 5040.1179793535985, 5040.1179793535985, 4387.188370151533, 4404.66964977628, 4408.030233348658, 4417.293233082692, 4419.447569053868, 3811.714121440903, 3813.7521061130938, 3835.5013848200115, 3848.749831260959, 3854.0750219947013, 4371.497277813029, 4372.497162826316, 4376.102898905717, 4405.493368262627, 4415.359772832921, 5056.469070747592, 5129.9230511542355, 5136.255242989746, 5141.305923700327, 5148.407933087743, 4243.724157929786, 4330.978345108254, 4365.738055716603, 4395.1868904252815, 4454.28731402974, 4666.906661866744, 4682.555157260055, 4692.225782745877, 4696.0842635686395, 4734.360861283752, 4839.854804355805, 4842.539958703917, 4843.24929635907, 4847.442544107473, 4891.070696567641, 5017.0, 5084.491550844886, 5149.382074150976, 5193.272941788228, 5230.0, 4879.219324907997, 4882.389701287339, 4885.633577481632, 4926.310316555757, 4930.211166213388, 5149.562287205629, 5155.484451554815, 5157.484251574962, 5160.793568257378, 5163.3545806774155, 5016.097102521581, 5044.54599086084, 5138.434772175133, 5179.067767801681, 5215.687058776485, 5033.798648054037, 5034.647574669738, 5037.899242015213, 5072.974635126949, 5087.949120508748, 4850.781714822912, 4994.700317980933, 5031.597472202142, 5053.267276244997, 5094.337736094284, 4841.975790121027, 4920.21276595743, 4922.360093187863, 4926.679765815211, 4937.5556199942175, 4959.181735013826, 5005.174146265826, 5011.84964451067, 5018.598512118949, 5075.847724568269, 4873.4882837301575, 4895.0, 4896.706197628154, 4905.23968784839, 4952.059108769322], "value": 4721.071484010354, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.311911716975, 558.5894367639784, 559.2143039030161, 560.812127937141, 566.2977907394832, 28010.0, 30376.544351834684, 30388.544171837413, 37915.620843791556, 39916.40125398114, 39579.208415831665, 40031.59968400315, 40046.79976600113, 40068.79965600175, 40146.39780403289, 7683.270089341504, 7846.960765196166, 7848.843023139534, 7854.214578542129, 7854.7250846220395, 7476.36450901674, 7662.157162712125, 7690.15408305086, 7778.105517865433, 9890.703278901608, 5786.56600754943, 5865.85335366615, 5871.911921321183, 5873.4420230078285, 5878.470937615613, 9726.999999999965, 9798.461084640341, 9812.264080193978, 9813.165880900133, 9824.508774561244, 9914.107730304235, 10001.79996400068, 10017.24870634698, 10043.849342259871, 10047.397156170653, 36704.265914681826, 37256.813715931516, 37279.0, 37329.81335093334, 37367.0, 3610.5125808015846, 3750.1749615084636, 3753.8498460061564, 3788.4885540452237, 3790.488284081669, 28401.431971360456, 28433.431331373464, 28593.428131437464, 28736.425271494452, 29799.702002980128, 3258.7230085442766, 3343.732501399882, 3399.9320013599836, 3496.4230901901155, 3522.8766993155373, 253002.0, 253897.0, 254513.0, 254591.99999999814, 255490.0, 46407.53592464099, 46896.0, 46896.5310346899, 46908.061838763715, 46910.06179876383, 48795.0, 48904.75547622239, 48907.51092489101, 48926.8197567157, 48979.755101224975, 12343.876561234452, 12509.624711258677, 12520.373981300898, 12522.436490357955, 12522.874771252353, 9624.133827955518, 9625.855612165824, 9630.614775408905, 9635.518224088768, 9664.37181583209, 13882.583522494342, 14226.928865355607, 14265.78601320981, 14717.92641036788, 14726.190059546614, 5701.971490142523, 6258.15514905491, 6390.348005577634, 6407.231132264158, 6513.0, 791.9762407127795, 796.3271035974592, 796.366888323784, 802.2734398718039, 827.668932427034, 26052.869735651202, 27318.863405682845, 27352.863235683693, 27554.0, 27769.444611107665, 880.506916126971, 892.2594246775291, 894.2443635128307, 897.599744398727, 1070.0476575847501, 14485.565433037025, 14784.704305913821, 14901.403943842124, 14922.179280139479, 15137.0, 350387.0, 405647.0, 411578.0, 413074.0, 618204.0, 37953.430698539545, 38641.806790965864, 42280.788596056824, 42998.0, 43156.3526547102, 1190.0776897904193, 1208.1663652080038, 1211.418519110814, 1214.5566868093083, 1217.9208351457128, 24697.2082677258, 25227.243182704548, 25905.222843314732, 25930.814115031924, 25999.61000584955, 36691.44962825506, 37349.25301493901, 37435.62564374322, 37438.81280593688, 37451.81274093612, 265655.0, 268897.0, 269270.0, 270469.0, 270771.0, 22524.21165259208, 22815.201467948544, 22873.656895146254, 22874.31377058691, 22918.73946932868, 34639.13402164865, 34932.65067349294, 35012.0, 36057.819710901276, 36073.0981725459, 29639.407211856073, 30138.69861301359, 30228.395432090794, 30257.24356891095, 30626.92805751877, 29749.553756693233, 30113.3977320448, 30277.545836812908, 30648.846755766077, 30841.38317233598, 29657.0, 29706.405871882875, 29769.404611908074, 31516.060492438446, 32182.0, 25277.0, 25900.61149082803, 26565.60151597689, 26856.59715104314, 27300.86349568319, 22613.43466413353, 22993.65509517325, 23035.6544651827, 23084.192053278053, 23158.652620210374], "value": 16388.26758538251, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.36941037888886, 363.3840640115006, 373.94577786221, 377.2210385553832, 398.47401430112245, 12732.172408793422, 15793.28930198141, 16137.757933631005, 16465.25906334215, 17229.0, 16653.91673041633, 16739.0, 16768.580785480368, 16996.235169417374, 17010.914945425287, 4932.432770231433, 4950.579200767921, 5073.036123136592, 5099.974500127494, 5114.795408183668, 3862.9034274143232, 4038.6567141792984, 5038.924416133761, 5102.0, 5102.821401250957, 5750.884982300351, 5994.700264986755, 6011.038233882579, 6094.116353128794, 6117.847053823645, 5810.506106980912, 5956.672383018932, 5998.790042348497, 6084.999999999978, 6100.145979562864, 5266.394364648077, 5268.5521730652945, 5270.314859068297, 5272.894542109175, 5273.4462881397285, 18518.814811851844, 20295.79704202954, 20543.0, 20642.48393790152, 20666.5866682667, 2333.813294936418, 2473.579491486452, 2486.4032632168332, 2491.53906527293, 2522.7603377679093, 8937.64249430022, 9106.499142547158, 9264.907350926473, 9595.088466595662, 9657.661981830672, 1670.7243304854806, 1716.3134746101534, 1719.9656006879793, 1737.3571778442058, 1755.6576467588825, 61596.384036159514, 63092.68453657702, 64409.355906440804, 64594.677026615034, 66353.0, 9456.52717364129, 9727.270454715925, 9822.361546499455, 9956.660184856553, 9973.202143828476, 13652.590422287347, 13656.590302290948, 13667.248301343521, 13675.24786136772, 13682.247476388695, 6803.65981700913, 6983.441324694012, 6988.231294557696, 7086.291370862872, 7134.500584959107, 6118.20463339763, 6272.435480806749, 6397.296297407282, 6510.283868774431, 6654.767083152066, 4236.2374772540625, 4292.12011537634, 4305.655547556188, 4338.739675619473, 4349.369341445473, 1752.2640490993813, 1780.4658602419222, 1798.9370372036915, 1819.5451137215696, 1820.927162913495, 722.5556282886092, 737.5279820914619, 744.2260049548518, 752.5747952406873, 758.6927294445758, 15661.765073523906, 16145.35418583267, 16230.51308460748, 16306.429274975319, 16971.321147154224, 755.6939439527, 756.5611945071845, 759.1080480435577, 759.5974133709143, 767.5241350362811, 8982.595783189772, 9510.33427660071, 9618.759531011781, 9631.807363852824, 9704.417734935785, 327162.0, 328803.0, 330369.0, 331290.0, 335918.0, 63803.0, 64122.35877641164, 65026.349736502976, 73923.26076739271, 75917.62041189759, 943.7923656795494, 972.3971137894409, 975.7755716185175, 980.4705459052175, 987.195435719886, 15126.697466050397, 15127.319270632628, 15713.764293535378, 15918.7612185815, 16001.0, 31875.52186717155, 32737.836310818293, 33320.16936527957, 33762.66237337694, 34763.0, 44876.0, 45289.54710452853, 45572.54427455683, 45778.54221457743, 45872.0, 7694.345980591713, 7703.730369437043, 7743.225677432437, 8040.316573091119, 8047.718329858427, 10736.624218152327, 11406.771864562828, 11580.942095289469, 11626.476808543466, 12040.819387709016, 10275.126614237874, 10285.331453455501, 10322.432266225429, 10539.262251642618, 10611.840822387825, 10199.184065274612, 10221.28451008452, 10367.32612380193, 10391.168706503311, 10484.21368397358, 9727.270454715783, 10134.746631333981, 10217.795644086927, 10481.459775709367, 10766.084882785051, 7682.539047657158, 7768.114206447941, 7917.327027202752, 8099.392545558989, 8168.428210025241, 9978.900210998088, 10174.796504070026, 10197.0, 10271.640492583023, 10803.783924321628], "value": 8034.10833492631, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.842698876135, 1313.770090234209, 1319.9142055766372, 1322.7619028574866, 1327.4756471193873, 6796.558223715461, 7179.676914538845, 7182.9281707182845, 7185.065941427613, 7194.964025179866, 6530.6408147551865, 6720.495962802785, 6806.863862722743, 7075.150981882169, 7112.933060040997, 2106.3154474795715, 2174.9891250543806, 2180.9345719628436, 2184.9344519664437, 2185.25701261571, 2144.2280778919576, 2171.1532502324108, 2177.923772667957, 2179.9019044142974, 2184.3337781976493, 1731.7575539424427, 1785.8392744652979, 2072.5336799220204, 2123.352377524854, 2139.101577337522, 2119.2582596091397, 2122.713433686448, 2123.3205374280237, 2123.9150433982622, 2125.5005073807642, 2064.5148390128306, 2090.2579584247524, 2096.1929657081982, 2099.2757498662936, 2160.503084290622, 7458.0, 7509.624518774094, 7518.849623007564, 7524.623768811538, 7554.962225188894, 4487.730736155841, 4615.446146462413, 4616.422947131608, 4622.95377046232, 4631.837885674019, 2715.1990162901793, 2887.4513842370093, 2913.1697466222026, 2917.9708202918123, 2992.0724575381482, 1047.5233768635328, 1099.8515200447916, 1105.1269497097283, 1121.0695123047851, 1138.9088872890231, 9573.617055317778, 9789.608415663437, 9798.804023919482, 9817.410955342702, 9831.754206144831, 3940.330143875548, 4063.817128229237, 4138.317177665652, 4171.541130475645, 4216.3464662977285, 8595.398322117517, 8606.5696715164, 8609.268212201907, 8622.784430389165, 8627.741167764978, 4622.19111655459, 4623.4451865775645, 4626.097910907408, 4636.675432719745, 4646.883827904329, 3728.683061939711, 3832.674222691047, 4079.143379890197, 4191.937120943188, 4198.832046718097, 3527.7530572859655, 3538.6992105670793, 3662.7436079474714, 3678.2275722098657, 3759.49246851677, 1178.3754610056683, 1206.8913797758244, 1209.9654795150195, 1210.2375503432957, 1210.2980271442543, 680.482833046878, 691.1386858406203, 703.0543918429704, 705.5519744961986, 723.4176487927269, 4500.392447019676, 4699.553542413465, 4757.50439681639, 4787.0904528139545, 4844.667716378024, 508.51182864449805, 512.8384558863946, 517.332640893251, 517.7023211653315, 521.7339157029942, 3000.604718805766, 3065.632124145117, 3119.454095533318, 3128.483800172946, 3195.3609278144463, 8647.956760216159, 8901.554922253861, 8934.642614295486, 8947.955260223658, 9029.819403611891, 8909.910900890907, 8920.24177944882, 8923.330750193762, 8933.15135062168, 8939.0, 6594.901076483857, 6769.593824370553, 6799.558028728117, 6805.761798337035, 6821.045053692487, 4695.600873925618, 4742.78657460422, 4761.642876784185, 4766.380370551806, 4804.567588917014, 8051.637676304464, 8065.637046332812, 8121.878171827309, 8161.183881611792, 8183.79540511492, 7984.600769961595, 8142.10436851934, 8204.548749818818, 8277.337812974827, 8280.668773249003, 6002.849928751816, 6086.1175129606845, 6158.353372895782, 6168.722407491584, 6185.907211391743, 6663.666816659051, 6720.563163394364, 6736.32636736342, 6738.49461290415, 6749.325067493408, 6173.135760993554, 6242.094896240111, 6247.906281405873, 6251.9687401562705, 6426.196725409324, 5927.792527261525, 6108.57239993214, 6147.231596050494, 6221.0, 6255.499560035278, 5207.088759467154, 5275.894482110413, 5284.471552844685, 5297.761600727899, 5302.2576839241765, 3816.561095473945, 3859.9614003860725, 3871.8838434847, 3954.8615798446917, 3966.8016599170505, 4037.253108174997, 4101.343784994387, 4106.260873042881, 4117.938230926478, 4174.582541745801], "value": 3828.8870674614172, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.3068693300333, 356.1132779379345, 361.49029867886276, 370.8553664071011, 371.15932413084386, 4618.214903466411, 4732.479427262998, 4777.90444191116, 4824.734639594825, 4832.275158726188, 4604.240300350438, 4709.411323584552, 4710.764461776915, 4715.693479923802, 4719.952800471994, 3837.827297771607, 3841.9807900960454, 3847.03824043989, 3851.788151651665, 3892.9756526606147, 3654.945175822365, 3720.869769558066, 3898.434726964588, 3905.3556163233034, 3940.921181576367, 3689.3543629864666, 3792.696584273265, 3799.734018618698, 3827.291950989062, 3836.7506112102765, 3818.4463252828464, 3818.694504439638, 3825.3305671507374, 3828.942565861514, 3836.9808150959066, 3433.5536380270405, 3453.4647129694686, 3455.51622772812, 3462.8787992420143, 3466.341395134916, 4537.546245375469, 4549.203889319356, 4611.953880461219, 4651.1395391852275, 4672.3925889634465, 4332.285172946492, 4333.39332493448, 4339.913201735948, 4347.087451407565, 4349.630281426083, 4962.1812400953795, 4972.403311602595, 4973.626977976666, 4999.525045120708, 5004.172642457, 3615.5299811024397, 3726.366517691999, 3740.0649837540614, 3750.8687195948282, 3758.830852611639, 5071.554606937005, 5119.46245644202, 5142.588592912595, 5146.897062058775, 5163.896722065538, 3841.1741475582885, 3844.3464611016193, 3851.326017946904, 3868.1103346230525, 3872.792508270259, 3952.110775075585, 3952.565217826037, 3957.3668213086344, 3957.7229593928723, 3971.245463361952, 4253.659707223414, 4296.398504209413, 4327.956720432819, 4335.219660461147, 4338.891527711832, 4951.0, 4954.801807927643, 4955.166588362257, 4957.4794646561595, 5027.773750181177, 1262.9557965471165, 1271.2309053022973, 1286.6168868466436, 1294.3657607772125, 1313.2908229556124, 929.7303781903172, 951.6478902805868, 951.9619215231452, 957.1863915671623, 962.9855552166723, 4205.137946720914, 4210.347396153601, 4216.325387937915, 4216.810243539048, 4225.704200705921, 4673.558579666633, 4866.270059491105, 4890.339804126468, 4921.630877684188, 4948.05986862495, 4107.794610269475, 4125.401816736557, 4143.08852052548, 4155.501339839178, 4174.791260437027, 2602.8568428736603, 2603.8047146463714, 2617.343396452602, 2624.8031397644872, 2626.947461050768, 4367.934480982788, 4558.495696420239, 4580.4045474088825, 4617.607503362252, 4630.536946305342, 4981.28145789702, 4991.627302491771, 5002.224655178453, 5014.674046186986, 5016.247562865599, 4582.862514124582, 4602.171609110393, 4603.838865639686, 4612.469565999953, 4623.3758442609815, 4743.644226682944, 4853.854384368475, 4899.4365647950945, 4912.410510738734, 4919.0, 4823.469418364048, 4827.831025914076, 4832.806687732451, 4845.53967373099, 4862.197737373435, 4593.242115051113, 4607.3549703040935, 4617.891705990471, 4624.606908412823, 4625.375574297562, 3650.1422165790486, 3703.6111208322736, 3752.6059763724415, 3765.1528406108405, 3783.9432408513344, 4866.75897646098, 4871.3667223260745, 4874.488178741181, 4906.705597664151, 4907.705537667752, 4681.7190968542, 4719.7404142772475, 4726.1020406122725, 4734.839964208791, 4756.667033307635, 4713.410823647044, 4741.952580474152, 4748.9050218994735, 4785.186518291829, 4875.536824001714, 4788.832390866303, 4871.87820304481, 4876.317315575751, 4885.780139893643, 4887.511248875227, 4974.353334066548, 4978.551930326288, 4987.0, 5029.371328583927, 5046.545810876892, 4704.176769065331, 4842.223066008519, 4864.416270047617, 4869.829555965524, 4880.219164933594], "value": 3768.641793804404, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.7968650031992, 653.9182602174725, 719.1980941250507, 729.9197088320284, 752.7139686918969, 11843.111766617505, 12871.871281287184, 12947.93526032371, 12974.545890893833, 12996.61010169694, 12845.67885802853, 13258.403371848266, 13473.461061557526, 13479.932600336984, 13488.841785314351, 805.1706742055677, 816.5876232502578, 824.7690646618927, 825.3685930263354, 825.455199568285, 814.9388795840335, 818.9344852411823, 819.2503858969066, 819.3813670678629, 819.5902048975512, 854.1586537260794, 857.9871301930476, 858.5750053723416, 858.8024754306513, 858.9269912057451, 850.5322072859902, 851.0850835352021, 851.4508142248277, 851.7572491839827, 852.3820230333021, 627.9089532017878, 629.1380808292622, 629.1789215074326, 629.3549112160027, 629.7260691599123, 11328.830067548994, 11356.977871991561, 11389.259698119597, 11415.888060708796, 11675.416229188591, 10918.454077296103, 11068.335899845955, 11200.215984881062, 11269.943650281779, 11394.316341019483, 9749.220062394992, 9784.266180036526, 9805.26460515457, 9857.063578959987, 10075.89924100757, 3210.950112412972, 3282.901512954615, 3287.983560082208, 3300.6864347886913, 3341.8997430076893, 38257.35435192907, 50538.0, 58256.708716456145, 58368.708156458946, 63716.681416593085, 9693.22454203662, 9697.806043879084, 9988.101070903653, 10029.247806414549, 10669.626563070411, 18665.44003679892, 18714.0, 18766.34317798898, 18888.433346999613, 19037.80962190388, 6555.278919318871, 6601.86796264072, 6631.27056023837, 6639.966800165968, 6867.587944723333, 3642.635736426389, 3712.535933008374, 3719.5536535615897, 3720.7023438124884, 3735.7198210134347, 2811.0723461257735, 2892.580575816537, 2964.273752930546, 3097.643770966323, 3098.9380212395627, 528.5639347538296, 533.9172428273624, 535.8767483478744, 536.1368197202501, 542.0568211312288, 597.158007209832, 611.5963464113663, 621.3755176048043, 622.2035794183453, 626.145311649595, 7481.177070522238, 7903.604819758989, 8024.879626805603, 8107.837843243102, 8338.791530211673, 479.6786153277313, 488.6286422319063, 494.83670388771617, 496.86087895389346, 502.8692539939642, 3759.3984962405884, 3884.3008258513737, 3932.4297976793778, 3996.5603783583783, 4029.7985100744845, 65171.67414162899, 67847.0, 68487.0, 71733.0, 72127.63936180285, 60717.48206294878, 62231.0, 62553.374466255664, 62762.0, 64615.353846461876, 53200.46799531955, 53781.1932821008, 53855.0, 53912.19131713027, 53998.95007874425, 7513.248675132443, 7767.417443691858, 7775.339096176888, 7937.801554961172, 7992.0, 37764.43353349647, 37975.594759021726, 38064.42903356507, 38206.235875282895, 39905.40141897816, 43590.12819743524, 47598.52401476079, 48427.5157248423, 48876.0, 49688.509344719714, 9812.55843487059, 9831.347778841744, 9878.209743220381, 10042.949785251027, 10455.268131230741, 11477.885221147682, 11493.712657183638, 11583.189176757545, 11725.41372931367, 11897.0, 10291.176705863363, 10295.639652612123, 10394.428306442916, 10554.366737995744, 10908.0, 9818.263630227619, 9851.892156324318, 9964.850527242243, 9992.450415227233, 10050.798984020425, 10049.447280399649, 10174.949125254327, 10177.389356638625, 10238.334508257238, 10282.897171028493, 8480.787980300542, 8750.343724220837, 8754.47473151613, 8793.604287806937, 8932.86600701003, 8302.667893284442, 8518.78703032429, 8525.573721314033, 8664.436811607226, 8731.650733970824], "value": 6089.465649076118, "unit": "score"}], "name": "dromaeo_css", "value": 6159.794626970073, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 118.0, 114.0, 114.0, 115.0, 114.0, 115.0, 114.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 133.0, 180.0, 129.0, 127.0, 124.0, 126.0, 147.0, 129.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 164.0, 162.0, 162.0, 163.0, 162.0, 163.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 104.0, 76.0, 77.0, 78.0, 77.0, 107.0, 76.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 105.0, 85.0, 85.0, 85.0, 108.0, 85.0, 85.0, 109.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 151.0, 150.0, 149.0, 150.0, 150.0, 149.0, 150.0], "value": 149.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 67.0, 66.0, 67.0, 67.0, 68.0, 67.0, 68.0, 68.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 68.0, 70.0, 68.0, 67.0, 73.0, 68.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 84.0, 84.0, 85.0, 84.0, 85.0, 89.0, 87.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 137.0, 137.0, 133.0, 131.0, 132.0, 133.0, 134.0, 181.0, 139.0], "value": 140.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 182.0, 176.0, 176.0, 174.0, 174.0, 172.0, 183.0, 175.0, 180.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 68.0, 68.0, 68.0, 68.0, 67.0, 68.0, 67.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1556.1, "alertThreshold": 2.0}]} 15:13:25 INFO - Return code: 0 15:13:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:13:25 INFO - # TBPL SUCCESS # 15:13:25 INFO - Running post-action listener: _resource_record_post_action 15:13:25 INFO - [mozharness: 2016-12-28 23:13:25.917262Z] Finished run-tests step (success) 15:13:25 INFO - Running post-run listener: _resource_record_post_run 15:13:25 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 382803968; Read time: 24; Write time: 252640 15:13:25 INFO - TinderboxPrint: CPU usage
13.1% 15:13:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 24 15:13:25 INFO - TinderboxPrint: I/O write bytes / time
382,803,968 / 252,640 15:13:25 INFO - TinderboxPrint: CPU idle
8,335.9 (86.9%) 15:13:25 INFO - TinderboxPrint: CPU user
1,214.0 (12.7%) 15:13:25 INFO - TinderboxPrint: Swap in / out
0 / 0 15:13:25 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99254272; Read time: 0; Write time: 68880 15:13:26 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 282517504; Read time: 0; Write time: 183728 15:13:26 INFO - Running post-run listener: _upload_blobber_files 15:13:26 INFO - Blob upload gear active. 15:13:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:13:26 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 15:13:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:13:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:13:26 INFO - (blobuploader) - INFO - Open directory for files ... 15:13:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:13:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:13:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:13:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:13:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:13:30 INFO - (blobuploader) - INFO - Done attempting. 15:13:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:13:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:13:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:13:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:13:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:13:31 INFO - (blobuploader) - INFO - Done attempting. 15:13:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:13:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:13:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:13:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:13:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:13:31 INFO - (blobuploader) - INFO - Done attempting. 15:13:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:13:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:13:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:13:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:13:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:13:32 INFO - (blobuploader) - INFO - Done attempting. 15:13:32 INFO - (blobuploader) - INFO - Iteration through files over. 15:13:32 INFO - Return code: 0 15:13:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:13:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:13:32 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef517e99a6bb09ec3aa041d6ed6926dcc017f5d3c99ac975601234b806fd4c4c881283fad94a219abff999a3d4299302e29e01333101a61b74697bac8d3ab4ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b246f095a57a77cba88cda61429cfe68b6d7febc60271fc5692fefa89cbb7a90845e7de4dd837052527a35db1bc90a51c19a6f78224e5185c69888aa6393058b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef25dcd21fc90b55e68b2b00eb086ca2ecf8275dc1c4639e5fa05145680b3dd0dbded548fc9b861ae7ad30c194ec3f506499a20cf2caad5a85e34e0a3dcbc980", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4ae6de021b3569120cedb34b5709714246078f2aa474ef13da0274e4dc02a462d49cb3bca8bfd25f81d306826f50c22d22bef825f5ce939c0d5e2a6eeb9be999"} 15:13:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:13:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:13:32 INFO - Contents: 15:13:32 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef517e99a6bb09ec3aa041d6ed6926dcc017f5d3c99ac975601234b806fd4c4c881283fad94a219abff999a3d4299302e29e01333101a61b74697bac8d3ab4ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b246f095a57a77cba88cda61429cfe68b6d7febc60271fc5692fefa89cbb7a90845e7de4dd837052527a35db1bc90a51c19a6f78224e5185c69888aa6393058b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef25dcd21fc90b55e68b2b00eb086ca2ecf8275dc1c4639e5fa05145680b3dd0dbded548fc9b861ae7ad30c194ec3f506499a20cf2caad5a85e34e0a3dcbc980", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4ae6de021b3569120cedb34b5709714246078f2aa474ef13da0274e4dc02a462d49cb3bca8bfd25f81d306826f50c22d22bef825f5ce939c0d5e2a6eeb9be999"} 15:13:32 INFO - Running post-run listener: copy_logs_to_upload_dir 15:13:32 INFO - Copying logs to upload dir... 15:13:32 INFO - mkdir: /builds/slave/test/build/upload/logs 15:13:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.398904 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-28 15:13:31.669067) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 15:13:31.672942) ========= 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=03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef517e99a6bb09ec3aa041d6ed6926dcc017f5d3c99ac975601234b806fd4c4c881283fad94a219abff999a3d4299302e29e01333101a61b74697bac8d3ab4ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b246f095a57a77cba88cda61429cfe68b6d7febc60271fc5692fefa89cbb7a90845e7de4dd837052527a35db1bc90a51c19a6f78224e5185c69888aa6393058b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef25dcd21fc90b55e68b2b00eb086ca2ecf8275dc1c4639e5fa05145680b3dd0dbded548fc9b861ae7ad30c194ec3f506499a20cf2caad5a85e34e0a3dcbc980", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4ae6de021b3569120cedb34b5709714246078f2aa474ef13da0274e4dc02a462d49cb3bca8bfd25f81d306826f50c22d22bef825f5ce939c0d5e2a6eeb9be999"} build_url:https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011934 build_url: 'https://queue.taskcluster.net/v1/task/Mya8INhITUG1wO3n0-9w2g/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef517e99a6bb09ec3aa041d6ed6926dcc017f5d3c99ac975601234b806fd4c4c881283fad94a219abff999a3d4299302e29e01333101a61b74697bac8d3ab4ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b246f095a57a77cba88cda61429cfe68b6d7febc60271fc5692fefa89cbb7a90845e7de4dd837052527a35db1bc90a51c19a6f78224e5185c69888aa6393058b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ef25dcd21fc90b55e68b2b00eb086ca2ecf8275dc1c4639e5fa05145680b3dd0dbded548fc9b861ae7ad30c194ec3f506499a20cf2caad5a85e34e0a3dcbc980", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4ae6de021b3569120cedb34b5709714246078f2aa474ef13da0274e4dc02a462d49cb3bca8bfd25f81d306826f50c22d22bef825f5ce939c0d5e2a6eeb9be999"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 15:13:31.699361) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 15:13:31.699719) ========= 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=03627c79f6bb3d1578c4a402000001ba-1482965326.165851-1911458434 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 15:13:31.782268) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 15:13:31.782587) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 15:13:31.782893) ========= ========= Total master_lag: 0.20 =========