builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-059 starttime: 1482862256.3 results: success (0) buildid: 20161227092439 builduid: b37698ce3c5241cbb6eda38544eca35b revision: 8000547183674ec092445b20f05105b6ea17aaab ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:56.295755) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:56.296184) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:56.296465) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:56.319773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:56.320041) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-27 10:10:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 102M=0s 2016-12-27 10:10:57 (102 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.239049 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:56.572974) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:56.573288) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021872 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:56.622408) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:56.622775) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 8000547183674ec092445b20f05105b6ea17aaab --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 8000547183674ec092445b20f05105b6ea17aaab --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-27 10:10:57,487 truncating revision to first 12 chars 2016-12-27 10:10:57,487 Setting DEBUG logging. 2016-12-27 10:10:57,487 attempt 1/10 2016-12-27 10:10:57,487 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/800054718367?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-27 10:10:57,905 unpacking tar archive at: graphics-800054718367/testing/mozharness/ program finished with exit code 0 elapsedTime=0.780080 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:57.416486) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:57.416807) ========= script_repo_revision: 8000547183674ec092445b20f05105b6ea17aaab ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:57.417191) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:57.417457) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 10:10:57.426016) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-27 10:10:57.426312) ========= /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=3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:10:58 INFO - MultiFileLogger online at 20161227 10:10:58 in /builds/slave/test 10:10:58 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 10:10:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:10:58 INFO - {'append_to_log': False, 10:10:58 INFO - 'base_work_dir': '/builds/slave/test', 10:10:58 INFO - 'blob_upload_branch': 'Graphics', 10:10:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:10:58 INFO - 'branch': 'Graphics', 10:10:58 INFO - 'buildbot_json_path': 'buildprops.json', 10:10:58 INFO - 'config_files': ('talos/linux_config.py',), 10:10:58 INFO - 'default_actions': ('clobber', 10:10:58 INFO - 'read-buildbot-config', 10:10:58 INFO - 'download-and-extract', 10:10:58 INFO - 'populate-webroot', 10:10:58 INFO - 'create-virtualenv', 10:10:58 INFO - 'install', 10:10:58 INFO - 'run-tests'), 10:10:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:10:58 INFO - 'download_minidump_stackwalk': True, 10:10:58 INFO - 'download_symbols': 'ondemand', 10:10:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:10:58 INFO - 'tooltool.py': '/tools/tooltool.py', 10:10:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:10:58 INFO - '/tools/misc-python/virtualenv.py')}, 10:10:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:10:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:10:58 INFO - 'installer_path': 'installer.exe', 10:10:58 INFO - 'log_level': 'info', 10:10:58 INFO - 'log_name': 'talos', 10:10:58 INFO - 'log_to_console': True, 10:10:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:10:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:10:58 INFO - 'opt_config_files': (), 10:10:58 INFO - 'pip_index': False, 10:10:58 INFO - 'sps_profile': False, 10:10:58 INFO - 'sps_profile_interval': 0, 10:10:58 INFO - 'suite': 'dromaeojs', 10:10:58 INFO - 'system_bits': '32', 10:10:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:10:58 INFO - 'title': 'talos-linux64-ix-059', 10:10:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:10:58 INFO - 'use_talos_json': True, 10:10:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:10:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:10:58 INFO - 'work_dir': 'build'} 10:10:58 INFO - [mozharness: 2016-12-27 18:10:58.383290Z] Running clobber step. 10:10:58 INFO - Running pre-action listener: _resource_record_pre_action 10:10:58 INFO - Running main action method: clobber 10:10:58 INFO - rmtree: /builds/slave/test/build 10:10:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:10:58 INFO - Running post-action listener: _resource_record_post_action 10:10:58 INFO - [mozharness: 2016-12-27 18:10:58.843866Z] Finished clobber step (success) 10:10:58 INFO - [mozharness: 2016-12-27 18:10:58.843966Z] Running read-buildbot-config step. 10:10:58 INFO - Running pre-action listener: _resource_record_pre_action 10:10:58 INFO - Running main action method: read_buildbot_config 10:10:58 INFO - Using buildbot properties: 10:10:58 INFO - { 10:10:58 INFO - "project": "", 10:10:58 INFO - "product": "firefox", 10:10:58 INFO - "script_repo_revision": "default", 10:10:58 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 10:10:58 INFO - "repository": "", 10:10:58 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 10:10:58 INFO - "buildid": "20161227092439", 10:10:58 INFO - "pgo_build": "False", 10:10:58 INFO - "basedir": "/builds/slave/test", 10:10:58 INFO - "buildnumber": 41, 10:10:58 INFO - "slavename": "talos-linux64-ix-059", 10:10:58 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:10:58 INFO - "platform": "ubuntu64_hw", 10:10:58 INFO - "scheduler": "tests-graphics-linux64-talos", 10:10:58 INFO - "branch": "graphics", 10:10:58 INFO - "repo_path": "projects/graphics", 10:10:58 INFO - "revision": "8000547183674ec092445b20f05105b6ea17aaab", 10:10:58 INFO - "stage_platform": "linux64", 10:10:58 INFO - "builduid": "b37698ce3c5241cbb6eda38544eca35b", 10:10:58 INFO - "slavebuilddir": "test" 10:10:58 INFO - } 10:10:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 10:10:58 INFO - Running post-action listener: _resource_record_post_action 10:10:58 INFO - [mozharness: 2016-12-27 18:10:58.846409Z] Finished read-buildbot-config step (success) 10:10:58 INFO - [mozharness: 2016-12-27 18:10:58.846497Z] Running download-and-extract step. 10:10:58 INFO - Running pre-action listener: _resource_record_pre_action 10:10:58 INFO - Running main action method: download_and_extract 10:10:58 INFO - mkdir: /builds/slave/test/build/tests 10:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:10:58 INFO - trying https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 10:10:58 INFO - Downloading https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 10:10:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:10:59 INFO - Downloaded 1361 bytes. 10:10:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 10:10:59 INFO - Using the following test package requirements: 10:10:59 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 10:10:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:10:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:10:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:10:59 INFO - u'jsshell-linux-x86_64.zip'], 10:10:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:10:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 10:10:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:10:59 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:10:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:10:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:10:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:10:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:10:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 10:10:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:10:59 INFO - Fetch https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 10:11:00 INFO - Expected file size: 20241358 10:11:00 INFO - Obtained file size: 20241358 10:11:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:11:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 10:11:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:11:04 INFO - Fetch https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 10:11:05 INFO - Expected file size: 20241358 10:11:05 INFO - Obtained file size: 20241358 10:11:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 10:11:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:11:09 INFO - Fetch https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 10:11:10 INFO - Expected file size: 14409776 10:11:10 INFO - Obtained file size: 14409776 10:11:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:10 INFO - trying https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 10:11:10 INFO - Downloading https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 10:11:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:11:12 INFO - Downloaded 55915942 bytes. 10:11:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 10:11:12 INFO - mkdir: /builds/slave/test/properties 10:11:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:11:12 INFO - Writing to file /builds/slave/test/properties/build_url 10:11:12 INFO - Contents: 10:11:12 INFO - build_url:https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 10:11:13 INFO - Running post-action listener: _resource_record_post_action 10:11:13 INFO - Running post-action listener: set_extra_try_arguments 10:11:13 INFO - [mozharness: 2016-12-27 18:11:13.673030Z] Finished download-and-extract step (success) 10:11:13 INFO - [mozharness: 2016-12-27 18:11:13.673257Z] Running populate-webroot step. 10:11:13 INFO - Running pre-action listener: _resource_record_pre_action 10:11:13 INFO - Running main action method: populate_webroot 10:11:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:11:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:11:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:11:13 INFO - '--tppagecycles', 10:11:13 INFO - '7'], 10:11:13 INFO - 'tests': ['tsvgm']}}, 10:11:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:11:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:11:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:11:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:11:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:11:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:11:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:11:13 INFO - 'talos_options': ['--disable-e10s'], 10:11:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:11:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:11:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:11:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:11:13 INFO - 'talos_options': ['--disable-e10s'], 10:11:13 INFO - 'tests': ['damp', 'tps']}, 10:11:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:11:13 INFO - 'tests': ['damp', 'tps']}, 10:11:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:11:13 INFO - 'tests': ['dromaeo_dom']}, 10:11:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:11:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:11:13 INFO - 'tests': ['basic_compositor_video']}, 10:11:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:11:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:11:13 INFO - 'tests': ['a11yr', 10:11:13 INFO - 'ts_paint', 10:11:13 INFO - 'tpaint', 10:11:13 INFO - 'sessionrestore', 10:11:13 INFO - 'sessionrestore_no_auto_restore', 10:11:13 INFO - 'tabpaint']}, 10:11:13 INFO - 'other-e10s': {'tests': ['a11yr', 10:11:13 INFO - 'ts_paint', 10:11:13 INFO - 'tpaint', 10:11:13 INFO - 'sessionrestore', 10:11:13 INFO - 'sessionrestore_no_auto_restore', 10:11:13 INFO - 'tabpaint']}, 10:11:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:11:13 INFO - 'ts_paint', 10:11:13 INFO - 'tpaint', 10:11:13 INFO - 'sessionrestore', 10:11:13 INFO - 'sessionrestore_no_auto_restore', 10:11:13 INFO - 'tabpaint']}, 10:11:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:11:13 INFO - 'ts_paint', 10:11:13 INFO - 'tpaint', 10:11:13 INFO - 'sessionrestore', 10:11:13 INFO - 'sessionrestore_no_auto_restore', 10:11:13 INFO - 'tabpaint']}, 10:11:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:11:13 INFO - 'tests': ['a11yr', 10:11:13 INFO - 'ts_paint', 10:11:13 INFO - 'tpaint', 10:11:13 INFO - 'sessionrestore', 10:11:13 INFO - 'sessionrestore_no_auto_restore', 10:11:13 INFO - 'tabpaint']}, 10:11:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:11:13 INFO - 'tests': ['a11yr', 10:11:13 INFO - 'ts_paint', 10:11:13 INFO - 'tpaint', 10:11:13 INFO - 'sessionrestore', 10:11:13 INFO - 'sessionrestore_no_auto_restore', 10:11:13 INFO - 'tabpaint']}, 10:11:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:11:13 INFO - 'tests': ['tsvgx', 10:11:13 INFO - 'tsvgr_opacity', 10:11:13 INFO - 'tart', 10:11:13 INFO - 'tscrollx', 10:11:13 INFO - 'cart', 10:11:13 INFO - 'tsvg_static']}, 10:11:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:11:13 INFO - 'tsvgr_opacity', 10:11:13 INFO - 'tart', 10:11:13 INFO - 'tscrollx', 10:11:13 INFO - 'cart', 10:11:13 INFO - 'tsvg_static']}, 10:11:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:11:13 INFO - 'talos_options': ['--disable-e10s'], 10:11:13 INFO - 'tests': ['tp5o']}, 10:11:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:11:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:11:13 INFO - 'talos_options': ['--disable-e10s', 10:11:13 INFO - '--xperf_path', 10:11:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:11:13 INFO - 'tests': ['tp5n']}, 10:11:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:11:13 INFO - 'talos_options': ['--xperf_path', 10:11:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:11:13 INFO - 'tests': ['tp5n']}}, 10:11:13 INFO - 'talos.zip': {'path': '', 10:11:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:11:13 INFO - Running post-action listener: _resource_record_post_action 10:11:13 INFO - [mozharness: 2016-12-27 18:11:13.689762Z] Finished populate-webroot step (success) 10:11:13 INFO - [mozharness: 2016-12-27 18:11:13.689850Z] Running create-virtualenv step. 10:11:13 INFO - Running pre-action listener: _resource_record_pre_action 10:11:13 INFO - Running main action method: create_virtualenv 10:11:13 INFO - Creating virtualenv /builds/slave/test/build/venv 10:11:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:11:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:11:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:11:13 INFO - Using real prefix '/usr' 10:11:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:11:14 INFO - Installing distribute.............................................................................................................................................................................................done. 10:11:16 INFO - Installing pip.................done. 10:11:16 INFO - Return code: 0 10:11:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:11:16 INFO - Reading from file tmpfile_stdout 10:11:16 INFO - Output received: 10:11:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:11:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2881c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287f3f0>, '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 0x287fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28801a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:11:16 INFO - Using env: {'DISPLAY': ':0', 10:11:16 INFO - 'HOME': '/home/cltbld', 10:11:16 INFO - 'LANG': 'en_US.UTF-8', 10:11:16 INFO - 'LANGUAGE': 'en_US:en', 10:11:16 INFO - 'LOGNAME': 'cltbld', 10:11:16 INFO - 'MAIL': '/var/mail/cltbld', 10:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:16 INFO - 'MOZ_NO_REMOTE': '1', 10:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:16 INFO - 'NO_EM_RESTART': '1', 10:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:16 INFO - 'PWD': '/builds/slave/test', 10:11:16 INFO - 'SHELL': '/bin/bash', 10:11:16 INFO - 'SHLVL': '1', 10:11:16 INFO - 'TERM': 'linux', 10:11:16 INFO - 'TMOUT': '86400', 10:11:16 INFO - 'USER': 'cltbld', 10:11:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 10:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:16 INFO - '_': '/tools/buildbot/bin/python'} 10:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:11:16 INFO - Cleaning up... 10:11:16 INFO - Return code: 0 10:11:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2881c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287f3f0>, '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 0x287fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28801a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:11:16 INFO - Using env: {'DISPLAY': ':0', 10:11:16 INFO - 'HOME': '/home/cltbld', 10:11:16 INFO - 'LANG': 'en_US.UTF-8', 10:11:16 INFO - 'LANGUAGE': 'en_US:en', 10:11:16 INFO - 'LOGNAME': 'cltbld', 10:11:16 INFO - 'MAIL': '/var/mail/cltbld', 10:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:16 INFO - 'MOZ_NO_REMOTE': '1', 10:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:16 INFO - 'NO_EM_RESTART': '1', 10:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:16 INFO - 'PWD': '/builds/slave/test', 10:11:16 INFO - 'SHELL': '/bin/bash', 10:11:16 INFO - 'SHLVL': '1', 10:11:16 INFO - 'TERM': 'linux', 10:11:16 INFO - 'TMOUT': '86400', 10:11:16 INFO - 'USER': 'cltbld', 10:11:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 10:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:16 INFO - '_': '/tools/buildbot/bin/python'} 10:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:16 INFO - Downloading/unpacking psutil>=3.1.1 10:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:11:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:11:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:11:19 INFO - Installing collected packages: psutil 10:11:19 INFO - Running setup.py install for psutil 10:11:19 INFO - building 'psutil._psutil_linux' extension 10:11:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:11:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:11:19 INFO - building 'psutil._psutil_posix' extension 10:11:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:11:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:11:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:11:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:11:19 INFO - Successfully installed psutil 10:11:19 INFO - Cleaning up... 10:11:19 INFO - Return code: 0 10:11:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2881c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287f3f0>, '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 0x287fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28801a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:11:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:11:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:11:19 INFO - Using env: {'DISPLAY': ':0', 10:11:19 INFO - 'HOME': '/home/cltbld', 10:11:19 INFO - 'LANG': 'en_US.UTF-8', 10:11:19 INFO - 'LANGUAGE': 'en_US:en', 10:11:19 INFO - 'LOGNAME': 'cltbld', 10:11:19 INFO - 'MAIL': '/var/mail/cltbld', 10:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:19 INFO - 'MOZ_NO_REMOTE': '1', 10:11:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:19 INFO - 'NO_EM_RESTART': '1', 10:11:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:19 INFO - 'PWD': '/builds/slave/test', 10:11:19 INFO - 'SHELL': '/bin/bash', 10:11:19 INFO - 'SHLVL': '1', 10:11:19 INFO - 'TERM': 'linux', 10:11:19 INFO - 'TMOUT': '86400', 10:11:19 INFO - 'USER': 'cltbld', 10:11:19 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 10:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:19 INFO - '_': '/tools/buildbot/bin/python'} 10:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:11:21 INFO - Installing collected packages: mozsystemmonitor 10:11:21 INFO - Running setup.py install for mozsystemmonitor 10:11:21 INFO - Successfully installed mozsystemmonitor 10:11:21 INFO - Cleaning up... 10:11:21 INFO - Return code: 0 10:11:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2881c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287f3f0>, '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 0x287fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28801a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:11:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:11:22 INFO - Using env: {'DISPLAY': ':0', 10:11:22 INFO - 'HOME': '/home/cltbld', 10:11:22 INFO - 'LANG': 'en_US.UTF-8', 10:11:22 INFO - 'LANGUAGE': 'en_US:en', 10:11:22 INFO - 'LOGNAME': 'cltbld', 10:11:22 INFO - 'MAIL': '/var/mail/cltbld', 10:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:22 INFO - 'MOZ_NO_REMOTE': '1', 10:11:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:22 INFO - 'NO_EM_RESTART': '1', 10:11:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:22 INFO - 'PWD': '/builds/slave/test', 10:11:22 INFO - 'SHELL': '/bin/bash', 10:11:22 INFO - 'SHLVL': '1', 10:11:22 INFO - 'TERM': 'linux', 10:11:22 INFO - 'TMOUT': '86400', 10:11:22 INFO - 'USER': 'cltbld', 10:11:22 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 10:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:22 INFO - '_': '/tools/buildbot/bin/python'} 10:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:22 INFO - Downloading/unpacking jsonschema==2.5.1 10:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:11:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:24 INFO - Downloading functools32-3.2.3-2.tar.gz 10:11:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:11:24 INFO - warning: no files found matching '*.txt' 10:11:24 INFO - no previously-included directories found matching 'build' 10:11:24 INFO - no previously-included directories found matching 'dist' 10:11:24 INFO - no previously-included directories found matching '.git*' 10:11:24 INFO - Installing collected packages: jsonschema, functools32 10:11:24 INFO - Running setup.py install for functools32 10:11:24 INFO - warning: no files found matching '*.txt' 10:11:24 INFO - no previously-included directories found matching 'build' 10:11:24 INFO - no previously-included directories found matching 'dist' 10:11:24 INFO - no previously-included directories found matching '.git*' 10:11:24 INFO - Successfully installed jsonschema functools32 10:11:24 INFO - Cleaning up... 10:11:24 INFO - Return code: 0 10:11:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2881c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287f3f0>, '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 0x287fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28801a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:11:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:11:24 INFO - Using env: {'DISPLAY': ':0', 10:11:24 INFO - 'HOME': '/home/cltbld', 10:11:24 INFO - 'LANG': 'en_US.UTF-8', 10:11:24 INFO - 'LANGUAGE': 'en_US:en', 10:11:24 INFO - 'LOGNAME': 'cltbld', 10:11:24 INFO - 'MAIL': '/var/mail/cltbld', 10:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:24 INFO - 'MOZ_NO_REMOTE': '1', 10:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:24 INFO - 'NO_EM_RESTART': '1', 10:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:24 INFO - 'PWD': '/builds/slave/test', 10:11:24 INFO - 'SHELL': '/bin/bash', 10:11:24 INFO - 'SHLVL': '1', 10:11:24 INFO - 'TERM': 'linux', 10:11:24 INFO - 'TMOUT': '86400', 10:11:24 INFO - 'USER': 'cltbld', 10:11:24 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 10:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:24 INFO - '_': '/tools/buildbot/bin/python'} 10:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:11:24 INFO - Cleaning up... 10:11:24 INFO - Return code: 0 10:11:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2881c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287f3f0>, '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 0x287fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28801a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:11:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:11:24 INFO - Using env: {'DISPLAY': ':0', 10:11:24 INFO - 'HOME': '/home/cltbld', 10:11:24 INFO - 'LANG': 'en_US.UTF-8', 10:11:24 INFO - 'LANGUAGE': 'en_US:en', 10:11:24 INFO - 'LOGNAME': 'cltbld', 10:11:24 INFO - 'MAIL': '/var/mail/cltbld', 10:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:24 INFO - 'MOZ_NO_REMOTE': '1', 10:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:24 INFO - 'NO_EM_RESTART': '1', 10:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:24 INFO - 'PWD': '/builds/slave/test', 10:11:24 INFO - 'SHELL': '/bin/bash', 10:11:24 INFO - 'SHLVL': '1', 10:11:24 INFO - 'TERM': 'linux', 10:11:24 INFO - 'TMOUT': '86400', 10:11:24 INFO - 'USER': 'cltbld', 10:11:24 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 10:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:24 INFO - '_': '/tools/buildbot/bin/python'} 10:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:25 INFO - Downloading/unpacking blobuploader==1.2.4 10:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:27 INFO - Downloading blobuploader-1.2.4.tar.gz 10:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:11:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:11:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:27 INFO - Downloading docopt-0.6.1.tar.gz 10:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:11:27 INFO - Installing collected packages: blobuploader, requests, docopt 10:11:27 INFO - Running setup.py install for blobuploader 10:11:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:11:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:11:28 INFO - Running setup.py install for requests 10:11:28 INFO - Running setup.py install for docopt 10:11:28 INFO - Successfully installed blobuploader requests docopt 10:11:28 INFO - Cleaning up... 10:11:28 INFO - Return code: 0 10:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2881c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287f3f0>, '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 0x287fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28801a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:11:28 INFO - Using env: {'DISPLAY': ':0', 10:11:28 INFO - 'HOME': '/home/cltbld', 10:11:28 INFO - 'LANG': 'en_US.UTF-8', 10:11:28 INFO - 'LANGUAGE': 'en_US:en', 10:11:28 INFO - 'LOGNAME': 'cltbld', 10:11:28 INFO - 'MAIL': '/var/mail/cltbld', 10:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:28 INFO - 'MOZ_NO_REMOTE': '1', 10:11:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:28 INFO - 'NO_EM_RESTART': '1', 10:11:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:28 INFO - 'PWD': '/builds/slave/test', 10:11:28 INFO - 'SHELL': '/bin/bash', 10:11:28 INFO - 'SHLVL': '1', 10:11:28 INFO - 'TERM': 'linux', 10:11:28 INFO - 'TMOUT': '86400', 10:11:28 INFO - 'USER': 'cltbld', 10:11:28 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 10:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:28 INFO - '_': '/tools/buildbot/bin/python'} 10:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:11:28 INFO - Running setup.py (path:/tmp/pip-DWxdMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:11:28 INFO - Running setup.py (path:/tmp/pip-Awy4Ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:11:28 INFO - Running setup.py (path:/tmp/pip-Y4zr86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:11:29 INFO - Running setup.py (path:/tmp/pip-tBUlKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:11:29 INFO - Running setup.py (path:/tmp/pip-SjjKJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:11:29 INFO - Running setup.py (path:/tmp/pip-uBEGSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:11:29 INFO - Running setup.py (path:/tmp/pip-hwuq3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:11:29 INFO - Running setup.py (path:/tmp/pip-8vwP57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:11:29 INFO - Running setup.py (path:/tmp/pip-0BZTkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:11:29 INFO - Running setup.py (path:/tmp/pip-__oIX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:11:29 INFO - Running setup.py (path:/tmp/pip-5pLDoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:11:29 INFO - Running setup.py (path:/tmp/pip-SRpOI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:11:29 INFO - Running setup.py (path:/tmp/pip-cyaCk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:11:29 INFO - Running setup.py (path:/tmp/pip-6zhl5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:29 INFO - Running setup.py (path:/tmp/pip-Qx0yWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:11:29 INFO - Running setup.py (path:/tmp/pip-h43Osm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:11:29 INFO - Running setup.py (path:/tmp/pip-jVnISz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:11:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:11:30 INFO - Running setup.py install for manifestparser 10:11:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:11:30 INFO - Running setup.py install for mozcrash 10:11:30 INFO - Running setup.py install for mozdebug 10:11:30 INFO - Running setup.py install for mozdevice 10:11:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:11:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:11:30 INFO - Running setup.py install for mozfile 10:11:30 INFO - Running setup.py install for mozhttpd 10:11:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:11:30 INFO - Running setup.py install for mozinfo 10:11:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:11:30 INFO - Running setup.py install for mozInstall 10:11:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:11:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:11:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:11:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:11:30 INFO - Running setup.py install for mozleak 10:11:30 INFO - Running setup.py install for mozlog 10:11:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:11:31 INFO - Running setup.py install for moznetwork 10:11:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:11:31 INFO - Running setup.py install for mozprocess 10:11:31 INFO - Running setup.py install for mozprofile 10:11:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:11:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:11:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:11:31 INFO - Running setup.py install for mozrunner 10:11:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:11:31 INFO - Running setup.py install for mozscreenshot 10:11:31 INFO - Running setup.py install for moztest 10:11:31 INFO - Running setup.py install for mozversion 10:11:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:11:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:11:31 INFO - Cleaning up... 10:11:31 INFO - Return code: 0 10:11:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2881c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287f3f0>, '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 0x287fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28801a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:11:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:11:31 INFO - Using env: {'DISPLAY': ':0', 10:11:31 INFO - 'HOME': '/home/cltbld', 10:11:31 INFO - 'LANG': 'en_US.UTF-8', 10:11:31 INFO - 'LANGUAGE': 'en_US:en', 10:11:31 INFO - 'LOGNAME': 'cltbld', 10:11:31 INFO - 'MAIL': '/var/mail/cltbld', 10:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:31 INFO - 'MOZ_NO_REMOTE': '1', 10:11:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:31 INFO - 'NO_EM_RESTART': '1', 10:11:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:31 INFO - 'PWD': '/builds/slave/test', 10:11:31 INFO - 'SHELL': '/bin/bash', 10:11:31 INFO - 'SHLVL': '1', 10:11:31 INFO - 'TERM': 'linux', 10:11:31 INFO - 'TMOUT': '86400', 10:11:31 INFO - 'USER': 'cltbld', 10:11:31 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 10:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:31 INFO - '_': '/tools/buildbot/bin/python'} 10:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:11:32 INFO - Running setup.py (path:/tmp/pip-n5VtZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:11:32 INFO - Running setup.py (path:/tmp/pip-fCyt0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:11:32 INFO - Running setup.py (path:/tmp/pip-KkxfRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:11:32 INFO - Running setup.py (path:/tmp/pip-Q69NqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:11:32 INFO - Running setup.py (path:/tmp/pip-ud7tfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:11:32 INFO - Running setup.py (path:/tmp/pip-5u_qUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:11:32 INFO - Running setup.py (path:/tmp/pip-ltPBKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:11:32 INFO - Running setup.py (path:/tmp/pip-gqoTPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:11:32 INFO - Running setup.py (path:/tmp/pip-E9GukP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:11:32 INFO - Running setup.py (path:/tmp/pip-Z69wR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:11:32 INFO - Running setup.py (path:/tmp/pip-hBai8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:11:32 INFO - Running setup.py (path:/tmp/pip-KGfbQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:11:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:11:32 INFO - Running setup.py (path:/tmp/pip-RHkN69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:11:33 INFO - Running setup.py (path:/tmp/pip-54qoYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:33 INFO - Running setup.py (path:/tmp/pip-DTpIGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:11:33 INFO - Running setup.py (path:/tmp/pip-tQEfiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:11:33 INFO - Running setup.py (path:/tmp/pip-XStkgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:11:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:11:35 INFO - Downloading blessings-1.6.tar.gz 10:11:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:11:35 INFO - Installing collected packages: blessings 10:11:35 INFO - Running setup.py install for blessings 10:11:35 INFO - Successfully installed blessings 10:11:35 INFO - Cleaning up... 10:11:35 INFO - Return code: 0 10:11:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:11:35 INFO - Reading from file tmpfile_stdout 10:11:35 INFO - Current package versions: 10:11:35 INFO - argparse == 1.2.1 10:11:35 INFO - blessings == 1.6 10:11:35 INFO - blobuploader == 1.2.4 10:11:35 INFO - docopt == 0.6.1 10:11:35 INFO - functools32 == 3.2.3-2 10:11:35 INFO - jsonschema == 2.5.1 10:11:35 INFO - manifestparser == 1.1 10:11:35 INFO - mozInstall == 1.12 10:11:35 INFO - mozcrash == 1.0 10:11:35 INFO - mozdebug == 0.1 10:11:35 INFO - mozdevice == 0.49 10:11:35 INFO - mozfile == 1.2 10:11:35 INFO - mozhttpd == 0.7 10:11:35 INFO - mozinfo == 0.9 10:11:35 INFO - mozleak == 0.1 10:11:35 INFO - mozlog == 3.4 10:11:35 INFO - moznetwork == 0.27 10:11:35 INFO - mozprocess == 0.23 10:11:35 INFO - mozprofile == 0.28 10:11:35 INFO - mozrunner == 6.13 10:11:35 INFO - mozscreenshot == 0.1 10:11:35 INFO - mozsystemmonitor == 0.3 10:11:35 INFO - moztest == 0.7 10:11:35 INFO - mozversion == 1.4 10:11:35 INFO - psutil == 3.1.1 10:11:35 INFO - requests == 1.2.3 10:11:35 INFO - wsgiref == 0.1.2 10:11:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2881c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287f3f0>, '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 0x287fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28801a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:11:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:11:35 INFO - Using env: {'DISPLAY': ':0', 10:11:35 INFO - 'HOME': '/home/cltbld', 10:11:35 INFO - 'LANG': 'en_US.UTF-8', 10:11:35 INFO - 'LANGUAGE': 'en_US:en', 10:11:35 INFO - 'LOGNAME': 'cltbld', 10:11:35 INFO - 'MAIL': '/var/mail/cltbld', 10:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:35 INFO - 'MOZ_NO_REMOTE': '1', 10:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:35 INFO - 'NO_EM_RESTART': '1', 10:11:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:35 INFO - 'PWD': '/builds/slave/test', 10:11:35 INFO - 'SHELL': '/bin/bash', 10:11:35 INFO - 'SHLVL': '1', 10:11:35 INFO - 'TERM': 'linux', 10:11:35 INFO - 'TMOUT': '86400', 10:11:35 INFO - 'USER': 'cltbld', 10:11:35 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 10:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:35 INFO - '_': '/tools/buildbot/bin/python'} 10:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:11:35 INFO - Cleaning up... 10:11:35 INFO - Return code: 0 10:11:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:11:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:11:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:11:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26dca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2881c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287f3f0>, '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 0x287fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28801a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:11:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:11:36 INFO - Using env: {'DISPLAY': ':0', 10:11:36 INFO - 'HOME': '/home/cltbld', 10:11:36 INFO - 'LANG': 'en_US.UTF-8', 10:11:36 INFO - 'LANGUAGE': 'en_US:en', 10:11:36 INFO - 'LOGNAME': 'cltbld', 10:11:36 INFO - 'MAIL': '/var/mail/cltbld', 10:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:36 INFO - 'MOZ_NO_REMOTE': '1', 10:11:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:36 INFO - 'NO_EM_RESTART': '1', 10:11:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:36 INFO - 'PWD': '/builds/slave/test', 10:11:36 INFO - 'SHELL': '/bin/bash', 10:11:36 INFO - 'SHLVL': '1', 10:11:36 INFO - 'TERM': 'linux', 10:11:36 INFO - 'TMOUT': '86400', 10:11:36 INFO - 'USER': 'cltbld', 10:11:36 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 10:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:36 INFO - '_': '/tools/buildbot/bin/python'} 10:11:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:11:36 INFO - Cleaning up... 10:11:36 INFO - Return code: 0 10:11:36 INFO - Running post-action listener: _resource_record_post_action 10:11:36 INFO - Running post-action listener: _start_resource_monitoring 10:11:36 INFO - Starting resource monitoring. 10:11:36 INFO - [mozharness: 2016-12-27 18:11:36.259979Z] Finished create-virtualenv step (success) 10:11:36 INFO - [mozharness: 2016-12-27 18:11:36.260491Z] Running install step. 10:11:36 INFO - Running pre-action listener: _resource_record_pre_action 10:11:36 INFO - Running main action method: install 10:11:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:11:36 INFO - Reading from file tmpfile_stdout 10:11:36 INFO - Detecting whether we're running mozinstall >=1.0... 10:11:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:11:36 INFO - Reading from file tmpfile_stdout 10:11:36 INFO - Output received: 10:11:36 INFO - Usage: mozinstall [options] installer 10:11:36 INFO - Options: 10:11:36 INFO - -h, --help show this help message and exit 10:11:36 INFO - -d DEST, --destination=DEST 10:11:36 INFO - Directory to install application into. [default: 10:11:36 INFO - "/builds/slave/test"] 10:11:36 INFO - --app=APP Application being installed. [default: firefox] 10:11:36 INFO - mkdir: /builds/slave/test/build/application 10:11:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 10:11:52 INFO - Reading from file tmpfile_stdout 10:11:52 INFO - Output received: 10:11:52 INFO - /builds/slave/test/build/application/firefox/firefox 10:11:52 INFO - Running post-action listener: _resource_record_post_action 10:11:52 INFO - [mozharness: 2016-12-27 18:11:52.673929Z] Finished install step (success) 10:11:52 INFO - [mozharness: 2016-12-27 18:11:52.674174Z] Running run-tests step. 10:11:52 INFO - Running pre-action listener: _resource_record_pre_action 10:11:52 INFO - Running main action method: run_tests 10:11:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:11:52 INFO - Python 2.7.3 10:11:52 INFO - Return code: 0 10:11:52 INFO - grabbing minidump binary from tooltool 10:11:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:52 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287f3f0>, '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 0x287fcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28801a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:11:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:11:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:11:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:11:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:11:52 INFO - Return code: 0 10:11:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:11:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:11:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:11:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:11:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:11:52 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/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:11:52 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/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:11:52 INFO - Using env: {'DISPLAY': ':0', 10:11:52 INFO - 'HOME': '/home/cltbld', 10:11:52 INFO - 'LANG': 'en_US.UTF-8', 10:11:52 INFO - 'LANGUAGE': 'en_US:en', 10:11:52 INFO - 'LOGNAME': 'cltbld', 10:11:52 INFO - 'MAIL': '/var/mail/cltbld', 10:11:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:11:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:52 INFO - 'MOZ_NO_REMOTE': '1', 10:11:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:11:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:11:52 INFO - 'NO_EM_RESTART': '1', 10:11:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:52 INFO - 'PWD': '/builds/slave/test', 10:11:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:11:52 INFO - 'SHELL': '/bin/bash', 10:11:52 INFO - 'SHLVL': '1', 10:11:52 INFO - 'TERM': 'linux', 10:11:52 INFO - 'TMOUT': '86400', 10:11:52 INFO - 'USER': 'cltbld', 10:11:52 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983', 10:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:52 INFO - '_': '/tools/buildbot/bin/python'} 10:11:52 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/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:11:52 INFO - mozversion application_buildid: 20161227092439 10:11:52 INFO - mozversion application_changeset: 8000547183674ec092445b20f05105b6ea17aaab 10:11:52 INFO - mozversion application_display_name: Nightly 10:11:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:11:52 INFO - mozversion application_name: Firefox 10:11:52 INFO - mozversion application_remotingname: firefox 10:11:52 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 10:11:52 INFO - mozversion application_vendor: Mozilla 10:11:52 INFO - mozversion application_version: 53.0a1 10:11:52 INFO - mozversion platform_buildid: 20161227092439 10:11:52 INFO - mozversion platform_changeset: 8000547183674ec092445b20f05105b6ea17aaab 10:11:52 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 10:11:52 INFO - mozversion platform_version: 53.0a1 10:11:52 INFO - using testdate: 1482862312 10:11:52 INFO - actual date: 1482862312 10:11:52 INFO - starting webserver on 'localhost:60493' 10:11:52 INFO - SUITE-START | Running 2 tests 10:11:52 INFO - TEST-START | dromaeo_css 10:11:52 INFO - operating with platform_type : linux_ 10:11:52 INFO - Initialising browser for dromaeo_css test... 10:11:52 INFO - TEST-INFO | started process 6534 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpML318E/profile http://localhost:60493/getInfo.html) 10:11:53 INFO - PROCESS | 6534 | 1482862313472 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 10:11:56 INFO - PROCESS | 6534 | __metrics Screen width/height:1600/1200 10:11:56 INFO - PROCESS | 6534 | colorDepth:24 10:11:56 INFO - PROCESS | 6534 | Browser inner width/height: 1024/697 10:11:56 INFO - PROCESS | 6534 | __metrics 10:11:57 INFO - PROCESS | 6534 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:11:57 INFO - PROCESS | 6534 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:11:57 INFO - PROCESS | 6534 | console.error: PushService: 10:11:57 INFO - PROCESS | 6534 | stateChangeProcessEnqueue: Error transitioning state 10:11:57 INFO - PROCESS | 6534 | UnknownError 10:11:58 INFO - TEST-INFO | 6534: exit 0 10:11:58 INFO - Browser initialized. 10:11:58 INFO - Running cycle 1/1 for dromaeo_css test... 10:11:58 INFO - TEST-INFO | started process 6616 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpML318E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:12:10 INFO - PROCESS | 6616 | RSS: Main: 151605248 10:12:10 INFO - PROCESS | 6616 | 10:15:15 INFO - PROCESS | 6616 | Cycle 1(1): loaded http://localhost:60493/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60493/tests/dromaeo/cssquery-ext.html) 10:15:17 INFO - PROCESS | 6616 | RSS: Main: 248217600 10:15:17 INFO - PROCESS | 6616 | 10:16:58 INFO - PROCESS | 6616 | ************************* 10:16:58 INFO - PROCESS | 6616 | A coding exception was thrown and uncaught in a Task. 10:16:58 INFO - PROCESS | 6616 | 10:16:58 INFO - PROCESS | 6616 | Full message: ReferenceError: fetch is not defined 10:16:58 INFO - PROCESS | 6616 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 10:16:58 INFO - PROCESS | 6616 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 10:16:58 INFO - PROCESS | 6616 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 10:16:58 INFO - PROCESS | 6616 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 10:16:58 INFO - PROCESS | 6616 | TaskImpl@resource://gre/modules/Task.jsm:277:3 10:16:58 INFO - PROCESS | 6616 | asyncFunction@resource://gre/modules/Task.jsm:252:14 10:16:58 INFO - PROCESS | 6616 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 10:16:58 INFO - PROCESS | 6616 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 10:16:58 INFO - PROCESS | 6616 | TaskImpl@resource://gre/modules/Task.jsm:277:3 10:16:58 INFO - PROCESS | 6616 | asyncFunction@resource://gre/modules/Task.jsm:252:14 10:16:58 INFO - PROCESS | 6616 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 10:16:58 INFO - PROCESS | 6616 | 10:16:58 INFO - PROCESS | 6616 | ************************* 10:18:19 INFO - PROCESS | 6616 | Cycle 1(1): loaded http://localhost:60493/tests/dromaeo/cssquery-ext.html (next: http://localhost:60493/tests/dromaeo/cssquery-jquery.html) 10:18:20 INFO - PROCESS | 6616 | RSS: Main: 244645888 10:18:20 INFO - PROCESS | 6616 | 10:21:22 INFO - PROCESS | 6616 | Cycle 1(1): loaded http://localhost:60493/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60493/tests/dromaeo/cssquery-mootools.html) 10:21:23 INFO - PROCESS | 6616 | RSS: Main: 185442304 10:21:23 INFO - PROCESS | 6616 | 10:24:25 INFO - PROCESS | 6616 | Cycle 1(1): loaded http://localhost:60493/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60493/tests/dromaeo/cssquery-prototype.html) 10:24:25 INFO - PROCESS | 6616 | RSS: Main: 184639488 10:24:25 INFO - PROCESS | 6616 | 10:27:29 INFO - PROCESS | 6616 | Cycle 1(1): loaded http://localhost:60493/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60493/tests/dromaeo/cssquery-yui.html) 10:27:29 INFO - PROCESS | 6616 | RSS: Main: 186380288 10:27:29 INFO - PROCESS | 6616 | 10:30:32 INFO - PROCESS | 6616 | Cycle 1(1): loaded http://localhost:60493/tests/dromaeo/cssquery-yui.html (next: http://localhost:60493/tests/dromaeo/cssquery-dojo.html) 10:30:32 INFO - PROCESS | 6616 | RSS: Main: 274677760 10:30:32 INFO - PROCESS | 6616 | 10:30:32 INFO - PROCESS | 6616 | __start_tp_report 10:30:32 INFO - PROCESS | 6616 | _x_x_mozilla_page_load 10:30:32 INFO - PROCESS | 6616 | _x_x_mozilla_page_load_details 10:30:32 INFO - PROCESS | 6616 | |i|pagename|runs| 10:30:32 INFO - PROCESS | 6616 | |0;dojo.html;2599.402137508373;2612.4122072533696;2642.3658322002725;2642.550766369717;2642.907498237562;4784.5933095686905;4827.975860120694;4830.541098595637;4840.1529732296885;4886.022795440917;4827.686200396981;4874.366332376786;4893.706377617345;4916.852494425164;4940.308356830046;4465.508794032654;4469.3296005599095;4484.910301793962;4498.235299999008;4583.175028494887;4479.8432054878085;4492.842750503733;4500.97749511252;4507.752073635948;4510;4326.718763280377;4347.326164444485;4500.09998000398;4534.569215924482;4543.568361005699;4366.759828209447;4393.253146965024;4393.582609652078;4394.36281739146;4399.78001099947;4367.825286988516;4374;4375.9562404375865;4378.16814805186;4379.693421460499;4783.115123702126;4846.466888642281;4854.927176092361;4855.344528488644;4871.658983871131;5141.15170996785;5152.407473140599;5160.0969830279555;5178.922316165261;5208.973955130238;5134.614903882187;5143.819966301162;5153.330067091291;5162.045021671003;5213.713245771481;4755.667103302771;4794.616430685606;4808.134535783557;4849.781759820817;4849.80600775965;5273.525382715574;5323.254744335796;5330.227117068081;5338.199270109515;5363.436839131835;5108.386993560796;5117.8720531987;5118.718470484159;5120.769565369567;5125.769340379692;5014.52362025607;5051.646384753032;5057.468965758542;5099.898002040012;5179.2749015137915;5070.61970352225;5075.3402057733;5082.491750824888;5108.003939231889;5125.384953805567;5077.847664570068;5082.263071854561;5088.287639730441;5095.643304968617;5131.61512886535;4337.306031035019;4378.759168245777;4438.7114837535455;4456.799444025027;4481.282994720894;3852.8651497198025;3868.207017561393;3898.161895192547;3908;3919.392494163409;4421.778911054499;4427.446569178853;4430.357598148251;4430.468343798765;4455.1757924784015;5100.438951715309;5146.330976972969;5216.843494695166;5218.034663587248;5230.398504172069;4379.255526560492;4404.823807047682;4406.801693923781;4409.757463339483;4465.017696106851;4708.411448568929;4709.552592503706;4711;4720.197566413649;4752.382190315237;4864.927026094682;4865.9026819463415;4870.853874383774;4870.952745159737;4915.901681966412;5007.7746501408255;5020.748962551931;5153.793848246029;5197.636165468532;5199.688018718889;4856.053069651384;4856.9757151215435;4857.319975203403;4889.951100489092;4900.705957642553;4986.227134794112;5032.043911656773;5036.84889453317;5095.796168153232;5119.692818430906;5119;5162.612804039637;5172.094883395315;5173.379194496684;5179.352580927384;4914.36113305268;4991.15150424436;5002.324686167321;5009.599232061353;5009.649324547247;4977.576905962889;4990.5508504234795;4991.575716064175;4999.725015124203;5034.4462109167225;4875.414950205998;4941.234108713155;4952.059108769322;4970.204767237225;5004.299398084344;5030.798768049237;5038.370203724535;5051.494850514919;5055.216441451581;5092.8472145835685;4914.9017019658695;4917.803287868445;4919.827806026772;4924.975375123101;4949.257611358181 10:30:32 INFO - PROCESS | 6616 | |1;ext.html;519.1978393382224;534.4281618668024;536.4447796530591;544.9128139497681;552.6711606594075;28806.711932880666;38119.80940095299;39511.407328890054;39681.20637587246;39806.80096599513;40283;40394.39408408876;40435.19129617406;40696.59303406972;40920;7487.1764105948305;7597.506162099474;7693.422993275499;7694.115176754689;7701.3453856422275;7525.435592330569;9995.300328976975;10262.640807571737;10266.178705703525;10546.209034322419;10209.183265338792;10262.7434314142;10275.332103413255;10283.177345812317;10285.228607854404;10144.847827282596;10200.13298869597;10211.387316760982;10213.08082272599;10216.335938163995;10034.69895903124;10096.091351778376;10108.140808031327;10109.848352274721;10113.24150688694;37398.25203495942;37699;37764.62235377639;37820.43269350962;37855.62144378549;3633.291508155911;3638.2905333459985;3738.3084129436143;3755.2301778135416;3764.270903594899;26676.866615666993;27151.913923443037;27173.456530869273;30428;31768;3366.9158271043175;3433.5193072969805;3506.6318036606044;3522.5420695309704;3562.590302115264;358330;367913;368178;368178;369472;56764.71617641968;57390.7130464345;57458;57467;57478.425215748146;60350.396496035355;60433.69783151056;60499.39500604937;60535;60597.39402605918;13304.600861973962;13412.597622071353;13436.462541498231;13593.116447431083;13600.659983500294;8906.109389061043;9091.36360454775;10330;10431.739206519745;10498.580056797642;18505.7224141638;18985.525361865788;19076.52308692266;19147.80852191488;19212.231510739413;7106.538075025107;7150.678219480136;7300.744473943387;7319.268073192638;7404.370628496638;774.2799196746967;787.2442455243031;803.0808012873248;806.9596520173968;814.4258297899967;27602.447951041268;27877.16368508948;28145.296367590563;28236.576451353245;28451.430971380458;869.626060793852;936.8360536906014;940.6754669638964;943.990560094404;947.7630592351912;14008.229547374993;14458.783118253235;14802.925985370004;15190.240487975778;15219.086854788748;360350;364030;364418;364593;364743;49646.75176624094;50096;50160.74919625378;50182.24726629104;50184;1183.973516381867;1219.762146381447;1229.8216758569956;1230.5877531027027;1234.635782444199;25886.093986710373;26616.733832661426;26686.733132668425;26828;27435.17694469169;41555;42066.57933420749;42080.78959605182;42114;42190;291258;294026;294324.05675943824;296036;296148;31268;35075.82462087673;37038.62961370352;37057;37834.0815693958;39332.80333598314;39426.60573394229;39813;40441;40449.595504044584;34102.317953641286;34763.478547822306;34815;35206.82396588001;35266.647333526336;34660.82669586636;34735;34920.82539587286;35386.29227415489;35427.04021437052;33059.33881322408;33301.50047749334;33705.15737106592;33974.49038264478;34282.828585856914;29554.55668165022;30974.845125774227;31307.530387043756;31584.210394740316;31776.205594860312;24071.879640601685;24163.51672966566;24296.514069718152;24589.877050614632;24704.876475617508 10:30:32 INFO - PROCESS | 6616 | |2;jquery.html;356.7288860466048;364.6736171126843;365.35697172975557;374.1506779610284;398.906257029598;12874.613761587156;15906.761398579016;16204.83795162048;16270.674586508263;17239.138043097828;14384;14544.854551454482;14550.199739014377;14728.484503042395;14968.550943471686;4517.322401639747;4969.4036715594175;5211.348581427322;5213.165893457048;6141.447269745722;6159.969200153993;6171.969140154315;6191.659458729768;6198.411150940653;6202.937970620281;5958.940410595882;6048.0625503047095;6097.847553811146;6103.328633850273;6148.108524264001;5933.643981361132;6101.450869421751;6132.386761323876;6134.69326533676;6146.6004709693725;5304.416514183436;5346.572274218053;5347.572194224453;5351.732413379315;5352.330958630171;19919;19973.10121044542;19976.900115499473;20130;20152.093155808023;2261.739899911515;2318.107528601484;2328.6041372966465;2331.966375835248;2363.515479326734;12745.36273186337;12972.740545189137;13054.804177937338;13085.345732713422;13622.86377136226;1754.1229385307347;1761.8502427293695;1774.1572752942377;1774.9290028398848;1791.9552011199694;66636;67566.3243367565;68736.6563167186;69849.30150698479;70173;8109.878351824609;8651.52416617092;8697.347698922606;9605.759856003517;9624.855627165598;11847.229930054518;11892.04863610909;11897.34564598938;11899.821502677467;11901.88098119025;6535.771248006297;6790.083338749206;6953.478489113337;7008.824779380557;7108.07595012645;6486.4486518646445;6520.0871877937125;6587.670616469158;6664.833379165462;6666.500012499082;4241.130568233566;4314.115606300701;4352.455943007124;4413.249747542925;4439.467263928349;1820.4720631016858;1834;1837.3110083718605;1844.9907750461161;1897.8386837118999;801.8893832042663;807.5477732469718;809.1908091907974;811.8376324735077;820.6922404098464;15341.693166136838;15850.128242946517;15909.681806363807;15993.840061599236;16848.46182301706;806.9515829050275;821.5029906906236;826.6569373709843;826.6982551368827;842.3808500751942;8992.730218093468;9007.054259302678;9279.907200927904;9318.86021709675;9326.533673316442;324528;327251;330690.27752086165;331812;332252;70247.64876175586;71837;71963.28036719671;73138;73877.26122738703;885.4908427654125;893.678275820691;917.5733284022962;919.2599957034652;919.70569417787;13800.654983625489;13885.236312002937;13900.51348202808;13955.790663139858;14592.92703536518;33231;35381.93854184379;35754.463683044254;35927.10182245465;36858.078548036516;47758.76120619514;48453.515464846314;48618;48633.270500943225;50345.244821328444;6855.931440685728;7235.2764723527225;7365.558066516026;7785.299323060952;7856.5678887661725;11183.832242516532;11861.5848445304;11900;11952.282863027898;12124.939375303067;9352.53237338144;9517.191038761506;9640.325177237806;9770.462624555717;9918.752031199278;9513.33406661527;10501.474926253504;10508.159347252355;10560.260781745512;10643.254972151875;10382.740431488972;10430.113440357654;10559.947200263949;10954.616588419367;11016.283941543774;7659.119201291923;7828.491148075357;7972.920270797218;8010.3992200584025;8131.67473301085;10022.599096036369;10535.841962370723;10750.838737418788;10779.568817247537;10936.398498082683 10:30:32 INFO - PROCESS | 6616 | |3;mootools.html;1279.379500942043;1295.792673172292;1301.9804702929464;1302.4920281090376;1318.3342412081897;7016.789496315112;7458.627068646573;7459.962700186505;7463.365613922816;7465.999999999994;6299.685015749205;6573.967130164342;6959.478039147059;7165.534240274378;7170.533915295502;2131.1049359269105;2168.5554461335387;2169.566086782642;2171.0230396321717;2173.9021744021557;2075.2425364741835;2174.7607763146043;2189.868607883524;2192.78072192781;2195.034184958613;1873.5784448499114;1945.873518221311;2091.6757902525055;2174.8151407130413;2175.8368122390807;2147.366526874576;2163.1888041984257;2184.0390228299498;2187.332863476639;2188.413400284797;2147.699322094908;2191.3425972208274;2192.6272533669317;2194.2758889566403;2195.4182141732454;7472.626368681598;7511.474196806225;7512.511686740399;7536.8115797104965;7550.5469671819865;4407.515173330931;4545.931811022837;4546.090781843644;4552.840650577247;4553.68124231304;2723.6595425571804;3018.2303512604144;3024.123004328742;3025.3495498467937;3026.485497465414;1049.255028929461;1096.950637221327;1133.8639363276377;1144.988550114505;1156.4044517073714;9259.537023148816;9604.135627793463;9640.614375424975;9792.90207097927;10014.24893133018;3892.844286228519;3935.665468435215;4047.1500984792933;4057.269691455507;4063.2482990646827;7802.414818888606;7808.960955195302;7815.765527034198;7816.140224575292;7822.882656760153;4503.054358584734;4507.233770259064;4508.932366014513;4510.2783554631105;4517.457905051415;4145.709800313949;4243.490781106287;4243.851465198703;4244.533101358848;4244.639205667491;3180.522921561738;3205.102571280045;3253.072874230833;3263.575735154415;3285.6221534523834;992.4739887859301;998.6854140945579;1005.5625802775816;1008.863803386548;1021.8467229915424;565.7567246084141;593.8990371636833;637.7735903754193;656.8325077105308;658.1608449227178;4822;4856.587190088811;4919.77860996256;4972.254161875675;4985.9501404985485;797.9361651067901;834.0275249036107;834.1408349400007;834.2116699718829;840.1514470384956;3119.7036281553214;3240.951385729216;3276.3938671345877;3292.2592416706048;3357.4220116545184;8400.915990840136;8598.656053757779;8689.956550217208;8695.913040869636;8744.95627521871;8562.315014798802;8644.308455323559;8655.956720216485;8672.306215502746;8674.349423793241;6431.456450451858;6549.279579246279;6560.671966401661;6566.376194261633;6566.8358291043105;4598.540145985508;4665.603423708887;4766.45185803637;4776.829676729085;4816.253480710496;7912.564808935564;7936.722214722457;7937.047554293522;7940.960295198718;7984.8802267967185;8024.799380015313;8066.556339401389;8168.346532277523;8178.1004089548915;8225.753227403187;5948.4943779779205;6022.488088512526;6058.636481811105;6061.515078793774;6066.696665166635;6834.384905358542;6846.246912839484;6902.275261097512;6907.309269073053;6918.896216556655;6250.874982500415;6359.904601431075;6390.968045159931;6391.2330520337855;6476.190476190476;6180.258368995749;6246.562740608114;6255.46828519581;6273.843153921189;6289.40250676185;5362.785488580413;5536.75084621201;5595.468430499096;5596.244506991667;5606.523445507178;3955.4660120884464;3980.7412518186225;4011.6991225657607;4109.547949725587;4176.102138040214;4011.4183443401125;4044;4064.30906745848;4228.471441069866;4348.913021739484 10:30:32 INFO - PROCESS | 6616 | |4;prototype.html;354.6914184659346;355.5608823103467;358.59836982579515;360.4881068882186;367.4139747103369;4814.759262036901;4828.251620998742;4835.1868049481445;4844.32179494871;4855.878603034926;4747.311639812234;4756.548127927841;4773.737444440554;4782.7608619569055;4795.424549054118;3678.2275722098393;3762.3039737648487;3770.340190466671;3791.867284645038;3924.764514129154;3770.7360484766077;3772.8113594320175;3785.3943369060958;3786.678132358753;3797.183605524812;3733.365327894251;3741.6632503074716;3754.9812250938708;3758.4778164843146;3769.9623003769884;3671.9816400918094;3741.195642936768;3747.5128233329765;3757.2297678975883;3768.792716400597;3245.140037889961;3291.078498020558;3303.306305675811;3305.9173520661934;3310.1228174533767;4640.862988567788;4656.930146047812;4674.135284972291;4678.438587369504;4686.882827929295;4224.619784219435;4238.618524332794;4240.681948853849;4269.060806622538;4269.530351661315;4792.400949881265;4986.252062190701;5016.247562865563;5016.372953380827;5074.873128171788;3589.353916295066;3596.1549035976523;3604.873829415984;3608.152084260223;3618.4210526315665;4999.999999999964;5225.869353266161;5246.685198888079;5265.262863199156;5266.83989606237;3505.702015328726;3547.2018795770746;3639.3267245559655;3659;3670.6696397324367;3686.576043754949;3699.130704284491;3699.72252081095;3709.703223742094;3719.5536535615897;4224.809883555247;4225.598568136023;4235.2800023996;4246.575342465729;4278.422412974209;4877.609791216694;4901.5588597026435;4956.677815941952;5023.497650234947;5028.7737051832755;1223.1499108119772;1228.588422878328;1229.6596709586593;1234.6357824441811;1239.702471406856;830.9252167304971;853.9453774588378;857.9018855864504;868.079835374505;873.1966590736538;4247.193033323659;4254.404383386328;4254.659627229814;4274.444322238089;4295.52749197588;4977.601791856643;4991.425986011583;4992;4998.500149985045;5001.64988450805;4238.21593005295;4268.893277668083;4276.636485898734;4281.7002809803635;4282.593153650399;2535.2267558394865;2648.8411944265363;2670.2656769388186;2687.6640419947507;2697.352635367537;4742.359781429463;4745.240761478147;4751.572358487752;4759.452662943737;4784.138855006063;5009.7244651544515;5168.8449346519665;5185.637005409585;5215.817446389434;5223.242629818731;4667.836625718083;4713.2192006716205;4715.528447155257;4725.905481890343;4731.4795372509;4987.276844857548;5009.273655319886;5035.521625445577;5037.118504261666;5123.410807757006;4715.174844402147;4978;4992.350994370709;5016.749162541932;5032.647714659939;4653.9301910470695;4656.41794775653;4686.765661716969;4727.739974301309;4727.834525791581;3490.441529355291;3563.893083207508;3609.3864043112208;3617.6201498842242;3661.7436779425184;5131.204663277198;5132.743362831919;5207;5226.085435048775;5230.895382092261;4865.610751139971;4995.875103122451;5005.699658020385;5029.170186919117;5032.798688052437;4853.781579828846;4881.975590122027;4884.413870335582;4899.314096026488;4968.22992436173;4844.903101938012;4874.221693634594;4877.170880950318;4900.068986892479;4909.239067944474;4817.860754260143;4996.650234483552;5021.874453138555;5025.522575355335;5035.345405097314;4821.710697358169;4838.903221935471;4858.1012512685265;4867.245576935581;4868.586170175575 10:30:32 INFO - PROCESS | 6616 | |5;yui.html;676;689.6965335252492;736.5654263984248;740.5001623903865;763.6830715253168;12298.877011229884;13184.670383240411;13200.075994680376;13227.14023588466;13236.139650922696;12735.108542402035;12754.936225318861;12788.616341509747;12808.93595532021;12889.93555032226;665.4609766089459;689.2590465249851;787.4960025583631;792.801799550111;800.6717245929141;821.1829229916212;825.4345773145385;826.7933016745814;828.5981299069946;829.5064436660208;866.5883705240024;868.3660927522913;869.026690107081;869.0787764969122;869.4261787220436;696.9686364113602;725.021221351177;832.7626626411504;860.0668274921718;865.2991077227434;634.6382561939696;634.7524465458475;634.987300253997;635.5709895820303;636.1983900285627;12473.93763031188;12478.625641230687;12485.812712809315;12497;12511.499540018389;12832.743345133138;12868.29224392658;13068.738625227443;13176.954487198609;13225.801612975813;11027.44862756867;11085.612003579916;11159;11391.54433822646;11498.770024599462;4098.836046558164;4131.338985762263;4136.420901073852;4303.591158839905;4315.82736690532;56461.43538564644;57452;58349.124763128166;58392.41607583912;59247.40752592505;9399.436033837992;9473.66842160521;9535.52322383892;9900.108990190918;10197.13324367437;18384.26462941494;18606.348777792984;18707.71938420925;18733.34433294828;18739.531511712044;6611.933880661228;6759.324067593299;6885.621290828953;6947.51367404287;6950;3692.280005398975;3709.387950988111;3781.716371272111;3786.072412258959;3804.5814960354337;2952.1733914503975;3005.413944280888;3031.651360093573;3033.8331391773663;3034.8482575871117;476.7568540044602;479.77930152129716;485.5702703107775;489.87753061734566;492.5025724018767;467.7941705649503;468.1129260052182;480.6467246573765;523.8742701751552;586.9442402971711;7696.268854458817;8518.446300990416;8576.356773242032;8655.610497527627;8792;449.0928324783918;466.7013111608573;471.45782350296747;524.7323864828965;567.2172402085179;3981.880543583697;4070.430139780433;4175.91648167035;4197.76912269822;4206.20082184384;64647;66552;67326.66336668383;68669;69030.65484672545;45122.77438612786;51953.74023129936;57569.71215143897;57787.711061444425;58473.415265847645;41247.175056498105;42844.143117137486;43010;46825.531744682114;51779.741101294254;8045.396595255263;8691.608877600396;8693.826123477369;8698.739037828622;8892.68875589377;31001.8449907749;32092.83953580217;32153.517697234092;33866.153346166546;34712.30575388529;41892;43034.78482607567;43283;44495.11009779722;44789.552104478535;11166.1067114629;11198.160137989846;11245.718857028312;11469.483873225892;11658.00906922921;12297.50809967627;12367.93816030914;12870.742585148431;13145.539906103622;13382.063255572406;11052.557897684324;11556.477383244308;11715.88284117182;11961.043116550481;12202.938985305373;10455.028991804493;10931.398773067558;10971.341719496855;11136.33182009082;11279.943600281946;11578.76842463163;11618.199179127248;11621.070314375;11704.180707350744;11975.580854670045;9381.484018379055;9505.714828555154;9697.854532182164;10018.849717254101;10333.379997200193;9374.67188648394;9569;9616.567254473426;9651.613935442503;9684.612615495302 10:30:32 INFO - PROCESS | 6616 | __end_tp_report 10:30:32 INFO - PROCESS | 6616 | __start_cc_report 10:30:32 INFO - PROCESS | 6616 | _x_x_mozilla_cycle_collect,1100 10:30:32 INFO - PROCESS | 6616 | __end_cc_report 10:30:32 INFO - PROCESS | 6616 | __startTimestamp1482863432900__endTimestamp 10:30:32 INFO - PROCESS | 6616 | 10:30:32 INFO - PROCESS | 6616 | ------- Summary: start ------- 10:30:32 INFO - PROCESS | 6616 | Number of tests: 6 10:30:32 INFO - PROCESS | 6616 | 10:30:32 INFO - PROCESS | 6616 | [#0] dojo.html Cycles:165 Average:4767.02 Median:4900.71 stddev:499.36 (10.2%) stddev-sans-first:471.05 10:30:32 INFO - PROCESS | 6616 | Values: 2599.4 2612.4 2642.4 2642.6 2642.9 4784.6 4828.0 4830.5 4840.2 4886.0 4827.7 4874.4 4893.7 4916.9 4940.3 4465.5 4469.3 4484.9 4498.2 4583.2 4479.8 4492.8 4501.0 4507.8 4510.0 4326.7 4347.3 4500.1 4534.6 4543.6 4366.8 4393.3 4393.6 4394.4 4399.8 4367.8 4374.0 4376.0 4378.2 4379.7 4783.1 4846.5 4854.9 4855.3 4871.7 5141.2 5152.4 5160.1 5178.9 5209.0 5134.6 5143.8 5153.3 5162.0 5213.7 4755.7 4794.6 4808.1 4849.8 4849.8 5273.5 5323.3 5330.2 5338.2 5363.4 5108.4 5117.9 5118.7 5120.8 5125.8 5014.5 5051.6 5057.5 5099.9 5179.3 5070.6 5075.3 5082.5 5108.0 5125.4 5077.8 5082.3 5088.3 5095.6 5131.6 4337.3 4378.8 4438.7 4456.8 4481.3 3852.9 3868.2 3898.2 3908.0 3919.4 4421.8 4427.4 4430.4 4430.5 4455.2 5100.4 5146.3 5216.8 5218.0 5230.4 4379.3 4404.8 4406.8 4409.8 4465.0 4708.4 4709.6 4711.0 4720.2 4752.4 4864.9 4865.9 4870.9 4871.0 4915.9 5007.8 5020.7 5153.8 5197.6 5199.7 4856.1 4857.0 4857.3 4890.0 4900.7 4986.2 5032.0 5036.8 5095.8 5119.7 5119.0 5162.6 5172.1 5173.4 5179.4 4914.4 4991.2 5002.3 5009.6 5009.6 4977.6 4990.6 4991.6 4999.7 5034.4 4875.4 4941.2 4952.1 4970.2 5004.3 5030.8 5038.4 5051.5 5055.2 5092.8 4914.9 4917.8 4919.8 4925.0 4949.3 10:30:32 INFO - PROCESS | 6616 | 10:30:32 INFO - PROCESS | 6616 | [#1] ext.html Cycles:180 Average:49734.07 Median:35326.47 stddev:90238.01 (255.4%) stddev-sans-first:90415.49 10:30:32 INFO - PROCESS | 6616 | Values: 519.2 534.4 536.4 544.9 552.7 28806.7 38119.8 39511.4 39681.2 39806.8 40283.0 40394.4 40435.2 40696.6 40920.0 7487.2 7597.5 7693.4 7694.1 7701.3 7525.4 9995.3 10262.6 10266.2 10546.2 10209.2 10262.7 10275.3 10283.2 10285.2 10144.8 10200.1 10211.4 10213.1 10216.3 10034.7 10096.1 10108.1 10109.8 10113.2 37398.3 37699.0 37764.6 37820.4 37855.6 3633.3 3638.3 3738.3 3755.2 3764.3 26676.9 27151.9 27173.5 30428.0 31768.0 3366.9 3433.5 3506.6 3522.5 3562.6 358330.0 367913.0 368178.0 368178.0 369472.0 56764.7 57390.7 57458.0 57467.0 57478.4 60350.4 60433.7 60499.4 60535.0 60597.4 13304.6 13412.6 13436.5 13593.1 13600.7 8906.1 9091.4 10330.0 10431.7 10498.6 18505.7 18985.5 19076.5 19147.8 19212.2 7106.5 7150.7 7300.7 7319.3 7404.4 774.3 787.2 803.1 807.0 814.4 27602.4 27877.2 28145.3 28236.6 28451.4 869.6 936.8 940.7 944.0 947.8 14008.2 14458.8 14802.9 15190.2 15219.1 360350.0 364030.0 364418.0 364593.0 364743.0 49646.8 50096.0 50160.7 50182.2 50184.0 1184.0 1219.8 1229.8 1230.6 1234.6 25886.1 26616.7 26686.7 26828.0 27435.2 41555.0 42066.6 42080.8 42114.0 42190.0 291258.0 294026.0 294324.1 296036.0 296148.0 31268.0 35075.8 37038.6 37057.0 37834.1 39332.8 39426.6 39813.0 40441.0 40449.6 34102.3 34763.5 34815.0 35206.8 35266.6 34660.8 34735.0 34920.8 35386.3 35427.0 33059.3 33301.5 33705.2 33974.5 34282.8 29554.6 30974.8 31307.5 31584.2 31776.2 24071.9 24163.5 24296.5 24589.9 24704.9 10:30:32 INFO - PROCESS | 6616 | 10:30:32 INFO - PROCESS | 6616 | [#2] jquery.html Cycles:180 Average:22271.15 Median:48106.14 stddev:54650.76 (113.6%) stddev-sans-first:54779.30 10:30:32 INFO - PROCESS | 6616 | Values: 356.7 364.7 365.4 374.2 398.9 12874.6 15906.8 16204.8 16270.7 17239.1 14384.0 14544.9 14550.2 14728.5 14968.6 4517.3 4969.4 5211.3 5213.2 6141.4 6160.0 6172.0 6191.7 6198.4 6202.9 5958.9 6048.1 6097.8 6103.3 6148.1 5933.6 6101.5 6132.4 6134.7 6146.6 5304.4 5346.6 5347.6 5351.7 5352.3 19919.0 19973.1 19976.9 20130.0 20152.1 2261.7 2318.1 2328.6 2332.0 2363.5 12745.4 12972.7 13054.8 13085.3 13622.9 1754.1 1761.9 1774.2 1774.9 1792.0 66636.0 67566.3 68736.7 69849.3 70173.0 8109.9 8651.5 8697.3 9605.8 9624.9 11847.2 11892.0 11897.3 11899.8 11901.9 6535.8 6790.1 6953.5 7008.8 7108.1 6486.4 6520.1 6587.7 6664.8 6666.5 4241.1 4314.1 4352.5 4413.2 4439.5 1820.5 1834.0 1837.3 1845.0 1897.8 801.9 807.5 809.2 811.8 820.7 15341.7 15850.1 15909.7 15993.8 16848.5 807.0 821.5 826.7 826.7 842.4 8992.7 9007.1 9279.9 9318.9 9326.5 324528.0 327251.0 330690.3 331812.0 332252.0 70247.6 71837.0 71963.3 73138.0 73877.3 885.5 893.7 917.6 919.3 919.7 13800.7 13885.2 13900.5 13955.8 14592.9 33231.0 35381.9 35754.5 35927.1 36858.1 47758.8 48453.5 48618.0 48633.3 50345.2 6855.9 7235.3 7365.6 7785.3 7856.6 11183.8 11861.6 11900.0 11952.3 12124.9 9352.5 9517.2 9640.3 9770.5 9918.8 9513.3 10501.5 10508.2 10560.3 10643.3 10382.7 10430.1 10559.9 10954.6 11016.3 7659.1 7828.5 7972.9 8010.4 8131.7 10022.6 10535.8 10750.8 10779.6 10936.4 10:30:32 INFO - PROCESS | 6616 | 10:30:32 INFO - PROCESS | 6616 | [#3] mootools.html Cycles:180 Average:4730.53 Median:4888.18 stddev:2558.66 (52.3%) stddev-sans-first:2552.70 10:30:32 INFO - PROCESS | 6616 | Values: 1279.4 1295.8 1302.0 1302.5 1318.3 7016.8 7458.6 7460.0 7463.4 7466.0 6299.7 6574.0 6959.5 7165.5 7170.5 2131.1 2168.6 2169.6 2171.0 2173.9 2075.2 2174.8 2189.9 2192.8 2195.0 1873.6 1945.9 2091.7 2174.8 2175.8 2147.4 2163.2 2184.0 2187.3 2188.4 2147.7 2191.3 2192.6 2194.3 2195.4 7472.6 7511.5 7512.5 7536.8 7550.5 4407.5 4545.9 4546.1 4552.8 4553.7 2723.7 3018.2 3024.1 3025.3 3026.5 1049.3 1097.0 1133.9 1145.0 1156.4 9259.5 9604.1 9640.6 9792.9 10014.2 3892.8 3935.7 4047.2 4057.3 4063.2 7802.4 7809.0 7815.8 7816.1 7822.9 4503.1 4507.2 4508.9 4510.3 4517.5 4145.7 4243.5 4243.9 4244.5 4244.6 3180.5 3205.1 3253.1 3263.6 3285.6 992.5 998.7 1005.6 1008.9 1021.8 565.8 593.9 637.8 656.8 658.2 4822.0 4856.6 4919.8 4972.3 4986.0 797.9 834.0 834.1 834.2 840.2 3119.7 3241.0 3276.4 3292.3 3357.4 8400.9 8598.7 8690.0 8695.9 8745.0 8562.3 8644.3 8656.0 8672.3 8674.3 6431.5 6549.3 6560.7 6566.4 6566.8 4598.5 4665.6 4766.5 4776.8 4816.3 7912.6 7936.7 7937.0 7941.0 7984.9 8024.8 8066.6 8168.3 8178.1 8225.8 5948.5 6022.5 6058.6 6061.5 6066.7 6834.4 6846.2 6902.3 6907.3 6918.9 6250.9 6359.9 6391.0 6391.2 6476.2 6180.3 6246.6 6255.5 6273.8 6289.4 5362.8 5536.8 5595.5 5596.2 5606.5 3955.5 3980.7 4011.7 4109.5 4176.1 4011.4 4044.0 4064.3 4228.5 4348.9 10:30:32 INFO - PROCESS | 6616 | 10:30:32 INFO - PROCESS | 6616 | [#4] prototype.html Cycles:180 Average:4123.75 Median:4715.35 stddev:1185.10 (25.1%) stddev-sans-first:1154.17 10:30:32 INFO - PROCESS | 6616 | Values: 354.7 355.6 358.6 360.5 367.4 4814.8 4828.3 4835.2 4844.3 4855.9 4747.3 4756.5 4773.7 4782.8 4795.4 3678.2 3762.3 3770.3 3791.9 3924.8 3770.7 3772.8 3785.4 3786.7 3797.2 3733.4 3741.7 3755.0 3758.5 3770.0 3672.0 3741.2 3747.5 3757.2 3768.8 3245.1 3291.1 3303.3 3305.9 3310.1 4640.9 4656.9 4674.1 4678.4 4686.9 4224.6 4238.6 4240.7 4269.1 4269.5 4792.4 4986.3 5016.2 5016.4 5074.9 3589.4 3596.2 3604.9 3608.2 3618.4 5000.0 5225.9 5246.7 5265.3 5266.8 3505.7 3547.2 3639.3 3659.0 3670.7 3686.6 3699.1 3699.7 3709.7 3719.6 4224.8 4225.6 4235.3 4246.6 4278.4 4877.6 4901.6 4956.7 5023.5 5028.8 1223.1 1228.6 1229.7 1234.6 1239.7 830.9 853.9 857.9 868.1 873.2 4247.2 4254.4 4254.7 4274.4 4295.5 4977.6 4991.4 4992.0 4998.5 5001.6 4238.2 4268.9 4276.6 4281.7 4282.6 2535.2 2648.8 2670.3 2687.7 2697.4 4742.4 4745.2 4751.6 4759.5 4784.1 5009.7 5168.8 5185.6 5215.8 5223.2 4667.8 4713.2 4715.5 4725.9 4731.5 4987.3 5009.3 5035.5 5037.1 5123.4 4715.2 4978.0 4992.4 5016.7 5032.6 4653.9 4656.4 4686.8 4727.7 4727.8 3490.4 3563.9 3609.4 3617.6 3661.7 5131.2 5132.7 5207.0 5226.1 5230.9 4865.6 4995.9 5005.7 5029.2 5032.8 4853.8 4882.0 4884.4 4899.3 4968.2 4844.9 4874.2 4877.2 4900.1 4909.2 4817.9 4996.7 5021.9 5025.5 5035.3 4821.7 4838.9 4858.1 4867.2 4868.6 10:30:32 INFO - PROCESS | 6616 | 10:30:32 INFO - PROCESS | 6616 | [#5] yui.html Cycles:180 Average:14296.17 Median:22619.32 stddev:17423.51 (77.0%) stddev-sans-first:17442.37 10:30:32 INFO - PROCESS | 6616 | Values: 676.0 689.7 736.6 740.5 763.7 12298.9 13184.7 13200.1 13227.1 13236.1 12735.1 12754.9 12788.6 12808.9 12889.9 665.5 689.3 787.5 792.8 800.7 821.2 825.4 826.8 828.6 829.5 866.6 868.4 869.0 869.1 869.4 697.0 725.0 832.8 860.1 865.3 634.6 634.8 635.0 635.6 636.2 12473.9 12478.6 12485.8 12497.0 12511.5 12832.7 12868.3 13068.7 13177.0 13225.8 11027.4 11085.6 11159.0 11391.5 11498.8 4098.8 4131.3 4136.4 4303.6 4315.8 56461.4 57452.0 58349.1 58392.4 59247.4 9399.4 9473.7 9535.5 9900.1 10197.1 18384.3 18606.3 18707.7 18733.3 18739.5 6611.9 6759.3 6885.6 6947.5 6950.0 3692.3 3709.4 3781.7 3786.1 3804.6 2952.2 3005.4 3031.7 3033.8 3034.8 476.8 479.8 485.6 489.9 492.5 467.8 468.1 480.6 523.9 586.9 7696.3 8518.4 8576.4 8655.6 8792.0 449.1 466.7 471.5 524.7 567.2 3981.9 4070.4 4175.9 4197.8 4206.2 64647.0 66552.0 67326.7 68669.0 69030.7 45122.8 51953.7 57569.7 57787.7 58473.4 41247.2 42844.1 43010.0 46825.5 51779.7 8045.4 8691.6 8693.8 8698.7 8892.7 31001.8 32092.8 32153.5 33866.2 34712.3 41892.0 43034.8 43283.0 44495.1 44789.6 11166.1 11198.2 11245.7 11469.5 11658.0 12297.5 12367.9 12870.7 13145.5 13382.1 11052.6 11556.5 11715.9 11961.0 12202.9 10455.0 10931.4 10971.3 11136.3 11279.9 11578.8 11618.2 11621.1 11704.2 11975.6 9381.5 9505.7 9697.9 10018.8 10333.4 9374.7 9569.0 9616.6 9651.6 9684.6 10:30:32 INFO - PROCESS | 6616 | -------- Summary: end -------- 10:30:32 INFO - PROCESS | 6616 | 10:30:33 INFO - TEST-INFO | 6616: exit 0 10:30:33 INFO - TEST-OK | dromaeo_css | took 1120743ms 10:30:33 INFO - TEST-START | kraken 10:30:33 INFO - operating with platform_type : linux_ 10:30:33 INFO - Initialising browser for kraken test... 10:30:33 INFO - TEST-INFO | started process 6921 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxlb02h/profile http://localhost:60493/getInfo.html) 10:30:34 INFO - PROCESS | 6921 | 1482863434195 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 10:30:37 INFO - PROCESS | 6921 | __metrics Screen width/height:1600/1200 10:30:37 INFO - PROCESS | 6921 | colorDepth:24 10:30:37 INFO - PROCESS | 6921 | Browser inner width/height: 1024/697 10:30:37 INFO - PROCESS | 6921 | __metrics 10:30:38 INFO - PROCESS | 6921 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:30:38 INFO - PROCESS | 6921 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:30:39 INFO - TEST-INFO | 6921: exit 0 10:30:39 INFO - Browser initialized. 10:30:39 INFO - Running cycle 1/1 for kraken test... 10:30:39 INFO - TEST-INFO | started process 7005 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxlb02h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:30:51 INFO - PROCESS | 7005 | RSS: Main: 151678976 10:30:51 INFO - PROCESS | 7005 | 10:30:53 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60493/tests/kraken/driver.html?testName=audio-beat-detection) 10:30:54 INFO - PROCESS | 7005 | RSS: Main: 205545472 10:30:54 INFO - PROCESS | 7005 | 10:30:57 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60493/tests/kraken/driver.html?testName=audio-dft) 10:30:58 INFO - PROCESS | 7005 | RSS: Main: 186081280 10:30:58 INFO - PROCESS | 7005 | 10:31:01 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60493/tests/kraken/driver.html?testName=audio-fft) 10:31:01 INFO - PROCESS | 7005 | RSS: Main: 268894208 10:31:01 INFO - PROCESS | 7005 | 10:31:04 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60493/tests/kraken/driver.html?testName=audio-oscillator) 10:31:04 INFO - PROCESS | 7005 | RSS: Main: 179499008 10:31:04 INFO - PROCESS | 7005 | 10:31:06 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60493/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:31:07 INFO - PROCESS | 7005 | RSS: Main: 182345728 10:31:07 INFO - PROCESS | 7005 | 10:31:10 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60493/tests/kraken/driver.html?testName=imaging-darkroom) 10:31:11 INFO - PROCESS | 7005 | RSS: Main: 221622272 10:31:11 INFO - PROCESS | 7005 | 10:31:14 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60493/tests/kraken/driver.html?testName=imaging-desaturate) 10:31:15 INFO - PROCESS | 7005 | RSS: Main: 221290496 10:31:15 INFO - PROCESS | 7005 | 10:31:18 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60493/tests/kraken/driver.html?testName=json-parse-financial) 10:31:18 INFO - PROCESS | 7005 | RSS: Main: 219398144 10:31:18 INFO - PROCESS | 7005 | 10:31:20 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60493/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:31:21 INFO - PROCESS | 7005 | RSS: Main: 182497280 10:31:21 INFO - PROCESS | 7005 | 10:31:23 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60493/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:31:23 INFO - PROCESS | 7005 | RSS: Main: 189456384 10:31:23 INFO - PROCESS | 7005 | 10:31:25 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60493/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:31:26 INFO - PROCESS | 7005 | RSS: Main: 197058560 10:31:26 INFO - PROCESS | 7005 | 10:31:29 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:31:30 INFO - PROCESS | 7005 | RSS: Main: 207163392 10:31:30 INFO - PROCESS | 7005 | 10:31:33 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:31:33 INFO - PROCESS | 7005 | RSS: Main: 203313152 10:31:33 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | Cycle 1(1): loaded http://localhost:60493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60493/tests/kraken/driver.html?testName=ai-astar) 10:31:35 INFO - PROCESS | 7005 | RSS: Main: 255401984 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | __start_tp_report 10:31:35 INFO - PROCESS | 7005 | _x_x_mozilla_page_load 10:31:35 INFO - PROCESS | 7005 | _x_x_mozilla_page_load_details 10:31:35 INFO - PROCESS | 7005 | |i|pagename|runs| 10:31:35 INFO - PROCESS | 7005 | |0;ai-astar;108;117;115;118;114;115;116;115;116;116 10:31:35 INFO - PROCESS | 7005 | |1;audio-beat-detection;155;128;128;164;132;135;124;124;153;131 10:31:35 INFO - PROCESS | 7005 | |2;audio-dft;161;164;160;163;162;162;161;161;162;162 10:31:35 INFO - PROCESS | 7005 | |3;audio-fft;77;77;76;107;78;78;77;77;95;78 10:31:35 INFO - PROCESS | 7005 | |4;audio-oscillator;85;85;113;85;85;85;100;84;84;101 10:31:35 INFO - PROCESS | 7005 | |5;imaging-gaussian-blur;118;117;117;117;117;117;118;117;119;117 10:31:35 INFO - PROCESS | 7005 | |6;imaging-darkroom;149;150;149;149;149;149;149;149;149;148 10:31:35 INFO - PROCESS | 7005 | |7;imaging-desaturate;95;96;96;95;96;96;96;96;97;96 10:31:35 INFO - PROCESS | 7005 | |8;json-parse-financial;67;67;66;66;66;66;67;66;68;68 10:31:35 INFO - PROCESS | 7005 | |9;json-stringify-tinderbox;68;69;68;68;69;68;69;70;69;68 10:31:35 INFO - PROCESS | 7005 | |10;stanford-crypto-aes;86;85;85;85;86;93;84;84;85;83 10:31:35 INFO - PROCESS | 7005 | |11;stanford-crypto-ccm;133;144;144;149;153;131;131;133;198;137 10:31:35 INFO - PROCESS | 7005 | |12;stanford-crypto-pbkdf2;175;173;173;175;173;174;178;174;172;175 10:31:35 INFO - PROCESS | 7005 | |13;stanford-crypto-sha256-iterative;69;67;72;69;71;68;67;67;70;73 10:31:35 INFO - PROCESS | 7005 | __end_tp_report 10:31:35 INFO - PROCESS | 7005 | __start_cc_report 10:31:35 INFO - PROCESS | 7005 | _x_x_mozilla_cycle_collect,1799 10:31:35 INFO - PROCESS | 7005 | __end_cc_report 10:31:35 INFO - PROCESS | 7005 | __startTimestamp1482863495746__endTimestamp 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | ------- Summary: start ------- 10:31:35 INFO - PROCESS | 7005 | Number of tests: 14 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#0] ai-astar Cycles:10 Average:115.00 Median:116.00 stddev:2.71 (2.3%) stddev-sans-first:1.20 10:31:35 INFO - PROCESS | 7005 | Values: 108.0 117.0 115.0 118.0 114.0 115.0 116.0 115.0 116.0 116.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:133.50 stddev:14.42 (10.8%) stddev-sans-first:13.82 10:31:35 INFO - PROCESS | 7005 | Values: 155.0 128.0 128.0 164.0 132.0 135.0 124.0 124.0 153.0 131.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.14 (0.7%) stddev-sans-first:1.17 10:31:35 INFO - PROCESS | 7005 | Values: 161.0 164.0 160.0 163.0 162.0 162.0 161.0 161.0 162.0 162.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#3] audio-fft Cycles:10 Average:82.00 Median:77.50 stddev:10.42 (13.5%) stddev-sans-first:10.90 10:31:35 INFO - PROCESS | 7005 | Values: 77.0 77.0 76.0 107.0 78.0 78.0 77.0 77.0 95.0 78.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:85.00 stddev:10.23 (12.0%) stddev-sans-first:10.64 10:31:35 INFO - PROCESS | 7005 | Values: 85.0 85.0 113.0 85.0 85.0 85.0 100.0 84.0 84.0 101.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 10:31:35 INFO - PROCESS | 7005 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 119.0 117.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 10:31:35 INFO - PROCESS | 7005 | Values: 149.0 150.0 149.0 149.0 149.0 149.0 149.0 149.0 149.0 148.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 10:31:35 INFO - PROCESS | 7005 | Values: 95.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 97.0 96.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#8] json-parse-financial Cycles:10 Average:66.70 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 10:31:35 INFO - PROCESS | 7005 | Values: 67.0 67.0 66.0 66.0 66.0 66.0 67.0 66.0 68.0 68.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:69.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 10:31:35 INFO - PROCESS | 7005 | Values: 68.0 69.0 68.0 68.0 69.0 68.0 69.0 70.0 69.0 68.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:85.00 stddev:2.76 (3.2%) stddev-sans-first:2.92 10:31:35 INFO - PROCESS | 7005 | Values: 86.0 85.0 85.0 85.0 86.0 93.0 84.0 84.0 85.0 83.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:144.00 stddev:20.09 (14.0%) stddev-sans-first:20.81 10:31:35 INFO - PROCESS | 7005 | Values: 133.0 144.0 144.0 149.0 153.0 131.0 131.0 133.0 198.0 137.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.20 Median:174.50 stddev:1.69 (1.0%) stddev-sans-first:1.76 10:31:35 INFO - PROCESS | 7005 | Values: 175.0 173.0 173.0 175.0 173.0 174.0 178.0 174.0 172.0 175.0 10:31:35 INFO - PROCESS | 7005 | 10:31:35 INFO - PROCESS | 7005 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.50 stddev:2.16 (3.1%) stddev-sans-first:2.29 10:31:35 INFO - PROCESS | 7005 | Values: 69.0 67.0 72.0 69.0 71.0 68.0 67.0 67.0 70.0 73.0 10:31:35 INFO - PROCESS | 7005 | -------- Summary: end -------- 10:31:35 INFO - PROCESS | 7005 | 10:31:36 INFO - TEST-INFO | 7005: exit 0 10:31:36 INFO - TEST-OK | kraken | took 63191ms 10:31:36 INFO - SUITE-END | took 1183s 10:31:37 INFO - Completed test suite (00:19:44) 10:31:37 INFO - javascript benchmark 10:31:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2599.402137508373, 2612.4122072533696, 2642.3658322002725, 2642.550766369717, 2642.907498237562, 4784.5933095686905, 4827.975860120694, 4830.541098595637, 4840.1529732296885, 4886.022795440917, 4827.686200396981, 4874.366332376786, 4893.706377617345, 4916.852494425164, 4940.308356830046, 4465.508794032654, 4469.3296005599095, 4484.910301793962, 4498.235299999008, 4583.175028494887, 4479.8432054878085, 4492.842750503733, 4500.97749511252, 4507.752073635948, 4510.0, 4326.718763280377, 4347.326164444485, 4500.09998000398, 4534.569215924482, 4543.568361005699, 4366.759828209447, 4393.253146965024, 4393.582609652078, 4394.36281739146, 4399.78001099947, 4367.825286988516, 4374.0, 4375.9562404375865, 4378.16814805186, 4379.693421460499, 4783.115123702126, 4846.466888642281, 4854.927176092361, 4855.344528488644, 4871.658983871131, 5141.15170996785, 5152.407473140599, 5160.0969830279555, 5178.922316165261, 5208.973955130238, 5134.614903882187, 5143.819966301162, 5153.330067091291, 5162.045021671003, 5213.713245771481, 4755.667103302771, 4794.616430685606, 4808.134535783557, 4849.781759820817, 4849.80600775965, 5273.525382715574, 5323.254744335796, 5330.227117068081, 5338.199270109515, 5363.436839131835, 5108.386993560796, 5117.8720531987, 5118.718470484159, 5120.769565369567, 5125.769340379692, 5014.52362025607, 5051.646384753032, 5057.468965758542, 5099.898002040012, 5179.2749015137915, 5070.61970352225, 5075.3402057733, 5082.491750824888, 5108.003939231889, 5125.384953805567, 5077.847664570068, 5082.263071854561, 5088.287639730441, 5095.643304968617, 5131.61512886535, 4337.306031035019, 4378.759168245777, 4438.7114837535455, 4456.799444025027, 4481.282994720894, 3852.8651497198025, 3868.207017561393, 3898.161895192547, 3908.0, 3919.392494163409, 4421.778911054499, 4427.446569178853, 4430.357598148251, 4430.468343798765, 4455.1757924784015, 5100.438951715309, 5146.330976972969, 5216.843494695166, 5218.034663587248, 5230.398504172069, 4379.255526560492, 4404.823807047682, 4406.801693923781, 4409.757463339483, 4465.017696106851, 4708.411448568929, 4709.552592503706, 4711.0, 4720.197566413649, 4752.382190315237, 4864.927026094682, 4865.9026819463415, 4870.853874383774, 4870.952745159737, 4915.901681966412, 5007.7746501408255, 5020.748962551931, 5153.793848246029, 5197.636165468532, 5199.688018718889, 4856.053069651384, 4856.9757151215435, 4857.319975203403, 4889.951100489092, 4900.705957642553, 4986.227134794112, 5032.043911656773, 5036.84889453317, 5095.796168153232, 5119.692818430906, 5119.0, 5162.612804039637, 5172.094883395315, 5173.379194496684, 5179.352580927384, 4914.36113305268, 4991.15150424436, 5002.324686167321, 5009.599232061353, 5009.649324547247, 4977.576905962889, 4990.5508504234795, 4991.575716064175, 4999.725015124203, 5034.4462109167225, 4875.414950205998, 4941.234108713155, 4952.059108769322, 4970.204767237225, 5004.299398084344, 5030.798768049237, 5038.370203724535, 5051.494850514919, 5055.216441451581, 5092.8472145835685, 4914.9017019658695, 4917.803287868445, 4919.827806026772, 4924.975375123101, 4949.257611358181], "value": 4734.4283064195815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.1978393382224, 534.4281618668024, 536.4447796530591, 544.9128139497681, 552.6711606594075, 28806.711932880666, 38119.80940095299, 39511.407328890054, 39681.20637587246, 39806.80096599513, 40283.0, 40394.39408408876, 40435.19129617406, 40696.59303406972, 40920.0, 7487.1764105948305, 7597.506162099474, 7693.422993275499, 7694.115176754689, 7701.3453856422275, 7525.435592330569, 9995.300328976975, 10262.640807571737, 10266.178705703525, 10546.209034322419, 10209.183265338792, 10262.7434314142, 10275.332103413255, 10283.177345812317, 10285.228607854404, 10144.847827282596, 10200.13298869597, 10211.387316760982, 10213.08082272599, 10216.335938163995, 10034.69895903124, 10096.091351778376, 10108.140808031327, 10109.848352274721, 10113.24150688694, 37398.25203495942, 37699.0, 37764.62235377639, 37820.43269350962, 37855.62144378549, 3633.291508155911, 3638.2905333459985, 3738.3084129436143, 3755.2301778135416, 3764.270903594899, 26676.866615666993, 27151.913923443037, 27173.456530869273, 30428.0, 31768.0, 3366.9158271043175, 3433.5193072969805, 3506.6318036606044, 3522.5420695309704, 3562.590302115264, 358330.0, 367913.0, 368178.0, 368178.0, 369472.0, 56764.71617641968, 57390.7130464345, 57458.0, 57467.0, 57478.425215748146, 60350.396496035355, 60433.69783151056, 60499.39500604937, 60535.0, 60597.39402605918, 13304.600861973962, 13412.597622071353, 13436.462541498231, 13593.116447431083, 13600.659983500294, 8906.109389061043, 9091.36360454775, 10330.0, 10431.739206519745, 10498.580056797642, 18505.7224141638, 18985.525361865788, 19076.52308692266, 19147.80852191488, 19212.231510739413, 7106.538075025107, 7150.678219480136, 7300.744473943387, 7319.268073192638, 7404.370628496638, 774.2799196746967, 787.2442455243031, 803.0808012873248, 806.9596520173968, 814.4258297899967, 27602.447951041268, 27877.16368508948, 28145.296367590563, 28236.576451353245, 28451.430971380458, 869.626060793852, 936.8360536906014, 940.6754669638964, 943.990560094404, 947.7630592351912, 14008.229547374993, 14458.783118253235, 14802.925985370004, 15190.240487975778, 15219.086854788748, 360350.0, 364030.0, 364418.0, 364593.0, 364743.0, 49646.75176624094, 50096.0, 50160.74919625378, 50182.24726629104, 50184.0, 1183.973516381867, 1219.762146381447, 1229.8216758569956, 1230.5877531027027, 1234.635782444199, 25886.093986710373, 26616.733832661426, 26686.733132668425, 26828.0, 27435.17694469169, 41555.0, 42066.57933420749, 42080.78959605182, 42114.0, 42190.0, 291258.0, 294026.0, 294324.05675943824, 296036.0, 296148.0, 31268.0, 35075.82462087673, 37038.62961370352, 37057.0, 37834.0815693958, 39332.80333598314, 39426.60573394229, 39813.0, 40441.0, 40449.595504044584, 34102.317953641286, 34763.478547822306, 34815.0, 35206.82396588001, 35266.647333526336, 34660.82669586636, 34735.0, 34920.82539587286, 35386.29227415489, 35427.04021437052, 33059.33881322408, 33301.50047749334, 33705.15737106592, 33974.49038264478, 34282.828585856914, 29554.55668165022, 30974.845125774227, 31307.530387043756, 31584.210394740316, 31776.205594860312, 24071.879640601685, 24163.51672966566, 24296.514069718152, 24589.877050614632, 24704.876475617508], "value": 18139.13841668486, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.7288860466048, 364.6736171126843, 365.35697172975557, 374.1506779610284, 398.906257029598, 12874.613761587156, 15906.761398579016, 16204.83795162048, 16270.674586508263, 17239.138043097828, 14384.0, 14544.854551454482, 14550.199739014377, 14728.484503042395, 14968.550943471686, 4517.322401639747, 4969.4036715594175, 5211.348581427322, 5213.165893457048, 6141.447269745722, 6159.969200153993, 6171.969140154315, 6191.659458729768, 6198.411150940653, 6202.937970620281, 5958.940410595882, 6048.0625503047095, 6097.847553811146, 6103.328633850273, 6148.108524264001, 5933.643981361132, 6101.450869421751, 6132.386761323876, 6134.69326533676, 6146.6004709693725, 5304.416514183436, 5346.572274218053, 5347.572194224453, 5351.732413379315, 5352.330958630171, 19919.0, 19973.10121044542, 19976.900115499473, 20130.0, 20152.093155808023, 2261.739899911515, 2318.107528601484, 2328.6041372966465, 2331.966375835248, 2363.515479326734, 12745.36273186337, 12972.740545189137, 13054.804177937338, 13085.345732713422, 13622.86377136226, 1754.1229385307347, 1761.8502427293695, 1774.1572752942377, 1774.9290028398848, 1791.9552011199694, 66636.0, 67566.3243367565, 68736.6563167186, 69849.30150698479, 70173.0, 8109.878351824609, 8651.52416617092, 8697.347698922606, 9605.759856003517, 9624.855627165598, 11847.229930054518, 11892.04863610909, 11897.34564598938, 11899.821502677467, 11901.88098119025, 6535.771248006297, 6790.083338749206, 6953.478489113337, 7008.824779380557, 7108.07595012645, 6486.4486518646445, 6520.0871877937125, 6587.670616469158, 6664.833379165462, 6666.500012499082, 4241.130568233566, 4314.115606300701, 4352.455943007124, 4413.249747542925, 4439.467263928349, 1820.4720631016858, 1834.0, 1837.3110083718605, 1844.9907750461161, 1897.8386837118999, 801.8893832042663, 807.5477732469718, 809.1908091907974, 811.8376324735077, 820.6922404098464, 15341.693166136838, 15850.128242946517, 15909.681806363807, 15993.840061599236, 16848.46182301706, 806.9515829050275, 821.5029906906236, 826.6569373709843, 826.6982551368827, 842.3808500751942, 8992.730218093468, 9007.054259302678, 9279.907200927904, 9318.86021709675, 9326.533673316442, 324528.0, 327251.0, 330690.27752086165, 331812.0, 332252.0, 70247.64876175586, 71837.0, 71963.28036719671, 73138.0, 73877.26122738703, 885.4908427654125, 893.678275820691, 917.5733284022962, 919.2599957034652, 919.70569417787, 13800.654983625489, 13885.236312002937, 13900.51348202808, 13955.790663139858, 14592.92703536518, 33231.0, 35381.93854184379, 35754.463683044254, 35927.10182245465, 36858.078548036516, 47758.76120619514, 48453.515464846314, 48618.0, 48633.270500943225, 50345.244821328444, 6855.931440685728, 7235.2764723527225, 7365.558066516026, 7785.299323060952, 7856.5678887661725, 11183.832242516532, 11861.5848445304, 11900.0, 11952.282863027898, 12124.939375303067, 9352.53237338144, 9517.191038761506, 9640.325177237806, 9770.462624555717, 9918.752031199278, 9513.33406661527, 10501.474926253504, 10508.159347252355, 10560.260781745512, 10643.254972151875, 10382.740431488972, 10430.113440357654, 10559.947200263949, 10954.616588419367, 11016.283941543774, 7659.119201291923, 7828.491148075357, 7972.920270797218, 8010.3992200584025, 8131.67473301085, 10022.599096036369, 10535.841962370723, 10750.838737418788, 10779.568817247537, 10936.398498082683], "value": 8148.414491557797, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1279.379500942043, 1295.792673172292, 1301.9804702929464, 1302.4920281090376, 1318.3342412081897, 7016.789496315112, 7458.627068646573, 7459.962700186505, 7463.365613922816, 7465.999999999994, 6299.685015749205, 6573.967130164342, 6959.478039147059, 7165.534240274378, 7170.533915295502, 2131.1049359269105, 2168.5554461335387, 2169.566086782642, 2171.0230396321717, 2173.9021744021557, 2075.2425364741835, 2174.7607763146043, 2189.868607883524, 2192.78072192781, 2195.034184958613, 1873.5784448499114, 1945.873518221311, 2091.6757902525055, 2174.8151407130413, 2175.8368122390807, 2147.366526874576, 2163.1888041984257, 2184.0390228299498, 2187.332863476639, 2188.413400284797, 2147.699322094908, 2191.3425972208274, 2192.6272533669317, 2194.2758889566403, 2195.4182141732454, 7472.626368681598, 7511.474196806225, 7512.511686740399, 7536.8115797104965, 7550.5469671819865, 4407.515173330931, 4545.931811022837, 4546.090781843644, 4552.840650577247, 4553.68124231304, 2723.6595425571804, 3018.2303512604144, 3024.123004328742, 3025.3495498467937, 3026.485497465414, 1049.255028929461, 1096.950637221327, 1133.8639363276377, 1144.988550114505, 1156.4044517073714, 9259.537023148816, 9604.135627793463, 9640.614375424975, 9792.90207097927, 10014.24893133018, 3892.844286228519, 3935.665468435215, 4047.1500984792933, 4057.269691455507, 4063.2482990646827, 7802.414818888606, 7808.960955195302, 7815.765527034198, 7816.140224575292, 7822.882656760153, 4503.054358584734, 4507.233770259064, 4508.932366014513, 4510.2783554631105, 4517.457905051415, 4145.709800313949, 4243.490781106287, 4243.851465198703, 4244.533101358848, 4244.639205667491, 3180.522921561738, 3205.102571280045, 3253.072874230833, 3263.575735154415, 3285.6221534523834, 992.4739887859301, 998.6854140945579, 1005.5625802775816, 1008.863803386548, 1021.8467229915424, 565.7567246084141, 593.8990371636833, 637.7735903754193, 656.8325077105308, 658.1608449227178, 4822.0, 4856.587190088811, 4919.77860996256, 4972.254161875675, 4985.9501404985485, 797.9361651067901, 834.0275249036107, 834.1408349400007, 834.2116699718829, 840.1514470384956, 3119.7036281553214, 3240.951385729216, 3276.3938671345877, 3292.2592416706048, 3357.4220116545184, 8400.915990840136, 8598.656053757779, 8689.956550217208, 8695.913040869636, 8744.95627521871, 8562.315014798802, 8644.308455323559, 8655.956720216485, 8672.306215502746, 8674.349423793241, 6431.456450451858, 6549.279579246279, 6560.671966401661, 6566.376194261633, 6566.8358291043105, 4598.540145985508, 4665.603423708887, 4766.45185803637, 4776.829676729085, 4816.253480710496, 7912.564808935564, 7936.722214722457, 7937.047554293522, 7940.960295198718, 7984.8802267967185, 8024.799380015313, 8066.556339401389, 8168.346532277523, 8178.1004089548915, 8225.753227403187, 5948.4943779779205, 6022.488088512526, 6058.636481811105, 6061.515078793774, 6066.696665166635, 6834.384905358542, 6846.246912839484, 6902.275261097512, 6907.309269073053, 6918.896216556655, 6250.874982500415, 6359.904601431075, 6390.968045159931, 6391.2330520337855, 6476.190476190476, 6180.258368995749, 6246.562740608114, 6255.46828519581, 6273.843153921189, 6289.40250676185, 5362.785488580413, 5536.75084621201, 5595.468430499096, 5596.244506991667, 5606.523445507178, 3955.4660120884464, 3980.7412518186225, 4011.6991225657607, 4109.547949725587, 4176.102138040214, 4011.4183443401125, 4044.0, 4064.30906745848, 4228.471441069866, 4348.913021739484], "value": 3849.5101703582104, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.6914184659346, 355.5608823103467, 358.59836982579515, 360.4881068882186, 367.4139747103369, 4814.759262036901, 4828.251620998742, 4835.1868049481445, 4844.32179494871, 4855.878603034926, 4747.311639812234, 4756.548127927841, 4773.737444440554, 4782.7608619569055, 4795.424549054118, 3678.2275722098393, 3762.3039737648487, 3770.340190466671, 3791.867284645038, 3924.764514129154, 3770.7360484766077, 3772.8113594320175, 3785.3943369060958, 3786.678132358753, 3797.183605524812, 3733.365327894251, 3741.6632503074716, 3754.9812250938708, 3758.4778164843146, 3769.9623003769884, 3671.9816400918094, 3741.195642936768, 3747.5128233329765, 3757.2297678975883, 3768.792716400597, 3245.140037889961, 3291.078498020558, 3303.306305675811, 3305.9173520661934, 3310.1228174533767, 4640.862988567788, 4656.930146047812, 4674.135284972291, 4678.438587369504, 4686.882827929295, 4224.619784219435, 4238.618524332794, 4240.681948853849, 4269.060806622538, 4269.530351661315, 4792.400949881265, 4986.252062190701, 5016.247562865563, 5016.372953380827, 5074.873128171788, 3589.353916295066, 3596.1549035976523, 3604.873829415984, 3608.152084260223, 3618.4210526315665, 4999.999999999964, 5225.869353266161, 5246.685198888079, 5265.262863199156, 5266.83989606237, 3505.702015328726, 3547.2018795770746, 3639.3267245559655, 3659.0, 3670.6696397324367, 3686.576043754949, 3699.130704284491, 3699.72252081095, 3709.703223742094, 3719.5536535615897, 4224.809883555247, 4225.598568136023, 4235.2800023996, 4246.575342465729, 4278.422412974209, 4877.609791216694, 4901.5588597026435, 4956.677815941952, 5023.497650234947, 5028.7737051832755, 1223.1499108119772, 1228.588422878328, 1229.6596709586593, 1234.6357824441811, 1239.702471406856, 830.9252167304971, 853.9453774588378, 857.9018855864504, 868.079835374505, 873.1966590736538, 4247.193033323659, 4254.404383386328, 4254.659627229814, 4274.444322238089, 4295.52749197588, 4977.601791856643, 4991.425986011583, 4992.0, 4998.500149985045, 5001.64988450805, 4238.21593005295, 4268.893277668083, 4276.636485898734, 4281.7002809803635, 4282.593153650399, 2535.2267558394865, 2648.8411944265363, 2670.2656769388186, 2687.6640419947507, 2697.352635367537, 4742.359781429463, 4745.240761478147, 4751.572358487752, 4759.452662943737, 4784.138855006063, 5009.7244651544515, 5168.8449346519665, 5185.637005409585, 5215.817446389434, 5223.242629818731, 4667.836625718083, 4713.2192006716205, 4715.528447155257, 4725.905481890343, 4731.4795372509, 4987.276844857548, 5009.273655319886, 5035.521625445577, 5037.118504261666, 5123.410807757006, 4715.174844402147, 4978.0, 4992.350994370709, 5016.749162541932, 5032.647714659939, 4653.9301910470695, 4656.41794775653, 4686.765661716969, 4727.739974301309, 4727.834525791581, 3490.441529355291, 3563.893083207508, 3609.3864043112208, 3617.6201498842242, 3661.7436779425184, 5131.204663277198, 5132.743362831919, 5207.0, 5226.085435048775, 5230.895382092261, 4865.610751139971, 4995.875103122451, 5005.699658020385, 5029.170186919117, 5032.798688052437, 4853.781579828846, 4881.975590122027, 4884.413870335582, 4899.314096026488, 4968.22992436173, 4844.903101938012, 4874.221693634594, 4877.170880950318, 4900.068986892479, 4909.239067944474, 4817.860754260143, 4996.650234483552, 5021.874453138555, 5025.522575355335, 5035.345405097314, 4821.710697358169, 4838.903221935471, 4858.1012512685265, 4867.245576935581, 4868.586170175575], "value": 3763.148271413167, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.0, 689.6965335252492, 736.5654263984248, 740.5001623903865, 763.6830715253168, 12298.877011229884, 13184.670383240411, 13200.075994680376, 13227.14023588466, 13236.139650922696, 12735.108542402035, 12754.936225318861, 12788.616341509747, 12808.93595532021, 12889.93555032226, 665.4609766089459, 689.2590465249851, 787.4960025583631, 792.801799550111, 800.6717245929141, 821.1829229916212, 825.4345773145385, 826.7933016745814, 828.5981299069946, 829.5064436660208, 866.5883705240024, 868.3660927522913, 869.026690107081, 869.0787764969122, 869.4261787220436, 696.9686364113602, 725.021221351177, 832.7626626411504, 860.0668274921718, 865.2991077227434, 634.6382561939696, 634.7524465458475, 634.987300253997, 635.5709895820303, 636.1983900285627, 12473.93763031188, 12478.625641230687, 12485.812712809315, 12497.0, 12511.499540018389, 12832.743345133138, 12868.29224392658, 13068.738625227443, 13176.954487198609, 13225.801612975813, 11027.44862756867, 11085.612003579916, 11159.0, 11391.54433822646, 11498.770024599462, 4098.836046558164, 4131.338985762263, 4136.420901073852, 4303.591158839905, 4315.82736690532, 56461.43538564644, 57452.0, 58349.124763128166, 58392.41607583912, 59247.40752592505, 9399.436033837992, 9473.66842160521, 9535.52322383892, 9900.108990190918, 10197.13324367437, 18384.26462941494, 18606.348777792984, 18707.71938420925, 18733.34433294828, 18739.531511712044, 6611.933880661228, 6759.324067593299, 6885.621290828953, 6947.51367404287, 6950.0, 3692.280005398975, 3709.387950988111, 3781.716371272111, 3786.072412258959, 3804.5814960354337, 2952.1733914503975, 3005.413944280888, 3031.651360093573, 3033.8331391773663, 3034.8482575871117, 476.7568540044602, 479.77930152129716, 485.5702703107775, 489.87753061734566, 492.5025724018767, 467.7941705649503, 468.1129260052182, 480.6467246573765, 523.8742701751552, 586.9442402971711, 7696.268854458817, 8518.446300990416, 8576.356773242032, 8655.610497527627, 8792.0, 449.0928324783918, 466.7013111608573, 471.45782350296747, 524.7323864828965, 567.2172402085179, 3981.880543583697, 4070.430139780433, 4175.91648167035, 4197.76912269822, 4206.20082184384, 64647.0, 66552.0, 67326.66336668383, 68669.0, 69030.65484672545, 45122.77438612786, 51953.74023129936, 57569.71215143897, 57787.711061444425, 58473.415265847645, 41247.175056498105, 42844.143117137486, 43010.0, 46825.531744682114, 51779.741101294254, 8045.396595255263, 8691.608877600396, 8693.826123477369, 8698.739037828622, 8892.68875589377, 31001.8449907749, 32092.83953580217, 32153.517697234092, 33866.153346166546, 34712.30575388529, 41892.0, 43034.78482607567, 43283.0, 44495.11009779722, 44789.552104478535, 11166.1067114629, 11198.160137989846, 11245.718857028312, 11469.483873225892, 11658.00906922921, 12297.50809967627, 12367.93816030914, 12870.742585148431, 13145.539906103622, 13382.063255572406, 11052.557897684324, 11556.477383244308, 11715.88284117182, 11961.043116550481, 12202.938985305373, 10455.028991804493, 10931.398773067558, 10971.341719496855, 11136.33182009082, 11279.943600281946, 11578.76842463163, 11618.199179127248, 11621.070314375, 11704.180707350744, 11975.580854670045, 9381.484018379055, 9505.714828555154, 9697.854532182164, 10018.849717254101, 10333.379997200193, 9374.67188648394, 9569.0, 9616.567254473426, 9651.613935442503, 9684.612615495302], "value": 6210.870461494192, "unit": "score"}], "name": "dromaeo_css", "value": 6307.444349052775, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 117.0, 115.0, 118.0, 114.0, 115.0, 116.0, 115.0, 116.0, 116.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 128.0, 128.0, 164.0, 132.0, 135.0, 124.0, 124.0, 153.0, 131.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 160.0, 163.0, 162.0, 162.0, 161.0, 161.0, 162.0, 162.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 76.0, 107.0, 78.0, 78.0, 77.0, 77.0, 95.0, 78.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 113.0, 85.0, 85.0, 85.0, 100.0, 84.0, 84.0, 101.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 119.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 148.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 68.0, 68.0], "value": 66.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 68.0, 69.0, 68.0, 69.0, 70.0, 69.0, 68.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 85.0, 86.0, 93.0, 84.0, 84.0, 85.0, 83.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 144.0, 144.0, 149.0, 153.0, 131.0, 131.0, 133.0, 198.0, 137.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 173.0, 173.0, 175.0, 173.0, 174.0, 178.0, 174.0, 172.0, 175.0], "value": 174.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 72.0, 69.0, 71.0, 68.0, 67.0, 67.0, 70.0, 73.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1558.8999999999999, "alertThreshold": 2.0}]} 10:31:37 INFO - Return code: 0 10:31:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:31:37 INFO - # TBPL SUCCESS # 10:31:37 INFO - Running post-action listener: _resource_record_post_action 10:31:37 INFO - [mozharness: 2016-12-27 18:31:37.525937Z] Finished run-tests step (success) 10:31:37 INFO - Running post-run listener: _resource_record_post_run 10:31:37 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 382255104; Read time: 12; Write time: 216464 10:31:37 INFO - TinderboxPrint: CPU usage
13.1% 10:31:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 10:31:37 INFO - TinderboxPrint: I/O write bytes / time
382,255,104 / 216,464 10:31:37 INFO - TinderboxPrint: CPU idle
8,329.2 (86.9%) 10:31:37 INFO - TinderboxPrint: CPU user
1,214.9 (12.7%) 10:31:37 INFO - TinderboxPrint: Swap in / out
0 / 0 10:31:37 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 100139008; Read time: 0; Write time: 60828 10:31:37 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 282116096; Read time: 0; Write time: 155636 10:31:38 INFO - Running post-run listener: _upload_blobber_files 10:31:38 INFO - Blob upload gear active. 10:31:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:31:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:31:38 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'] 10:31:38 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 10:31:38 INFO - (blobuploader) - INFO - Open directory for files ... 10:31:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:31:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:31:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:31:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:31:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:31:41 INFO - (blobuploader) - INFO - Done attempting. 10:31:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:31:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:31:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:31:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:31:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:31:42 INFO - (blobuploader) - INFO - Done attempting. 10:31:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:31:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:31:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:31:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:31:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:31:43 INFO - (blobuploader) - INFO - Done attempting. 10:31:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:31:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:31:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:31:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:31:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:31:44 INFO - (blobuploader) - INFO - Done attempting. 10:31:44 INFO - (blobuploader) - INFO - Iteration through files over. 10:31:44 INFO - Return code: 0 10:31:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:31:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:31:44 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/16ca01cc1a1ed3adfae71d138a9ffe6821dc654b14a2866c2627d09c30cf20fefad5c1fade3d0d4faf90a732b8188b1fa9b6f2636159e439f410f0fbffef88c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b13e0a03da8afc65c30723473cae016b9df25d004bdc33dc15d1929b6bc2b419de875070bc4039f668a521b053ad731860752ae8ef6fa14ddae5279bb02f3659", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3792ecb796e0de1b5b53f1a2a9f2db0796d6a6dff41d3a94cd2e9687ecee89cd975ebb67c2626d6fda616a86e92e3b6d2de593801851ef8f086141cff92f1aab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/666b0ce5eb22ca496c1f1e773f197472cebe57d3f7401f68eb355b1e25d40bb6a2164699183edd3d295a8c7a26fe6fd0f769bbfbec5f90ae726c62ef1eece04a"} 10:31:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:31:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:31:44 INFO - Contents: 10:31:44 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/16ca01cc1a1ed3adfae71d138a9ffe6821dc654b14a2866c2627d09c30cf20fefad5c1fade3d0d4faf90a732b8188b1fa9b6f2636159e439f410f0fbffef88c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b13e0a03da8afc65c30723473cae016b9df25d004bdc33dc15d1929b6bc2b419de875070bc4039f668a521b053ad731860752ae8ef6fa14ddae5279bb02f3659", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3792ecb796e0de1b5b53f1a2a9f2db0796d6a6dff41d3a94cd2e9687ecee89cd975ebb67c2626d6fda616a86e92e3b6d2de593801851ef8f086141cff92f1aab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/666b0ce5eb22ca496c1f1e773f197472cebe57d3f7401f68eb355b1e25d40bb6a2164699183edd3d295a8c7a26fe6fd0f769bbfbec5f90ae726c62ef1eece04a"} 10:31:44 INFO - Running post-run listener: copy_logs_to_upload_dir 10:31:44 INFO - Copying logs to upload dir... 10:31:44 INFO - mkdir: /builds/slave/test/build/upload/logs 10:31:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.528761 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-27 10:31:43.948707) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 10:31:43.954052) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983 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/16ca01cc1a1ed3adfae71d138a9ffe6821dc654b14a2866c2627d09c30cf20fefad5c1fade3d0d4faf90a732b8188b1fa9b6f2636159e439f410f0fbffef88c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b13e0a03da8afc65c30723473cae016b9df25d004bdc33dc15d1929b6bc2b419de875070bc4039f668a521b053ad731860752ae8ef6fa14ddae5279bb02f3659", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3792ecb796e0de1b5b53f1a2a9f2db0796d6a6dff41d3a94cd2e9687ecee89cd975ebb67c2626d6fda616a86e92e3b6d2de593801851ef8f086141cff92f1aab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/666b0ce5eb22ca496c1f1e773f197472cebe57d3f7401f68eb355b1e25d40bb6a2164699183edd3d295a8c7a26fe6fd0f769bbfbec5f90ae726c62ef1eece04a"} build_url:https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011823 build_url: 'https://queue.taskcluster.net/v1/task/BUvvbRoISzOguYYRRC-Oug/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/16ca01cc1a1ed3adfae71d138a9ffe6821dc654b14a2866c2627d09c30cf20fefad5c1fade3d0d4faf90a732b8188b1fa9b6f2636159e439f410f0fbffef88c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b13e0a03da8afc65c30723473cae016b9df25d004bdc33dc15d1929b6bc2b419de875070bc4039f668a521b053ad731860752ae8ef6fa14ddae5279bb02f3659", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3792ecb796e0de1b5b53f1a2a9f2db0796d6a6dff41d3a94cd2e9687ecee89cd975ebb67c2626d6fda616a86e92e3b6d2de593801851ef8f086141cff92f1aab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/666b0ce5eb22ca496c1f1e773f197472cebe57d3f7401f68eb355b1e25d40bb6a2164699183edd3d295a8c7a26fe6fd0f769bbfbec5f90ae726c62ef1eece04a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 10:31:43.979868) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:31:43.980198) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1482859994.677988-1221671983 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004554 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:31:44.062420) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 10:31:44.062756) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 10:31:44.063055) ========= ========= Total master_lag: 0.14 =========