builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-012 starttime: 1483635053.15 results: success (0) revision: d7fcda4f0cdb39ad7d2fd00fe03112268b3e690d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:53.148302) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:53.148776) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:53.149069) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:53.163622) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:53.163987) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-05 08:50:53-- 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% 42.8M=0s 2017-01-05 08:50:53 (42.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118121 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:53.295719) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:53.296043) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014065 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:53.345956) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 08:50:53.346307) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev d7fcda4f0cdb39ad7d2fd00fe03112268b3e690d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/graphics --rev d7fcda4f0cdb39ad7d2fd00fe03112268b3e690d --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=2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-05 08:50:53,815 truncating revision to first 12 chars 2017-01-05 08:50:53,815 Setting DEBUG logging. 2017-01-05 08:50:53,815 attempt 1/10 2017-01-05 08:50:53,815 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/d7fcda4f0cdb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 08:50:54,807 unpacking tar archive at: graphics-d7fcda4f0cdb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.298232 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 08:50:54.657404) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:54.657722) ========= script_repo_revision: d7fcda4f0cdb39ad7d2fd00fe03112268b3e690d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:54.658109) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:54.658522) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 08:50:54.668272) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-01-05 08:50:54.668625) ========= /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=2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:50:55 INFO - MultiFileLogger online at 20170105 08:50:55 in /builds/slave/test 08:50:55 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 08:50:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:50:55 INFO - {'append_to_log': False, 08:50:55 INFO - 'base_work_dir': '/builds/slave/test', 08:50:55 INFO - 'blob_upload_branch': 'Graphics', 08:50:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:50:55 INFO - 'branch': 'Graphics', 08:50:55 INFO - 'buildbot_json_path': 'buildprops.json', 08:50:55 INFO - 'config_files': ('talos/linux_config.py',), 08:50:55 INFO - 'default_actions': ('clobber', 08:50:55 INFO - 'read-buildbot-config', 08:50:55 INFO - 'download-and-extract', 08:50:55 INFO - 'populate-webroot', 08:50:55 INFO - 'create-virtualenv', 08:50:55 INFO - 'install', 08:50:55 INFO - 'run-tests'), 08:50:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:50:55 INFO - 'download_minidump_stackwalk': True, 08:50:55 INFO - 'download_symbols': 'ondemand', 08:50:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:50:55 INFO - 'tooltool.py': '/tools/tooltool.py', 08:50:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:50:55 INFO - '/tools/misc-python/virtualenv.py')}, 08:50:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:50:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:50:55 INFO - 'installer_path': 'installer.exe', 08:50:55 INFO - 'log_level': 'info', 08:50:55 INFO - 'log_name': 'talos', 08:50:55 INFO - 'log_to_console': True, 08:50:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:50:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:50:55 INFO - 'opt_config_files': (), 08:50:55 INFO - 'pip_index': False, 08:50:55 INFO - 'sps_profile': False, 08:50:55 INFO - 'sps_profile_interval': 0, 08:50:55 INFO - 'suite': 'dromaeojs', 08:50:55 INFO - 'system_bits': '32', 08:50:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:50:55 INFO - 'title': 'talos-linux64-ix-012', 08:50:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:50:55 INFO - 'use_talos_json': True, 08:50:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:50:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:50:55 INFO - 'work_dir': 'build'} 08:50:55 INFO - [mozharness: 2017-01-05 16:50:55.236749Z] Running clobber step. 08:50:55 INFO - Running pre-action listener: _resource_record_pre_action 08:50:55 INFO - Running main action method: clobber 08:50:55 INFO - rmtree: /builds/slave/test/build 08:50:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:50:55 INFO - Running post-action listener: _resource_record_post_action 08:50:55 INFO - [mozharness: 2017-01-05 16:50:55.697200Z] Finished clobber step (success) 08:50:55 INFO - [mozharness: 2017-01-05 16:50:55.697301Z] Running read-buildbot-config step. 08:50:55 INFO - Running pre-action listener: _resource_record_pre_action 08:50:55 INFO - Running main action method: read_buildbot_config 08:50:55 INFO - Using buildbot properties: 08:50:55 INFO - { 08:50:55 INFO - "project": "", 08:50:55 INFO - "product": "firefox", 08:50:55 INFO - "who": "kgupta@mozilla.com", 08:50:55 INFO - "installer_path": "public/build/target.tar.bz2", 08:50:55 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 08:50:55 INFO - "repository": "https://hg.mozilla.org/projects/graphics/", 08:50:55 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 08:50:55 INFO - "stage_platform": "linux64", 08:50:55 INFO - "basedir": "/builds/slave/test", 08:50:55 INFO - "buildnumber": 41, 08:50:55 INFO - "platform": "ubuntu64_hw", 08:50:55 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:50:55 INFO - "slavebuilddir": "test", 08:50:55 INFO - "taskId": "KYLtRCcGRx67btiNahNvyQ", 08:50:55 INFO - "branch": "graphics", 08:50:55 INFO - "script_repo_revision": "default", 08:50:55 INFO - "revision": "d7fcda4f0cdb39ad7d2fd00fe03112268b3e690d", 08:50:55 INFO - "slavename": "talos-linux64-ix-012", 08:50:55 INFO - "repo_path": "projects/graphics" 08:50:55 INFO - } 08:50:55 INFO - Finding installer, test and symbols from parent task. 08:50:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KYLtRCcGRx67btiNahNvyQ'}, attempt #1 08:50:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KYLtRCcGRx67btiNahNvyQ'}, attempt #1 08:50:56 INFO - Task dependencies: RFwXurLSSei6aewabJj5sA 08:50:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA'}, attempt #1 08:50:57 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.tar.bz2 08:50:57 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/test_packages.json 08:50:57 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.crashreporter-symbols.zip 08:50:57 INFO - Running post-action listener: _resource_record_post_action 08:50:57 INFO - [mozharness: 2017-01-05 16:50:57.075356Z] Finished read-buildbot-config step (success) 08:50:57 INFO - [mozharness: 2017-01-05 16:50:57.075574Z] Running download-and-extract step. 08:50:57 INFO - Running pre-action listener: _resource_record_pre_action 08:50:57 INFO - Running main action method: download_and_extract 08:50:57 INFO - mkdir: /builds/slave/test/build/tests 08:50:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:57 INFO - trying https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.test_packages.json 08:50:57 INFO - Downloading https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:50:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:50:57 INFO - Downloaded 896 bytes. 08:50:57 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:50:57 INFO - Using the following test package requirements: 08:50:57 INFO - {u'common': [u'target.common.tests.zip'], 08:50:57 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:50:57 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:50:57 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:50:57 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:50:57 INFO - u'mozbase': [u'target.common.tests.zip'], 08:50:57 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:50:57 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:50:57 INFO - u'web-platform': [u'target.common.tests.zip', 08:50:57 INFO - u'target.web-platform.tests.zip'], 08:50:57 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:50:57 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:50:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.common.tests.zip 08:50:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:50:57 INFO - Fetch https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.common.tests.zip into memory 08:50:58 INFO - Expected file size: 20244966 08:50:58 INFO - Obtained file size: 20244966 08:51:02 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:51:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.common.tests.zip 08:51:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:51:02 INFO - Fetch https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.common.tests.zip into memory 08:51:03 INFO - Expected file size: 20244966 08:51:03 INFO - Obtained file size: 20244966 08:51:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.talos.tests.zip 08:51:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:51:07 INFO - Fetch https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.talos.tests.zip into memory 08:51:08 INFO - Expected file size: 14403730 08:51:08 INFO - Obtained file size: 14403730 08:51:08 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')]} 08:51:08 INFO - trying https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.tar.bz2 08:51:08 INFO - Downloading https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:51:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:51:09 INFO - Downloaded 55882918 bytes. 08:51:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.tar.bz2 08:51:09 INFO - mkdir: /builds/slave/test/properties 08:51:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:51:09 INFO - Writing to file /builds/slave/test/properties/build_url 08:51:09 INFO - Contents: 08:51:09 INFO - build_url:https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.tar.bz2 08:51:09 INFO - Running post-action listener: _resource_record_post_action 08:51:09 INFO - Running post-action listener: set_extra_try_arguments 08:51:09 INFO - [mozharness: 2017-01-05 16:51:09.662591Z] Finished download-and-extract step (success) 08:51:09 INFO - [mozharness: 2017-01-05 16:51:09.662689Z] Running populate-webroot step. 08:51:09 INFO - Running pre-action listener: _resource_record_pre_action 08:51:09 INFO - Running main action method: populate_webroot 08:51:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:51:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:51:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:51:09 INFO - '--tppagecycles', 08:51:09 INFO - '7'], 08:51:09 INFO - 'tests': ['tsvgm']}}, 08:51:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:51:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:51:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:51:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:51:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:51:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:51:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:51:09 INFO - 'talos_options': ['--disable-e10s'], 08:51:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:51:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:51:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:51:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:51:09 INFO - 'talos_options': ['--disable-e10s'], 08:51:09 INFO - 'tests': ['damp', 'tps']}, 08:51:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:51:09 INFO - 'tests': ['damp', 'tps']}, 08:51:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:51:09 INFO - 'tests': ['dromaeo_dom']}, 08:51:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:51:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:51:09 INFO - 'tests': ['basic_compositor_video']}, 08:51:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:51:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:51:09 INFO - 'tests': ['a11yr', 08:51:09 INFO - 'ts_paint', 08:51:09 INFO - 'tpaint', 08:51:09 INFO - 'sessionrestore', 08:51:09 INFO - 'sessionrestore_no_auto_restore', 08:51:09 INFO - 'tabpaint']}, 08:51:09 INFO - 'other-e10s': {'tests': ['a11yr', 08:51:09 INFO - 'ts_paint', 08:51:09 INFO - 'tpaint', 08:51:09 INFO - 'sessionrestore', 08:51:09 INFO - 'sessionrestore_no_auto_restore', 08:51:09 INFO - 'tabpaint']}, 08:51:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:51:09 INFO - 'ts_paint', 08:51:09 INFO - 'tpaint', 08:51:09 INFO - 'sessionrestore', 08:51:09 INFO - 'sessionrestore_no_auto_restore', 08:51:09 INFO - 'tabpaint']}, 08:51:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:51:09 INFO - 'ts_paint', 08:51:09 INFO - 'tpaint', 08:51:09 INFO - 'sessionrestore', 08:51:09 INFO - 'sessionrestore_no_auto_restore', 08:51:09 INFO - 'tabpaint']}, 08:51:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:51:09 INFO - 'tests': ['a11yr', 08:51:09 INFO - 'ts_paint', 08:51:09 INFO - 'tpaint', 08:51:09 INFO - 'sessionrestore', 08:51:09 INFO - 'sessionrestore_no_auto_restore', 08:51:09 INFO - 'tabpaint']}, 08:51:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:51:09 INFO - 'tests': ['a11yr', 08:51:09 INFO - 'ts_paint', 08:51:09 INFO - 'tpaint', 08:51:09 INFO - 'sessionrestore', 08:51:09 INFO - 'sessionrestore_no_auto_restore', 08:51:09 INFO - 'tabpaint']}, 08:51:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:51:09 INFO - 'tests': ['tsvgx', 08:51:09 INFO - 'tsvgr_opacity', 08:51:09 INFO - 'tart', 08:51:09 INFO - 'tscrollx', 08:51:09 INFO - 'cart', 08:51:09 INFO - 'tsvg_static']}, 08:51:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:51:09 INFO - 'tsvgr_opacity', 08:51:09 INFO - 'tart', 08:51:09 INFO - 'tscrollx', 08:51:09 INFO - 'cart', 08:51:09 INFO - 'tsvg_static']}, 08:51:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:51:09 INFO - 'talos_options': ['--disable-e10s'], 08:51:09 INFO - 'tests': ['tp5o']}, 08:51:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:51:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:51:09 INFO - 'talos_options': ['--disable-e10s', 08:51:09 INFO - '--xperf_path', 08:51:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:51:09 INFO - 'tests': ['tp5n']}, 08:51:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:51:09 INFO - 'talos_options': ['--xperf_path', 08:51:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:51:09 INFO - 'tests': ['tp5n']}}, 08:51:09 INFO - 'talos.zip': {'path': '', 08:51:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:51:09 INFO - Running post-action listener: _resource_record_post_action 08:51:09 INFO - [mozharness: 2017-01-05 16:51:09.675727Z] Finished populate-webroot step (success) 08:51:09 INFO - [mozharness: 2017-01-05 16:51:09.675820Z] Running create-virtualenv step. 08:51:09 INFO - Running pre-action listener: _resource_record_pre_action 08:51:09 INFO - Running main action method: create_virtualenv 08:51:09 INFO - Creating virtualenv /builds/slave/test/build/venv 08:51:09 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 08:51:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:51:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:51:09 INFO - Using real prefix '/usr' 08:51:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:51:10 INFO - Installing distribute.............................................................................................................................................................................................done. 08:51:12 INFO - Installing pip.................done. 08:51:12 INFO - Return code: 0 08:51:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:51:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:51:12 INFO - Reading from file tmpfile_stdout 08:51:12 INFO - Output received: 08:51:12 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) 08:51:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xeef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1260c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125e3f0>, '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 0x125ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125f1a0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', '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 08:51:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:51:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:51:12 INFO - Using env: {'DISPLAY': ':0', 08:51:12 INFO - 'HOME': '/home/cltbld', 08:51:12 INFO - 'LANG': 'en_US.UTF-8', 08:51:12 INFO - 'LANGUAGE': 'en_US:en', 08:51:12 INFO - 'LOGNAME': 'cltbld', 08:51:12 INFO - 'MAIL': '/var/mail/cltbld', 08:51:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:12 INFO - 'MOZ_NO_REMOTE': '1', 08:51:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:12 INFO - 'NO_EM_RESTART': '1', 08:51:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:12 INFO - 'PWD': '/builds/slave/test', 08:51:12 INFO - 'SHELL': '/bin/bash', 08:51:12 INFO - 'SHLVL': '1', 08:51:12 INFO - 'TERM': 'linux', 08:51:12 INFO - 'TMOUT': '86400', 08:51:12 INFO - 'USER': 'cltbld', 08:51:12 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', 08:51:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:12 INFO - '_': '/tools/buildbot/bin/python'} 08:51:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:12 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 08:51:12 INFO - Cleaning up... 08:51:12 INFO - Return code: 0 08:51:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xeef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1260c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125e3f0>, '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 0x125ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125f1a0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', '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 08:51:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:51:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:51:12 INFO - Using env: {'DISPLAY': ':0', 08:51:12 INFO - 'HOME': '/home/cltbld', 08:51:12 INFO - 'LANG': 'en_US.UTF-8', 08:51:12 INFO - 'LANGUAGE': 'en_US:en', 08:51:12 INFO - 'LOGNAME': 'cltbld', 08:51:12 INFO - 'MAIL': '/var/mail/cltbld', 08:51:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:12 INFO - 'MOZ_NO_REMOTE': '1', 08:51:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:12 INFO - 'NO_EM_RESTART': '1', 08:51:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:12 INFO - 'PWD': '/builds/slave/test', 08:51:12 INFO - 'SHELL': '/bin/bash', 08:51:12 INFO - 'SHLVL': '1', 08:51:12 INFO - 'TERM': 'linux', 08:51:12 INFO - 'TMOUT': '86400', 08:51:12 INFO - 'USER': 'cltbld', 08:51:12 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', 08:51:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:12 INFO - '_': '/tools/buildbot/bin/python'} 08:51:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:12 INFO - Downloading/unpacking psutil>=3.1.1 08:51:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:51:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:51:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:51:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:51:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:51:15 INFO - Installing collected packages: psutil 08:51:15 INFO - Running setup.py install for psutil 08:51:15 INFO - building 'psutil._psutil_linux' extension 08:51:15 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 08:51:15 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 08:51:15 INFO - building 'psutil._psutil_posix' extension 08:51:15 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 08:51:15 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 08:51:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:51:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:51:15 INFO - Successfully installed psutil 08:51:15 INFO - Cleaning up... 08:51:15 INFO - Return code: 0 08:51:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:51:15 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')]} 08:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:15 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')]} 08:51:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:15 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 0xeef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1260c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125e3f0>, '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 0x125ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125f1a0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', '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 08:51:15 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 08:51:15 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 08:51:15 INFO - Using env: {'DISPLAY': ':0', 08:51:15 INFO - 'HOME': '/home/cltbld', 08:51:15 INFO - 'LANG': 'en_US.UTF-8', 08:51:15 INFO - 'LANGUAGE': 'en_US:en', 08:51:15 INFO - 'LOGNAME': 'cltbld', 08:51:15 INFO - 'MAIL': '/var/mail/cltbld', 08:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:15 INFO - 'MOZ_NO_REMOTE': '1', 08:51:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:15 INFO - 'NO_EM_RESTART': '1', 08:51:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:15 INFO - 'PWD': '/builds/slave/test', 08:51:15 INFO - 'SHELL': '/bin/bash', 08:51:15 INFO - 'SHLVL': '1', 08:51:15 INFO - 'TERM': 'linux', 08:51:15 INFO - 'TMOUT': '86400', 08:51:15 INFO - 'USER': 'cltbld', 08:51:15 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', 08:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:15 INFO - '_': '/tools/buildbot/bin/python'} 08:51:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:51:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:51:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:51:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:51:17 INFO - Installing collected packages: mozsystemmonitor 08:51:17 INFO - Running setup.py install for mozsystemmonitor 08:51:17 INFO - Successfully installed mozsystemmonitor 08:51:17 INFO - Cleaning up... 08:51:17 INFO - Return code: 0 08:51:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:51:17 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')]} 08:51:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:17 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')]} 08:51:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:18 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 0xeef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1260c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125e3f0>, '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 0x125ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125f1a0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', '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 08:51:18 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 08:51:18 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 08:51:18 INFO - Using env: {'DISPLAY': ':0', 08:51:18 INFO - 'HOME': '/home/cltbld', 08:51:18 INFO - 'LANG': 'en_US.UTF-8', 08:51:18 INFO - 'LANGUAGE': 'en_US:en', 08:51:18 INFO - 'LOGNAME': 'cltbld', 08:51:18 INFO - 'MAIL': '/var/mail/cltbld', 08:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:18 INFO - 'MOZ_NO_REMOTE': '1', 08:51:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:18 INFO - 'NO_EM_RESTART': '1', 08:51:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:18 INFO - 'PWD': '/builds/slave/test', 08:51:18 INFO - 'SHELL': '/bin/bash', 08:51:18 INFO - 'SHLVL': '1', 08:51:18 INFO - 'TERM': 'linux', 08:51:18 INFO - 'TMOUT': '86400', 08:51:18 INFO - 'USER': 'cltbld', 08:51:18 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', 08:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:18 INFO - '_': '/tools/buildbot/bin/python'} 08:51:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:18 INFO - Downloading/unpacking jsonschema==2.5.1 08:51:18 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 08:51:18 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 08:51:18 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 08:51:18 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 08:51:18 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 08:51:18 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 08:51:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:51:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:51:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:51:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:51:20 INFO - Downloading functools32-3.2.3-2.tar.gz 08:51:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:51:20 INFO - warning: no files found matching '*.txt' 08:51:20 INFO - no previously-included directories found matching 'build' 08:51:20 INFO - no previously-included directories found matching 'dist' 08:51:20 INFO - no previously-included directories found matching '.git*' 08:51:20 INFO - Installing collected packages: jsonschema, functools32 08:51:20 INFO - Running setup.py install for functools32 08:51:20 INFO - warning: no files found matching '*.txt' 08:51:20 INFO - no previously-included directories found matching 'build' 08:51:20 INFO - no previously-included directories found matching 'dist' 08:51:20 INFO - no previously-included directories found matching '.git*' 08:51:20 INFO - Successfully installed jsonschema functools32 08:51:20 INFO - Cleaning up... 08:51:20 INFO - Return code: 0 08:51:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:51:20 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')]} 08:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:20 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')]} 08:51:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:20 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 0xeef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1260c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125e3f0>, '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 0x125ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125f1a0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', '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 08:51:20 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 08:51:20 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 08:51:20 INFO - Using env: {'DISPLAY': ':0', 08:51:20 INFO - 'HOME': '/home/cltbld', 08:51:20 INFO - 'LANG': 'en_US.UTF-8', 08:51:20 INFO - 'LANGUAGE': 'en_US:en', 08:51:20 INFO - 'LOGNAME': 'cltbld', 08:51:20 INFO - 'MAIL': '/var/mail/cltbld', 08:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:20 INFO - 'MOZ_NO_REMOTE': '1', 08:51:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:20 INFO - 'NO_EM_RESTART': '1', 08:51:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:20 INFO - 'PWD': '/builds/slave/test', 08:51:20 INFO - 'SHELL': '/bin/bash', 08:51:20 INFO - 'SHLVL': '1', 08:51:20 INFO - 'TERM': 'linux', 08:51:20 INFO - 'TMOUT': '86400', 08:51:20 INFO - 'USER': 'cltbld', 08:51:20 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', 08:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:20 INFO - '_': '/tools/buildbot/bin/python'} 08:51:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:51:20 INFO - Cleaning up... 08:51:20 INFO - Return code: 0 08:51:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:51:20 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')]} 08:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:20 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')]} 08:51:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:20 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 0xeef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1260c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125e3f0>, '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 0x125ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125f1a0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', '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 08:51:20 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 08:51:20 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 08:51:20 INFO - Using env: {'DISPLAY': ':0', 08:51:20 INFO - 'HOME': '/home/cltbld', 08:51:20 INFO - 'LANG': 'en_US.UTF-8', 08:51:20 INFO - 'LANGUAGE': 'en_US:en', 08:51:20 INFO - 'LOGNAME': 'cltbld', 08:51:20 INFO - 'MAIL': '/var/mail/cltbld', 08:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:20 INFO - 'MOZ_NO_REMOTE': '1', 08:51:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:20 INFO - 'NO_EM_RESTART': '1', 08:51:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:20 INFO - 'PWD': '/builds/slave/test', 08:51:20 INFO - 'SHELL': '/bin/bash', 08:51:20 INFO - 'SHLVL': '1', 08:51:20 INFO - 'TERM': 'linux', 08:51:20 INFO - 'TMOUT': '86400', 08:51:20 INFO - 'USER': 'cltbld', 08:51:20 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', 08:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:20 INFO - '_': '/tools/buildbot/bin/python'} 08:51:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:21 INFO - Downloading/unpacking blobuploader==1.2.4 08:51:21 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 08:51:21 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 08:51:21 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 08:51:21 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 08:51:21 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 08:51:21 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 08:51:23 INFO - Downloading blobuploader-1.2.4.tar.gz 08:51:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:51:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:51:23 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 08:51:23 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 08:51:23 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 08:51:23 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 08:51:23 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 08:51:23 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 08:51:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:51:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:51:23 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 08:51:23 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 08:51:23 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 08:51:23 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 08:51:23 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 08:51:23 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 08:51:23 INFO - Downloading docopt-0.6.1.tar.gz 08:51:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:51:23 INFO - Installing collected packages: blobuploader, requests, docopt 08:51:23 INFO - Running setup.py install for blobuploader 08:51:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:51:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:51:24 INFO - Running setup.py install for requests 08:51:24 INFO - Running setup.py install for docopt 08:51:24 INFO - Successfully installed blobuploader requests docopt 08:51:24 INFO - Cleaning up... 08:51:24 INFO - Return code: 0 08:51:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51: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')]} 08:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51: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')]} 08:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:24 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 0xeef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1260c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125e3f0>, '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 0x125ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125f1a0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', '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 08:51:24 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 08:51:24 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 08:51:24 INFO - Using env: {'DISPLAY': ':0', 08:51:24 INFO - 'HOME': '/home/cltbld', 08:51:24 INFO - 'LANG': 'en_US.UTF-8', 08:51:24 INFO - 'LANGUAGE': 'en_US:en', 08:51:24 INFO - 'LOGNAME': 'cltbld', 08:51:24 INFO - 'MAIL': '/var/mail/cltbld', 08:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:24 INFO - 'MOZ_NO_REMOTE': '1', 08:51:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:24 INFO - 'NO_EM_RESTART': '1', 08:51:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:24 INFO - 'PWD': '/builds/slave/test', 08:51:24 INFO - 'SHELL': '/bin/bash', 08:51:24 INFO - 'SHLVL': '1', 08:51:24 INFO - 'TERM': 'linux', 08:51:24 INFO - 'TMOUT': '86400', 08:51:24 INFO - 'USER': 'cltbld', 08:51:24 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', 08:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:24 INFO - '_': '/tools/buildbot/bin/python'} 08:51:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:51:24 INFO - Running setup.py (path:/tmp/pip-_Btwwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:51:24 INFO - Running setup.py (path:/tmp/pip-iBFM0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:51:24 INFO - Running setup.py (path:/tmp/pip-7K6yEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:51:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:51:25 INFO - Running setup.py (path:/tmp/pip-EUrQuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:51:25 INFO - Running setup.py (path:/tmp/pip-cGMATq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:51:25 INFO - Running setup.py (path:/tmp/pip-NRnFLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:51:25 INFO - Running setup.py (path:/tmp/pip-MrCdwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:51:25 INFO - Running setup.py (path:/tmp/pip-5zanIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:51:25 INFO - Running setup.py (path:/tmp/pip-Gb9dzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:51:25 INFO - Running setup.py (path:/tmp/pip-NK1Rl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:51:25 INFO - Running setup.py (path:/tmp/pip-CYOLWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:51:25 INFO - Running setup.py (path:/tmp/pip-jeiv5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:51:25 INFO - Running setup.py (path:/tmp/pip-s4sNOV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:51:25 INFO - Running setup.py (path:/tmp/pip-rT7nnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:25 INFO - Running setup.py (path:/tmp/pip-FKnS6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:51:25 INFO - Running setup.py (path:/tmp/pip-gBkpOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:51:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:51:25 INFO - Running setup.py (path:/tmp/pip-X95rt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:51:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:51:26 INFO - Running setup.py install for manifestparser 08:51:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:51:26 INFO - Running setup.py install for mozcrash 08:51:26 INFO - Running setup.py install for mozdebug 08:51:26 INFO - Running setup.py install for mozdevice 08:51:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:51:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:51:26 INFO - Running setup.py install for mozfile 08:51:26 INFO - Running setup.py install for mozhttpd 08:51:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:51:26 INFO - Running setup.py install for mozinfo 08:51:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:51:26 INFO - Running setup.py install for mozInstall 08:51:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:51:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:51:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:51:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:51:26 INFO - Running setup.py install for mozleak 08:51:26 INFO - Running setup.py install for mozlog 08:51:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:51:27 INFO - Running setup.py install for moznetwork 08:51:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:51:27 INFO - Running setup.py install for mozprocess 08:51:27 INFO - Running setup.py install for mozprofile 08:51:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:51:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:51:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:51:27 INFO - Running setup.py install for mozrunner 08:51:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:51:27 INFO - Running setup.py install for mozscreenshot 08:51:27 INFO - Running setup.py install for moztest 08:51:27 INFO - Running setup.py install for mozversion 08:51:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:51:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:51:27 INFO - Cleaning up... 08:51:27 INFO - Return code: 0 08:51:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51:27 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')]} 08:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:27 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')]} 08:51:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:27 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 0xeef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1260c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125e3f0>, '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 0x125ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125f1a0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', '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 08:51:27 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 08:51:27 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 08:51:27 INFO - Using env: {'DISPLAY': ':0', 08:51:27 INFO - 'HOME': '/home/cltbld', 08:51:27 INFO - 'LANG': 'en_US.UTF-8', 08:51:27 INFO - 'LANGUAGE': 'en_US:en', 08:51:27 INFO - 'LOGNAME': 'cltbld', 08:51:27 INFO - 'MAIL': '/var/mail/cltbld', 08:51:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:27 INFO - 'MOZ_NO_REMOTE': '1', 08:51:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:27 INFO - 'NO_EM_RESTART': '1', 08:51:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:27 INFO - 'PWD': '/builds/slave/test', 08:51:27 INFO - 'SHELL': '/bin/bash', 08:51:27 INFO - 'SHLVL': '1', 08:51:27 INFO - 'TERM': 'linux', 08:51:27 INFO - 'TMOUT': '86400', 08:51:27 INFO - 'USER': 'cltbld', 08:51:27 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', 08:51:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:27 INFO - '_': '/tools/buildbot/bin/python'} 08:51:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:51:28 INFO - Running setup.py (path:/tmp/pip-T88UqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:51:28 INFO - Running setup.py (path:/tmp/pip-2qOYEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:51:28 INFO - Running setup.py (path:/tmp/pip-YcJgmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:51:28 INFO - Running setup.py (path:/tmp/pip-Mbzi30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:51:28 INFO - Running setup.py (path:/tmp/pip-JTL1lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:51:28 INFO - Running setup.py (path:/tmp/pip-ERyydE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:51:28 INFO - Running setup.py (path:/tmp/pip-3OtrpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:51:28 INFO - Running setup.py (path:/tmp/pip-ieb9E3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:51:28 INFO - Running setup.py (path:/tmp/pip-RrS6D2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:51:28 INFO - Running setup.py (path:/tmp/pip-hfwTU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:51:28 INFO - Running setup.py (path:/tmp/pip-IQHvPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:51:28 INFO - Running setup.py (path:/tmp/pip-LoVbuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:51:28 INFO - Running setup.py (path:/tmp/pip-fCtexd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:51:28 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)) 08:51:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:51:28 INFO - Running setup.py (path:/tmp/pip-EgGtiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:51:29 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)) 08:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:29 INFO - Running setup.py (path:/tmp/pip-hFpjZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:29 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)) 08:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:51:29 INFO - Running setup.py (path:/tmp/pip-6Tk6qy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:51:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 08:51:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:51:29 INFO - Running setup.py (path:/tmp/pip-hK7_Lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:51:29 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)) 08:51:29 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)) 08:51:29 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)) 08:51:29 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)) 08:51:29 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)) 08:51:29 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)) 08:51:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:51:29 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 08:51:29 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 08:51:29 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 08:51:29 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 08:51:29 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 08:51:29 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 08:51:31 INFO - Downloading blessings-1.6.tar.gz 08:51:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:51:31 INFO - Installing collected packages: blessings 08:51:31 INFO - Running setup.py install for blessings 08:51:31 INFO - Successfully installed blessings 08:51:31 INFO - Cleaning up... 08:51:31 INFO - Return code: 0 08:51:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:51:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:31 INFO - Reading from file tmpfile_stdout 08:51:31 INFO - Current package versions: 08:51:31 INFO - argparse == 1.2.1 08:51:31 INFO - blessings == 1.6 08:51:31 INFO - blobuploader == 1.2.4 08:51:31 INFO - docopt == 0.6.1 08:51:31 INFO - functools32 == 3.2.3-2 08:51:31 INFO - jsonschema == 2.5.1 08:51:31 INFO - manifestparser == 1.1 08:51:31 INFO - mozInstall == 1.12 08:51:31 INFO - mozcrash == 1.0 08:51:31 INFO - mozdebug == 0.1 08:51:31 INFO - mozdevice == 0.49 08:51:31 INFO - mozfile == 1.2 08:51:31 INFO - mozhttpd == 0.7 08:51:31 INFO - mozinfo == 0.9 08:51:31 INFO - mozleak == 0.1 08:51:31 INFO - mozlog == 3.4 08:51:31 INFO - moznetwork == 0.27 08:51:31 INFO - mozprocess == 0.23 08:51:31 INFO - mozprofile == 0.28 08:51:31 INFO - mozrunner == 6.13 08:51:31 INFO - mozscreenshot == 0.1 08:51:31 INFO - mozsystemmonitor == 0.3 08:51:31 INFO - moztest == 0.8 08:51:31 INFO - mozversion == 1.4 08:51:31 INFO - psutil == 3.1.1 08:51:31 INFO - requests == 1.2.3 08:51:31 INFO - wsgiref == 0.1.2 08:51:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51: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')]} 08:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51: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')]} 08:51:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:31 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 0xeef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1260c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125e3f0>, '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 0x125ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125f1a0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', '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 08:51:31 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 08:51:31 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 08:51:31 INFO - Using env: {'DISPLAY': ':0', 08:51:31 INFO - 'HOME': '/home/cltbld', 08:51:31 INFO - 'LANG': 'en_US.UTF-8', 08:51:31 INFO - 'LANGUAGE': 'en_US:en', 08:51:31 INFO - 'LOGNAME': 'cltbld', 08:51:31 INFO - 'MAIL': '/var/mail/cltbld', 08:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:31 INFO - 'MOZ_NO_REMOTE': '1', 08:51:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:31 INFO - 'NO_EM_RESTART': '1', 08:51:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:31 INFO - 'PWD': '/builds/slave/test', 08:51:31 INFO - 'SHELL': '/bin/bash', 08:51:31 INFO - 'SHLVL': '1', 08:51:31 INFO - 'TERM': 'linux', 08:51:31 INFO - 'TMOUT': '86400', 08:51:31 INFO - 'USER': 'cltbld', 08:51:31 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', 08:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:31 INFO - '_': '/tools/buildbot/bin/python'} 08:51:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:31 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)) 08:51:31 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)) 08:51:31 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)) 08:51:31 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)) 08:51:31 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)) 08:51:31 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)) 08:51:31 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)) 08:51:31 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)) 08:51:31 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)) 08:51:31 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)) 08:51:31 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)) 08:51:31 INFO - Cleaning up... 08:51:31 INFO - Return code: 0 08:51:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:51: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')]} 08:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51: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')]} 08:51:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:31 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 0xeef1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1260c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125e3f0>, '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 0x125ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125f1a0>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', '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 08:51:31 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 08:51:31 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 08:51:31 INFO - Using env: {'DISPLAY': ':0', 08:51:31 INFO - 'HOME': '/home/cltbld', 08:51:31 INFO - 'LANG': 'en_US.UTF-8', 08:51:31 INFO - 'LANGUAGE': 'en_US:en', 08:51:31 INFO - 'LOGNAME': 'cltbld', 08:51:31 INFO - 'MAIL': '/var/mail/cltbld', 08:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:31 INFO - 'MOZ_NO_REMOTE': '1', 08:51:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:31 INFO - 'NO_EM_RESTART': '1', 08:51:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:31 INFO - 'PWD': '/builds/slave/test', 08:51:31 INFO - 'SHELL': '/bin/bash', 08:51:31 INFO - 'SHLVL': '1', 08:51:31 INFO - 'TERM': 'linux', 08:51:31 INFO - 'TMOUT': '86400', 08:51:31 INFO - 'USER': 'cltbld', 08:51:31 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', 08:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:31 INFO - '_': '/tools/buildbot/bin/python'} 08:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:51:32 INFO - Cleaning up... 08:51:32 INFO - Return code: 0 08:51:32 INFO - Running post-action listener: _resource_record_post_action 08:51:32 INFO - Running post-action listener: _start_resource_monitoring 08:51:32 INFO - Starting resource monitoring. 08:51:32 INFO - [mozharness: 2017-01-05 16:51:32.225702Z] Finished create-virtualenv step (success) 08:51:32 INFO - [mozharness: 2017-01-05 16:51:32.226161Z] Running install step. 08:51:32 INFO - Running pre-action listener: _resource_record_pre_action 08:51:32 INFO - Running main action method: install 08:51:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:32 INFO - Reading from file tmpfile_stdout 08:51:32 INFO - Detecting whether we're running mozinstall >=1.0... 08:51:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:51:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:51:32 INFO - Reading from file tmpfile_stdout 08:51:32 INFO - Output received: 08:51:32 INFO - Usage: mozinstall [options] installer 08:51:32 INFO - Options: 08:51:32 INFO - -h, --help show this help message and exit 08:51:32 INFO - -d DEST, --destination=DEST 08:51:32 INFO - Directory to install application into. [default: 08:51:32 INFO - "/builds/slave/test"] 08:51:32 INFO - --app=APP Application being installed. [default: firefox] 08:51:32 INFO - mkdir: /builds/slave/test/build/application 08:51:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:51:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:51:48 INFO - Reading from file tmpfile_stdout 08:51:48 INFO - Output received: 08:51:48 INFO - /builds/slave/test/build/application/firefox/firefox 08:51:48 INFO - Running post-action listener: _resource_record_post_action 08:51:48 INFO - [mozharness: 2017-01-05 16:51:48.684034Z] Finished install step (success) 08:51:48 INFO - [mozharness: 2017-01-05 16:51:48.684315Z] Running run-tests step. 08:51:48 INFO - Running pre-action listener: _resource_record_pre_action 08:51:48 INFO - Running main action method: run_tests 08:51:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:51:48 INFO - Python 2.7.3 08:51:48 INFO - Return code: 0 08:51:48 INFO - grabbing minidump binary from tooltool 08:51:48 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')]} 08:51:48 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 0x125e3f0>, '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 0x125ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125f1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:51:48 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 08:51:48 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 08:51:48 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 08:51:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:51:48 INFO - Return code: 0 08:51:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:51:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:51:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:51:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:51:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:51:48 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/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 08:51:48 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/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-012 --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 08:51:48 INFO - Using env: {'DISPLAY': ':0', 08:51:48 INFO - 'HOME': '/home/cltbld', 08:51:48 INFO - 'LANG': 'en_US.UTF-8', 08:51:48 INFO - 'LANGUAGE': 'en_US:en', 08:51:48 INFO - 'LOGNAME': 'cltbld', 08:51:48 INFO - 'MAIL': '/var/mail/cltbld', 08:51:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:51:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:51:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:48 INFO - 'MOZ_NO_REMOTE': '1', 08:51:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:51:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:48 INFO - 'NO_EM_RESTART': '1', 08:51:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:48 INFO - 'PWD': '/builds/slave/test', 08:51:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:51:48 INFO - 'SHELL': '/bin/bash', 08:51:48 INFO - 'SHLVL': '1', 08:51:48 INFO - 'TERM': 'linux', 08:51:48 INFO - 'TMOUT': '86400', 08:51:48 INFO - 'USER': 'cltbld', 08:51:48 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843', 08:51:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:48 INFO - '_': '/tools/buildbot/bin/python'} 08:51:48 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/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--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 08:51:48 INFO - mozversion application_buildid: 20170105161023 08:51:48 INFO - mozversion application_changeset: d7fcda4f0cdb39ad7d2fd00fe03112268b3e690d 08:51:48 INFO - mozversion application_display_name: Nightly 08:51:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:51:48 INFO - mozversion application_name: Firefox 08:51:48 INFO - mozversion application_remotingname: firefox 08:51:48 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 08:51:48 INFO - mozversion application_vendor: Mozilla 08:51:48 INFO - mozversion application_version: 53.0a1 08:51:48 INFO - mozversion platform_buildid: 20170105161023 08:51:48 INFO - mozversion platform_changeset: d7fcda4f0cdb39ad7d2fd00fe03112268b3e690d 08:51:48 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 08:51:48 INFO - mozversion platform_version: 53.0a1 08:51:48 INFO - using testdate: 1483635108 08:51:48 INFO - actual date: 1483635108 08:51:48 INFO - starting webserver on 'localhost:59226' 08:51:48 INFO - SUITE-START | Running 2 tests 08:51:48 INFO - TEST-START | dromaeo_css 08:51:48 INFO - operating with platform_type : linux_ 08:51:48 INFO - Initialising browser for dromaeo_css test... 08:51:48 INFO - TEST-INFO | started process 20434 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc5lVdT/profile http://localhost:59226/getInfo.html) 08:51:49 INFO - PROCESS | 20434 | 1483635109471 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:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:263 < _startProvider()@resource://gre/modules/AddonManager.jsm:758 < startup()@resource://gre/modules/AddonManager.jsm:940 < startup()@resource://gre/modules/AddonManager.jsm:2913 < observe()@resource://gre/components/addonManager.js:65 08:51:52 INFO - PROCESS | 20434 | __metrics Screen width/height:1600/1200 08:51:52 INFO - PROCESS | 20434 | colorDepth:24 08:51:52 INFO - PROCESS | 20434 | Browser inner width/height: 1024/697 08:51:52 INFO - PROCESS | 20434 | __metrics 08:51:53 INFO - PROCESS | 20434 | 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" 08:51:53 INFO - PROCESS | 20434 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:51:54 INFO - TEST-INFO | 20434: exit 0 08:51:54 INFO - Browser initialized. 08:51:54 INFO - Running cycle 1/1 for dromaeo_css test... 08:51:54 INFO - TEST-INFO | started process 20516 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc5lVdT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:52:06 INFO - PROCESS | 20516 | RSS: Main: 151547904 08:52:06 INFO - PROCESS | 20516 | 08:52:56 INFO - PROCESS | 20516 | 1483635176768 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:55:10 INFO - PROCESS | 20516 | Cycle 1(1): loaded http://localhost:59226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59226/tests/dromaeo/cssquery-ext.html) 08:55:12 INFO - PROCESS | 20516 | RSS: Main: 297840640 08:55:12 INFO - PROCESS | 20516 | 08:56:55 INFO - PROCESS | 20516 | ************************* 08:56:55 INFO - PROCESS | 20516 | A coding exception was thrown and uncaught in a Task. 08:56:55 INFO - PROCESS | 20516 | 08:56:55 INFO - PROCESS | 20516 | Full message: ReferenceError: fetch is not defined 08:56:55 INFO - PROCESS | 20516 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:56:55 INFO - PROCESS | 20516 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:56:55 INFO - PROCESS | 20516 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:56:55 INFO - PROCESS | 20516 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:56:55 INFO - PROCESS | 20516 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:56:55 INFO - PROCESS | 20516 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:56:55 INFO - PROCESS | 20516 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:56:55 INFO - PROCESS | 20516 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:56:55 INFO - PROCESS | 20516 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:56:55 INFO - PROCESS | 20516 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:56:55 INFO - PROCESS | 20516 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:56:55 INFO - PROCESS | 20516 | 08:56:55 INFO - PROCESS | 20516 | ************************* 08:58:15 INFO - PROCESS | 20516 | Cycle 1(1): loaded http://localhost:59226/tests/dromaeo/cssquery-ext.html (next: http://localhost:59226/tests/dromaeo/cssquery-jquery.html) 08:58:15 INFO - PROCESS | 20516 | RSS: Main: 235454464 08:58:15 INFO - PROCESS | 20516 | 09:01:18 INFO - PROCESS | 20516 | Cycle 1(1): loaded http://localhost:59226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59226/tests/dromaeo/cssquery-mootools.html) 09:01:18 INFO - PROCESS | 20516 | RSS: Main: 184094720 09:01:18 INFO - PROCESS | 20516 | 09:04:20 INFO - PROCESS | 20516 | Cycle 1(1): loaded http://localhost:59226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59226/tests/dromaeo/cssquery-prototype.html) 09:04:21 INFO - PROCESS | 20516 | RSS: Main: 184938496 09:04:21 INFO - PROCESS | 20516 | 09:07:24 INFO - PROCESS | 20516 | Cycle 1(1): loaded http://localhost:59226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59226/tests/dromaeo/cssquery-yui.html) 09:07:25 INFO - PROCESS | 20516 | RSS: Main: 183926784 09:07:25 INFO - PROCESS | 20516 | 09:10:28 INFO - PROCESS | 20516 | Cycle 1(1): loaded http://localhost:59226/tests/dromaeo/cssquery-yui.html (next: http://localhost:59226/tests/dromaeo/cssquery-dojo.html) 09:10:28 INFO - PROCESS | 20516 | RSS: Main: 270024704 09:10:28 INFO - PROCESS | 20516 | 09:10:28 INFO - PROCESS | 20516 | __start_tp_report 09:10:28 INFO - PROCESS | 20516 | _x_x_mozilla_page_load 09:10:28 INFO - PROCESS | 20516 | _x_x_mozilla_page_load_details 09:10:28 INFO - PROCESS | 20516 | |i|pagename|runs| 09:10:28 INFO - PROCESS | 20516 | |0;dojo.html;2538.695356557213;2560.9103681371157;2592.4296654735963;2593.170185540627;2596.2470883443802;4608.377868987685;4666.603338716205;4697.929531057032;4700.589823053083;4700.905981880364;4680.9297860532115;4692.953070469294;4695.624350051996;4723.598494128003;4780.426348838144;4315.525292217869;4321.092570560186;4322.07075478772;4330.242207613671;4443.289073748201;4389.714668546534;4410.889727756799;4435.711678740888;4469.910601787962;4480.708753930984;4235.98336399265;4342.392065110887;4358.629516491102;4378.978105109486;4453.866384008485;4270.850520231777;4322.459692538433;4325.653947684178;4348.913021739548;4361;4330.653547716207;4332.111917057027;4335.154644844257;4384.912301753947;4387;4700.059988002379;4705.929411058837;4716.52834716529;4716.952830471686;4730.455997560291;4936.136176169192;4959.7024178549045;4987.900241995176;5027;5073.213651883964;5104.566111880495;5121.692698438068;5134.614903882224;5137.7944882204665;5185.792568297264;4778.068276686013;4786.425628924517;4787.521247875185;4799.760011999386;4837.564619184291;5216.895662086813;5226.189940559219;5229.084910140787;5229.895402091937;5233.816816411408;4854.490278520704;4897.094037603055;4897.3878265216845;5020.7740651670765;5070.974645126751;4959.404871415453;5010.924836127461;5158.664686795421;5193;5208.322918020709;4944.90110197794;5001.64988450805;5033.320501732219;5059.721715305694;5139.486051394831;5048.343715316985;5050.924236136461;5056.747162641853;5091.389033316026;5102.846914592568;4223.873283801491;4315.136972605492;4316.934976166894;4323.935140972888;4368.039031413084;3686.4470329450796;3693.224422871227;3700.5559332880225;3716.7398282120535;3762.398016317431;4375.409319741858;4378.8029538670835;4400.801963911632;4412.161689279091;4427.977860110679;5019.07147177766;5027.648064635514;5035.77339019745;5074.314967479417;5088.6946783193125;4299.699021068495;4328.220920234325;4337.175936572041;4349.652027837766;4380.3210502372185;4556.726596404226;4562.452505699271;4586.977065114653;4591.79336929839;4597.517260687647;4719.952800471951;4721.811127554996;4725.905481890274;4733.455652599995;4760.309755085424;5006.499350064964;5026.623003274842;5050.72221027847;5066.341375621146;5082.720450375265;4703.435587729357;4735.124002059492;4751.952480475151;4759.833405830917;4788.209945358977;5010.749462526932;5026.52248036436;5026.94434168814;5038.798448062036;5059.443461219189;4985.75071246429;5010.749462526932;5089.262057001788;5090.694558326512;5125.974370128126;4879.243717223836;4932.679375840559;4939.950600494093;4948.430930443045;4958.851234462972;4936.259561065869;4938.08645400602;4939.901201975868;4949.010197960494;4957.405111386656;4853.199222128309;4890.24201248807;4896.877578060577;4902.558769710743;4951.801927922843;4932.235503496964;4985.651004429655;4987.251912213197;4999.825006124768;5044.041632089891;4852.8301509447;4876.073546026244;4878.1950978089635;4884.706917584814;4894.657373983787 09:10:28 INFO - PROCESS | 20516 | |1;ext.html;537.9704116273605;561.9409961953996;564.6047766563406;574.9310082790066;581.6393835821791;28568.285792855164;29150.27124321893;38888.80555597221;39251.214975700466;39633.207335853265;39904.20191596166;40304.19391612166;40325;40330.19339613206;40374.596254037526;7601.84796304075;7664.233576642347;7738.419618528605;7741.3419859312025;7750.302472777449;7445;7611.961940190291;7768.883466748003;9965.651202207924;10268.486575671186;10027.849582256273;10095.141912937372;10113.646022389217;10153.086222239997;10173.796524069514;9990.300678952477;10045.14616257619;10049.849252261221;10057.698269051903;10065.849012264822;9795.265355098396;9857.309988300822;9862.704118876374;9867.851982220272;9869.753256168653;36960;37247;37249;37279.440808387895;37311.626883731355;3527.8236088195745;3632.8183590820354;3753.9812300938593;3784.9023783102866;3803.9429408558894;24128.155514556907;25843.74156258451;25970;26017.609735853977;26783.464330713276;3026.2888221267986;3074.3697542003833;3078.7690923180853;3083.5991321128336;3181.427343078245;238684;239493.99999999825;240167;240353;240454;45767.54232457633;46215.30677039847;46217;46269.53730462719;46304.768476158075;48331;48366;48409.5159048412;48426.5157348422;48448.27327590089;11609.3614851184;11901;11906.940465297617;11925.940370298267;11928.343941083323;8308.418410711192;9315.860262096074;9385.671501497414;9427.292953028551;9435.811283774286;13677.863221367857;15033.398664053315;15041.47354842597;15407.768883466757;15881.920590397205;6509.772157974448;6511.479081673455;6551.6069035858;6664.100346453264;6681.432078273304;748.4424104042523;761.2615762710162;765.4374035084209;778.0391216847189;781.7654703588901;26994.460110797674;27870.442591148065;27988.72011279902;28420.857895710804;28494.572581411296;883.5626364949392;954.3659499780655;961.7307153996892;972.0959507657805;977.9559919803626;13758.587242382744;14671.193084380247;14725;14920.70158596822;14948.10311381299;358053;360774;361324;362297;363370;49882;50400.49599504031;50467.49532504701;50475;50482.74758626183;1202.5307136160861;1210.6368089573093;1217.896478799312;1226.8528925454727;1241.9068569857297;23338.766612334337;26371.472570548864;27184.36893786379;27848.860755696904;27882;41740;42255.366169508095;42298.577014229464;42329.5767042338;42361.57638423576;281113;281778;282123;282624;284842;23681;24166.51666966686;24713.135040274225;25059.248222553353;25093.372665682775;39416.60583394129;40632.92524043041;41028.17943641051;44798.552014479435;45208.32187517124;35216.64783352134;35314.82342588271;35823.46264805978;36036.81981590075;36113.27773444569;35492.93521194368;35588;35619.46570801492;35877.461838071926;35878.820605896806;31538.842305788323;34515.309693806485;35033.29933401369;35459.29081418409;35690;30313.545296820124;32374.83812580922;32450.675493244766;32694.34611307808;32899.835500822344;24103.39741506406;24802.37994050091;24810.87594562088;24891.502169956137;25191.622125668495 09:10:28 INFO - PROCESS | 20516 | |2;jquery.html;344.03669724770634;351.7484998226268;362.61924978772294;366.14322485384196;379.8271786337217;13376.933115334421;16486.093264870444;16590.25343859526;16694.248758805854;17850.553736156602;17534.82465175348;17840.92954422732;17899.821001789976;18033.90983045083;18052.819471805276;4759.190937540609;5049.873753156164;5079.746012699368;5098.898022039557;5105;3807.8476860925666;3981.6615587675083;5006.54941055305;5053.747312634372;5739;5999.790007349744;6011.489023433013;6018.3981601839905;6030.216071910645;6032.607880487776;5739.253896993385;5865.648061116347;5890.234269544974;5917.733701983421;5937.85155371117;5174.301469301634;5178.689278643255;5178.715170665612;5179.223116532513;5188.766505507224;20905;20980.370588882357;21036.474088147766;21051.894740526353;21067.894660526752;2520.1809411941194;2575.072973729456;2587.7153513113544;2591.8704064796875;2605.1663467690346;11427.714457108528;12477.688057798629;13662.863371366258;13680.31598420085;13873.098248613807;1682.7475878618184;1755.5435586747537;1772.97340539892;1773.8758286919922;1814.1836173721879;71893.28106718918;73746.26253737448;73874;75317.24682753158;76016.61991690006;9431.48126853016;9622.951885240669;9648.276379271583;9676.806463870824;9762.170215531618;12933.417996190194;12933.611991640266;12942.223466592035;12947.223166610034;12949.352532373343;6477.255115661665;6590.176227971504;6658.766943156967;6746.123004009447;6753.0208119822355;6176.752929882754;6234.751153798179;6344.49244060474;6375.171227740364;6402.167718196604;6035.215421995113;6084.787032453843;6108.969455152695;6131.816045518642;6219.315875253718;1697.6010637500178;1730.679824232521;1743.6599863026843;1766.355280322673;1774.7781527309087;807.5962018990505;821.326512259953;829.7387970285133;859.9570021499026;861.2722249698994;16600.169991500617;16796.916015419843;17126;17702;17750.467485975183;800.2877439079224;804.5896592737746;812.9187081291942;817.6811043692965;835.2148979958819;9673;9737.853932191023;10045.648402305884;10080.14318782897;10253.58985640581;523038;527489;528933;544326;547957;77523;77656.2234377649;82687.58656206801;82862;83339.5833020831;907.3557773980416;920.1074957291551;942.9245660347156;945.550863339908;946.3186505715873;14643.341049652578;14660.70678586401;15019.849801501845;15330.693386131992;15437.15095669749;36899.26201475902;36992.81503592465;37000.62999369972;37552.43671344987;37725.434118488796;49199.26201106915;50274.74313142201;50711.2393314108;50711.492885072155;50743.49256507535;7172.282771722786;7328.303811137934;7564.810879728051;7896.32881205104;7899.368050556057;11941.701457463285;12121.515139394678;12232.510699572273;12318.630441086782;12425;10287.624416893377;10665.733356666145;10693.306096011622;10718.410487423267;10995.175361848052;10401.115905148146;10814.783704325711;10885.727856803644;10935.781284374109;11130.387828669178;10534.631287904887;10633.468326583794;10923.180761442765;11049.668509944715;11129.66611001639;6802.93197068023;6863.416609588241;7000.124984376953;7744.4578879477895;8049.356051515748;10321.380717156995;10665.733356666145;10976.835347469954;11071.446427678746;11247.212695111264 09:10:28 INFO - PROCESS | 20516 | |3;mootools.html;1291.535047382942;1303.3678665847065;1318.2749487781718;1323.1531819635436;1327.104204661853;6543.378379053988;6890.0353950446915;6900.137482814648;6900.585964842113;6904.9654751726175;5888.146218798272;6098.634081955075;6420.7752728654505;6608.636524991123;6618.966905165479;2012.7282816819761;2055.0341339570377;2056.321413933406;2057.4547744847628;2061.597988392264;1958.2558627721446;2058.310465993894;2065.5249292662697;2067.4417907165057;2073.139647046474;1763.8765286429955;1841.1438681013326;1983.7917018713044;2009.25657506722;2070.461679963213;2048.272863133581;2054.9178032878667;2057.609054279682;2057.9485512862298;2060.701198326249;1945.9318923837598;1989.0651393844869;1997.9001049947444;2001.35956493922;2036.4908772806798;6891.37977582015;6925.376716095576;6939.132608423947;6943.201531823855;6949.791506254821;4105.055837157441;4209.536950935425;4209.684273679486;4217.514985776645;4218.873433796991;2614.3202767280454;2705.0937935791535;2725.3322935880838;2726.263908744648;2775.4310366374843;1024.5748014574017;1040.71380370398;1068.1294744782977;1096.555894862574;1097.1497089755424;8525.658973641046;8539.188777066169;8545.2309292164;8567.443116197428;8588.8711669325;3545.8758943436856;3625.220577575834;3684.9815750921075;3690.4833323334756;3745.3071181831447;7768.378529717609;7773.533587984739;7775.455718099792;7789.805254868674;7790.766277011699;4210.621044106045;4245.1934132514725;4253.680973926967;4263.978680106642;4268.445102136702;3745.382011968049;3913.119548101711;3920.4903362562686;3923.1761330120426;3926.764394136361;3235.838208089633;3249.7400207983305;3258.5600943872746;3315.3203593263806;3317.336532693471;1097.8188598881254;1097.961571345015;1100.6697990602788;1103.189155970361;1113.549012649878;780.4653812138703;790.3716545346464;793.5199204481321;800.7397595781395;805.7018903005808;4517.412736344254;4574.153781550423;4673.485916549177;4734.171519984058;4765.666403351732;498.88775025619674;520.2716197323701;880.4497189256715;928.5310917986509;931.0084759731001;3150.6061742282213;3229.838508074587;3232.886848960275;3233.4503134467195;3389.864405423755;8157.347412207009;8201.056878459054;8314.750557483285;8343.374246931504;8350.874736878952;8271.710490132937;8281.751547453587;8290.751277461686;8296.87554686668;8302.128276530877;6457.225132984072;6597.60414375139;6614.768483103165;6618.271990081087;6624.337566243337;4656.161890859677;4734.786934587883;4749.35883655702;4777.761111944458;4837.7581120943105;7110.857782844418;7251.818704532217;7260.854782904418;7388.482806203514;7424.220456851953;7480.588567628833;7515.661795219337;7535.849283014419;7568.772936811684;7572.583507907118;5872.706364681834;6001.639901605918;6053.152558641706;6054.878902422015;6062.878742425215;6424.325445828121;6456.741730330734;6458.54790164684;6463.547551671338;6465;5919.881602367843;6005.699715014319;6020.307664618625;6023.367546407564;6148.44663980244;5886.911696324473;5910.70446477683;5919.763209471573;5958.851028724316;5975.133605627247;5458.208559758733;5542.667439953616;5545.417731138173;5545.778168873361;5553.111502159635;3915.4322623218904;3939.1333906540517;4010.7192496524963;4029.576894426043;4112.856050038235;3904.4143378493454;3965.6629186518317;3999.140184860269;4011.0774521860717;4095.18096380731 09:10:28 INFO - PROCESS | 20516 | |4;prototype.html;322.9660885607011;328.97861638993464;333.17705267988066;336.1327774342196;339.0168511317181;4905.215165573509;4936.13617616917;4936.728479933602;4970.434313191342;4974.527419895113;4828.855134345967;4858.36841210643;4877.317175595419;4895.16782147035;4898.583620392271;3693.4644476550952;3784.451254568086;3800.289869558696;3829.3872980323154;3849.826757795892;3853.614638536152;3865.188310454808;3871.7870517121546;3889.9416508752392;3893.669038131748;3816.370298900678;3833.367494363427;3841.2509560635685;3843.577206507282;3860.9227815443674;3695.4132107524706;3731.1231860512758;3818.6563209311294;3849.7690138591774;3875.4380614810975;3431.365197438482;3435.347284016029;3441.2773317603082;3448.620651728308;3452.9827350863084;4710.552497512731;4714.481407045222;4747.12178247022;4757;4795.856124316276;4310.521450166136;4391.824327026947;4440.800163992628;4451;4460.0633866887665;5063.341765570489;5101.362329708786;5104.591632669378;5115.309433226504;5116.334876466036;3754.530683664542;3758.4214629855255;3764.849406023756;3767.3407153747985;3790.2040571480024;5105.106606343875;5313.867153321159;5343.572514198855;5343.893122137574;5370.892582148335;3823.5794062653085;3838.3858582627204;3851.345271303885;3853.171568112813;3861.826217820205;3902.551206611219;3910.804459777;3917.2557214129224;3922.8035449188274;3927.626875446828;4408.184485870061;4420.093880754438;4421.823127074946;4433.889152771207;4442.489113751895;4828.95171048292;5100.821471248489;5122.103631864409;5125.513076257749;5159.432462429129;1242.1987817857432;1250.831137796404;1252.5803855708439;1255.347219445883;1257.4592925042136;814.2264848393932;818.1736446189392;838.5681374092347;849.4860609331389;856.8457677618088;4219.367094935784;4356.760378179167;4437.511873693891;4444.555544445529;4463.866084017484;5216.321878155815;5237.895242095136;5246;5250.842474725764;5254.448282930313;4433.866983990485;4438.889027774331;4459.665525085632;4460.8661740147845;4467.932981005288;2577.6262441945814;2699.5422471865368;2711.8034392142035;2718.9879489177865;2723.536998710224;4718.386609740781;4736.218523943579;4741.336212930192;4744.95610965587;4756.809727610926;4873.463918968911;5155.407128180233;5205.583553315726;5218.530332270114;5235.528802407802;4650.953535454496;4679.532046795362;4686.742229177292;4690.458703678701;4719.693219940693;5050.924236136388;5097.9490205098955;5105.18317069282;5109.131447653982;5119.590432765444;4872.439669437918;5049.3841970569065;5056.342675452168;5056.6713163644245;5063.645544811975;4847.394075740533;4868.586170175575;4872.658913875995;4874.756262186947;4876.390791038878;3629.292288003814;3647.8176091195865;3651.7036452060197;3656.8902932912056;3670.632936706415;5086.440491546001;5233.267342571967;5239.947600523946;5243.161094225058;5244.685318880879;5049.722265275445;5075.543201111917;5075.934053848733;5077.974610127074;5120.283160357628;5070.41690205631;5075.553467261812;5086.974565127299;5087.489632153802;5107.131787596043;4760.309755085563;5044.798208071636;5049.84850454487;5050.772715227897;5058.898822023465;4837.40365679318;4976.900461990812;4981.6014718823135;5051.722155281494;5075.7969681212335;4964.429090654621;4966.404031516241;4969.279454479152;4986.401631804207;4990.80036798524 09:10:28 INFO - PROCESS | 20516 | |5;yui.html;661.4476911778664;670.4502308107351;724.5507785173194;750.3809357280244;759.2066290726192;12033.759324813509;12610.873891261108;12694.80957785634;12705.682357941045;13410.26243556604;13292.601221963356;13463.932680336584;13464;13489.595312140627;13495.527656532024;815.4658698552457;817.2603793566832;819.3404309530825;819.4304958054155;819.6885183630242;813.190875079294;817.342039658075;817.7628487738549;818.4925346285312;818.5048045932215;692.1693967239307;716.373173473211;828.4035494444021;846.7586737779734;856.1310270075878;844.932405407566;845.6448291717494;845.7166849105557;846.289117141601;846.4032856835915;622.5299898576591;623.8565130020121;624.2914292278251;624.5128799536369;625.1997443272618;9836.754081147958;10112.64605738803;12872.09895307329;13133.86866131336;13151.473941042346;12936.48254069836;13098.214107153506;13282.203067815963;13376.130551514121;13389.19664820114;10309.896901030968;10400.635977740818;10415.687529374056;10521.316114452537;10645.787084258349;3251.3822373748912;3291.9835400822844;3292.193412613901;3297.1757060734817;3406.0633325835347;61940.380596194365;62734;63118;63824;64253.357466425674;9916.504174791231;9975.501224938724;9981.650642227487;10007.949960250153;10219.642312519027;20159.496012599804;20251.392458226277;20251.59496810085;20518.38444846657;20830.791692082887;6320.083587879823;6347.587406818542;6372.490200783926;6448.322926092693;6618.139641846529;3229.370272796782;3294.077658255644;3294.1435226841195;3306.206510437478;3318.4026875162704;2596.5585850405478;2651.8674066296608;2668.3329167708075;2669.119190667075;2669.7330266973536;447.4362303497617;453.0327750253167;458.11355028020887;463.2056023918995;469.7698127917296;590.2032256453753;606.3663471672113;619.2105066040834;619.44250174843;620.1194304088116;7387.2612738726775;7456.552606843498;7690.923090769133;7707.421943354158;7823.960880195563;605.8848818724429;609.1593600830826;623.6195920488499;624.5347216323868;628.1269036039909;3477.1133360992785;3558.964410355915;3586.5696116466193;3653.5067765851268;3752.1557649528636;64121.2672669363;66247.33752662412;67518.32481675218;69210.65394673095;69260.3073969264;56923.43076569181;57899;58175.70912145412;58713.706431467566;59163.70418147882;52302.47697522976;52454.47545524572;52614.21078683823;54166;56120.719396402754;7040.401565866959;7163.713451461883;7201.4958952874895;7259.818504537428;7359.8160045999275;36630.45054324134;37306.25387492181;37404.81297593495;37433;38223.39973505939;44411.55588444074;45577.54422455733;45848.31227531523;45910.77044614756;47900;10276.588936442758;10447.68656940293;10567.63013294531;10801.513931872927;10899.618513351994;11115.666530004113;11141.832872506757;11211.775764484828;11336.829947550958;12073.39633018328;10095.44475053879;10328.122109620766;10595.629152979609;10788.460576971276;10845.349279043283;9601.231901447727;9812.607495700093;9957.950210248902;9969.750756231153;10107.949460252652;9755.122039016238;9788.265880058882;9873.950630246803;10289.639862604772;10326.01902819231;8056.436049476481;8069.112397636138;8363.456375335583;8366.916330836613;8401.95799021001;7214.422846172399;7234.16807067194;7364.631768411451;7443.4789564729945;7637.923620763721 09:10:28 INFO - PROCESS | 20516 | __end_tp_report 09:10:28 INFO - PROCESS | 20516 | __start_cc_report 09:10:28 INFO - PROCESS | 20516 | _x_x_mozilla_cycle_collect,1559 09:10:28 INFO - PROCESS | 20516 | __end_cc_report 09:10:28 INFO - PROCESS | 20516 | __startTimestamp1483636228646__endTimestamp 09:10:28 INFO - PROCESS | 20516 | 09:10:28 INFO - PROCESS | 20516 | ------- Summary: start ------- 09:10:28 INFO - PROCESS | 20516 | Number of tests: 6 09:10:28 INFO - PROCESS | 20516 | 09:10:28 INFO - PROCESS | 20516 | [#0] dojo.html Cycles:165 Average:4680.77 Median:4788.21 stddev:502.60 (10.5%) stddev-sans-first:475.22 09:10:28 INFO - PROCESS | 20516 | Values: 2538.7 2560.9 2592.4 2593.2 2596.2 4608.4 4666.6 4697.9 4700.6 4700.9 4680.9 4693.0 4695.6 4723.6 4780.4 4315.5 4321.1 4322.1 4330.2 4443.3 4389.7 4410.9 4435.7 4469.9 4480.7 4236.0 4342.4 4358.6 4379.0 4453.9 4270.9 4322.5 4325.7 4348.9 4361.0 4330.7 4332.1 4335.2 4384.9 4387.0 4700.1 4705.9 4716.5 4717.0 4730.5 4936.1 4959.7 4987.9 5027.0 5073.2 5104.6 5121.7 5134.6 5137.8 5185.8 4778.1 4786.4 4787.5 4799.8 4837.6 5216.9 5226.2 5229.1 5229.9 5233.8 4854.5 4897.1 4897.4 5020.8 5071.0 4959.4 5010.9 5158.7 5193.0 5208.3 4944.9 5001.6 5033.3 5059.7 5139.5 5048.3 5050.9 5056.7 5091.4 5102.8 4223.9 4315.1 4316.9 4323.9 4368.0 3686.4 3693.2 3700.6 3716.7 3762.4 4375.4 4378.8 4400.8 4412.2 4428.0 5019.1 5027.6 5035.8 5074.3 5088.7 4299.7 4328.2 4337.2 4349.7 4380.3 4556.7 4562.5 4587.0 4591.8 4597.5 4720.0 4721.8 4725.9 4733.5 4760.3 5006.5 5026.6 5050.7 5066.3 5082.7 4703.4 4735.1 4752.0 4759.8 4788.2 5010.7 5026.5 5026.9 5038.8 5059.4 4985.8 5010.7 5089.3 5090.7 5126.0 4879.2 4932.7 4940.0 4948.4 4958.9 4936.3 4938.1 4939.9 4949.0 4957.4 4853.2 4890.2 4896.9 4902.6 4951.8 4932.2 4985.7 4987.3 4999.8 5044.0 4852.8 4876.1 4878.2 4884.7 4894.7 09:10:28 INFO - PROCESS | 20516 | 09:10:28 INFO - PROCESS | 20516 | [#1] ext.html Cycles:180 Average:44630.59 Median:35878.14 stddev:78428.22 (218.6%) stddev-sans-first:78578.36 09:10:28 INFO - PROCESS | 20516 | Values: 538.0 561.9 564.6 574.9 581.6 28568.3 29150.3 38888.8 39251.2 39633.2 39904.2 40304.2 40325.0 40330.2 40374.6 7601.8 7664.2 7738.4 7741.3 7750.3 7445.0 7612.0 7768.9 9965.7 10268.5 10027.8 10095.1 10113.6 10153.1 10173.8 9990.3 10045.1 10049.8 10057.7 10065.8 9795.3 9857.3 9862.7 9867.9 9869.8 36960.0 37247.0 37249.0 37279.4 37311.6 3527.8 3632.8 3754.0 3784.9 3803.9 24128.2 25843.7 25970.0 26017.6 26783.5 3026.3 3074.4 3078.8 3083.6 3181.4 238684.0 239494.0 240167.0 240353.0 240454.0 45767.5 46215.3 46217.0 46269.5 46304.8 48331.0 48366.0 48409.5 48426.5 48448.3 11609.4 11901.0 11906.9 11925.9 11928.3 8308.4 9315.9 9385.7 9427.3 9435.8 13677.9 15033.4 15041.5 15407.8 15881.9 6509.8 6511.5 6551.6 6664.1 6681.4 748.4 761.3 765.4 778.0 781.8 26994.5 27870.4 27988.7 28420.9 28494.6 883.6 954.4 961.7 972.1 978.0 13758.6 14671.2 14725.0 14920.7 14948.1 358053.0 360774.0 361324.0 362297.0 363370.0 49882.0 50400.5 50467.5 50475.0 50482.7 1202.5 1210.6 1217.9 1226.9 1241.9 23338.8 26371.5 27184.4 27848.9 27882.0 41740.0 42255.4 42298.6 42329.6 42361.6 281113.0 281778.0 282123.0 282624.0 284842.0 23681.0 24166.5 24713.1 25059.2 25093.4 39416.6 40632.9 41028.2 44798.6 45208.3 35216.6 35314.8 35823.5 36036.8 36113.3 35492.9 35588.0 35619.5 35877.5 35878.8 31538.8 34515.3 35033.3 35459.3 35690.0 30313.5 32374.8 32450.7 32694.3 32899.8 24103.4 24802.4 24810.9 24891.5 25191.6 09:10:28 INFO - PROCESS | 20516 | 09:10:28 INFO - PROCESS | 20516 | [#2] jquery.html Cycles:180 Average:28747.85 Median:26979.23 stddev:87631.98 (324.8%) stddev-sans-first:87851.85 09:10:28 INFO - PROCESS | 20516 | Values: 344.0 351.7 362.6 366.1 379.8 13376.9 16486.1 16590.3 16694.2 17850.6 17534.8 17840.9 17899.8 18033.9 18052.8 4759.2 5049.9 5079.7 5098.9 5105.0 3807.8 3981.7 5006.5 5053.7 5739.0 5999.8 6011.5 6018.4 6030.2 6032.6 5739.3 5865.6 5890.2 5917.7 5937.9 5174.3 5178.7 5178.7 5179.2 5188.8 20905.0 20980.4 21036.5 21051.9 21067.9 2520.2 2575.1 2587.7 2591.9 2605.2 11427.7 12477.7 13662.9 13680.3 13873.1 1682.7 1755.5 1773.0 1773.9 1814.2 71893.3 73746.3 73874.0 75317.2 76016.6 9431.5 9623.0 9648.3 9676.8 9762.2 12933.4 12933.6 12942.2 12947.2 12949.4 6477.3 6590.2 6658.8 6746.1 6753.0 6176.8 6234.8 6344.5 6375.2 6402.2 6035.2 6084.8 6109.0 6131.8 6219.3 1697.6 1730.7 1743.7 1766.4 1774.8 807.6 821.3 829.7 860.0 861.3 16600.2 16796.9 17126.0 17702.0 17750.5 800.3 804.6 812.9 817.7 835.2 9673.0 9737.9 10045.6 10080.1 10253.6 523038.0 527489.0 528933.0 544326.0 547957.0 77523.0 77656.2 82687.6 82862.0 83339.6 907.4 920.1 942.9 945.6 946.3 14643.3 14660.7 15019.8 15330.7 15437.2 36899.3 36992.8 37000.6 37552.4 37725.4 49199.3 50274.7 50711.2 50711.5 50743.5 7172.3 7328.3 7564.8 7896.3 7899.4 11941.7 12121.5 12232.5 12318.6 12425.0 10287.6 10665.7 10693.3 10718.4 10995.2 10401.1 10814.8 10885.7 10935.8 11130.4 10534.6 10633.5 10923.2 11049.7 11129.7 6802.9 6863.4 7000.1 7744.5 8049.4 10321.4 10665.7 10976.8 11071.4 11247.2 09:10:28 INFO - PROCESS | 20516 | 09:10:28 INFO - PROCESS | 20516 | [#3] mootools.html Cycles:180 Average:4501.41 Median:4742.07 stddev:2385.83 (50.3%) stddev-sans-first:2380.33 09:10:28 INFO - PROCESS | 20516 | Values: 1291.5 1303.4 1318.3 1323.2 1327.1 6543.4 6890.0 6900.1 6900.6 6905.0 5888.1 6098.6 6420.8 6608.6 6619.0 2012.7 2055.0 2056.3 2057.5 2061.6 1958.3 2058.3 2065.5 2067.4 2073.1 1763.9 1841.1 1983.8 2009.3 2070.5 2048.3 2054.9 2057.6 2057.9 2060.7 1945.9 1989.1 1997.9 2001.4 2036.5 6891.4 6925.4 6939.1 6943.2 6949.8 4105.1 4209.5 4209.7 4217.5 4218.9 2614.3 2705.1 2725.3 2726.3 2775.4 1024.6 1040.7 1068.1 1096.6 1097.1 8525.7 8539.2 8545.2 8567.4 8588.9 3545.9 3625.2 3685.0 3690.5 3745.3 7768.4 7773.5 7775.5 7789.8 7790.8 4210.6 4245.2 4253.7 4264.0 4268.4 3745.4 3913.1 3920.5 3923.2 3926.8 3235.8 3249.7 3258.6 3315.3 3317.3 1097.8 1098.0 1100.7 1103.2 1113.5 780.5 790.4 793.5 800.7 805.7 4517.4 4574.2 4673.5 4734.2 4765.7 498.9 520.3 880.4 928.5 931.0 3150.6 3229.8 3232.9 3233.5 3389.9 8157.3 8201.1 8314.8 8343.4 8350.9 8271.7 8281.8 8290.8 8296.9 8302.1 6457.2 6597.6 6614.8 6618.3 6624.3 4656.2 4734.8 4749.4 4777.8 4837.8 7110.9 7251.8 7260.9 7388.5 7424.2 7480.6 7515.7 7535.8 7568.8 7572.6 5872.7 6001.6 6053.2 6054.9 6062.9 6424.3 6456.7 6458.5 6463.5 6465.0 5919.9 6005.7 6020.3 6023.4 6148.4 5886.9 5910.7 5919.8 5958.9 5975.1 5458.2 5542.7 5545.4 5545.8 5553.1 3915.4 3939.1 4010.7 4029.6 4112.9 3904.4 3965.7 3999.1 4011.1 4095.2 09:10:28 INFO - PROCESS | 20516 | 09:10:28 INFO - PROCESS | 20516 | [#4] prototype.html Cycles:180 Average:4217.78 Median:4746.04 stddev:1208.49 (25.5%) stddev-sans-first:1175.99 09:10:28 INFO - PROCESS | 20516 | Values: 323.0 329.0 333.2 336.1 339.0 4905.2 4936.1 4936.7 4970.4 4974.5 4828.9 4858.4 4877.3 4895.2 4898.6 3693.5 3784.5 3800.3 3829.4 3849.8 3853.6 3865.2 3871.8 3889.9 3893.7 3816.4 3833.4 3841.3 3843.6 3860.9 3695.4 3731.1 3818.7 3849.8 3875.4 3431.4 3435.3 3441.3 3448.6 3453.0 4710.6 4714.5 4747.1 4757.0 4795.9 4310.5 4391.8 4440.8 4451.0 4460.1 5063.3 5101.4 5104.6 5115.3 5116.3 3754.5 3758.4 3764.8 3767.3 3790.2 5105.1 5313.9 5343.6 5343.9 5370.9 3823.6 3838.4 3851.3 3853.2 3861.8 3902.6 3910.8 3917.3 3922.8 3927.6 4408.2 4420.1 4421.8 4433.9 4442.5 4829.0 5100.8 5122.1 5125.5 5159.4 1242.2 1250.8 1252.6 1255.3 1257.5 814.2 818.2 838.6 849.5 856.8 4219.4 4356.8 4437.5 4444.6 4463.9 5216.3 5237.9 5246.0 5250.8 5254.4 4433.9 4438.9 4459.7 4460.9 4467.9 2577.6 2699.5 2711.8 2719.0 2723.5 4718.4 4736.2 4741.3 4745.0 4756.8 4873.5 5155.4 5205.6 5218.5 5235.5 4651.0 4679.5 4686.7 4690.5 4719.7 5050.9 5097.9 5105.2 5109.1 5119.6 4872.4 5049.4 5056.3 5056.7 5063.6 4847.4 4868.6 4872.7 4874.8 4876.4 3629.3 3647.8 3651.7 3656.9 3670.6 5086.4 5233.3 5239.9 5243.2 5244.7 5049.7 5075.5 5075.9 5078.0 5120.3 5070.4 5075.6 5087.0 5087.5 5107.1 4760.3 5044.8 5049.8 5050.8 5058.9 4837.4 4976.9 4981.6 5051.7 5075.8 4964.4 4966.4 4969.3 4986.4 4990.8 09:10:28 INFO - PROCESS | 20516 | 09:10:28 INFO - PROCESS | 20516 | [#5] yui.html Cycles:180 Average:14609.76 Median:58037.35 stddev:18875.56 (32.5%) stddev-sans-first:18899.45 09:10:28 INFO - PROCESS | 20516 | Values: 661.4 670.5 724.6 750.4 759.2 12033.8 12610.9 12694.8 12705.7 13410.3 13292.6 13463.9 13464.0 13489.6 13495.5 815.5 817.3 819.3 819.4 819.7 813.2 817.3 817.8 818.5 818.5 692.2 716.4 828.4 846.8 856.1 844.9 845.6 845.7 846.3 846.4 622.5 623.9 624.3 624.5 625.2 9836.8 10112.6 12872.1 13133.9 13151.5 12936.5 13098.2 13282.2 13376.1 13389.2 10309.9 10400.6 10415.7 10521.3 10645.8 3251.4 3292.0 3292.2 3297.2 3406.1 61940.4 62734.0 63118.0 63824.0 64253.4 9916.5 9975.5 9981.7 10007.9 10219.6 20159.5 20251.4 20251.6 20518.4 20830.8 6320.1 6347.6 6372.5 6448.3 6618.1 3229.4 3294.1 3294.1 3306.2 3318.4 2596.6 2651.9 2668.3 2669.1 2669.7 447.4 453.0 458.1 463.2 469.8 590.2 606.4 619.2 619.4 620.1 7387.3 7456.6 7690.9 7707.4 7824.0 605.9 609.2 623.6 624.5 628.1 3477.1 3559.0 3586.6 3653.5 3752.2 64121.3 66247.3 67518.3 69210.7 69260.3 56923.4 57899.0 58175.7 58713.7 59163.7 52302.5 52454.5 52614.2 54166.0 56120.7 7040.4 7163.7 7201.5 7259.8 7359.8 36630.5 37306.3 37404.8 37433.0 38223.4 44411.6 45577.5 45848.3 45910.8 47900.0 10276.6 10447.7 10567.6 10801.5 10899.6 11115.7 11141.8 11211.8 11336.8 12073.4 10095.4 10328.1 10595.6 10788.5 10845.3 9601.2 9812.6 9958.0 9969.8 10107.9 9755.1 9788.3 9874.0 10289.6 10326.0 8056.4 8069.1 8363.5 8366.9 8402.0 7214.4 7234.2 7364.6 7443.5 7637.9 09:10:28 INFO - PROCESS | 20516 | -------- Summary: end -------- 09:10:28 INFO - PROCESS | 20516 | 09:10:29 INFO - TEST-INFO | 20516: exit 0 09:10:29 INFO - TEST-OK | dromaeo_css | took 1120530ms 09:10:29 INFO - TEST-START | kraken 09:10:29 INFO - operating with platform_type : linux_ 09:10:29 INFO - Initialising browser for kraken test... 09:10:29 INFO - TEST-INFO | started process 20821 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV9ZIDA/profile http://localhost:59226/getInfo.html) 09:10:29 INFO - PROCESS | 20821 | 1483636229983 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:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:263 < _startProvider()@resource://gre/modules/AddonManager.jsm:758 < startup()@resource://gre/modules/AddonManager.jsm:940 < startup()@resource://gre/modules/AddonManager.jsm:2913 < observe()@resource://gre/components/addonManager.js:65 09:10:33 INFO - PROCESS | 20821 | __metrics Screen width/height:1600/1200 09:10:33 INFO - PROCESS | 20821 | colorDepth:24 09:10:33 INFO - PROCESS | 20821 | Browser inner width/height: 1024/697 09:10:33 INFO - PROCESS | 20821 | __metrics 09:10:33 INFO - PROCESS | 20821 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:10:33 INFO - PROCESS | 20821 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:10:34 INFO - PROCESS | 20821 | console.error: PushService: 09:10:34 INFO - PROCESS | 20821 | stateChangeProcessEnqueue: Error transitioning state 09:10:34 INFO - PROCESS | 20821 | UnknownError 09:10:34 INFO - TEST-INFO | 20821: exit 0 09:10:34 INFO - Browser initialized. 09:10:34 INFO - Running cycle 1/1 for kraken test... 09:10:34 INFO - TEST-INFO | started process 20905 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV9ZIDA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:46 INFO - PROCESS | 20905 | RSS: Main: 151687168 09:10:46 INFO - PROCESS | 20905 | 09:10:49 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59226/tests/kraken/driver.html?testName=audio-beat-detection) 09:10:50 INFO - PROCESS | 20905 | RSS: Main: 205316096 09:10:50 INFO - PROCESS | 20905 | 09:10:53 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59226/tests/kraken/driver.html?testName=audio-dft) 09:10:53 INFO - PROCESS | 20905 | RSS: Main: 184893440 09:10:53 INFO - PROCESS | 20905 | 09:10:56 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59226/tests/kraken/driver.html?testName=audio-fft) 09:10:57 INFO - PROCESS | 20905 | RSS: Main: 267476992 09:10:57 INFO - PROCESS | 20905 | 09:10:59 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59226/tests/kraken/driver.html?testName=audio-oscillator) 09:11:00 INFO - PROCESS | 20905 | RSS: Main: 179015680 09:11:00 INFO - PROCESS | 20905 | 09:11:02 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:11:03 INFO - PROCESS | 20905 | RSS: Main: 181493760 09:11:03 INFO - PROCESS | 20905 | 09:11:06 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59226/tests/kraken/driver.html?testName=imaging-darkroom) 09:11:06 INFO - PROCESS | 20905 | RSS: Main: 221700096 09:11:06 INFO - PROCESS | 20905 | 09:11:10 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59226/tests/kraken/driver.html?testName=imaging-desaturate) 09:11:10 INFO - PROCESS | 20905 | RSS: Main: 215343104 09:11:10 INFO - PROCESS | 20905 | 09:11:13 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59226/tests/kraken/driver.html?testName=json-parse-financial) 09:11:14 INFO - PROCESS | 20905 | RSS: Main: 216817664 09:11:14 INFO - PROCESS | 20905 | 09:11:16 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:11:16 INFO - PROCESS | 20905 | RSS: Main: 180797440 09:11:16 INFO - PROCESS | 20905 | 09:11:18 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59226/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:11:19 INFO - PROCESS | 20905 | RSS: Main: 187277312 09:11:19 INFO - PROCESS | 20905 | 09:11:21 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:11:22 INFO - PROCESS | 20905 | RSS: Main: 196100096 09:11:22 INFO - PROCESS | 20905 | 09:11:24 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:11:25 INFO - PROCESS | 20905 | RSS: Main: 200310784 09:11:25 INFO - PROCESS | 20905 | 09:11:28 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:11:29 INFO - PROCESS | 20905 | RSS: Main: 202182656 09:11:29 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | Cycle 1(1): loaded http://localhost:59226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59226/tests/kraken/driver.html?testName=ai-astar) 09:11:31 INFO - PROCESS | 20905 | RSS: Main: 254398464 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | __start_tp_report 09:11:31 INFO - PROCESS | 20905 | _x_x_mozilla_page_load 09:11:31 INFO - PROCESS | 20905 | _x_x_mozilla_page_load_details 09:11:31 INFO - PROCESS | 20905 | |i|pagename|runs| 09:11:31 INFO - PROCESS | 20905 | |0;ai-astar;108;118;114;115;114;115;116;115;117;114 09:11:31 INFO - PROCESS | 20905 | |1;audio-beat-detection;156;125;127;168;129;126;130;126;144;129 09:11:31 INFO - PROCESS | 20905 | |2;audio-dft;163;162;162;161;162;161;163;163;161;160 09:11:31 INFO - PROCESS | 20905 | |3;audio-fft;77;76;76;108;77;77;77;75;97;75 09:11:31 INFO - PROCESS | 20905 | |4;audio-oscillator;83;85;106;85;84;85;109;85;84;112 09:11:31 INFO - PROCESS | 20905 | |5;imaging-gaussian-blur;118;118;117;118;116;116;117;117;117;117 09:11:31 INFO - PROCESS | 20905 | |6;imaging-darkroom;151;151;152;152;154;152;154;152;151;149 09:11:31 INFO - PROCESS | 20905 | |7;imaging-desaturate;95;95;96;95;95;95;95;107;96;95 09:11:31 INFO - PROCESS | 20905 | |8;json-parse-financial;65;63;63;63;63;65;65;65;67;64 09:11:31 INFO - PROCESS | 20905 | |9;json-stringify-tinderbox;66;67;67;66;68;67;67;67;69;67 09:11:31 INFO - PROCESS | 20905 | |10;stanford-crypto-aes;84;85;84;84;83;84;84;83;84;83 09:11:31 INFO - PROCESS | 20905 | |11;stanford-crypto-ccm;139;141;145;143;133;131;135;132;230;132 09:11:31 INFO - PROCESS | 20905 | |12;stanford-crypto-pbkdf2;180;181;182;180;184;182;177;181;177;174 09:11:31 INFO - PROCESS | 20905 | |13;stanford-crypto-sha256-iterative;68;68;68;68;67;68;68;68;76;66 09:11:31 INFO - PROCESS | 20905 | __end_tp_report 09:11:31 INFO - PROCESS | 20905 | __start_cc_report 09:11:31 INFO - PROCESS | 20905 | _x_x_mozilla_cycle_collect,1855 09:11:31 INFO - PROCESS | 20905 | __end_cc_report 09:11:31 INFO - PROCESS | 20905 | __startTimestamp1483636291419__endTimestamp 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | ------- Summary: start ------- 09:11:31 INFO - PROCESS | 20905 | Number of tests: 14 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#0] ai-astar Cycles:10 Average:114.60 Median:115.00 stddev:2.67 (2.3%) stddev-sans-first:1.41 09:11:31 INFO - PROCESS | 20905 | Values: 108.0 118.0 114.0 115.0 114.0 115.0 116.0 115.0 117.0 114.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:129.50 stddev:15.00 (11.6%) stddev-sans-first:14.05 09:11:31 INFO - PROCESS | 20905 | Values: 156.0 125.0 127.0 168.0 129.0 126.0 130.0 126.0 144.0 129.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.03 (0.6%) stddev-sans-first:1.00 09:11:31 INFO - PROCESS | 20905 | Values: 163.0 162.0 162.0 161.0 162.0 161.0 163.0 163.0 161.0 160.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#3] audio-fft Cycles:10 Average:81.50 Median:77.00 stddev:11.39 (14.8%) stddev-sans-first:11.97 09:11:31 INFO - PROCESS | 20905 | Values: 77.0 76.0 76.0 108.0 77.0 77.0 77.0 75.0 97.0 75.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:84.50 stddev:11.97 (14.2%) stddev-sans-first:12.27 09:11:31 INFO - PROCESS | 20905 | Values: 83.0 85.0 106.0 85.0 84.0 85.0 109.0 85.0 84.0 112.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 09:11:31 INFO - PROCESS | 20905 | Values: 118.0 118.0 117.0 118.0 116.0 116.0 117.0 117.0 117.0 117.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#6] imaging-darkroom Cycles:10 Average:151.80 Median:152.00 stddev:1.48 (1.0%) stddev-sans-first:1.54 09:11:31 INFO - PROCESS | 20905 | Values: 151.0 151.0 152.0 152.0 154.0 152.0 154.0 152.0 151.0 149.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:95.00 stddev:3.75 (3.9%) stddev-sans-first:3.94 09:11:31 INFO - PROCESS | 20905 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 107.0 96.0 95.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:65.00 stddev:1.34 (2.1%) stddev-sans-first:1.39 09:11:31 INFO - PROCESS | 20905 | Values: 65.0 63.0 63.0 63.0 63.0 65.0 65.0 65.0 67.0 64.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.83 09:11:31 INFO - PROCESS | 20905 | Values: 66.0 67.0 67.0 66.0 68.0 67.0 67.0 67.0 69.0 67.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:0.63 (0.8%) stddev-sans-first:0.67 09:11:31 INFO - PROCESS | 20905 | Values: 84.0 85.0 84.0 84.0 83.0 84.0 84.0 83.0 84.0 83.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:140.00 stddev:29.90 (21.4%) stddev-sans-first:31.60 09:11:31 INFO - PROCESS | 20905 | Values: 139.0 141.0 145.0 143.0 133.0 131.0 135.0 132.0 230.0 132.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.80 Median:181.00 stddev:2.97 (1.6%) stddev-sans-first:3.15 09:11:31 INFO - PROCESS | 20905 | Values: 180.0 181.0 182.0 180.0 184.0 182.0 177.0 181.0 177.0 174.0 09:11:31 INFO - PROCESS | 20905 | 09:11:31 INFO - PROCESS | 20905 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.00 stddev:2.72 (4.0%) stddev-sans-first:2.88 09:11:31 INFO - PROCESS | 20905 | Values: 68.0 68.0 68.0 68.0 67.0 68.0 68.0 68.0 76.0 66.0 09:11:31 INFO - PROCESS | 20905 | -------- Summary: end -------- 09:11:31 INFO - PROCESS | 20905 | 09:11:32 INFO - TEST-INFO | 20905: exit 0 09:11:32 INFO - TEST-OK | kraken | took 63032ms 09:11:32 INFO - SUITE-END | took 1183s 09:11:32 INFO - Completed test suite (00:19:43) 09:11:32 INFO - javascript benchmark 09:11:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2538.695356557213, 2560.9103681371157, 2592.4296654735963, 2593.170185540627, 2596.2470883443802, 4608.377868987685, 4666.603338716205, 4697.929531057032, 4700.589823053083, 4700.905981880364, 4680.9297860532115, 4692.953070469294, 4695.624350051996, 4723.598494128003, 4780.426348838144, 4315.525292217869, 4321.092570560186, 4322.07075478772, 4330.242207613671, 4443.289073748201, 4389.714668546534, 4410.889727756799, 4435.711678740888, 4469.910601787962, 4480.708753930984, 4235.98336399265, 4342.392065110887, 4358.629516491102, 4378.978105109486, 4453.866384008485, 4270.850520231777, 4322.459692538433, 4325.653947684178, 4348.913021739548, 4361.0, 4330.653547716207, 4332.111917057027, 4335.154644844257, 4384.912301753947, 4387.0, 4700.059988002379, 4705.929411058837, 4716.52834716529, 4716.952830471686, 4730.455997560291, 4936.136176169192, 4959.7024178549045, 4987.900241995176, 5027.0, 5073.213651883964, 5104.566111880495, 5121.692698438068, 5134.614903882224, 5137.7944882204665, 5185.792568297264, 4778.068276686013, 4786.425628924517, 4787.521247875185, 4799.760011999386, 4837.564619184291, 5216.895662086813, 5226.189940559219, 5229.084910140787, 5229.895402091937, 5233.816816411408, 4854.490278520704, 4897.094037603055, 4897.3878265216845, 5020.7740651670765, 5070.974645126751, 4959.404871415453, 5010.924836127461, 5158.664686795421, 5193.0, 5208.322918020709, 4944.90110197794, 5001.64988450805, 5033.320501732219, 5059.721715305694, 5139.486051394831, 5048.343715316985, 5050.924236136461, 5056.747162641853, 5091.389033316026, 5102.846914592568, 4223.873283801491, 4315.136972605492, 4316.934976166894, 4323.935140972888, 4368.039031413084, 3686.4470329450796, 3693.224422871227, 3700.5559332880225, 3716.7398282120535, 3762.398016317431, 4375.409319741858, 4378.8029538670835, 4400.801963911632, 4412.161689279091, 4427.977860110679, 5019.07147177766, 5027.648064635514, 5035.77339019745, 5074.314967479417, 5088.6946783193125, 4299.699021068495, 4328.220920234325, 4337.175936572041, 4349.652027837766, 4380.3210502372185, 4556.726596404226, 4562.452505699271, 4586.977065114653, 4591.79336929839, 4597.517260687647, 4719.952800471951, 4721.811127554996, 4725.905481890274, 4733.455652599995, 4760.309755085424, 5006.499350064964, 5026.623003274842, 5050.72221027847, 5066.341375621146, 5082.720450375265, 4703.435587729357, 4735.124002059492, 4751.952480475151, 4759.833405830917, 4788.209945358977, 5010.749462526932, 5026.52248036436, 5026.94434168814, 5038.798448062036, 5059.443461219189, 4985.75071246429, 5010.749462526932, 5089.262057001788, 5090.694558326512, 5125.974370128126, 4879.243717223836, 4932.679375840559, 4939.950600494093, 4948.430930443045, 4958.851234462972, 4936.259561065869, 4938.08645400602, 4939.901201975868, 4949.010197960494, 4957.405111386656, 4853.199222128309, 4890.24201248807, 4896.877578060577, 4902.558769710743, 4951.801927922843, 4932.235503496964, 4985.651004429655, 4987.251912213197, 4999.825006124768, 5044.041632089891, 4852.8301509447, 4876.073546026244, 4878.1950978089635, 4884.706917584814, 4894.657373983787], "value": 4647.360755060058, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.9704116273605, 561.9409961953996, 564.6047766563406, 574.9310082790066, 581.6393835821791, 28568.285792855164, 29150.27124321893, 38888.80555597221, 39251.214975700466, 39633.207335853265, 39904.20191596166, 40304.19391612166, 40325.0, 40330.19339613206, 40374.596254037526, 7601.84796304075, 7664.233576642347, 7738.419618528605, 7741.3419859312025, 7750.302472777449, 7445.0, 7611.961940190291, 7768.883466748003, 9965.651202207924, 10268.486575671186, 10027.849582256273, 10095.141912937372, 10113.646022389217, 10153.086222239997, 10173.796524069514, 9990.300678952477, 10045.14616257619, 10049.849252261221, 10057.698269051903, 10065.849012264822, 9795.265355098396, 9857.309988300822, 9862.704118876374, 9867.851982220272, 9869.753256168653, 36960.0, 37247.0, 37249.0, 37279.440808387895, 37311.626883731355, 3527.8236088195745, 3632.8183590820354, 3753.9812300938593, 3784.9023783102866, 3803.9429408558894, 24128.155514556907, 25843.74156258451, 25970.0, 26017.609735853977, 26783.464330713276, 3026.2888221267986, 3074.3697542003833, 3078.7690923180853, 3083.5991321128336, 3181.427343078245, 238684.0, 239493.99999999825, 240167.0, 240353.0, 240454.0, 45767.54232457633, 46215.30677039847, 46217.0, 46269.53730462719, 46304.768476158075, 48331.0, 48366.0, 48409.5159048412, 48426.5157348422, 48448.27327590089, 11609.3614851184, 11901.0, 11906.940465297617, 11925.940370298267, 11928.343941083323, 8308.418410711192, 9315.860262096074, 9385.671501497414, 9427.292953028551, 9435.811283774286, 13677.863221367857, 15033.398664053315, 15041.47354842597, 15407.768883466757, 15881.920590397205, 6509.772157974448, 6511.479081673455, 6551.6069035858, 6664.100346453264, 6681.432078273304, 748.4424104042523, 761.2615762710162, 765.4374035084209, 778.0391216847189, 781.7654703588901, 26994.460110797674, 27870.442591148065, 27988.72011279902, 28420.857895710804, 28494.572581411296, 883.5626364949392, 954.3659499780655, 961.7307153996892, 972.0959507657805, 977.9559919803626, 13758.587242382744, 14671.193084380247, 14725.0, 14920.70158596822, 14948.10311381299, 358053.0, 360774.0, 361324.0, 362297.0, 363370.0, 49882.0, 50400.49599504031, 50467.49532504701, 50475.0, 50482.74758626183, 1202.5307136160861, 1210.6368089573093, 1217.896478799312, 1226.8528925454727, 1241.9068569857297, 23338.766612334337, 26371.472570548864, 27184.36893786379, 27848.860755696904, 27882.0, 41740.0, 42255.366169508095, 42298.577014229464, 42329.5767042338, 42361.57638423576, 281113.0, 281778.0, 282123.0, 282624.0, 284842.0, 23681.0, 24166.51666966686, 24713.135040274225, 25059.248222553353, 25093.372665682775, 39416.60583394129, 40632.92524043041, 41028.17943641051, 44798.552014479435, 45208.32187517124, 35216.64783352134, 35314.82342588271, 35823.46264805978, 36036.81981590075, 36113.27773444569, 35492.93521194368, 35588.0, 35619.46570801492, 35877.461838071926, 35878.820605896806, 31538.842305788323, 34515.309693806485, 35033.29933401369, 35459.29081418409, 35690.0, 30313.545296820124, 32374.83812580922, 32450.675493244766, 32694.34611307808, 32899.835500822344, 24103.39741506406, 24802.37994050091, 24810.87594562088, 24891.502169956137, 25191.622125668495], "value": 17116.63303017424, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.03669724770634, 351.7484998226268, 362.61924978772294, 366.14322485384196, 379.8271786337217, 13376.933115334421, 16486.093264870444, 16590.25343859526, 16694.248758805854, 17850.553736156602, 17534.82465175348, 17840.92954422732, 17899.821001789976, 18033.90983045083, 18052.819471805276, 4759.190937540609, 5049.873753156164, 5079.746012699368, 5098.898022039557, 5105.0, 3807.8476860925666, 3981.6615587675083, 5006.54941055305, 5053.747312634372, 5739.0, 5999.790007349744, 6011.489023433013, 6018.3981601839905, 6030.216071910645, 6032.607880487776, 5739.253896993385, 5865.648061116347, 5890.234269544974, 5917.733701983421, 5937.85155371117, 5174.301469301634, 5178.689278643255, 5178.715170665612, 5179.223116532513, 5188.766505507224, 20905.0, 20980.370588882357, 21036.474088147766, 21051.894740526353, 21067.894660526752, 2520.1809411941194, 2575.072973729456, 2587.7153513113544, 2591.8704064796875, 2605.1663467690346, 11427.714457108528, 12477.688057798629, 13662.863371366258, 13680.31598420085, 13873.098248613807, 1682.7475878618184, 1755.5435586747537, 1772.97340539892, 1773.8758286919922, 1814.1836173721879, 71893.28106718918, 73746.26253737448, 73874.0, 75317.24682753158, 76016.61991690006, 9431.48126853016, 9622.951885240669, 9648.276379271583, 9676.806463870824, 9762.170215531618, 12933.417996190194, 12933.611991640266, 12942.223466592035, 12947.223166610034, 12949.352532373343, 6477.255115661665, 6590.176227971504, 6658.766943156967, 6746.123004009447, 6753.0208119822355, 6176.752929882754, 6234.751153798179, 6344.49244060474, 6375.171227740364, 6402.167718196604, 6035.215421995113, 6084.787032453843, 6108.969455152695, 6131.816045518642, 6219.315875253718, 1697.6010637500178, 1730.679824232521, 1743.6599863026843, 1766.355280322673, 1774.7781527309087, 807.5962018990505, 821.326512259953, 829.7387970285133, 859.9570021499026, 861.2722249698994, 16600.169991500617, 16796.916015419843, 17126.0, 17702.0, 17750.467485975183, 800.2877439079224, 804.5896592737746, 812.9187081291942, 817.6811043692965, 835.2148979958819, 9673.0, 9737.853932191023, 10045.648402305884, 10080.14318782897, 10253.58985640581, 523038.0, 527489.0, 528933.0, 544326.0, 547957.0, 77523.0, 77656.2234377649, 82687.58656206801, 82862.0, 83339.5833020831, 907.3557773980416, 920.1074957291551, 942.9245660347156, 945.550863339908, 946.3186505715873, 14643.341049652578, 14660.70678586401, 15019.849801501845, 15330.693386131992, 15437.15095669749, 36899.26201475902, 36992.81503592465, 37000.62999369972, 37552.43671344987, 37725.434118488796, 49199.26201106915, 50274.74313142201, 50711.2393314108, 50711.492885072155, 50743.49256507535, 7172.282771722786, 7328.303811137934, 7564.810879728051, 7896.32881205104, 7899.368050556057, 11941.701457463285, 12121.515139394678, 12232.510699572273, 12318.630441086782, 12425.0, 10287.624416893377, 10665.733356666145, 10693.306096011622, 10718.410487423267, 10995.175361848052, 10401.115905148146, 10814.783704325711, 10885.727856803644, 10935.781284374109, 11130.387828669178, 10534.631287904887, 10633.468326583794, 10923.180761442765, 11049.668509944715, 11129.66611001639, 6802.93197068023, 6863.416609588241, 7000.124984376953, 7744.4578879477895, 8049.356051515748, 10321.380717156995, 10665.733356666145, 10976.835347469954, 11071.446427678746, 11247.212695111264], "value": 8495.986349873769, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1291.535047382942, 1303.3678665847065, 1318.2749487781718, 1323.1531819635436, 1327.104204661853, 6543.378379053988, 6890.0353950446915, 6900.137482814648, 6900.585964842113, 6904.9654751726175, 5888.146218798272, 6098.634081955075, 6420.7752728654505, 6608.636524991123, 6618.966905165479, 2012.7282816819761, 2055.0341339570377, 2056.321413933406, 2057.4547744847628, 2061.597988392264, 1958.2558627721446, 2058.310465993894, 2065.5249292662697, 2067.4417907165057, 2073.139647046474, 1763.8765286429955, 1841.1438681013326, 1983.7917018713044, 2009.25657506722, 2070.461679963213, 2048.272863133581, 2054.9178032878667, 2057.609054279682, 2057.9485512862298, 2060.701198326249, 1945.9318923837598, 1989.0651393844869, 1997.9001049947444, 2001.35956493922, 2036.4908772806798, 6891.37977582015, 6925.376716095576, 6939.132608423947, 6943.201531823855, 6949.791506254821, 4105.055837157441, 4209.536950935425, 4209.684273679486, 4217.514985776645, 4218.873433796991, 2614.3202767280454, 2705.0937935791535, 2725.3322935880838, 2726.263908744648, 2775.4310366374843, 1024.5748014574017, 1040.71380370398, 1068.1294744782977, 1096.555894862574, 1097.1497089755424, 8525.658973641046, 8539.188777066169, 8545.2309292164, 8567.443116197428, 8588.8711669325, 3545.8758943436856, 3625.220577575834, 3684.9815750921075, 3690.4833323334756, 3745.3071181831447, 7768.378529717609, 7773.533587984739, 7775.455718099792, 7789.805254868674, 7790.766277011699, 4210.621044106045, 4245.1934132514725, 4253.680973926967, 4263.978680106642, 4268.445102136702, 3745.382011968049, 3913.119548101711, 3920.4903362562686, 3923.1761330120426, 3926.764394136361, 3235.838208089633, 3249.7400207983305, 3258.5600943872746, 3315.3203593263806, 3317.336532693471, 1097.8188598881254, 1097.961571345015, 1100.6697990602788, 1103.189155970361, 1113.549012649878, 780.4653812138703, 790.3716545346464, 793.5199204481321, 800.7397595781395, 805.7018903005808, 4517.412736344254, 4574.153781550423, 4673.485916549177, 4734.171519984058, 4765.666403351732, 498.88775025619674, 520.2716197323701, 880.4497189256715, 928.5310917986509, 931.0084759731001, 3150.6061742282213, 3229.838508074587, 3232.886848960275, 3233.4503134467195, 3389.864405423755, 8157.347412207009, 8201.056878459054, 8314.750557483285, 8343.374246931504, 8350.874736878952, 8271.710490132937, 8281.751547453587, 8290.751277461686, 8296.87554686668, 8302.128276530877, 6457.225132984072, 6597.60414375139, 6614.768483103165, 6618.271990081087, 6624.337566243337, 4656.161890859677, 4734.786934587883, 4749.35883655702, 4777.761111944458, 4837.7581120943105, 7110.857782844418, 7251.818704532217, 7260.854782904418, 7388.482806203514, 7424.220456851953, 7480.588567628833, 7515.661795219337, 7535.849283014419, 7568.772936811684, 7572.583507907118, 5872.706364681834, 6001.639901605918, 6053.152558641706, 6054.878902422015, 6062.878742425215, 6424.325445828121, 6456.741730330734, 6458.54790164684, 6463.547551671338, 6465.0, 5919.881602367843, 6005.699715014319, 6020.307664618625, 6023.367546407564, 6148.44663980244, 5886.911696324473, 5910.70446477683, 5919.763209471573, 5958.851028724316, 5975.133605627247, 5458.208559758733, 5542.667439953616, 5545.417731138173, 5545.778168873361, 5553.111502159635, 3915.4322623218904, 3939.1333906540517, 4010.7192496524963, 4029.576894426043, 4112.856050038235, 3904.4143378493454, 3965.6629186518317, 3999.140184860269, 4011.0774521860717, 4095.18096380731], "value": 3704.83934702489, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.9660885607011, 328.97861638993464, 333.17705267988066, 336.1327774342196, 339.0168511317181, 4905.215165573509, 4936.13617616917, 4936.728479933602, 4970.434313191342, 4974.527419895113, 4828.855134345967, 4858.36841210643, 4877.317175595419, 4895.16782147035, 4898.583620392271, 3693.4644476550952, 3784.451254568086, 3800.289869558696, 3829.3872980323154, 3849.826757795892, 3853.614638536152, 3865.188310454808, 3871.7870517121546, 3889.9416508752392, 3893.669038131748, 3816.370298900678, 3833.367494363427, 3841.2509560635685, 3843.577206507282, 3860.9227815443674, 3695.4132107524706, 3731.1231860512758, 3818.6563209311294, 3849.7690138591774, 3875.4380614810975, 3431.365197438482, 3435.347284016029, 3441.2773317603082, 3448.620651728308, 3452.9827350863084, 4710.552497512731, 4714.481407045222, 4747.12178247022, 4757.0, 4795.856124316276, 4310.521450166136, 4391.824327026947, 4440.800163992628, 4451.0, 4460.0633866887665, 5063.341765570489, 5101.362329708786, 5104.591632669378, 5115.309433226504, 5116.334876466036, 3754.530683664542, 3758.4214629855255, 3764.849406023756, 3767.3407153747985, 3790.2040571480024, 5105.106606343875, 5313.867153321159, 5343.572514198855, 5343.893122137574, 5370.892582148335, 3823.5794062653085, 3838.3858582627204, 3851.345271303885, 3853.171568112813, 3861.826217820205, 3902.551206611219, 3910.804459777, 3917.2557214129224, 3922.8035449188274, 3927.626875446828, 4408.184485870061, 4420.093880754438, 4421.823127074946, 4433.889152771207, 4442.489113751895, 4828.95171048292, 5100.821471248489, 5122.103631864409, 5125.513076257749, 5159.432462429129, 1242.1987817857432, 1250.831137796404, 1252.5803855708439, 1255.347219445883, 1257.4592925042136, 814.2264848393932, 818.1736446189392, 838.5681374092347, 849.4860609331389, 856.8457677618088, 4219.367094935784, 4356.760378179167, 4437.511873693891, 4444.555544445529, 4463.866084017484, 5216.321878155815, 5237.895242095136, 5246.0, 5250.842474725764, 5254.448282930313, 4433.866983990485, 4438.889027774331, 4459.665525085632, 4460.8661740147845, 4467.932981005288, 2577.6262441945814, 2699.5422471865368, 2711.8034392142035, 2718.9879489177865, 2723.536998710224, 4718.386609740781, 4736.218523943579, 4741.336212930192, 4744.95610965587, 4756.809727610926, 4873.463918968911, 5155.407128180233, 5205.583553315726, 5218.530332270114, 5235.528802407802, 4650.953535454496, 4679.532046795362, 4686.742229177292, 4690.458703678701, 4719.693219940693, 5050.924236136388, 5097.9490205098955, 5105.18317069282, 5109.131447653982, 5119.590432765444, 4872.439669437918, 5049.3841970569065, 5056.342675452168, 5056.6713163644245, 5063.645544811975, 4847.394075740533, 4868.586170175575, 4872.658913875995, 4874.756262186947, 4876.390791038878, 3629.292288003814, 3647.8176091195865, 3651.7036452060197, 3656.8902932912056, 3670.632936706415, 5086.440491546001, 5233.267342571967, 5239.947600523946, 5243.161094225058, 5244.685318880879, 5049.722265275445, 5075.543201111917, 5075.934053848733, 5077.974610127074, 5120.283160357628, 5070.41690205631, 5075.553467261812, 5086.974565127299, 5087.489632153802, 5107.131787596043, 4760.309755085563, 5044.798208071636, 5049.84850454487, 5050.772715227897, 5058.898822023465, 4837.40365679318, 4976.900461990812, 4981.6014718823135, 5051.722155281494, 5075.7969681212335, 4964.429090654621, 4966.404031516241, 4969.279454479152, 4986.401631804207, 4990.80036798524], "value": 3836.9923980414837, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.4476911778664, 670.4502308107351, 724.5507785173194, 750.3809357280244, 759.2066290726192, 12033.759324813509, 12610.873891261108, 12694.80957785634, 12705.682357941045, 13410.26243556604, 13292.601221963356, 13463.932680336584, 13464.0, 13489.595312140627, 13495.527656532024, 815.4658698552457, 817.2603793566832, 819.3404309530825, 819.4304958054155, 819.6885183630242, 813.190875079294, 817.342039658075, 817.7628487738549, 818.4925346285312, 818.5048045932215, 692.1693967239307, 716.373173473211, 828.4035494444021, 846.7586737779734, 856.1310270075878, 844.932405407566, 845.6448291717494, 845.7166849105557, 846.289117141601, 846.4032856835915, 622.5299898576591, 623.8565130020121, 624.2914292278251, 624.5128799536369, 625.1997443272618, 9836.754081147958, 10112.64605738803, 12872.09895307329, 13133.86866131336, 13151.473941042346, 12936.48254069836, 13098.214107153506, 13282.203067815963, 13376.130551514121, 13389.19664820114, 10309.896901030968, 10400.635977740818, 10415.687529374056, 10521.316114452537, 10645.787084258349, 3251.3822373748912, 3291.9835400822844, 3292.193412613901, 3297.1757060734817, 3406.0633325835347, 61940.380596194365, 62734.0, 63118.0, 63824.0, 64253.357466425674, 9916.504174791231, 9975.501224938724, 9981.650642227487, 10007.949960250153, 10219.642312519027, 20159.496012599804, 20251.392458226277, 20251.59496810085, 20518.38444846657, 20830.791692082887, 6320.083587879823, 6347.587406818542, 6372.490200783926, 6448.322926092693, 6618.139641846529, 3229.370272796782, 3294.077658255644, 3294.1435226841195, 3306.206510437478, 3318.4026875162704, 2596.5585850405478, 2651.8674066296608, 2668.3329167708075, 2669.119190667075, 2669.7330266973536, 447.4362303497617, 453.0327750253167, 458.11355028020887, 463.2056023918995, 469.7698127917296, 590.2032256453753, 606.3663471672113, 619.2105066040834, 619.44250174843, 620.1194304088116, 7387.2612738726775, 7456.552606843498, 7690.923090769133, 7707.421943354158, 7823.960880195563, 605.8848818724429, 609.1593600830826, 623.6195920488499, 624.5347216323868, 628.1269036039909, 3477.1133360992785, 3558.964410355915, 3586.5696116466193, 3653.5067765851268, 3752.1557649528636, 64121.2672669363, 66247.33752662412, 67518.32481675218, 69210.65394673095, 69260.3073969264, 56923.43076569181, 57899.0, 58175.70912145412, 58713.706431467566, 59163.70418147882, 52302.47697522976, 52454.47545524572, 52614.21078683823, 54166.0, 56120.719396402754, 7040.401565866959, 7163.713451461883, 7201.4958952874895, 7259.818504537428, 7359.8160045999275, 36630.45054324134, 37306.25387492181, 37404.81297593495, 37433.0, 38223.39973505939, 44411.55588444074, 45577.54422455733, 45848.31227531523, 45910.77044614756, 47900.0, 10276.588936442758, 10447.68656940293, 10567.63013294531, 10801.513931872927, 10899.618513351994, 11115.666530004113, 11141.832872506757, 11211.775764484828, 11336.829947550958, 12073.39633018328, 10095.44475053879, 10328.122109620766, 10595.629152979609, 10788.460576971276, 10845.349279043283, 9601.231901447727, 9812.607495700093, 9957.950210248902, 9969.750756231153, 10107.949460252652, 9755.122039016238, 9788.265880058882, 9873.950630246803, 10289.639862604772, 10326.01902819231, 8056.436049476481, 8069.112397636138, 8363.456375335583, 8366.916330836613, 8401.95799021001, 7214.422846172399, 7234.16807067194, 7364.631768411451, 7443.4789564729945, 7637.923620763721], "value": 6033.393954397941, "unit": "score"}], "name": "dromaeo_css", "value": 6221.12601751776, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 118.0, 114.0, 115.0, 114.0, 115.0, 116.0, 115.0, 117.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 125.0, 127.0, 168.0, 129.0, 126.0, 130.0, 126.0, 144.0, 129.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 161.0, 162.0, 161.0, 163.0, 163.0, 161.0, 160.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 108.0, 77.0, 77.0, 77.0, 75.0, 97.0, 75.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 106.0, 85.0, 84.0, 85.0, 109.0, 85.0, 84.0, 112.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 118.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 152.0, 152.0, 154.0, 152.0, 154.0, 152.0, 151.0, 149.0], "value": 151.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 107.0, 96.0, 95.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 63.0, 63.0, 63.0, 65.0, 65.0, 65.0, 67.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 68.0, 67.0, 67.0, 67.0, 69.0, 67.0], "value": 67.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 83.0, 84.0, 84.0, 83.0, 84.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 141.0, 145.0, 143.0, 133.0, 131.0, 135.0, 132.0, 230.0, 132.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 181.0, 182.0, 180.0, 184.0, 182.0, 177.0, 181.0, 177.0, 174.0], "value": 179.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 67.0, 68.0, 68.0, 68.0, 76.0, 66.0], "value": 68.5, "unit": "score"}], "name": "kraken", "value": 1560.5999999999997, "alertThreshold": 2.0}]} 09:11:32 INFO - Return code: 0 09:11:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:11:32 INFO - # TBPL SUCCESS # 09:11:32 INFO - Running post-action listener: _resource_record_post_action 09:11:32 INFO - [mozharness: 2017-01-05 17:11:32.726013Z] Finished run-tests step (success) 09:11:32 INFO - Running post-run listener: _resource_record_post_run 09:11:32 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 8192; Write bytes: 385044480; Read time: 92; Write time: 240076 09:11:32 INFO - TinderboxPrint: CPU usage
13.1% 09:11:32 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 92 09:11:32 INFO - TinderboxPrint: I/O write bytes / time
385,044,480 / 240,076 09:11:32 INFO - TinderboxPrint: CPU idle
8,324.1 (86.9%) 09:11:32 INFO - TinderboxPrint: CPU user
1,212.0 (12.7%) 09:11:32 INFO - TinderboxPrint: Swap in / out
0 / 0 09:11:32 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99889152; Read time: 0; Write time: 93144 09:11:32 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285155328; Read time: 84; Write time: 146932 09:11:33 INFO - Running post-run listener: _upload_blobber_files 09:11:33 INFO - Blob upload gear active. 09:11:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:11:33 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:11:33 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'] 09:11:33 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 09:11:33 INFO - (blobuploader) - INFO - Open directory for files ... 09:11:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:11:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:11:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:34 INFO - (blobuploader) - INFO - Done attempting. 09:11:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:11:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:11:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:37 INFO - (blobuploader) - INFO - Done attempting. 09:11:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:11:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:11:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:38 INFO - (blobuploader) - INFO - Done attempting. 09:11:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:11:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:11:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:39 INFO - (blobuploader) - INFO - Done attempting. 09:11:39 INFO - (blobuploader) - INFO - Iteration through files over. 09:11:39 INFO - Return code: 0 09:11:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:11:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:11:39 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2806ce7c70c43d3427b47544e82c9a0f493b440bbeb0efd6ff6516f309d4d97a91b5362817aa78520f9eaee1cf7633bbf5565d6a7b134f4f01e45571dd685d7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a2a66aa9ad6b5ac6b1b715aca59ce40d5def0abfe9b024041025f5b19fd72dc81effbd1090b2ff053dbff82250d6c254e62dbebd8d0758f04db73a255f307d8a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/14055653f2027d5ce4c9b0e0f6912778508434075e24a5ba77daae8a9128eae0ec21d40e0513ada41b1f16d7fc56d041802d31e7475dea2ceec460c64cc69f6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0349b58e31971f75c85bc9ce8f1f361e8a5e0d804001534a2192c17a3b562dfabf7c81d017f3a7a6971c21925c5998ef2def90bc512740e8060e9d03950cce8a"} 09:11:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:11:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:11:39 INFO - Contents: 09:11:39 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2806ce7c70c43d3427b47544e82c9a0f493b440bbeb0efd6ff6516f309d4d97a91b5362817aa78520f9eaee1cf7633bbf5565d6a7b134f4f01e45571dd685d7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a2a66aa9ad6b5ac6b1b715aca59ce40d5def0abfe9b024041025f5b19fd72dc81effbd1090b2ff053dbff82250d6c254e62dbebd8d0758f04db73a255f307d8a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/14055653f2027d5ce4c9b0e0f6912778508434075e24a5ba77daae8a9128eae0ec21d40e0513ada41b1f16d7fc56d041802d31e7475dea2ceec460c64cc69f6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0349b58e31971f75c85bc9ce8f1f361e8a5e0d804001534a2192c17a3b562dfabf7c81d017f3a7a6971c21925c5998ef2def90bc512740e8060e9d03950cce8a"} 09:11:39 INFO - Running post-run listener: copy_logs_to_upload_dir 09:11:39 INFO - Copying logs to upload dir... 09:11:39 INFO - mkdir: /builds/slave/test/build/upload/logs 09:11:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.686355 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-01-05 09:11:39.376893) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 09:11:39.380746) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843 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/2806ce7c70c43d3427b47544e82c9a0f493b440bbeb0efd6ff6516f309d4d97a91b5362817aa78520f9eaee1cf7633bbf5565d6a7b134f4f01e45571dd685d7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a2a66aa9ad6b5ac6b1b715aca59ce40d5def0abfe9b024041025f5b19fd72dc81effbd1090b2ff053dbff82250d6c254e62dbebd8d0758f04db73a255f307d8a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/14055653f2027d5ce4c9b0e0f6912778508434075e24a5ba77daae8a9128eae0ec21d40e0513ada41b1f16d7fc56d041802d31e7475dea2ceec460c64cc69f6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0349b58e31971f75c85bc9ce8f1f361e8a5e0d804001534a2192c17a3b562dfabf7c81d017f3a7a6971c21925c5998ef2def90bc512740e8060e9d03950cce8a"} build_url:https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011145 build_url: 'https://queue.taskcluster.net/v1/task/RFwXurLSSei6aewabJj5sA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2806ce7c70c43d3427b47544e82c9a0f493b440bbeb0efd6ff6516f309d4d97a91b5362817aa78520f9eaee1cf7633bbf5565d6a7b134f4f01e45571dd685d7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a2a66aa9ad6b5ac6b1b715aca59ce40d5def0abfe9b024041025f5b19fd72dc81effbd1090b2ff053dbff82250d6c254e62dbebd8d0758f04db73a255f307d8a", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/14055653f2027d5ce4c9b0e0f6912778508434075e24a5ba77daae8a9128eae0ec21d40e0513ada41b1f16d7fc56d041802d31e7475dea2ceec460c64cc69f6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0349b58e31971f75c85bc9ce8f1f361e8a5e0d804001534a2192c17a3b562dfabf7c81d017f3a7a6971c21925c5998ef2def90bc512740e8060e9d03950cce8a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 09:11:39.407516) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 09:11:39.407852) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1483635052.941715-1178756843 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 09:11:39.492488) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 09:11:39.492824) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 09:11:39.493179) ========= ========= Total master_lag: 0.18 =========