builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1491930282.89 results: success (0) revision: c876f8b62d63bc7ba2dd15870add8e461da05427 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:42.891429) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:42.891865) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:42.892150) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:42.906693) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:42.906995) ========= 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=df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-11 10:04:43-- 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% 39.8M=0s 2017-04-11 10:04:43 (39.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154439 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:43.075055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:43.075359) ========= 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=df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017616 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:43.124598) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 10:04:43.125229) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c876f8b62d63bc7ba2dd15870add8e461da05427 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c876f8b62d63bc7ba2dd15870add8e461da05427 --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=df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-11 10:04:43,291 truncating revision to first 12 chars 2017-04-11 10:04:43,291 Setting DEBUG logging. 2017-04-11 10:04:43,291 attempt 1/10 2017-04-11 10:04:43,291 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c876f8b62d63?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 10:04:44,364 unpacking tar archive at: mozilla-aurora-c876f8b62d63/testing/mozharness/ program finished with exit code 0 elapsedTime=1.646489 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 10:04:44.786567) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:44.786894) ========= script_repo_revision: c876f8b62d63bc7ba2dd15870add8e461da05427 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:44.787255) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:44.787514) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 10:04:44.795502) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-04-11 10:04:44.795860) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:04:45 INFO - MultiFileLogger online at 20170411 10:04:45 in /builds/slave/test 10:04:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:04:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:04:45 INFO - {'append_to_log': False, 10:04:45 INFO - 'base_work_dir': '/builds/slave/test', 10:04:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:04:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:04:45 INFO - 'branch': 'Mozilla-Aurora', 10:04:45 INFO - 'buildbot_json_path': 'buildprops.json', 10:04:45 INFO - 'config_files': ('talos/linux_config.py',), 10:04:45 INFO - 'default_actions': ('clobber', 10:04:45 INFO - 'read-buildbot-config', 10:04:45 INFO - 'download-and-extract', 10:04:45 INFO - 'populate-webroot', 10:04:45 INFO - 'create-virtualenv', 10:04:45 INFO - 'install', 10:04:45 INFO - 'run-tests'), 10:04:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:04:45 INFO - 'download_minidump_stackwalk': True, 10:04:45 INFO - 'download_symbols': 'ondemand', 10:04:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:04:45 INFO - 'tooltool.py': '/tools/tooltool.py', 10:04:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:04:45 INFO - '/tools/misc-python/virtualenv.py')}, 10:04:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:04:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:04:45 INFO - 'gecko_profile': False, 10:04:45 INFO - 'gecko_profile_interval': 0, 10:04:45 INFO - 'installer_path': 'installer.exe', 10:04:45 INFO - 'log_level': 'info', 10:04:45 INFO - 'log_name': 'talos', 10:04:45 INFO - 'log_to_console': True, 10:04:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:04:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:04:45 INFO - 'opt_config_files': (), 10:04:45 INFO - 'pip_index': False, 10:04:45 INFO - 'suite': 'dromaeojs', 10:04:45 INFO - 'system_bits': '32', 10:04:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:04:45 INFO - 'title': 'talos-linux64-ix-021', 10:04:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:04:45 INFO - 'use_talos_json': True, 10:04:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:04:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:04:45 INFO - 'work_dir': 'build'} 10:04:45 INFO - [mozharness: 2017-04-11 17:04:45.065632Z] Running clobber step. 10:04:45 INFO - Running pre-action listener: _resource_record_pre_action 10:04:45 INFO - Running main action method: clobber 10:04:45 INFO - rmtree: /builds/slave/test/build 10:04:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:04:46 INFO - Running post-action listener: _resource_record_post_action 10:04:46 INFO - [mozharness: 2017-04-11 17:04:46.005305Z] Finished clobber step (success) 10:04:46 INFO - [mozharness: 2017-04-11 17:04:46.005405Z] Running read-buildbot-config step. 10:04:46 INFO - Running pre-action listener: _resource_record_pre_action 10:04:46 INFO - Running main action method: read_buildbot_config 10:04:46 INFO - Using buildbot properties: 10:04:46 INFO - { 10:04:46 INFO - "project": "", 10:04:46 INFO - "product": "firefox", 10:04:46 INFO - "who": "ffxbld@noreply.mozilla.org", 10:04:46 INFO - "installer_path": "public/build/target.tar.bz2", 10:04:46 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 10:04:46 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 10:04:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 10:04:46 INFO - "stage_platform": "linux64-pgo", 10:04:46 INFO - "basedir": "/builds/slave/test", 10:04:46 INFO - "buildnumber": 880, 10:04:46 INFO - "platform": "ubuntu64_hw", 10:04:46 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:04:46 INFO - "slavebuilddir": "test", 10:04:46 INFO - "taskId": "JwZgbAAiQ-etgFrxMa2xlw", 10:04:46 INFO - "branch": "mozilla-aurora", 10:04:46 INFO - "script_repo_revision": "production", 10:04:46 INFO - "revision": "c876f8b62d63bc7ba2dd15870add8e461da05427", 10:04:46 INFO - "slavename": "talos-linux64-ix-021", 10:04:46 INFO - "repo_path": "releases/mozilla-aurora" 10:04:46 INFO - } 10:04:46 INFO - Finding installer, test and symbols from parent task. 10:04:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JwZgbAAiQ-etgFrxMa2xlw'}, attempt #1 10:04:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JwZgbAAiQ-etgFrxMa2xlw'}, attempt #1 10:04:46 INFO - Task dependencies: X8gTuUkYRlCdtTMRTQGOpA 10:04:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA'}, attempt #1 10:04:47 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.tar.bz2 10:04:47 INFO - Set test_url: https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/test_packages.json 10:04:47 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.crashreporter-symbols.zip 10:04:47 INFO - Running post-action listener: _resource_record_post_action 10:04:47 INFO - [mozharness: 2017-04-11 17:04:47.384859Z] Finished read-buildbot-config step (success) 10:04:47 INFO - [mozharness: 2017-04-11 17:04:47.385077Z] Running download-and-extract step. 10:04:47 INFO - Running pre-action listener: _resource_record_pre_action 10:04:47 INFO - Running main action method: download_and_extract 10:04:47 INFO - mkdir: /builds/slave/test/build/tests 10:04:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:47 INFO - trying https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.test_packages.json 10:04:47 INFO - Downloading https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:04:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:04:47 INFO - Downloaded 896 bytes. 10:04:47 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:04:47 INFO - Using the following test package requirements: 10:04:47 INFO - {u'common': [u'target.common.tests.zip'], 10:04:47 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:04:47 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:04:47 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:04:47 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:04:47 INFO - u'mozbase': [u'target.common.tests.zip'], 10:04:47 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:04:47 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:04:47 INFO - u'web-platform': [u'target.common.tests.zip', 10:04:47 INFO - u'target.web-platform.tests.zip'], 10:04:47 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:04:47 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 10:04:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.common.tests.zip 10:04:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:04:47 INFO - Fetch https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.common.tests.zip into memory 10:04:49 INFO - Expected file size: 59545022 10:04:49 INFO - Obtained file size: 59545022 10:04:59 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 10:04:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.common.tests.zip 10:04:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:04:59 INFO - Fetch https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.common.tests.zip into memory 10:05:00 INFO - Expected file size: 59545022 10:05:00 INFO - Obtained file size: 59545022 10:05:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.talos.tests.zip 10:05:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:05:11 INFO - Fetch https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.talos.tests.zip into memory 10:05:12 INFO - Expected file size: 14404424 10:05:12 INFO - Obtained file size: 14404424 10:05: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')]} 10:05:12 INFO - trying https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.tar.bz2 10:05:12 INFO - Downloading https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:05:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:05:13 INFO - Downloaded 66750813 bytes. 10:05:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.tar.bz2 10:05:13 INFO - mkdir: /builds/slave/test/properties 10:05:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:05:13 INFO - Writing to file /builds/slave/test/properties/build_url 10:05:13 INFO - Contents: 10:05:13 INFO - build_url:https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.tar.bz2 10:05:13 INFO - Running post-action listener: _resource_record_post_action 10:05:13 INFO - Running post-action listener: set_extra_try_arguments 10:05:13 INFO - [mozharness: 2017-04-11 17:05:13.712548Z] Finished download-and-extract step (success) 10:05:13 INFO - [mozharness: 2017-04-11 17:05:13.712644Z] Running populate-webroot step. 10:05:13 INFO - Running pre-action listener: _resource_record_pre_action 10:05:13 INFO - Running main action method: populate_webroot 10:05:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:05:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:05:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:05:13 INFO - '--tppagecycles', 10:05:13 INFO - '7'], 10:05:13 INFO - 'tests': ['tsvgm']}}, 10:05:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:05:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:05:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:05:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:05:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:05:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['damp', 'tps']}, 10:05:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'tests': ['damp', 'tps']}, 10:05:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['dromaeo_dom']}, 10:05:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:05:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['basic_compositor_video']}, 10:05:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:05:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'other-e10s': {'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['a11yr', 10:05:13 INFO - 'ts_paint', 10:05:13 INFO - 'tpaint', 10:05:13 INFO - 'sessionrestore', 10:05:13 INFO - 'sessionrestore_no_auto_restore', 10:05:13 INFO - 'tabpaint']}, 10:05:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['tsvgx', 10:05:13 INFO - 'tsvgr_opacity', 10:05:13 INFO - 'tart', 10:05:13 INFO - 'tscrollx', 10:05:13 INFO - 'cart', 10:05:13 INFO - 'tsvg_static']}, 10:05:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:05:13 INFO - 'tsvgr_opacity', 10:05:13 INFO - 'tart', 10:05:13 INFO - 'tscrollx', 10:05:13 INFO - 'cart', 10:05:13 INFO - 'tsvg_static']}, 10:05:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'talos_options': ['--disable-e10s'], 10:05:13 INFO - 'tests': ['tp5o']}, 10:05:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:05:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'talos_options': ['--disable-e10s', 10:05:13 INFO - '--xperf_path', 10:05:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:13 INFO - 'tests': ['tp5n']}, 10:05:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:05:13 INFO - 'talos_options': ['--xperf_path', 10:05:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:05:13 INFO - 'tests': ['tp5n']}}, 10:05:13 INFO - 'talos.zip': {'path': '', 10:05:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:05:13 INFO - Running post-action listener: _resource_record_post_action 10:05:13 INFO - [mozharness: 2017-04-11 17:05:13.725794Z] Finished populate-webroot step (success) 10:05:13 INFO - [mozharness: 2017-04-11 17:05:13.725885Z] Running create-virtualenv step. 10:05:13 INFO - Running pre-action listener: _resource_record_pre_action 10:05:13 INFO - Running main action method: create_virtualenv 10:05:13 INFO - Creating virtualenv /builds/slave/test/build/venv 10:05:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:05:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:05:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:05:13 INFO - Using real prefix '/usr' 10:05:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:05:14 INFO - Installing distribute.............................................................................................................................................................................................done. 10:05:16 INFO - Installing pip.................done. 10:05:16 INFO - Return code: 0 10:05:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:05:16 INFO - Reading from file tmpfile_stdout 10:05:16 INFO - Output received: 10:05:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:05:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, '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': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:05:16 INFO - Using env: {'DISPLAY': ':0', 10:05:16 INFO - 'HOME': '/home/cltbld', 10:05:16 INFO - 'LANG': 'en_US.UTF-8', 10:05:16 INFO - 'LANGUAGE': 'en_US:en', 10:05:16 INFO - 'LOGNAME': 'cltbld', 10:05:16 INFO - 'MAIL': '/var/mail/cltbld', 10:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:16 INFO - 'MOZ_NO_REMOTE': '1', 10:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:16 INFO - 'NO_EM_RESTART': '1', 10:05:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:16 INFO - 'PWD': '/builds/slave/test', 10:05:16 INFO - 'SHELL': '/bin/bash', 10:05:16 INFO - 'SHLVL': '1', 10:05:16 INFO - 'TERM': 'linux', 10:05:16 INFO - 'TMOUT': '86400', 10:05:16 INFO - 'USER': 'cltbld', 10:05:16 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 10:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:16 INFO - '_': '/tools/buildbot/bin/python'} 10:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:05:16 INFO - Cleaning up... 10:05:16 INFO - Return code: 0 10:05:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, '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': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:05:16 INFO - Using env: {'DISPLAY': ':0', 10:05:16 INFO - 'HOME': '/home/cltbld', 10:05:16 INFO - 'LANG': 'en_US.UTF-8', 10:05:16 INFO - 'LANGUAGE': 'en_US:en', 10:05:16 INFO - 'LOGNAME': 'cltbld', 10:05:16 INFO - 'MAIL': '/var/mail/cltbld', 10:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:16 INFO - 'MOZ_NO_REMOTE': '1', 10:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:16 INFO - 'NO_EM_RESTART': '1', 10:05:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:16 INFO - 'PWD': '/builds/slave/test', 10:05:16 INFO - 'SHELL': '/bin/bash', 10:05:16 INFO - 'SHLVL': '1', 10:05:16 INFO - 'TERM': 'linux', 10:05:16 INFO - 'TMOUT': '86400', 10:05:16 INFO - 'USER': 'cltbld', 10:05:16 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 10:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:16 INFO - '_': '/tools/buildbot/bin/python'} 10:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:16 INFO - Downloading/unpacking psutil>=3.1.1 10:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:05:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:19 INFO - Installing collected packages: psutil 10:05:19 INFO - Running setup.py install for psutil 10:05:19 INFO - building 'psutil._psutil_linux' extension 10:05:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:05:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:05:19 INFO - building 'psutil._psutil_posix' extension 10:05:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:05:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:05:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:05:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:05:19 INFO - Successfully installed psutil 10:05:19 INFO - Cleaning up... 10:05:19 INFO - Return code: 0 10:05:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, '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': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:05:19 INFO - Using env: {'DISPLAY': ':0', 10:05:19 INFO - 'HOME': '/home/cltbld', 10:05:19 INFO - 'LANG': 'en_US.UTF-8', 10:05:19 INFO - 'LANGUAGE': 'en_US:en', 10:05:19 INFO - 'LOGNAME': 'cltbld', 10:05:19 INFO - 'MAIL': '/var/mail/cltbld', 10:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:19 INFO - 'MOZ_NO_REMOTE': '1', 10:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:19 INFO - 'NO_EM_RESTART': '1', 10:05:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:19 INFO - 'PWD': '/builds/slave/test', 10:05:19 INFO - 'SHELL': '/bin/bash', 10:05:19 INFO - 'SHLVL': '1', 10:05:19 INFO - 'TERM': 'linux', 10:05:19 INFO - 'TMOUT': '86400', 10:05:19 INFO - 'USER': 'cltbld', 10:05:19 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 10:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:19 INFO - '_': '/tools/buildbot/bin/python'} 10:05:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:05: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 10:05: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 10:05: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 10:05: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 10:05: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 10:05: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 10:05:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:05:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:05:22 INFO - Installing collected packages: mozsystemmonitor 10:05:22 INFO - Running setup.py install for mozsystemmonitor 10:05:22 INFO - Successfully installed mozsystemmonitor 10:05:22 INFO - Cleaning up... 10:05:22 INFO - Return code: 0 10:05:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, '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': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:05:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:05:22 INFO - Using env: {'DISPLAY': ':0', 10:05:22 INFO - 'HOME': '/home/cltbld', 10:05:22 INFO - 'LANG': 'en_US.UTF-8', 10:05:22 INFO - 'LANGUAGE': 'en_US:en', 10:05:22 INFO - 'LOGNAME': 'cltbld', 10:05:22 INFO - 'MAIL': '/var/mail/cltbld', 10:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:22 INFO - 'MOZ_NO_REMOTE': '1', 10:05:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:22 INFO - 'NO_EM_RESTART': '1', 10:05:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:22 INFO - 'PWD': '/builds/slave/test', 10:05:22 INFO - 'SHELL': '/bin/bash', 10:05:22 INFO - 'SHLVL': '1', 10:05:22 INFO - 'TERM': 'linux', 10:05:22 INFO - 'TMOUT': '86400', 10:05:22 INFO - 'USER': 'cltbld', 10:05:22 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 10:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:22 INFO - '_': '/tools/buildbot/bin/python'} 10:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:22 INFO - Downloading/unpacking jsonschema==2.5.1 10:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:05:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:24 INFO - Downloading functools32-3.2.3-2.tar.gz 10:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:05:24 INFO - warning: no files found matching '*.txt' 10:05:24 INFO - no previously-included directories found matching 'build' 10:05:24 INFO - no previously-included directories found matching 'dist' 10:05:24 INFO - no previously-included directories found matching '.git*' 10:05:24 INFO - Installing collected packages: jsonschema, functools32 10:05:24 INFO - Running setup.py install for functools32 10:05:24 INFO - warning: no files found matching '*.txt' 10:05:24 INFO - no previously-included directories found matching 'build' 10:05:24 INFO - no previously-included directories found matching 'dist' 10:05:24 INFO - no previously-included directories found matching '.git*' 10:05:24 INFO - Successfully installed jsonschema functools32 10:05:24 INFO - Cleaning up... 10:05:24 INFO - Return code: 0 10:05:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, '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': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:05:25 INFO - Using env: {'DISPLAY': ':0', 10:05:25 INFO - 'HOME': '/home/cltbld', 10:05:25 INFO - 'LANG': 'en_US.UTF-8', 10:05:25 INFO - 'LANGUAGE': 'en_US:en', 10:05:25 INFO - 'LOGNAME': 'cltbld', 10:05:25 INFO - 'MAIL': '/var/mail/cltbld', 10:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:25 INFO - 'MOZ_NO_REMOTE': '1', 10:05:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:25 INFO - 'NO_EM_RESTART': '1', 10:05:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:25 INFO - 'PWD': '/builds/slave/test', 10:05:25 INFO - 'SHELL': '/bin/bash', 10:05:25 INFO - 'SHLVL': '1', 10:05:25 INFO - 'TERM': 'linux', 10:05:25 INFO - 'TMOUT': '86400', 10:05:25 INFO - 'USER': 'cltbld', 10:05:25 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 10:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:25 INFO - '_': '/tools/buildbot/bin/python'} 10:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:05:25 INFO - Cleaning up... 10:05:25 INFO - Return code: 0 10:05:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, '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': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:05:25 INFO - Using env: {'DISPLAY': ':0', 10:05:25 INFO - 'HOME': '/home/cltbld', 10:05:25 INFO - 'LANG': 'en_US.UTF-8', 10:05:25 INFO - 'LANGUAGE': 'en_US:en', 10:05:25 INFO - 'LOGNAME': 'cltbld', 10:05:25 INFO - 'MAIL': '/var/mail/cltbld', 10:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:25 INFO - 'MOZ_NO_REMOTE': '1', 10:05:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:25 INFO - 'NO_EM_RESTART': '1', 10:05:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:25 INFO - 'PWD': '/builds/slave/test', 10:05:25 INFO - 'SHELL': '/bin/bash', 10:05:25 INFO - 'SHLVL': '1', 10:05:25 INFO - 'TERM': 'linux', 10:05:25 INFO - 'TMOUT': '86400', 10:05:25 INFO - 'USER': 'cltbld', 10:05:25 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 10:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:25 INFO - '_': '/tools/buildbot/bin/python'} 10:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:25 INFO - Downloading/unpacking blobuploader==1.2.4 10:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:27 INFO - Downloading blobuploader-1.2.4.tar.gz 10:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:05:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:05:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:28 INFO - Downloading docopt-0.6.1.tar.gz 10:05:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:05:28 INFO - Installing collected packages: blobuploader, requests, docopt 10:05:28 INFO - Running setup.py install for blobuploader 10:05:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:05:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:05:28 INFO - Running setup.py install for requests 10:05:28 INFO - Running setup.py install for docopt 10:05:28 INFO - Successfully installed blobuploader requests docopt 10:05:28 INFO - Cleaning up... 10:05:28 INFO - Return code: 0 10:05:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, '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': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:05:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:05:28 INFO - Using env: {'DISPLAY': ':0', 10:05:28 INFO - 'HOME': '/home/cltbld', 10:05:28 INFO - 'LANG': 'en_US.UTF-8', 10:05:28 INFO - 'LANGUAGE': 'en_US:en', 10:05:28 INFO - 'LOGNAME': 'cltbld', 10:05:28 INFO - 'MAIL': '/var/mail/cltbld', 10:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:28 INFO - 'MOZ_NO_REMOTE': '1', 10:05:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:28 INFO - 'NO_EM_RESTART': '1', 10:05:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:28 INFO - 'PWD': '/builds/slave/test', 10:05:28 INFO - 'SHELL': '/bin/bash', 10:05:28 INFO - 'SHLVL': '1', 10:05:28 INFO - 'TERM': 'linux', 10:05:28 INFO - 'TMOUT': '86400', 10:05:28 INFO - 'USER': 'cltbld', 10:05:28 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 10:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:28 INFO - '_': '/tools/buildbot/bin/python'} 10:05:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:29 INFO - Running setup.py (path:/tmp/pip-ccyL5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:29 INFO - Running setup.py (path:/tmp/pip-hDMhpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:29 INFO - Running setup.py (path:/tmp/pip-76LU1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:29 INFO - Running setup.py (path:/tmp/pip-4g6OD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:29 INFO - Running setup.py (path:/tmp/pip-smpVAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:29 INFO - Running setup.py (path:/tmp/pip-_XMLWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:29 INFO - Running setup.py (path:/tmp/pip-BaBeWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:29 INFO - Running setup.py (path:/tmp/pip-Sp5fgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:29 INFO - Running setup.py (path:/tmp/pip-ie06Uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:29 INFO - Running setup.py (path:/tmp/pip-q0eMZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:29 INFO - Running setup.py (path:/tmp/pip-odl4ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:29 INFO - Running setup.py (path:/tmp/pip-wHbzFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:29 INFO - Running setup.py (path:/tmp/pip-aSgEuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:30 INFO - Running setup.py (path:/tmp/pip-SKWsuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:30 INFO - Running setup.py (path:/tmp/pip-p3j2ic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:30 INFO - Running setup.py (path:/tmp/pip-loKu4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:30 INFO - Running setup.py (path:/tmp/pip-EF1nog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:05:30 INFO - Running setup.py install for manifestparser 10:05:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Running setup.py install for mozcrash 10:05:30 INFO - Running setup.py install for mozdebug 10:05:30 INFO - Running setup.py install for mozdevice 10:05:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Running setup.py install for mozfile 10:05:30 INFO - Running setup.py install for mozhttpd 10:05:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:05:30 INFO - Running setup.py install for mozinfo 10:05:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Running setup.py install for mozInstall 10:05:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Running setup.py install for mozleak 10:05:31 INFO - Running setup.py install for mozlog 10:05:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Running setup.py install for moznetwork 10:05:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Running setup.py install for mozprocess 10:05:31 INFO - Running setup.py install for mozprofile 10:05:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Running setup.py install for mozrunner 10:05:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:05:31 INFO - Running setup.py install for mozscreenshot 10:05:31 INFO - Running setup.py install for moztest 10:05:32 INFO - Running setup.py install for mozversion 10:05:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:05:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:05:32 INFO - Cleaning up... 10:05:32 INFO - Return code: 0 10:05:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:32 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 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, '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': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:05:32 INFO - Using env: {'DISPLAY': ':0', 10:05:32 INFO - 'HOME': '/home/cltbld', 10:05:32 INFO - 'LANG': 'en_US.UTF-8', 10:05:32 INFO - 'LANGUAGE': 'en_US:en', 10:05:32 INFO - 'LOGNAME': 'cltbld', 10:05:32 INFO - 'MAIL': '/var/mail/cltbld', 10:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:32 INFO - 'MOZ_NO_REMOTE': '1', 10:05:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:32 INFO - 'NO_EM_RESTART': '1', 10:05:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:32 INFO - 'PWD': '/builds/slave/test', 10:05:32 INFO - 'SHELL': '/bin/bash', 10:05:32 INFO - 'SHLVL': '1', 10:05:32 INFO - 'TERM': 'linux', 10:05:32 INFO - 'TMOUT': '86400', 10:05:32 INFO - 'USER': 'cltbld', 10:05:32 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 10:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:32 INFO - '_': '/tools/buildbot/bin/python'} 10:05:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:05:32 INFO - Running setup.py (path:/tmp/pip-esfaQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:05:32 INFO - Running setup.py (path:/tmp/pip-RjxNLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:05:32 INFO - Running setup.py (path:/tmp/pip-NnH61n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:05:32 INFO - Running setup.py (path:/tmp/pip-lfN8Mk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:05:32 INFO - Running setup.py (path:/tmp/pip-d09TcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:05:32 INFO - Running setup.py (path:/tmp/pip-Gmsqgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:05:32 INFO - Running setup.py (path:/tmp/pip-wN7z5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:05:32 INFO - Running setup.py (path:/tmp/pip-1x4bSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:05:32 INFO - Running setup.py (path:/tmp/pip-dggRsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:05:33 INFO - Running setup.py (path:/tmp/pip-HB3KBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:05:33 INFO - Running setup.py (path:/tmp/pip-lh4q6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:05:33 INFO - Running setup.py (path:/tmp/pip-Xv3MBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:05:33 INFO - Running setup.py (path:/tmp/pip-06dW_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:05:33 INFO - Running setup.py (path:/tmp/pip-pTfRFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:33 INFO - Running setup.py (path:/tmp/pip-ux32DG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:05:33 INFO - Running setup.py (path:/tmp/pip-eF2hcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:05:33 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)) 10:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:05:33 INFO - Running setup.py (path:/tmp/pip-20m4ME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:05:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:05:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:05:35 INFO - Downloading blessings-1.6.tar.gz 10:05:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:05:35 INFO - Installing collected packages: blessings 10:05:35 INFO - Running setup.py install for blessings 10:05:35 INFO - Successfully installed blessings 10:05:35 INFO - Cleaning up... 10:05:35 INFO - Return code: 0 10:05:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:05:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:36 INFO - Reading from file tmpfile_stdout 10:05:36 INFO - Current package versions: 10:05:36 INFO - argparse == 1.2.1 10:05:36 INFO - blessings == 1.6 10:05:36 INFO - blobuploader == 1.2.4 10:05:36 INFO - docopt == 0.6.1 10:05:36 INFO - functools32 == 3.2.3-2 10:05:36 INFO - jsonschema == 2.5.1 10:05:36 INFO - manifestparser == 1.1 10:05:36 INFO - mozInstall == 1.12 10:05:36 INFO - mozcrash == 1.0 10:05:36 INFO - mozdebug == 0.1 10:05:36 INFO - mozdevice == 0.49 10:05:36 INFO - mozfile == 1.2 10:05:36 INFO - mozhttpd == 0.7 10:05:36 INFO - mozinfo == 0.9 10:05:36 INFO - mozleak == 0.1 10:05:36 INFO - mozlog == 3.4 10:05:36 INFO - moznetwork == 0.27 10:05:36 INFO - mozprocess == 0.25 10:05:36 INFO - mozprofile == 0.28 10:05:36 INFO - mozrunner == 6.13 10:05:36 INFO - mozscreenshot == 0.1 10:05:36 INFO - mozsystemmonitor == 0.3 10:05:36 INFO - moztest == 0.8 10:05:36 INFO - mozversion == 1.4 10:05:36 INFO - psutil == 3.1.1 10:05:36 INFO - requests == 1.2.3 10:05:36 INFO - wsgiref == 0.1.2 10:05:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:36 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 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, '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': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:05:36 INFO - Using env: {'DISPLAY': ':0', 10:05:36 INFO - 'HOME': '/home/cltbld', 10:05:36 INFO - 'LANG': 'en_US.UTF-8', 10:05:36 INFO - 'LANGUAGE': 'en_US:en', 10:05:36 INFO - 'LOGNAME': 'cltbld', 10:05:36 INFO - 'MAIL': '/var/mail/cltbld', 10:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:36 INFO - 'MOZ_NO_REMOTE': '1', 10:05:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:36 INFO - 'NO_EM_RESTART': '1', 10:05:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:36 INFO - 'PWD': '/builds/slave/test', 10:05:36 INFO - 'SHELL': '/bin/bash', 10:05:36 INFO - 'SHLVL': '1', 10:05:36 INFO - 'TERM': 'linux', 10:05:36 INFO - 'TMOUT': '86400', 10:05:36 INFO - 'USER': 'cltbld', 10:05:36 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 10:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:36 INFO - '_': '/tools/buildbot/bin/python'} 10:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:05:36 INFO - Cleaning up... 10:05:36 INFO - Return code: 0 10:05:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:05:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e7b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f73a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2118b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, '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': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:05:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:05:36 INFO - Using env: {'DISPLAY': ':0', 10:05:36 INFO - 'HOME': '/home/cltbld', 10:05:36 INFO - 'LANG': 'en_US.UTF-8', 10:05:36 INFO - 'LANGUAGE': 'en_US:en', 10:05:36 INFO - 'LOGNAME': 'cltbld', 10:05:36 INFO - 'MAIL': '/var/mail/cltbld', 10:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:36 INFO - 'MOZ_NO_REMOTE': '1', 10:05:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:36 INFO - 'NO_EM_RESTART': '1', 10:05:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:36 INFO - 'PWD': '/builds/slave/test', 10:05:36 INFO - 'SHELL': '/bin/bash', 10:05:36 INFO - 'SHLVL': '1', 10:05:36 INFO - 'TERM': 'linux', 10:05:36 INFO - 'TMOUT': '86400', 10:05:36 INFO - 'USER': 'cltbld', 10:05:36 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 10:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:36 INFO - '_': '/tools/buildbot/bin/python'} 10:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:05:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:05:36 INFO - Cleaning up... 10:05:36 INFO - Return code: 0 10:05:36 INFO - Running post-action listener: _resource_record_post_action 10:05:36 INFO - Running post-action listener: _start_resource_monitoring 10:05:36 INFO - Starting resource monitoring. 10:05:36 INFO - [mozharness: 2017-04-11 17:05:36.624803Z] Finished create-virtualenv step (success) 10:05:36 INFO - [mozharness: 2017-04-11 17:05:36.625263Z] Running install step. 10:05:36 INFO - Running pre-action listener: _resource_record_pre_action 10:05:36 INFO - Running main action method: install 10:05:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:05:36 INFO - Reading from file tmpfile_stdout 10:05:36 INFO - Detecting whether we're running mozinstall >=1.0... 10:05:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:05:36 INFO - Reading from file tmpfile_stdout 10:05:36 INFO - Output received: 10:05:36 INFO - Usage: mozinstall [options] installer 10:05:36 INFO - Options: 10:05:36 INFO - -h, --help show this help message and exit 10:05:36 INFO - -d DEST, --destination=DEST 10:05:36 INFO - Directory to install application into. [default: 10:05:36 INFO - "/builds/slave/test"] 10:05:36 INFO - --app=APP Application being installed. [default: firefox] 10:05:36 INFO - mkdir: /builds/slave/test/build/application 10:05:36 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'] 10:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:05:54 INFO - Reading from file tmpfile_stdout 10:05:54 INFO - Output received: 10:05:54 INFO - /builds/slave/test/build/application/firefox/firefox 10:05:54 INFO - Running post-action listener: _resource_record_post_action 10:05:54 INFO - [mozharness: 2017-04-11 17:05:54.315533Z] Finished install step (success) 10:05:54 INFO - [mozharness: 2017-04-11 17:05:54.315809Z] Running run-tests step. 10:05:54 INFO - Running pre-action listener: _resource_record_pre_action 10:05:54 INFO - Running main action method: run_tests 10:05:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:05:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:05:54 INFO - Python 2.7.3 10:05:54 INFO - Return code: 0 10:05:54 INFO - grabbing minidump binary from tooltool 10:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:05:54 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 0x2116290>, '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 0x2116b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2117040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:05:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:05:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:05:54 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:05:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:05:54 INFO - Return code: 0 10:05:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:05:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:05:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:05:54 INFO - ENV: RUST_BACKTRACE is now 1 10:05:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:05:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:05:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:05:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:05:54 INFO - Using env: {'DISPLAY': ':0', 10:05:54 INFO - 'HOME': '/home/cltbld', 10:05:54 INFO - 'LANG': 'en_US.UTF-8', 10:05:54 INFO - 'LANGUAGE': 'en_US:en', 10:05:54 INFO - 'LOGNAME': 'cltbld', 10:05:54 INFO - 'MAIL': '/var/mail/cltbld', 10:05:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:05:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:54 INFO - 'MOZ_NO_REMOTE': '1', 10:05:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:05:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:05:54 INFO - 'NO_EM_RESTART': '1', 10:05:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:54 INFO - 'PWD': '/builds/slave/test', 10:05:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:05:54 INFO - 'RUST_BACKTRACE': '1', 10:05:54 INFO - 'SHELL': '/bin/bash', 10:05:54 INFO - 'SHLVL': '1', 10:05:54 INFO - 'TERM': 'linux', 10:05:54 INFO - 'TMOUT': '86400', 10:05:54 INFO - 'USER': 'cltbld', 10:05:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348', 10:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:54 INFO - '_': '/tools/buildbot/bin/python'} 10:05:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:05:54 INFO - mozversion application_buildid: 20170411155603 10:05:54 INFO - mozversion application_changeset: c876f8b62d63bc7ba2dd15870add8e461da05427 10:05:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:05:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:05:54 INFO - mozversion application_name: Firefox 10:05:54 INFO - mozversion application_remotingname: firefox-dev 10:05:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:05:54 INFO - mozversion application_vendor: Mozilla 10:05:54 INFO - mozversion application_version: 54.0a2 10:05:54 INFO - mozversion platform_buildid: 20170411155603 10:05:54 INFO - mozversion platform_changeset: c876f8b62d63bc7ba2dd15870add8e461da05427 10:05:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:05:54 INFO - mozversion platform_version: 54.0a2 10:05:54 INFO - using testdate: 1491930354 10:05:54 INFO - actual date: 1491930354 10:05:54 INFO - starting webserver on 'localhost:59152' 10:05:54 INFO - SUITE-START | Running 2 tests 10:05:54 INFO - TEST-START | dromaeo_css 10:05:54 INFO - operating with platform_type : linux_ 10:05:54 INFO - Initialising browser for dromaeo_css test... 10:05:54 INFO - TEST-INFO | started process 29331 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb3ow8a/profile http://localhost:59152/getInfo.html) 10:05:58 INFO - PROCESS | 29331 | __metrics Screen width/height:1600/1200 10:05:58 INFO - PROCESS | 29331 | colorDepth:24 10:05:58 INFO - PROCESS | 29331 | Browser inner width/height: 1024/697 10:05:58 INFO - PROCESS | 29331 | __metrics 10:05:59 INFO - PROCESS | 29331 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:05:59 INFO - PROCESS | 29331 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:06:00 INFO - PROCESS | 29331 | console.error: PushService: 10:06:00 INFO - PROCESS | 29331 | stateChangeProcessEnqueue: Error transitioning state 10:06:00 INFO - PROCESS | 29331 | UnknownError 10:06:00 INFO - TEST-INFO | 29331: exit 0 10:06:00 INFO - Browser initialized. 10:06:00 INFO - Running cycle 1/1 for dromaeo_css test... 10:06:00 INFO - TEST-INFO | started process 29423 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpb3ow8a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:06:12 INFO - PROCESS | 29423 | RSS: Main: 139919360 10:06:12 INFO - PROCESS | 29423 | 10:09:14 INFO - PROCESS | 29423 | Cycle 1(1): loaded http://localhost:59152/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59152/tests/dromaeo/cssquery-ext.html) 10:09:15 INFO - PROCESS | 29423 | RSS: Main: 230035456 10:09:15 INFO - PROCESS | 29423 | 10:11:02 INFO - PROCESS | 29423 | ************************* 10:11:02 INFO - PROCESS | 29423 | A coding exception was thrown and uncaught in a Task. 10:11:02 INFO - PROCESS | 29423 | 10:11:02 ERROR - PROCESS | 29423 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:11:02 INFO - PROCESS | 29423 | Full stack: 10:11:02 INFO - PROCESS | 29423 | ************************* 10:11:02 INFO - PROCESS | 29423 | ************************* 10:11:02 INFO - PROCESS | 29423 | A coding exception was thrown and uncaught in a Task. 10:11:02 INFO - PROCESS | 29423 | 10:11:02 ERROR - PROCESS | 29423 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:11:02 INFO - PROCESS | 29423 | Full stack: 10:11:02 INFO - PROCESS | 29423 | ************************* 10:11:02 INFO - PROCESS | 29423 | ************************* 10:11:02 INFO - PROCESS | 29423 | A coding exception was thrown and uncaught in a Task. 10:11:02 INFO - PROCESS | 29423 | 10:11:02 ERROR - PROCESS | 29423 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:11:02 INFO - PROCESS | 29423 | Full stack: 10:11:02 INFO - PROCESS | 29423 | ************************* 10:11:02 INFO - PROCESS | 29423 | ************************* 10:11:02 INFO - PROCESS | 29423 | A coding exception was thrown and uncaught in a Task. 10:11:02 INFO - PROCESS | 29423 | 10:11:02 ERROR - PROCESS | 29423 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:11:02 INFO - PROCESS | 29423 | Full stack: 10:11:02 INFO - PROCESS | 29423 | ************************* 10:12:17 INFO - PROCESS | 29423 | Cycle 1(1): loaded http://localhost:59152/tests/dromaeo/cssquery-ext.html (next: http://localhost:59152/tests/dromaeo/cssquery-jquery.html) 10:12:18 INFO - PROCESS | 29423 | RSS: Main: 218497024 10:12:18 INFO - PROCESS | 29423 | 10:15:20 INFO - PROCESS | 29423 | Cycle 1(1): loaded http://localhost:59152/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59152/tests/dromaeo/cssquery-mootools.html) 10:15:20 INFO - PROCESS | 29423 | RSS: Main: 194248704 10:15:20 INFO - PROCESS | 29423 | 10:18:22 INFO - PROCESS | 29423 | Cycle 1(1): loaded http://localhost:59152/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59152/tests/dromaeo/cssquery-prototype.html) 10:18:23 INFO - PROCESS | 29423 | RSS: Main: 212664320 10:18:23 INFO - PROCESS | 29423 | 10:21:26 INFO - PROCESS | 29423 | Cycle 1(1): loaded http://localhost:59152/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59152/tests/dromaeo/cssquery-yui.html) 10:21:26 INFO - PROCESS | 29423 | RSS: Main: 173379584 10:21:26 INFO - PROCESS | 29423 | 10:24:29 INFO - PROCESS | 29423 | Cycle 1(1): loaded http://localhost:59152/tests/dromaeo/cssquery-yui.html (next: http://localhost:59152/tests/dromaeo/cssquery-dojo.html) 10:24:29 INFO - PROCESS | 29423 | RSS: Main: 255582208 10:24:29 INFO - PROCESS | 29423 | 10:24:29 INFO - PROCESS | 29423 | __start_tp_report 10:24:29 INFO - PROCESS | 29423 | _x_x_mozilla_page_load 10:24:29 INFO - PROCESS | 29423 | _x_x_mozilla_page_load_details 10:24:29 INFO - PROCESS | 29423 | |i|pagename|runs| 10:24:29 INFO - PROCESS | 29423 | |0;dojo.html;3344.9331013379724;3378.206121561433;3386.745994050447;3412.0787387405417;3446.4657978013415;5731.226284451598;5739.770409183633;5758.1362795580635;5763.250777398932;5774.855628609282;5754.079347304432;5806.82579522615;5809.535237181026;5810.825675229739;5820.185174075622;5146.794128234867;5153.227015947619;5156.58747300217;5176.922346164811;5204.505571970657;5010.123228435027;5254.31693879797;5263.473652634744;5264;5292.814751483699;5064.594832413417;5075.9492405076035;5110.386753589574;5183.974080129594;5199.766010529516;5179.611529135311;5203.843884683484;5205;5209.348831396075;5215.139501982168;4948.134076536628;5014.598832093424;5082.847514574569;5130.948690513084;5138.58891288696;5643.266375371217;5644.435556444364;5697.487226149667;5711.286089238845;5724.5992780505385;6172.69136543171;6211.09939058834;6220.87558248837;6225.7509699611965;6271.059341098826;6235.501159907197;6244.2819075805955;6261.46777523911;6278.937210627927;6283.151774510428;5596.636218645775;5638.351589567212;5728.198052272685;5792.275965504312;5841.094630332305;6344.016677415008;6350.206224221973;6351.174347334817;6354.078658594571;6354.142190804275;6061.424164704346;6108.602940808833;6110.5722599418505;6120.693965301717;6123.38766123393;6021.126936594169;6086.573939824169;6087.665178415141;6139.10982907476;6225.937740622536;6147.508199344042;6174.475169610633;6184.474319682878;6186.443220110212;6190.3190649028575;5925.377835327315;5963.5527335450015;6050.334463209131;6178.814635560941;6181.505479561624;4508.346289787963;4582.404287442611;4599.678022538457;4602.631789456836;4618.330342100377;4091.774952377648;4100.528439229467;4102.815373308208;4117.279476091672;4233.661307095486;5952.226210592596;5955.255593050869;5967.1347654589845;5984.1921340618455;6002.939970600238;6129.785457508966;6136.6011209271255;6159.753609855556;6160.322364539897;6163.722632481549;5814.360420353758;5861.824145275648;5878.147668588031;5900.911486327708;5908.14331921869;5276.1030624793875;5281.524662780368;5296.523312901857;5301.5493683037375;5307.28351672527;6476.6114033158165;6478.384553467413;6484.416402523795;6486.2216534016225;6489.415952564292;5861.35525092231;5908.468237858613;6241.968790156202;6243.250809902659;6246.1255424239735;6314.589551679126;6336.524760643062;6427.58220715652;6447.451966582893;6472.870542589216;6129.540284478593;6132.662703551169;6136.386361364007;6136.7852125175405;6139.3553676863285;6191.9690401547705;6245.531585131042;6249.34381889895;6256.155419018556;6261.154744109487;5838.4453476919625;5870.706464676664;6001.549883758648;6057.242844644419;6134.478569321657;6083.178770865877;6087.421694938974;6089.786857460145;6090.23872015998;6121.418465245794;5837.970810145922;5856.502197313276;6042.425969532887;6049.485793707408;6053.243344581927;5926.703664816656;6022.488088512526;6041.486473649789;6124.777645458507;6145.832291864532;5977.252843394575;6003.429674180946;6010.038393856962;6013.789517366871;6037.577369584087 10:24:29 INFO - PROCESS | 29423 | |1;ext.html;786.8111653203233;833.2833762963852;857.9142085791422;881.9382643214977;898.4384759525296;31389.372212555736;44120.33819492706;44483;44878.102437951304;45000.3249951251;44618.55381446185;44848.77575612118;44870.55129448704;44880.20479180829;44905;7709.113451953013;7875.251851074138;8635.179657932502;8752.474851508898;8756.912430875705;10477.685669429891;10727.78544429111;11046.226764126473;11051.72370690735;11056.668299951014;10714.732131696732;10731.892681073168;10756;10776.19178561607;10778.245522813406;10634.149268058576;10677.305975111633;10680.465976701173;10691.358518488916;10744.14046876248;10582.153427725763;10625;10627.628033018807;10632.627858024933;10634.46827658614;41337.58662413368;41448.585514144776;41639.1672166558;41639.1672166558;41699.7915010426;4099.262132816092;4131.194417088669;4153.418521407006;4156.729812562174;4175.645070169039;29524;29790.40419191604;29945.850270748506;29984.700152998626;30345.089647310615;4268.167707297078;4303.289957157065;4364.716293440916;4393.231184542724;4473.552644735533;239410;240497.99999999825;240535;240891;241720;54923.450765491834;55390.44609553934;55463.72268138714;55489;55567.72216138974;57488.42511574831;57544.42455575475;57556.13665795016;57704.134437982626;57826;15459.149746764037;15784.92107539455;15797.68404631901;15800.36798528069;15805;13375.665608359868;13553.254570998493;13628.318584070956;13663.248521331423;13668.52160174389;13342.866571334356;13471.663208419672;13572.117812342167;13933.676300751413;14009.108770315877;5681.545476361881;5682.488576028095;5683.147527870769;5687.8862422752145;5752;1021.642425151206;1024.5594394410325;1027.712240572641;1028.3367228137809;1035.4460363705382;28025.038247322493;28078.85960570184;28221.15336539907;28548;28636.570451443244;1104.5857803323754;1106.9225154239125;1108.2630051016145;1116.7208197950513;1122.848415463902;14231.857681423053;15304.617384565252;15487.767683484757;15719;15736.842631573767;294631;295763;297135;297730;298598;55600.721996390566;55786.44213557894;55859.72070139705;55871.44128558744;56004;1493.111598598832;1521.5739592914224;1546.087808193149;1553.642662187703;1562.3672412672886;26209.868950655124;27030.1890943272;27429;27637.58543621807;28023.859880701282;41798.791006044776;41874.79062604668;41935;41970.37044444275;42023.7898810504;290689;290725;291315;291450;291625;29735.702642973294;32457.35085298328;34745.47881781826;34847.303053939286;36047.27905441824;45213.773931130134;46352.53647463482;46373;46383;46467;28429.431411372072;28465.28836779097;29272;29341.706582933897;29709.5543566851;28786.424271514872;28871.85564072166;28884.277893052;28943.565846511898;29074.418511630072;37244.25511489809;39308.80345598254;39507.40738888861;39757.2048559033;40010.1997960045;26191.73808261893;26296;26401.207963761117;26500.73499264983;26532.734672653027;25307;25360;25389.492210155324;25813.3546661335;25823.483530329664 10:24:29 INFO - PROCESS | 29423 | |2;jquery.html;463.7959297908922;464.1899884701197;480.0998128509045;491.39558343237815;492.3771429142134;19511.41465756028;21531.67702484464;21601.02795374208;21936.45158871025;22055.779442205574;22533.887330563364;22792.772072279273;22846.543069138606;23074.65388019181;23157;6567.573107748005;6569.211694596631;6589.20929488462;6642.136522252101;6644.60132392058;6593.142891424132;6721.1598550181225;7513.361364284043;7859.842803143933;7880.093789214227;7854;7874;7876.05487341517;7995.120536740954;8015.639296231654;7887.921120788834;8042.798930026737;8080.070791858923;8081.191880811931;8118.878216826723;7942.841143177104;7948.682052717884;7996.4402491825595;8008.279254867031;8014.599270036533;28956.710432895612;28966.710332896822;29785;29880;29892.70107298943;3144.1825125467544;3158.731507821838;3197.328561002192;3202;3226.58054452922;17602.647947041114;17637.382691605733;18186.45440636783;18278.24971791296;19039.809601904082;2356.952860942789;2428.0651948999584;2429.7448767879296;2472.7403622619545;2497.2383423055962;118836.40581797122;122890.99999999911;122902;123542;123587.38206309002;21077.367678969655;22334.664980025314;22372;22483.21308754186;22904.312870613907;43611.34582981258;43788;44219;44414.555854441685;44492;11331.716707082389;11376.146788990858;11415.573053368329;11538.250013749079;11558.768824623628;9008.504532250658;9053.366264361563;9063.59213835379;9313.487758173365;9371.250299975985;11077.556897724162;11312.773744525064;11586.899244571752;11603.6518904433;11644.41777911101;4321.805518751664;4326.891827704332;4436.201483732959;4477.753723545171;4563.977180114144;1156.8149096144577;1187.329159025148;1189.7501524679915;1190.214458457414;1191.267370567095;20482.487937801674;20620.27829025977;20730.689039664416;20758.79241207599;20860.37418877436;1150.8676502202186;1160.5705888821021;1181.704573856536;1187.5724739093919;1210.8365370674846;10862.239643224899;11315.377654228932;11325.830112548318;11334.943325283486;11392.145589080686;464127;667543;676260;683287;698937;84841;117452;122350.3882480582;124650;125451.37274313753;1337.8729020743015;1384.1556650443222;1385.5012195609572;1394;1397.9650508737363;18972.810271897106;19246.615067698847;19281.710774338677;19295.614087718448;19341.1296491661;52409;52621.47378526166;52673.209901850736;52931.735341323045;54836.17745733738;67950.3204967944;68701.65649171722;68838;69472;70275;12123.454444550192;12241.755164896475;12321.93839030799;12468.064895133082;12488.562900298446;13123.475060997835;13263.336833158497;13293.867061329263;13562.93218533901;13733.969952253421;11310.547578096784;11506.884931150582;11721.941390292994;11852;11906.40467976615;10205.489725513844;10555.208359372924;10571.577136914744;10657.786844262917;10878.510467028844;11670;11685.182037257311;11780.293182409083;11791.587294444653;11990.220635658654;11956;12369.505219791108;12420.937895310466;12478.18891772032;12764.17032892859;11451.59919402817;11568.363739994382;11687.824682629598;11964.880351196376;11999.40002999829 10:24:29 INFO - PROCESS | 29423 | |3;mootools.html;1425.6934759026808;1517.6737001544666;1546.1727975533092;1583.9208039598018;1585.2390852390852;8803.647854085837;10998.12015038797;11318.32090074595;11386.772264554704;11390.772184556305;11527.077833773299;11605.767884642302;11610.941945290262;11612.477438515267;11624.825627615592;1850.851931845456;2444.022391043579;2478.39279376553;2479.0827393864297;2479.6776419065495;1953.1601411393094;2413.794827439661;2507.786838118753;2530.8861101250395;2535.366158460385;2444.6455263986713;2473.9010439582394;2480.4419005723657;2481.280428675682;2481.553320402327;2259.164109279569;2263.7509873913855;2266.626006708891;2269.3645779181857;2282.7146606674164;2259.164109279577;2282.292489328297;2282.8630282182958;2286.588414085464;2287.2109122352763;10830.079443247334;10925.07136893365;10956.890431095666;11000.339979601249;11000.449977501094;5698.059820129673;5832.941670583325;5842.386549412293;5845.678487683176;5847.56143289255;3372.1738174147245;3409.1988382730287;3559.839807208656;3581.3195492856535;3596.6762991330907;1482.8072350594462;1485.123776971592;1518.6507103366287;1537.415782002843;1550.1086875046892;17155.656886862194;17161.399351022777;17172.22724977379;17203;17218.827811721847;4766.237402015661;4989.7006179628615;5010.398752149766;5016.899662006813;5020.021095886267;14478.420863165566;14496;14505.854941450661;14509.201993890227;14510.709785804225;6062.727177277033;6069.575129740964;6077.604955677867;6085.148079268906;6085.878282434326;4480.529544397857;4619.86140415788;5442.945570544323;5477.04151773438;5505.229267902497;3861.8455261789213;4381.583749543788;4400.3399490076745;4525.841595544206;4561.543845615478;1173.524722487394;1189.8810118988206;1190.2085113399664;1193.570314686712;1211.4669545400172;1088.1621151713136;1100.0469471967488;1110.36775939369;1114.4093630375914;1122.8820973797797;7076.292370762985;7129.714811407485;7309.269073092754;7408.925910740932;7432.9256707433315;1118.904893084097;1131.8132508136066;1151.205668089016;1153.6308381317897;1173.9589114380956;4715.646326525524;4974.081890250937;4977.776000080005;4994.175960966472;5100.030994111181;13969.511067112602;14511.1293322401;14584.48954286595;14601.269936503344;14611.488597899022;14189.929050354682;14196.645083872985;14341.99606027589;14434.061785984088;14446.638834029234;10219.978002199869;10541.104006159563;10622.787544249071;10653.467326633638;10730.517126729315;7216.530925489827;7228.421726261781;7280.745173918887;7342.082239720035;7429.851402972018;12708.618741437771;12972.35138243103;13055.21668699881;13164.210147391188;13186.670333241746;13119.081664283409;13270.469181232642;13316;13319.733605328032;13444.731105378034;8024.117347091698;9760.121589057018;9948.45283509414;9949.104580587782;9969.252306076929;9028.638854445748;11060.225784195029;11285.830712539155;11350.091992640733;11411.60059397917;9265.583048762686;9282.814343713222;9298.814023719622;9333.673321433716;9463.2429405646;8921.866172007554;9041.412308199946;9075.682351117679;9083.591238394156;9241.260699143917;8336.7915802103;8368.581570921551;8423.241908228289;8455.027671817574;8515.105913878953;6356.9046464304;6377.74489020434;6395.744170233139;6484.533531304132;6569.507286953402;6315.08431277453;6498.610083395011;6570.342965703391;6603.20761508622;6628.105205797156 10:24:29 INFO - PROCESS | 29423 | |4;prototype.html;412.2065024827207;414.77394819823195;427.5318526213796;435.23181584503357;440.48463297941424;5126.436092029874;5136.974315128419;5233.555147812436;5344.866378340544;5366.651167674103;5053.267276244942;5165.9225111623355;5175.430702622718;5198.8700282492955;5225.712585807779;4467.82128714851;4474.284114541674;4486.439195100613;4487.551244875503;4492.573205545468;4389.122175564884;4443.866683999485;4477.328400739882;4491.550844915499;4495.011097558532;4445.777711114448;4448.755318457484;4450.933236001462;4460.420145381112;4468.687191896569;4436.803782127835;4439.200943830126;4452.821887124511;4472.306792447176;4472.865814025585;4100.794960252006;4305.557638191179;4308.202982448257;4327.199468098381;4328.134373125387;5040.470750571174;5059.824275561507;5102.260172275037;5132.769025393866;5149.562287205592;5137.3064636274;5218.921716174261;5226.503482169188;5261.921071183935;5279.128943724281;5309;5467.781288748445;5495.340559132929;5516.7517461714315;5519.31008623922;4783.545563171493;4785.306130611042;4804.645723595448;4813.518648135194;4827.758612069418;5688.260526131577;5714.799982000652;5717.942820571782;5731.598788084836;5740.7703691852275;4848.442429120626;4910.238912968424;4967.751612419364;4988;4996.275540046673;5020.221865610836;5053.917110562554;5054.696718196847;5075.695458272516;5088.440271570124;5250.343707036621;5258.1849813279005;5263.36839579253;5270.683758974474;5289.090999950012;5245.370555533361;5451.454854514517;5474.3303292495375;5479.479449452296;5517.337919449611;1551.5733173377412;1570.9607259818367;1616.1272912627285;1661.9252133654015;1662.401535447238;1240.4480006397248;1256.5978886756334;1266.968325791844;1286.2668279080833;1287.3305880942046;5367.845913128696;5387.464572596791;5399.352077750695;5403.189521571796;5405.918816236768;5593.916091258634;5635.943640563624;5642.802501912413;5643;5654.6324488908085;5248.448912864171;5280.524752772269;5352.4915133062295;5397.892042159135;5400.351957765015;2954.6897575754665;2956.52695568708;2957.778166637511;2960.1119664100684;2968.257935516121;5469.371022332482;5786.681732504752;5789.334226563915;5810.564207684441;5825.514493804053;5602.299712535933;5654.660720356792;5705.258316418839;5713.342965558931;5737.565608597851;5701.4298570142655;5770.538356931435;5798.449147330997;5807.274090738658;5832.912506312494;5279.26090347344;5529.668219906818;5543.835794483204;5629.437056294338;5633.380328163817;5473.2063850742215;5475.561955043667;5550.195221692913;5551.750171242223;5620.437956204347;5784.392638773036;5789.652620842763;5865.269745425025;5884.6469211847425;5889.587728858939;4566.931496027629;4711.764411779466;4712.622990160848;4721.512723492089;4733.195356789423;5430.126606320813;5539.806106786243;5647.6893770843;5664.886702266014;5673.9432605675065;5403.756830942539;5515.834524964097;5597.916031259616;5605.551555875439;5613.494785469327;5522.183686319925;5580.469855363896;5586.664800112007;5599.216109744721;5605.243292155506;5236.764345604381;5490.752916118864;5527.309086364205;5555.611107222618;5568.331800184003;5544.500994910478;5576.163575463713;5580.316990786062;5581.6371935824045;5590.301212348457;5249.7375131242525;5467.687754938706;5483.835484935458;5485.451454854642;5523.061079616395 10:24:29 INFO - PROCESS | 29423 | |5;yui.html;827.0447632983905;879.728791895711;911.5123408976195;929.6374413978548;942.1238248428963;14954.252287385629;15038.097714137159;15066.2466876656;15152.090874547535;16731.54667612798;16528.173591320447;16862.915685421554;16954.49136525903;17159.74260386092;17184.312627494885;848.8302339532088;866.9913300867005;870.0212261206143;872.7948932000975;873.3624454148476;869.1829680100717;895.274827389813;904.3307952115421;905.1672461335554;906.1074841281333;938.6714649872558;940.1773448232797;941.1623654947102;946.3706635087658;949.7862980829327;939.5161491831713;940.5673390240497;940.8118376324762;941.0354386753564;941.1247539787994;687.7902239816854;688.5249178067118;688.7245101959256;689.1385767790263;689.1557841643996;17851.196696148705;18041.278348866028;18120.63758724818;18273.451796446127;18454.35409760652;13480.730385392335;13555.86444135556;13840.584782456543;13941.930290348484;14023.228722420263;13604;13733.45066197351;13750.106243094167;13928.164310141425;13971.79042314366;3860.4402361657403;3937.330653788863;3943.7042221833476;3946.3093958557424;3986.621270979252;90271.22935377447;91836.54081729549;91974;93261.53369233178;94252;12792.232466052066;12822.80765788514;12825.615231543068;12835.165714228546;12875.613731588068;24877;25111.121110761036;25251.242462726146;25260.621090683653;25600.231993040237;7880.960595196987;8675.696350627824;8744.737657870273;8914.064023277462;8939.382614545286;4803.351547541107;4876.171050921352;4910.189818679878;4950.43070046942;4967.726775027336;3752.399616061417;3853;3853.9807300963316;3855.845766169322;3856.5950575189763;626.4017862940881;636.4685487617799;636.5448704176536;648.3807963394946;655.8425977765302;655.5640499068162;657.9605223686594;676.631235976394;687.4431710314661;827.896512935883;10591.57633694659;11519.136064795173;11591.420428978518;11615.941920290345;11906.285622862655;658.6015460646336;680.9761658342034;684.8630273945231;706.4313227851629;800.9799755006055;5531.861703457365;5611.410801865745;5764.855878603068;5774.393688662714;5806.564507661942;77138.2286177131;78302;78567.60716196382;80183;83337;60586.39413605896;66235.66882165655;68170;69557.65221173962;70176.6491167541;53574.46425535695;53734.7313263439;59362.70318648466;61694;62405.68797156076;11793.52825886955;12181.634550963485;12254.20347677398;12279.877201227873;12585.93707031459;35383.82308088443;36380.454293185096;37562.24875502529;39453;42398;47403.28895066646;47879.281810773566;48296.517034830606;49095.754521227165;50169.99660006852;13439.529616463376;13474.326283685972;13569.728605428034;13764.31178441094;14008.299585020912;15967.361305548113;16206.756898646294;16812.495625131265;17540.828132059756;17810.287588496314;15767.526974190792;15775;15838.445654402041;16125.919370403073;16154.838451615333;14817.999140231861;15024.549263522113;15050.69898602;15214.543563693107;15590.04907497162;15199.696006080037;15386.307616157075;15428.768568471258;15436.382544698086;16363.34546618122;10854.18593605467;10899.29154604948;11145.275557088764;11180.217384782987;11400.771984560428;12729.236245825281;12732.427040783372;12932.159409638343;12960.546380876624;13010.609681709564 10:24:29 INFO - PROCESS | 29423 | __end_tp_report 10:24:29 INFO - PROCESS | 29423 | __start_cc_report 10:24:29 INFO - PROCESS | 29423 | _x_x_mozilla_cycle_collect,1221 10:24:29 INFO - PROCESS | 29423 | __end_cc_report 10:24:29 INFO - PROCESS | 29423 | __startTimestamp1491931469408__endTimestamp 10:24:29 INFO - PROCESS | 29423 | 10:24:29 INFO - PROCESS | 29423 | ------- Summary: start ------- 10:24:29 INFO - PROCESS | 29423 | Number of tests: 6 10:24:29 INFO - PROCESS | 29423 | 10:24:29 INFO - PROCESS | 29423 | [#0] dojo.html Cycles:165 Average:5723.48 Median:5977.25 stddev:678.30 (11.3%) stddev-sans-first:654.21 10:24:29 INFO - PROCESS | 29423 | Values: 3344.9 3378.2 3386.7 3412.1 3446.5 5731.2 5739.8 5758.1 5763.3 5774.9 5754.1 5806.8 5809.5 5810.8 5820.2 5146.8 5153.2 5156.6 5176.9 5204.5 5010.1 5254.3 5263.5 5264.0 5292.8 5064.6 5075.9 5110.4 5184.0 5199.8 5179.6 5203.8 5205.0 5209.3 5215.1 4948.1 5014.6 5082.8 5130.9 5138.6 5643.3 5644.4 5697.5 5711.3 5724.6 6172.7 6211.1 6220.9 6225.8 6271.1 6235.5 6244.3 6261.5 6278.9 6283.2 5596.6 5638.4 5728.2 5792.3 5841.1 6344.0 6350.2 6351.2 6354.1 6354.1 6061.4 6108.6 6110.6 6120.7 6123.4 6021.1 6086.6 6087.7 6139.1 6225.9 6147.5 6174.5 6184.5 6186.4 6190.3 5925.4 5963.6 6050.3 6178.8 6181.5 4508.3 4582.4 4599.7 4602.6 4618.3 4091.8 4100.5 4102.8 4117.3 4233.7 5952.2 5955.3 5967.1 5984.2 6002.9 6129.8 6136.6 6159.8 6160.3 6163.7 5814.4 5861.8 5878.1 5900.9 5908.1 5276.1 5281.5 5296.5 5301.5 5307.3 6476.6 6478.4 6484.4 6486.2 6489.4 5861.4 5908.5 6242.0 6243.3 6246.1 6314.6 6336.5 6427.6 6447.5 6472.9 6129.5 6132.7 6136.4 6136.8 6139.4 6192.0 6245.5 6249.3 6256.2 6261.2 5838.4 5870.7 6001.5 6057.2 6134.5 6083.2 6087.4 6089.8 6090.2 6121.4 5838.0 5856.5 6042.4 6049.5 6053.2 5926.7 6022.5 6041.5 6124.8 6145.8 5977.3 6003.4 6010.0 6013.8 6037.6 10:24:29 INFO - PROCESS | 29423 | 10:24:29 INFO - PROCESS | 29423 | [#1] ext.html Cycles:180 Average:44726.59 Median:291537.50 stddev:72319.16 (24.8%) stddev-sans-first:72446.78 10:24:29 INFO - PROCESS | 29423 | Values: 786.8 833.3 857.9 881.9 898.4 31389.4 44120.3 44483.0 44878.1 45000.3 44618.6 44848.8 44870.6 44880.2 44905.0 7709.1 7875.3 8635.2 8752.5 8756.9 10477.7 10727.8 11046.2 11051.7 11056.7 10714.7 10731.9 10756.0 10776.2 10778.2 10634.1 10677.3 10680.5 10691.4 10744.1 10582.2 10625.0 10627.6 10632.6 10634.5 41337.6 41448.6 41639.2 41639.2 41699.8 4099.3 4131.2 4153.4 4156.7 4175.6 29524.0 29790.4 29945.9 29984.7 30345.1 4268.2 4303.3 4364.7 4393.2 4473.6 239410.0 240498.0 240535.0 240891.0 241720.0 54923.5 55390.4 55463.7 55489.0 55567.7 57488.4 57544.4 57556.1 57704.1 57826.0 15459.1 15784.9 15797.7 15800.4 15805.0 13375.7 13553.3 13628.3 13663.2 13668.5 13342.9 13471.7 13572.1 13933.7 14009.1 5681.5 5682.5 5683.1 5687.9 5752.0 1021.6 1024.6 1027.7 1028.3 1035.4 28025.0 28078.9 28221.2 28548.0 28636.6 1104.6 1106.9 1108.3 1116.7 1122.8 14231.9 15304.6 15487.8 15719.0 15736.8 294631.0 295763.0 297135.0 297730.0 298598.0 55600.7 55786.4 55859.7 55871.4 56004.0 1493.1 1521.6 1546.1 1553.6 1562.4 26209.9 27030.2 27429.0 27637.6 28023.9 41798.8 41874.8 41935.0 41970.4 42023.8 290689.0 290725.0 291315.0 291450.0 291625.0 29735.7 32457.4 34745.5 34847.3 36047.3 45213.8 46352.5 46373.0 46383.0 46467.0 28429.4 28465.3 29272.0 29341.7 29709.6 28786.4 28871.9 28884.3 28943.6 29074.4 37244.3 39308.8 39507.4 39757.2 40010.2 26191.7 26296.0 26401.2 26500.7 26532.7 25307.0 25360.0 25389.5 25813.4 25823.5 10:24:29 INFO - PROCESS | 29423 | 10:24:29 INFO - PROCESS | 29423 | [#2] jquery.html Cycles:180 Average:38191.26 Median:21304.52 stddev:106424.69 (499.5%) stddev-sans-first:106685.53 10:24:29 INFO - PROCESS | 29423 | Values: 463.8 464.2 480.1 491.4 492.4 19511.4 21531.7 21601.0 21936.5 22055.8 22533.9 22792.8 22846.5 23074.7 23157.0 6567.6 6569.2 6589.2 6642.1 6644.6 6593.1 6721.2 7513.4 7859.8 7880.1 7854.0 7874.0 7876.1 7995.1 8015.6 7887.9 8042.8 8080.1 8081.2 8118.9 7942.8 7948.7 7996.4 8008.3 8014.6 28956.7 28966.7 29785.0 29880.0 29892.7 3144.2 3158.7 3197.3 3202.0 3226.6 17602.6 17637.4 18186.5 18278.2 19039.8 2357.0 2428.1 2429.7 2472.7 2497.2 118836.4 122891.0 122902.0 123542.0 123587.4 21077.4 22334.7 22372.0 22483.2 22904.3 43611.3 43788.0 44219.0 44414.6 44492.0 11331.7 11376.1 11415.6 11538.3 11558.8 9008.5 9053.4 9063.6 9313.5 9371.3 11077.6 11312.8 11586.9 11603.7 11644.4 4321.8 4326.9 4436.2 4477.8 4564.0 1156.8 1187.3 1189.8 1190.2 1191.3 20482.5 20620.3 20730.7 20758.8 20860.4 1150.9 1160.6 1181.7 1187.6 1210.8 10862.2 11315.4 11325.8 11334.9 11392.1 464127.0 667543.0 676260.0 683287.0 698937.0 84841.0 117452.0 122350.4 124650.0 125451.4 1337.9 1384.2 1385.5 1394.0 1398.0 18972.8 19246.6 19281.7 19295.6 19341.1 52409.0 52621.5 52673.2 52931.7 54836.2 67950.3 68701.7 68838.0 69472.0 70275.0 12123.5 12241.8 12321.9 12468.1 12488.6 13123.5 13263.3 13293.9 13562.9 13734.0 11310.5 11506.9 11721.9 11852.0 11906.4 10205.5 10555.2 10571.6 10657.8 10878.5 11670.0 11685.2 11780.3 11791.6 11990.2 11956.0 12369.5 12420.9 12478.2 12764.2 11451.6 11568.4 11687.8 11964.9 11999.4 10:24:29 INFO - PROCESS | 29423 | 10:24:29 INFO - PROCESS | 29423 | [#3] mootools.html Cycles:180 Average:7176.58 Median:2428.91 stddev:4571.65 (188.2%) stddev-sans-first:4564.05 10:24:29 INFO - PROCESS | 29423 | Values: 1425.7 1517.7 1546.2 1583.9 1585.2 8803.6 10998.1 11318.3 11386.8 11390.8 11527.1 11605.8 11610.9 11612.5 11624.8 1850.9 2444.0 2478.4 2479.1 2479.7 1953.2 2413.8 2507.8 2530.9 2535.4 2444.6 2473.9 2480.4 2481.3 2481.6 2259.2 2263.8 2266.6 2269.4 2282.7 2259.2 2282.3 2282.9 2286.6 2287.2 10830.1 10925.1 10956.9 11000.3 11000.4 5698.1 5832.9 5842.4 5845.7 5847.6 3372.2 3409.2 3559.8 3581.3 3596.7 1482.8 1485.1 1518.7 1537.4 1550.1 17155.7 17161.4 17172.2 17203.0 17218.8 4766.2 4989.7 5010.4 5016.9 5020.0 14478.4 14496.0 14505.9 14509.2 14510.7 6062.7 6069.6 6077.6 6085.1 6085.9 4480.5 4619.9 5442.9 5477.0 5505.2 3861.8 4381.6 4400.3 4525.8 4561.5 1173.5 1189.9 1190.2 1193.6 1211.5 1088.2 1100.0 1110.4 1114.4 1122.9 7076.3 7129.7 7309.3 7408.9 7432.9 1118.9 1131.8 1151.2 1153.6 1174.0 4715.6 4974.1 4977.8 4994.2 5100.0 13969.5 14511.1 14584.5 14601.3 14611.5 14189.9 14196.6 14342.0 14434.1 14446.6 10220.0 10541.1 10622.8 10653.5 10730.5 7216.5 7228.4 7280.7 7342.1 7429.9 12708.6 12972.4 13055.2 13164.2 13186.7 13119.1 13270.5 13316.0 13319.7 13444.7 8024.1 9760.1 9948.5 9949.1 9969.3 9028.6 11060.2 11285.8 11350.1 11411.6 9265.6 9282.8 9298.8 9333.7 9463.2 8921.9 9041.4 9075.7 9083.6 9241.3 8336.8 8368.6 8423.2 8455.0 8515.1 6356.9 6377.7 6395.7 6484.5 6569.5 6315.1 6498.6 6570.3 6603.2 6628.1 10:24:29 INFO - PROCESS | 29423 | 10:24:29 INFO - PROCESS | 29423 | [#4] prototype.html Cycles:180 Average:4837.90 Median:5279.19 stddev:1272.18 (24.1%) stddev-sans-first:1231.62 10:24:29 INFO - PROCESS | 29423 | Values: 412.2 414.8 427.5 435.2 440.5 5126.4 5137.0 5233.6 5344.9 5366.7 5053.3 5165.9 5175.4 5198.9 5225.7 4467.8 4474.3 4486.4 4487.6 4492.6 4389.1 4443.9 4477.3 4491.6 4495.0 4445.8 4448.8 4450.9 4460.4 4468.7 4436.8 4439.2 4452.8 4472.3 4472.9 4100.8 4305.6 4308.2 4327.2 4328.1 5040.5 5059.8 5102.3 5132.8 5149.6 5137.3 5218.9 5226.5 5261.9 5279.1 5309.0 5467.8 5495.3 5516.8 5519.3 4783.5 4785.3 4804.6 4813.5 4827.8 5688.3 5714.8 5717.9 5731.6 5740.8 4848.4 4910.2 4967.8 4988.0 4996.3 5020.2 5053.9 5054.7 5075.7 5088.4 5250.3 5258.2 5263.4 5270.7 5289.1 5245.4 5451.5 5474.3 5479.5 5517.3 1551.6 1571.0 1616.1 1661.9 1662.4 1240.4 1256.6 1267.0 1286.3 1287.3 5367.8 5387.5 5399.4 5403.2 5405.9 5593.9 5635.9 5642.8 5643.0 5654.6 5248.4 5280.5 5352.5 5397.9 5400.4 2954.7 2956.5 2957.8 2960.1 2968.3 5469.4 5786.7 5789.3 5810.6 5825.5 5602.3 5654.7 5705.3 5713.3 5737.6 5701.4 5770.5 5798.4 5807.3 5832.9 5279.3 5529.7 5543.8 5629.4 5633.4 5473.2 5475.6 5550.2 5551.8 5620.4 5784.4 5789.7 5865.3 5884.6 5889.6 4566.9 4711.8 4712.6 4721.5 4733.2 5430.1 5539.8 5647.7 5664.9 5673.9 5403.8 5515.8 5597.9 5605.6 5613.5 5522.2 5580.5 5586.7 5599.2 5605.2 5236.8 5490.8 5527.3 5555.6 5568.3 5544.5 5576.2 5580.3 5581.6 5590.3 5249.7 5467.7 5483.8 5485.5 5523.1 10:24:29 INFO - PROCESS | 29423 | 10:24:29 INFO - PROCESS | 29423 | [#5] yui.html Cycles:180 Average:18302.41 Median:21698.35 stddev:22718.42 (104.7%) stddev-sans-first:22744.25 10:24:29 INFO - PROCESS | 29423 | Values: 827.0 879.7 911.5 929.6 942.1 14954.3 15038.1 15066.2 15152.1 16731.5 16528.2 16862.9 16954.5 17159.7 17184.3 848.8 867.0 870.0 872.8 873.4 869.2 895.3 904.3 905.2 906.1 938.7 940.2 941.2 946.4 949.8 939.5 940.6 940.8 941.0 941.1 687.8 688.5 688.7 689.1 689.2 17851.2 18041.3 18120.6 18273.5 18454.4 13480.7 13555.9 13840.6 13941.9 14023.2 13604.0 13733.5 13750.1 13928.2 13971.8 3860.4 3937.3 3943.7 3946.3 3986.6 90271.2 91836.5 91974.0 93261.5 94252.0 12792.2 12822.8 12825.6 12835.2 12875.6 24877.0 25111.1 25251.2 25260.6 25600.2 7881.0 8675.7 8744.7 8914.1 8939.4 4803.4 4876.2 4910.2 4950.4 4967.7 3752.4 3853.0 3854.0 3855.8 3856.6 626.4 636.5 636.5 648.4 655.8 655.6 658.0 676.6 687.4 827.9 10591.6 11519.1 11591.4 11615.9 11906.3 658.6 681.0 684.9 706.4 801.0 5531.9 5611.4 5764.9 5774.4 5806.6 77138.2 78302.0 78567.6 80183.0 83337.0 60586.4 66235.7 68170.0 69557.7 70176.6 53574.5 53734.7 59362.7 61694.0 62405.7 11793.5 12181.6 12254.2 12279.9 12585.9 35383.8 36380.5 37562.2 39453.0 42398.0 47403.3 47879.3 48296.5 49095.8 50170.0 13439.5 13474.3 13569.7 13764.3 14008.3 15967.4 16206.8 16812.5 17540.8 17810.3 15767.5 15775.0 15838.4 16125.9 16154.8 14818.0 15024.5 15050.7 15214.5 15590.0 15199.7 15386.3 15428.8 15436.4 16363.3 10854.2 10899.3 11145.3 11180.2 11400.8 12729.2 12732.4 12932.2 12960.5 13010.6 10:24:29 INFO - PROCESS | 29423 | -------- Summary: end -------- 10:24:29 INFO - PROCESS | 29423 | 10:24:30 INFO - TEST-INFO | 29423: exit 0 10:24:30 INFO - TEST-OK | dromaeo_css | took 1115624ms 10:24:30 INFO - TEST-START | kraken 10:24:30 INFO - operating with platform_type : linux_ 10:24:30 INFO - Initialising browser for kraken test... 10:24:30 INFO - TEST-INFO | started process 29750 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyiQhU2/profile http://localhost:59152/getInfo.html) 10:24:34 INFO - PROCESS | 29750 | __metrics Screen width/height:1600/1200 10:24:34 INFO - PROCESS | 29750 | colorDepth:24 10:24:34 INFO - PROCESS | 29750 | Browser inner width/height: 1024/697 10:24:34 INFO - PROCESS | 29750 | __metrics 10:24:35 INFO - PROCESS | 29750 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:24:35 INFO - PROCESS | 29750 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:24:36 INFO - TEST-INFO | 29750: exit 0 10:24:36 INFO - Browser initialized. 10:24:36 INFO - Running cycle 1/1 for kraken test... 10:24:36 INFO - TEST-INFO | started process 29843 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyiQhU2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:24:48 INFO - PROCESS | 29843 | RSS: Main: 140161024 10:24:48 INFO - PROCESS | 29843 | 10:24:51 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59152/tests/kraken/driver.html?testName=audio-beat-detection) 10:24:52 INFO - PROCESS | 29843 | RSS: Main: 183267328 10:24:52 INFO - PROCESS | 29843 | 10:24:54 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59152/tests/kraken/driver.html?testName=audio-dft) 10:24:55 INFO - PROCESS | 29843 | RSS: Main: 174112768 10:24:55 INFO - PROCESS | 29843 | 10:24:57 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59152/tests/kraken/driver.html?testName=audio-fft) 10:24:58 INFO - PROCESS | 29843 | RSS: Main: 265625600 10:24:58 INFO - PROCESS | 29843 | 10:25:00 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59152/tests/kraken/driver.html?testName=audio-oscillator) 10:25:01 INFO - PROCESS | 29843 | RSS: Main: 172060672 10:25:01 INFO - PROCESS | 29843 | 10:25:03 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59152/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:25:03 INFO - PROCESS | 29843 | RSS: Main: 174649344 10:25:03 INFO - PROCESS | 29843 | 10:25:06 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59152/tests/kraken/driver.html?testName=imaging-darkroom) 10:25:07 INFO - PROCESS | 29843 | RSS: Main: 213995520 10:25:07 INFO - PROCESS | 29843 | 10:25:10 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59152/tests/kraken/driver.html?testName=imaging-desaturate) 10:25:11 INFO - PROCESS | 29843 | RSS: Main: 212520960 10:25:11 INFO - PROCESS | 29843 | 10:25:14 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59152/tests/kraken/driver.html?testName=json-parse-financial) 10:25:14 INFO - PROCESS | 29843 | RSS: Main: 210481152 10:25:14 INFO - PROCESS | 29843 | 10:25:16 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59152/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:25:16 INFO - PROCESS | 29843 | RSS: Main: 172814336 10:25:16 INFO - PROCESS | 29843 | 10:25:18 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59152/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:25:19 INFO - PROCESS | 29843 | RSS: Main: 179752960 10:25:19 INFO - PROCESS | 29843 | 10:25:21 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59152/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:25:21 INFO - PROCESS | 29843 | RSS: Main: 189112320 10:25:21 INFO - PROCESS | 29843 | 10:25:24 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59152/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:25:24 INFO - PROCESS | 29843 | RSS: Main: 198283264 10:25:24 INFO - PROCESS | 29843 | 10:25:27 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59152/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:25:28 INFO - PROCESS | 29843 | RSS: Main: 195067904 10:25:28 INFO - PROCESS | 29843 | 10:25:29 INFO - PROCESS | 29843 | Cycle 1(1): loaded http://localhost:59152/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59152/tests/kraken/driver.html?testName=ai-astar) 10:25:30 INFO - PROCESS | 29843 | RSS: Main: 245223424 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | __start_tp_report 10:25:30 INFO - PROCESS | 29843 | _x_x_mozilla_page_load 10:25:30 INFO - PROCESS | 29843 | _x_x_mozilla_page_load_details 10:25:30 INFO - PROCESS | 29843 | |i|pagename|runs| 10:25:30 INFO - PROCESS | 29843 | |0;ai-astar;103;112;112;113;113;114;113;112;112;112 10:25:30 INFO - PROCESS | 29843 | |1;audio-beat-detection;147;118;118;153;121;116;114;116;130;116 10:25:30 INFO - PROCESS | 29843 | |2;audio-dft;163;163;162;162;161;164;159;163;160;159 10:25:30 INFO - PROCESS | 29843 | |3;audio-fft;75;77;75;101;76;76;76;77;100;76 10:25:30 INFO - PROCESS | 29843 | |4;audio-oscillator;82;83;105;82;82;82;115;81;82;104 10:25:30 INFO - PROCESS | 29843 | |5;imaging-gaussian-blur;113;113;113;112;113;113;113;114;112;113 10:25:30 INFO - PROCESS | 29843 | |6;imaging-darkroom;146;146;145;147;146;146;148;147;146;146 10:25:30 INFO - PROCESS | 29843 | |7;imaging-desaturate;94;94;94;94;95;95;94;94;95;94 10:25:30 INFO - PROCESS | 29843 | |8;json-parse-financial;55;56;54;54;54;54;54;53;54;54 10:25:30 INFO - PROCESS | 29843 | |9;json-stringify-tinderbox;52;52;52;52;52;53;53;53;52;52 10:25:30 INFO - PROCESS | 29843 | |10;stanford-crypto-aes;75;75;76;76;79;82;81;75;79;79 10:25:30 INFO - PROCESS | 29843 | |11;stanford-crypto-ccm;122;124;122;122;128;128;117;117;156;131 10:25:30 INFO - PROCESS | 29843 | |12;stanford-crypto-pbkdf2;157;158;161;157;156;160;159;170;160;160 10:25:30 INFO - PROCESS | 29843 | |13;stanford-crypto-sha256-iterative;68;60;60;61;59;59;60;60;60;58 10:25:30 INFO - PROCESS | 29843 | __end_tp_report 10:25:30 INFO - PROCESS | 29843 | __start_cc_report 10:25:30 INFO - PROCESS | 29843 | _x_x_mozilla_cycle_collect,1668 10:25:30 INFO - PROCESS | 29843 | __end_cc_report 10:25:30 INFO - PROCESS | 29843 | __startTimestamp1491931530106__endTimestamp 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | ------- Summary: start ------- 10:25:30 INFO - PROCESS | 29843 | Number of tests: 14 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:3.10 (2.8%) stddev-sans-first:0.73 10:25:30 INFO - PROCESS | 29843 | Values: 103.0 112.0 112.0 113.0 113.0 114.0 113.0 112.0 112.0 112.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:119.50 stddev:14.03 (11.7%) stddev-sans-first:12.39 10:25:30 INFO - PROCESS | 29843 | Values: 147.0 118.0 118.0 153.0 121.0 116.0 114.0 116.0 130.0 116.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.50 stddev:1.78 (1.1%) stddev-sans-first:1.81 10:25:30 INFO - PROCESS | 29843 | Values: 163.0 163.0 162.0 162.0 161.0 164.0 159.0 163.0 160.0 159.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#3] audio-fft Cycles:10 Average:80.90 Median:76.00 stddev:10.35 (13.6%) stddev-sans-first:10.76 10:25:30 INFO - PROCESS | 29843 | Values: 75.0 77.0 75.0 101.0 76.0 76.0 76.0 77.0 100.0 76.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:82.00 stddev:12.89 (15.7%) stddev-sans-first:13.36 10:25:30 INFO - PROCESS | 29843 | Values: 82.0 83.0 105.0 82.0 82.0 82.0 115.0 81.0 82.0 104.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 10:25:30 INFO - PROCESS | 29843 | Values: 113.0 113.0 113.0 112.0 113.0 113.0 113.0 114.0 112.0 113.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 10:25:30 INFO - PROCESS | 29843 | Values: 146.0 146.0 145.0 147.0 146.0 146.0 148.0 147.0 146.0 146.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:25:30 INFO - PROCESS | 29843 | Values: 94.0 94.0 94.0 94.0 95.0 95.0 94.0 94.0 95.0 94.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:0.79 (1.5%) stddev-sans-first:0.78 10:25:30 INFO - PROCESS | 29843 | Values: 55.0 56.0 54.0 54.0 54.0 54.0 54.0 53.0 54.0 54.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#9] json-stringify-tinderbox Cycles:10 Average:52.30 Median:52.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 10:25:30 INFO - PROCESS | 29843 | Values: 52.0 52.0 52.0 52.0 52.0 53.0 53.0 53.0 52.0 52.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#10] stanford-crypto-aes Cycles:10 Average:77.70 Median:79.00 stddev:2.63 (3.3%) stddev-sans-first:2.60 10:25:30 INFO - PROCESS | 29843 | Values: 75.0 75.0 76.0 76.0 79.0 82.0 81.0 75.0 79.0 79.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:126.00 stddev:11.26 (8.9%) stddev-sans-first:11.82 10:25:30 INFO - PROCESS | 29843 | Values: 122.0 124.0 122.0 122.0 128.0 128.0 117.0 117.0 156.0 131.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.80 Median:160.00 stddev:3.94 (2.5%) stddev-sans-first:4.04 10:25:30 INFO - PROCESS | 29843 | Values: 157.0 158.0 161.0 157.0 156.0 160.0 159.0 170.0 160.0 160.0 10:25:30 INFO - PROCESS | 29843 | 10:25:30 INFO - PROCESS | 29843 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.50 Median:60.00 stddev:2.76 (4.6%) stddev-sans-first:0.87 10:25:30 INFO - PROCESS | 29843 | Values: 68.0 60.0 60.0 61.0 59.0 59.0 60.0 60.0 60.0 58.0 10:25:30 INFO - PROCESS | 29843 | -------- Summary: end -------- 10:25:30 INFO - PROCESS | 29843 | 10:25:31 INFO - TEST-INFO | 29843: exit 0 10:25:31 INFO - TEST-OK | kraken | took 60993ms 10:25:31 INFO - SUITE-END | took 1176s 10:25:31 INFO - Completed test suite (00:19:36) 10:25:31 INFO - javascript benchmark 10:25:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7924.421477588847, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.9331013379724, 3378.206121561433, 3386.745994050447, 3412.0787387405417, 3446.4657978013415, 5731.226284451598, 5739.770409183633, 5758.1362795580635, 5763.250777398932, 5774.855628609282, 5754.079347304432, 5806.82579522615, 5809.535237181026, 5810.825675229739, 5820.185174075622, 5146.794128234867, 5153.227015947619, 5156.58747300217, 5176.922346164811, 5204.505571970657, 5010.123228435027, 5254.31693879797, 5263.473652634744, 5264.0, 5292.814751483699, 5064.594832413417, 5075.9492405076035, 5110.386753589574, 5183.974080129594, 5199.766010529516, 5179.611529135311, 5203.843884683484, 5205.0, 5209.348831396075, 5215.139501982168, 4948.134076536628, 5014.598832093424, 5082.847514574569, 5130.948690513084, 5138.58891288696, 5643.266375371217, 5644.435556444364, 5697.487226149667, 5711.286089238845, 5724.5992780505385, 6172.69136543171, 6211.09939058834, 6220.87558248837, 6225.7509699611965, 6271.059341098826, 6235.501159907197, 6244.2819075805955, 6261.46777523911, 6278.937210627927, 6283.151774510428, 5596.636218645775, 5638.351589567212, 5728.198052272685, 5792.275965504312, 5841.094630332305, 6344.016677415008, 6350.206224221973, 6351.174347334817, 6354.078658594571, 6354.142190804275, 6061.424164704346, 6108.602940808833, 6110.5722599418505, 6120.693965301717, 6123.38766123393, 6021.126936594169, 6086.573939824169, 6087.665178415141, 6139.10982907476, 6225.937740622536, 6147.508199344042, 6174.475169610633, 6184.474319682878, 6186.443220110212, 6190.3190649028575, 5925.377835327315, 5963.5527335450015, 6050.334463209131, 6178.814635560941, 6181.505479561624, 4508.346289787963, 4582.404287442611, 4599.678022538457, 4602.631789456836, 4618.330342100377, 4091.774952377648, 4100.528439229467, 4102.815373308208, 4117.279476091672, 4233.661307095486, 5952.226210592596, 5955.255593050869, 5967.1347654589845, 5984.1921340618455, 6002.939970600238, 6129.785457508966, 6136.6011209271255, 6159.753609855556, 6160.322364539897, 6163.722632481549, 5814.360420353758, 5861.824145275648, 5878.147668588031, 5900.911486327708, 5908.14331921869, 5276.1030624793875, 5281.524662780368, 5296.523312901857, 5301.5493683037375, 5307.28351672527, 6476.6114033158165, 6478.384553467413, 6484.416402523795, 6486.2216534016225, 6489.415952564292, 5861.35525092231, 5908.468237858613, 6241.968790156202, 6243.250809902659, 6246.1255424239735, 6314.589551679126, 6336.524760643062, 6427.58220715652, 6447.451966582893, 6472.870542589216, 6129.540284478593, 6132.662703551169, 6136.386361364007, 6136.7852125175405, 6139.3553676863285, 6191.9690401547705, 6245.531585131042, 6249.34381889895, 6256.155419018556, 6261.154744109487, 5838.4453476919625, 5870.706464676664, 6001.549883758648, 6057.242844644419, 6134.478569321657, 6083.178770865877, 6087.421694938974, 6089.786857460145, 6090.23872015998, 6121.418465245794, 5837.970810145922, 5856.502197313276, 6042.425969532887, 6049.485793707408, 6053.243344581927, 5926.703664816656, 6022.488088512526, 6041.486473649789, 6124.777645458507, 6145.832291864532, 5977.252843394575, 6003.429674180946, 6010.038393856962, 6013.789517366871, 6037.577369584087], "value": 5676.48170520408, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.8111653203233, 833.2833762963852, 857.9142085791422, 881.9382643214977, 898.4384759525296, 31389.372212555736, 44120.33819492706, 44483.0, 44878.102437951304, 45000.3249951251, 44618.55381446185, 44848.77575612118, 44870.55129448704, 44880.20479180829, 44905.0, 7709.113451953013, 7875.251851074138, 8635.179657932502, 8752.474851508898, 8756.912430875705, 10477.685669429891, 10727.78544429111, 11046.226764126473, 11051.72370690735, 11056.668299951014, 10714.732131696732, 10731.892681073168, 10756.0, 10776.19178561607, 10778.245522813406, 10634.149268058576, 10677.305975111633, 10680.465976701173, 10691.358518488916, 10744.14046876248, 10582.153427725763, 10625.0, 10627.628033018807, 10632.627858024933, 10634.46827658614, 41337.58662413368, 41448.585514144776, 41639.1672166558, 41639.1672166558, 41699.7915010426, 4099.262132816092, 4131.194417088669, 4153.418521407006, 4156.729812562174, 4175.645070169039, 29524.0, 29790.40419191604, 29945.850270748506, 29984.700152998626, 30345.089647310615, 4268.167707297078, 4303.289957157065, 4364.716293440916, 4393.231184542724, 4473.552644735533, 239410.0, 240497.99999999825, 240535.0, 240891.0, 241720.0, 54923.450765491834, 55390.44609553934, 55463.72268138714, 55489.0, 55567.72216138974, 57488.42511574831, 57544.42455575475, 57556.13665795016, 57704.134437982626, 57826.0, 15459.149746764037, 15784.92107539455, 15797.68404631901, 15800.36798528069, 15805.0, 13375.665608359868, 13553.254570998493, 13628.318584070956, 13663.248521331423, 13668.52160174389, 13342.866571334356, 13471.663208419672, 13572.117812342167, 13933.676300751413, 14009.108770315877, 5681.545476361881, 5682.488576028095, 5683.147527870769, 5687.8862422752145, 5752.0, 1021.642425151206, 1024.5594394410325, 1027.712240572641, 1028.3367228137809, 1035.4460363705382, 28025.038247322493, 28078.85960570184, 28221.15336539907, 28548.0, 28636.570451443244, 1104.5857803323754, 1106.9225154239125, 1108.2630051016145, 1116.7208197950513, 1122.848415463902, 14231.857681423053, 15304.617384565252, 15487.767683484757, 15719.0, 15736.842631573767, 294631.0, 295763.0, 297135.0, 297730.0, 298598.0, 55600.721996390566, 55786.44213557894, 55859.72070139705, 55871.44128558744, 56004.0, 1493.111598598832, 1521.5739592914224, 1546.087808193149, 1553.642662187703, 1562.3672412672886, 26209.868950655124, 27030.1890943272, 27429.0, 27637.58543621807, 28023.859880701282, 41798.791006044776, 41874.79062604668, 41935.0, 41970.37044444275, 42023.7898810504, 290689.0, 290725.0, 291315.0, 291450.0, 291625.0, 29735.702642973294, 32457.35085298328, 34745.47881781826, 34847.303053939286, 36047.27905441824, 45213.773931130134, 46352.53647463482, 46373.0, 46383.0, 46467.0, 28429.431411372072, 28465.28836779097, 29272.0, 29341.706582933897, 29709.5543566851, 28786.424271514872, 28871.85564072166, 28884.277893052, 28943.565846511898, 29074.418511630072, 37244.25511489809, 39308.80345598254, 39507.40738888861, 39757.2048559033, 40010.1997960045, 26191.73808261893, 26296.0, 26401.207963761117, 26500.73499264983, 26532.734672653027, 25307.0, 25360.0, 25389.492210155324, 25813.3546661335, 25823.483530329664], "value": 18795.10984137501, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.7959297908922, 464.1899884701197, 480.0998128509045, 491.39558343237815, 492.3771429142134, 19511.41465756028, 21531.67702484464, 21601.02795374208, 21936.45158871025, 22055.779442205574, 22533.887330563364, 22792.772072279273, 22846.543069138606, 23074.65388019181, 23157.0, 6567.573107748005, 6569.211694596631, 6589.20929488462, 6642.136522252101, 6644.60132392058, 6593.142891424132, 6721.1598550181225, 7513.361364284043, 7859.842803143933, 7880.093789214227, 7854.0, 7874.0, 7876.05487341517, 7995.120536740954, 8015.639296231654, 7887.921120788834, 8042.798930026737, 8080.070791858923, 8081.191880811931, 8118.878216826723, 7942.841143177104, 7948.682052717884, 7996.4402491825595, 8008.279254867031, 8014.599270036533, 28956.710432895612, 28966.710332896822, 29785.0, 29880.0, 29892.70107298943, 3144.1825125467544, 3158.731507821838, 3197.328561002192, 3202.0, 3226.58054452922, 17602.647947041114, 17637.382691605733, 18186.45440636783, 18278.24971791296, 19039.809601904082, 2356.952860942789, 2428.0651948999584, 2429.7448767879296, 2472.7403622619545, 2497.2383423055962, 118836.40581797122, 122890.99999999911, 122902.0, 123542.0, 123587.38206309002, 21077.367678969655, 22334.664980025314, 22372.0, 22483.21308754186, 22904.312870613907, 43611.34582981258, 43788.0, 44219.0, 44414.555854441685, 44492.0, 11331.716707082389, 11376.146788990858, 11415.573053368329, 11538.250013749079, 11558.768824623628, 9008.504532250658, 9053.366264361563, 9063.59213835379, 9313.487758173365, 9371.250299975985, 11077.556897724162, 11312.773744525064, 11586.899244571752, 11603.6518904433, 11644.41777911101, 4321.805518751664, 4326.891827704332, 4436.201483732959, 4477.753723545171, 4563.977180114144, 1156.8149096144577, 1187.329159025148, 1189.7501524679915, 1190.214458457414, 1191.267370567095, 20482.487937801674, 20620.27829025977, 20730.689039664416, 20758.79241207599, 20860.37418877436, 1150.8676502202186, 1160.5705888821021, 1181.704573856536, 1187.5724739093919, 1210.8365370674846, 10862.239643224899, 11315.377654228932, 11325.830112548318, 11334.943325283486, 11392.145589080686, 464127.0, 667543.0, 676260.0, 683287.0, 698937.0, 84841.0, 117452.0, 122350.3882480582, 124650.0, 125451.37274313753, 1337.8729020743015, 1384.1556650443222, 1385.5012195609572, 1394.0, 1397.9650508737363, 18972.810271897106, 19246.615067698847, 19281.710774338677, 19295.614087718448, 19341.1296491661, 52409.0, 52621.47378526166, 52673.209901850736, 52931.735341323045, 54836.17745733738, 67950.3204967944, 68701.65649171722, 68838.0, 69472.0, 70275.0, 12123.454444550192, 12241.755164896475, 12321.93839030799, 12468.064895133082, 12488.562900298446, 13123.475060997835, 13263.336833158497, 13293.867061329263, 13562.93218533901, 13733.969952253421, 11310.547578096784, 11506.884931150582, 11721.941390292994, 11852.0, 11906.40467976615, 10205.489725513844, 10555.208359372924, 10571.577136914744, 10657.786844262917, 10878.510467028844, 11670.0, 11685.182037257311, 11780.293182409083, 11791.587294444653, 11990.220635658654, 11956.0, 12369.505219791108, 12420.937895310466, 12478.18891772032, 12764.17032892859, 11451.59919402817, 11568.363739994382, 11687.824682629598, 11964.880351196376, 11999.40002999829], "value": 12185.624587215825, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.6934759026808, 1517.6737001544666, 1546.1727975533092, 1583.9208039598018, 1585.2390852390852, 8803.647854085837, 10998.12015038797, 11318.32090074595, 11386.772264554704, 11390.772184556305, 11527.077833773299, 11605.767884642302, 11610.941945290262, 11612.477438515267, 11624.825627615592, 1850.851931845456, 2444.022391043579, 2478.39279376553, 2479.0827393864297, 2479.6776419065495, 1953.1601411393094, 2413.794827439661, 2507.786838118753, 2530.8861101250395, 2535.366158460385, 2444.6455263986713, 2473.9010439582394, 2480.4419005723657, 2481.280428675682, 2481.553320402327, 2259.164109279569, 2263.7509873913855, 2266.626006708891, 2269.3645779181857, 2282.7146606674164, 2259.164109279577, 2282.292489328297, 2282.8630282182958, 2286.588414085464, 2287.2109122352763, 10830.079443247334, 10925.07136893365, 10956.890431095666, 11000.339979601249, 11000.449977501094, 5698.059820129673, 5832.941670583325, 5842.386549412293, 5845.678487683176, 5847.56143289255, 3372.1738174147245, 3409.1988382730287, 3559.839807208656, 3581.3195492856535, 3596.6762991330907, 1482.8072350594462, 1485.123776971592, 1518.6507103366287, 1537.415782002843, 1550.1086875046892, 17155.656886862194, 17161.399351022777, 17172.22724977379, 17203.0, 17218.827811721847, 4766.237402015661, 4989.7006179628615, 5010.398752149766, 5016.899662006813, 5020.021095886267, 14478.420863165566, 14496.0, 14505.854941450661, 14509.201993890227, 14510.709785804225, 6062.727177277033, 6069.575129740964, 6077.604955677867, 6085.148079268906, 6085.878282434326, 4480.529544397857, 4619.86140415788, 5442.945570544323, 5477.04151773438, 5505.229267902497, 3861.8455261789213, 4381.583749543788, 4400.3399490076745, 4525.841595544206, 4561.543845615478, 1173.524722487394, 1189.8810118988206, 1190.2085113399664, 1193.570314686712, 1211.4669545400172, 1088.1621151713136, 1100.0469471967488, 1110.36775939369, 1114.4093630375914, 1122.8820973797797, 7076.292370762985, 7129.714811407485, 7309.269073092754, 7408.925910740932, 7432.9256707433315, 1118.904893084097, 1131.8132508136066, 1151.205668089016, 1153.6308381317897, 1173.9589114380956, 4715.646326525524, 4974.081890250937, 4977.776000080005, 4994.175960966472, 5100.030994111181, 13969.511067112602, 14511.1293322401, 14584.48954286595, 14601.269936503344, 14611.488597899022, 14189.929050354682, 14196.645083872985, 14341.99606027589, 14434.061785984088, 14446.638834029234, 10219.978002199869, 10541.104006159563, 10622.787544249071, 10653.467326633638, 10730.517126729315, 7216.530925489827, 7228.421726261781, 7280.745173918887, 7342.082239720035, 7429.851402972018, 12708.618741437771, 12972.35138243103, 13055.21668699881, 13164.210147391188, 13186.670333241746, 13119.081664283409, 13270.469181232642, 13316.0, 13319.733605328032, 13444.731105378034, 8024.117347091698, 9760.121589057018, 9948.45283509414, 9949.104580587782, 9969.252306076929, 9028.638854445748, 11060.225784195029, 11285.830712539155, 11350.091992640733, 11411.60059397917, 9265.583048762686, 9282.814343713222, 9298.814023719622, 9333.673321433716, 9463.2429405646, 8921.866172007554, 9041.412308199946, 9075.682351117679, 9083.591238394156, 9241.260699143917, 8336.7915802103, 8368.581570921551, 8423.241908228289, 8455.027671817574, 8515.105913878953, 6356.9046464304, 6377.74489020434, 6395.744170233139, 6484.533531304132, 6569.507286953402, 6315.08431277453, 6498.610083395011, 6570.342965703391, 6603.20761508622, 6628.105205797156], "value": 5465.67025092492, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.2065024827207, 414.77394819823195, 427.5318526213796, 435.23181584503357, 440.48463297941424, 5126.436092029874, 5136.974315128419, 5233.555147812436, 5344.866378340544, 5366.651167674103, 5053.267276244942, 5165.9225111623355, 5175.430702622718, 5198.8700282492955, 5225.712585807779, 4467.82128714851, 4474.284114541674, 4486.439195100613, 4487.551244875503, 4492.573205545468, 4389.122175564884, 4443.866683999485, 4477.328400739882, 4491.550844915499, 4495.011097558532, 4445.777711114448, 4448.755318457484, 4450.933236001462, 4460.420145381112, 4468.687191896569, 4436.803782127835, 4439.200943830126, 4452.821887124511, 4472.306792447176, 4472.865814025585, 4100.794960252006, 4305.557638191179, 4308.202982448257, 4327.199468098381, 4328.134373125387, 5040.470750571174, 5059.824275561507, 5102.260172275037, 5132.769025393866, 5149.562287205592, 5137.3064636274, 5218.921716174261, 5226.503482169188, 5261.921071183935, 5279.128943724281, 5309.0, 5467.781288748445, 5495.340559132929, 5516.7517461714315, 5519.31008623922, 4783.545563171493, 4785.306130611042, 4804.645723595448, 4813.518648135194, 4827.758612069418, 5688.260526131577, 5714.799982000652, 5717.942820571782, 5731.598788084836, 5740.7703691852275, 4848.442429120626, 4910.238912968424, 4967.751612419364, 4988.0, 4996.275540046673, 5020.221865610836, 5053.917110562554, 5054.696718196847, 5075.695458272516, 5088.440271570124, 5250.343707036621, 5258.1849813279005, 5263.36839579253, 5270.683758974474, 5289.090999950012, 5245.370555533361, 5451.454854514517, 5474.3303292495375, 5479.479449452296, 5517.337919449611, 1551.5733173377412, 1570.9607259818367, 1616.1272912627285, 1661.9252133654015, 1662.401535447238, 1240.4480006397248, 1256.5978886756334, 1266.968325791844, 1286.2668279080833, 1287.3305880942046, 5367.845913128696, 5387.464572596791, 5399.352077750695, 5403.189521571796, 5405.918816236768, 5593.916091258634, 5635.943640563624, 5642.802501912413, 5643.0, 5654.6324488908085, 5248.448912864171, 5280.524752772269, 5352.4915133062295, 5397.892042159135, 5400.351957765015, 2954.6897575754665, 2956.52695568708, 2957.778166637511, 2960.1119664100684, 2968.257935516121, 5469.371022332482, 5786.681732504752, 5789.334226563915, 5810.564207684441, 5825.514493804053, 5602.299712535933, 5654.660720356792, 5705.258316418839, 5713.342965558931, 5737.565608597851, 5701.4298570142655, 5770.538356931435, 5798.449147330997, 5807.274090738658, 5832.912506312494, 5279.26090347344, 5529.668219906818, 5543.835794483204, 5629.437056294338, 5633.380328163817, 5473.2063850742215, 5475.561955043667, 5550.195221692913, 5551.750171242223, 5620.437956204347, 5784.392638773036, 5789.652620842763, 5865.269745425025, 5884.6469211847425, 5889.587728858939, 4566.931496027629, 4711.764411779466, 4712.622990160848, 4721.512723492089, 4733.195356789423, 5430.126606320813, 5539.806106786243, 5647.6893770843, 5664.886702266014, 5673.9432605675065, 5403.756830942539, 5515.834524964097, 5597.916031259616, 5605.551555875439, 5613.494785469327, 5522.183686319925, 5580.469855363896, 5586.664800112007, 5599.216109744721, 5605.243292155506, 5236.764345604381, 5490.752916118864, 5527.309086364205, 5555.611107222618, 5568.331800184003, 5544.500994910478, 5576.163575463713, 5580.316990786062, 5581.6371935824045, 5590.301212348457, 5249.7375131242525, 5467.687754938706, 5483.835484935458, 5485.451454854642, 5523.061079616395], "value": 4465.812375430125, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.0447632983905, 879.728791895711, 911.5123408976195, 929.6374413978548, 942.1238248428963, 14954.252287385629, 15038.097714137159, 15066.2466876656, 15152.090874547535, 16731.54667612798, 16528.173591320447, 16862.915685421554, 16954.49136525903, 17159.74260386092, 17184.312627494885, 848.8302339532088, 866.9913300867005, 870.0212261206143, 872.7948932000975, 873.3624454148476, 869.1829680100717, 895.274827389813, 904.3307952115421, 905.1672461335554, 906.1074841281333, 938.6714649872558, 940.1773448232797, 941.1623654947102, 946.3706635087658, 949.7862980829327, 939.5161491831713, 940.5673390240497, 940.8118376324762, 941.0354386753564, 941.1247539787994, 687.7902239816854, 688.5249178067118, 688.7245101959256, 689.1385767790263, 689.1557841643996, 17851.196696148705, 18041.278348866028, 18120.63758724818, 18273.451796446127, 18454.35409760652, 13480.730385392335, 13555.86444135556, 13840.584782456543, 13941.930290348484, 14023.228722420263, 13604.0, 13733.45066197351, 13750.106243094167, 13928.164310141425, 13971.79042314366, 3860.4402361657403, 3937.330653788863, 3943.7042221833476, 3946.3093958557424, 3986.621270979252, 90271.22935377447, 91836.54081729549, 91974.0, 93261.53369233178, 94252.0, 12792.232466052066, 12822.80765788514, 12825.615231543068, 12835.165714228546, 12875.613731588068, 24877.0, 25111.121110761036, 25251.242462726146, 25260.621090683653, 25600.231993040237, 7880.960595196987, 8675.696350627824, 8744.737657870273, 8914.064023277462, 8939.382614545286, 4803.351547541107, 4876.171050921352, 4910.189818679878, 4950.43070046942, 4967.726775027336, 3752.399616061417, 3853.0, 3853.9807300963316, 3855.845766169322, 3856.5950575189763, 626.4017862940881, 636.4685487617799, 636.5448704176536, 648.3807963394946, 655.8425977765302, 655.5640499068162, 657.9605223686594, 676.631235976394, 687.4431710314661, 827.896512935883, 10591.57633694659, 11519.136064795173, 11591.420428978518, 11615.941920290345, 11906.285622862655, 658.6015460646336, 680.9761658342034, 684.8630273945231, 706.4313227851629, 800.9799755006055, 5531.861703457365, 5611.410801865745, 5764.855878603068, 5774.393688662714, 5806.564507661942, 77138.2286177131, 78302.0, 78567.60716196382, 80183.0, 83337.0, 60586.39413605896, 66235.66882165655, 68170.0, 69557.65221173962, 70176.6491167541, 53574.46425535695, 53734.7313263439, 59362.70318648466, 61694.0, 62405.68797156076, 11793.52825886955, 12181.634550963485, 12254.20347677398, 12279.877201227873, 12585.93707031459, 35383.82308088443, 36380.454293185096, 37562.24875502529, 39453.0, 42398.0, 47403.28895066646, 47879.281810773566, 48296.517034830606, 49095.754521227165, 50169.99660006852, 13439.529616463376, 13474.326283685972, 13569.728605428034, 13764.31178441094, 14008.299585020912, 15967.361305548113, 16206.756898646294, 16812.495625131265, 17540.828132059756, 17810.287588496314, 15767.526974190792, 15775.0, 15838.445654402041, 16125.919370403073, 16154.838451615333, 14817.999140231861, 15024.549263522113, 15050.69898602, 15214.543563693107, 15590.04907497162, 15199.696006080037, 15386.307616157075, 15428.768568471258, 15436.382544698086, 16363.34546618122, 10854.18593605467, 10899.29154604948, 11145.275557088764, 11180.217384782987, 11400.771984560428, 12729.236245825281, 12732.427040783372, 12932.159409638343, 12960.546380876624, 13010.609681709564], "value": 7802.806021426709, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 112.0, 113.0, 113.0, 114.0, 113.0, 112.0, 112.0, 112.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 118.0, 118.0, 153.0, 121.0, 116.0, 114.0, 116.0, 130.0, 116.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 162.0, 161.0, 164.0, 159.0, 163.0, 160.0, 159.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 101.0, 76.0, 76.0, 76.0, 77.0, 100.0, 76.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 105.0, 82.0, 82.0, 82.0, 115.0, 81.0, 82.0, 104.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 114.0, 112.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 147.0, 146.0, 146.0, 148.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 54.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 52.0, 53.0, 53.0, 53.0, 52.0, 52.0], "value": 52.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 76.0, 79.0, 82.0, 81.0, 75.0, 79.0, 79.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 124.0, 122.0, 122.0, 128.0, 128.0, 117.0, 117.0, 156.0, 131.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 158.0, 161.0, 157.0, 156.0, 160.0, 159.0, 170.0, 160.0, 160.0], "value": 159.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 60.0, 60.0, 61.0, 59.0, 59.0, 60.0, 60.0, 60.0, 58.0], "value": 60.5, "unit": "score"}]}]} 10:25:31 INFO - Return code: 0 10:25:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:25:31 ERROR - # TBPL SUCCESS # 10:25:31 INFO - Running post-action listener: _resource_record_post_action 10:25:31 INFO - [mozharness: 2017-04-11 17:25:31.410185Z] Finished run-tests step (success) 10:25:31 INFO - Running post-run listener: _resource_record_post_run 10:25:31 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 266240; Write bytes: 370716672; Read time: 28; Write time: 553128 10:25:31 INFO - TinderboxPrint: CPU usage
13.2% 10:25:31 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 10:25:31 INFO - TinderboxPrint: I/O write bytes / time
370,716,672 / 553,128 10:25:31 INFO - TinderboxPrint: CPU idle
8,275.1 (86.8%) 10:25:31 INFO - TinderboxPrint: CPU user
1,201.8 (12.6%) 10:25:31 INFO - TinderboxPrint: Swap in / out
0 / 0 10:25:31 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 120164352; Read time: 0; Write time: 222500 10:25:31 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 262144; Write bytes: 250368000; Read time: 20; Write time: 330608 10:25:32 INFO - Running post-run listener: _upload_blobber_files 10:25:32 INFO - Blob upload gear active. 10:25:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:25:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:25:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:25:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:25:32 INFO - (blobuploader) - INFO - Open directory for files ... 10:25:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:25:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:25:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:34 INFO - (blobuploader) - INFO - Done attempting. 10:25:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:25:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:25:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:38 INFO - (blobuploader) - INFO - Done attempting. 10:25:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:25:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:25:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:38 INFO - (blobuploader) - INFO - Done attempting. 10:25:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:25:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:40 INFO - (blobuploader) - INFO - Done attempting. 10:25:40 INFO - (blobuploader) - INFO - Iteration through files over. 10:25:40 INFO - Return code: 0 10:25:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:25:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:25:40 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a48c5f74df2716ab35013033d09752c5653d497a28aa9f31a9ff58b945388e6c0ae676aebde08bbfaaac585bb32b09109fca3562fa4c41678218321764b76f9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d01f731707db7ddac99bf48a1cf623ade42ae300e775719bce899ab3565ff67da4dc68f5391ddcf60cee8e0321442e7224fc62990ba8fe3dd8b58457c4c9a131", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1219f560f5d780b460cf9ddac20b639e0be5cdf7687e3a8609f996b72b7a4fc1343c317c22a555149be3fc1854b8350bc10663df09f98c2d6052e06f3d8f67be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d17637688da99eaa7b68013c470ea52738f231d69bfad97f7a2b36465b98725e137ad468f1a34b340efbc5ca66350f10c58e2f08d79232fb326548705e12c9d9"} 10:25:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:25:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:25:40 INFO - Contents: 10:25:40 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a48c5f74df2716ab35013033d09752c5653d497a28aa9f31a9ff58b945388e6c0ae676aebde08bbfaaac585bb32b09109fca3562fa4c41678218321764b76f9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d01f731707db7ddac99bf48a1cf623ade42ae300e775719bce899ab3565ff67da4dc68f5391ddcf60cee8e0321442e7224fc62990ba8fe3dd8b58457c4c9a131", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1219f560f5d780b460cf9ddac20b639e0be5cdf7687e3a8609f996b72b7a4fc1343c317c22a555149be3fc1854b8350bc10663df09f98c2d6052e06f3d8f67be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d17637688da99eaa7b68013c470ea52738f231d69bfad97f7a2b36465b98725e137ad468f1a34b340efbc5ca66350f10c58e2f08d79232fb326548705e12c9d9"} 10:25:40 INFO - Running post-run listener: copy_logs_to_upload_dir 10:25:40 INFO - Copying logs to upload dir... 10:25:40 INFO - mkdir: /builds/slave/test/build/upload/logs 10:25:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.763998 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-04-11 10:25:40.573975) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 10:25:40.577062) ========= 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=df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a48c5f74df2716ab35013033d09752c5653d497a28aa9f31a9ff58b945388e6c0ae676aebde08bbfaaac585bb32b09109fca3562fa4c41678218321764b76f9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d01f731707db7ddac99bf48a1cf623ade42ae300e775719bce899ab3565ff67da4dc68f5391ddcf60cee8e0321442e7224fc62990ba8fe3dd8b58457c4c9a131", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1219f560f5d780b460cf9ddac20b639e0be5cdf7687e3a8609f996b72b7a4fc1343c317c22a555149be3fc1854b8350bc10663df09f98c2d6052e06f3d8f67be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d17637688da99eaa7b68013c470ea52738f231d69bfad97f7a2b36465b98725e137ad468f1a34b340efbc5ca66350f10c58e2f08d79232fb326548705e12c9d9"} build_url:https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011499 build_url: 'https://queue.taskcluster.net/v1/task/X8gTuUkYRlCdtTMRTQGOpA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a48c5f74df2716ab35013033d09752c5653d497a28aa9f31a9ff58b945388e6c0ae676aebde08bbfaaac585bb32b09109fca3562fa4c41678218321764b76f9d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d01f731707db7ddac99bf48a1cf623ade42ae300e775719bce899ab3565ff67da4dc68f5391ddcf60cee8e0321442e7224fc62990ba8fe3dd8b58457c4c9a131", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1219f560f5d780b460cf9ddac20b639e0be5cdf7687e3a8609f996b72b7a4fc1343c317c22a555149be3fc1854b8350bc10663df09f98c2d6052e06f3d8f67be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d17637688da99eaa7b68013c470ea52738f231d69bfad97f7a2b36465b98725e137ad468f1a34b340efbc5ca66350f10c58e2f08d79232fb326548705e12c9d9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 10:25:40.603181) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:25:40.603508) ========= 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=df17445c3590ff7897617a0e00000225-1491930281.574789-1717456348 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004532 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:25:40.687477) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 10:25:40.687843) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 10:25:40.688154) ========= ========= Total master_lag: 0.17 =========