builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-052 starttime: 1502380922.93 results: success (0) revision: 5322c03f4c8587fe526172d3f87160031faa6d75 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:02.927283) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:02.927769) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:02.930074) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:02.947024) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:02.947390) ========= 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=826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-10 09:02:05-- 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% 100M=0s 2017-08-10 09:02:05 (100 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084618 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:03.058016) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:03.058338) ========= 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=826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018395 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:03.126883) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-10 09:02:03.127327) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5322c03f4c8587fe526172d3f87160031faa6d75 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 5322c03f4c8587fe526172d3f87160031faa6d75 --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=826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-10 09:02:05,362 truncating revision to first 12 chars 2017-08-10 09:02:05,362 Setting DEBUG logging. 2017-08-10 09:02:05,362 attempt 1/10 2017-08-10 09:02:05,362 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5322c03f4c85?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-10 09:02:06,068 sleeping for 10.00s (attempt 1/10) 2017-08-10 09:02:16,078 attempt 2/10 2017-08-10 09:02:16,079 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5322c03f4c85?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-10 09:02:17,265 unpacking tar archive at: mozilla-central-5322c03f4c85/testing/mozharness/ program finished with exit code 0 elapsedTime=12.219408 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-10 09:02:15.373362) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:15.373672) ========= script_repo_revision: 5322c03f4c8587fe526172d3f87160031faa6d75 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:15.374052) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:15.374319) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-10 09:02:15.386499) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-10 09:02:15.386767) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:02:17 INFO - MultiFileLogger online at 20170810 09:02:17 in /builds/slave/test 09:02:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:02:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:02:17 INFO - {'append_to_log': False, 09:02:17 INFO - 'base_work_dir': '/builds/slave/test', 09:02:17 INFO - 'blob_upload_branch': 'Firefox', 09:02:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:02:17 INFO - 'branch': 'Firefox', 09:02:17 INFO - 'buildbot_json_path': 'buildprops.json', 09:02:17 INFO - 'code_coverage': False, 09:02:17 INFO - 'config_files': ('talos/linux_config.py',), 09:02:17 INFO - 'default_actions': ('clobber', 09:02:17 INFO - 'read-buildbot-config', 09:02:17 INFO - 'download-and-extract', 09:02:17 INFO - 'populate-webroot', 09:02:17 INFO - 'create-virtualenv', 09:02:17 INFO - 'install', 09:02:17 INFO - 'setup-mitmproxy', 09:02:17 INFO - 'run-tests'), 09:02:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:02:17 INFO - 'disable_ccov_upload': False, 09:02:17 INFO - 'download_minidump_stackwalk': True, 09:02:17 INFO - 'download_symbols': 'ondemand', 09:02:17 INFO - 'enable_stylo': False, 09:02:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:02:17 INFO - 'tooltool.py': '/tools/tooltool.py', 09:02:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:02:17 INFO - '/tools/misc-python/virtualenv.py')}, 09:02:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:02:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:02:17 INFO - 'gecko_profile': False, 09:02:17 INFO - 'gecko_profile_interval': 0, 09:02:17 INFO - 'installer_path': 'installer.exe', 09:02:17 INFO - 'log_level': 'info', 09:02:17 INFO - 'log_name': 'talos', 09:02:17 INFO - 'log_to_console': True, 09:02:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:02:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:02:17 INFO - 'opt_config_files': (), 09:02:17 INFO - 'pip_index': False, 09:02:17 INFO - 'suite': 'dromaeojs-e10s', 09:02:17 INFO - 'system_bits': '32', 09:02:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:02:17 INFO - 'title': 'talos-linux64-ix-052', 09:02:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:02:17 INFO - 'use_talos_json': True, 09:02:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:02:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:02:17 INFO - 'work_dir': 'build'} 09:02:17 INFO - [mozharness: 2017-08-10 16:02:17.714061Z] Running clobber step. 09:02:17 INFO - Running pre-action listener: _resource_record_pre_action 09:02:17 INFO - Running main action method: clobber 09:02:17 INFO - rmtree: /builds/slave/test/build 09:02:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:02:18 INFO - Running post-action listener: _resource_record_post_action 09:02:18 INFO - [mozharness: 2017-08-10 16:02:18.755117Z] Finished clobber step (success) 09:02:18 INFO - [mozharness: 2017-08-10 16:02:18.755219Z] Running read-buildbot-config step. 09:02:18 INFO - Running pre-action listener: _resource_record_pre_action 09:02:18 INFO - Running main action method: read_buildbot_config 09:02:18 INFO - Using buildbot properties: 09:02:18 INFO - { 09:02:18 INFO - "project": "", 09:02:18 INFO - "product": "firefox", 09:02:18 INFO - "who": "archaeopteryx@coole-files.de", 09:02:18 INFO - "installer_path": "public/build/target.tar.bz2", 09:02:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 09:02:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 09:02:18 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 09:02:18 INFO - "stage_platform": "linux64-stylo", 09:02:18 INFO - "basedir": "/builds/slave/test", 09:02:18 INFO - "buildnumber": 20, 09:02:18 INFO - "platform": "ubuntu64_hw_stylo", 09:02:18 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:02:18 INFO - "slavebuilddir": "test", 09:02:18 INFO - "taskId": "A1q77fRsQCewikMDesgRyQ", 09:02:18 INFO - "branch": "mozilla-central", 09:02:18 INFO - "script_repo_revision": "production", 09:02:18 INFO - "revision": "5322c03f4c8587fe526172d3f87160031faa6d75", 09:02:18 INFO - "slavename": "talos-linux64-ix-052", 09:02:18 INFO - "repo_path": "mozilla-central" 09:02:18 INFO - } 09:02:18 INFO - Finding installer, test and symbols from parent task. 09:02:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A1q77fRsQCewikMDesgRyQ'}, attempt #1 09:02:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A1q77fRsQCewikMDesgRyQ'}, attempt #1 09:02:19 INFO - Task dependencies: LIC2wUu3Sb-3_r_z6QiqiA 09:02:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA'}, attempt #1 09:02:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.tar.bz2 09:02:20 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.test_packages.json 09:02:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.crashreporter-symbols.zip 09:02:20 INFO - Running post-action listener: _resource_record_post_action 09:02:20 INFO - [mozharness: 2017-08-10 16:02:20.143231Z] Finished read-buildbot-config step (success) 09:02:20 INFO - [mozharness: 2017-08-10 16:02:20.143454Z] Running download-and-extract step. 09:02:20 INFO - Running pre-action listener: _resource_record_pre_action 09:02:20 INFO - Running main action method: download_and_extract 09:02:20 INFO - mkdir: /builds/slave/test/build/tests 09:02:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:20 INFO - trying https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.test_packages.json 09:02:20 INFO - Downloading https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:02:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:02:20 INFO - Downloaded 989 bytes. 09:02:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:02:20 INFO - Using the following test package requirements: 09:02:20 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:02:20 INFO - u'common': [u'target.common.tests.zip'], 09:02:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:02:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:02:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:02:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:02:20 INFO - u'mozbase': [u'target.common.tests.zip'], 09:02:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:02:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:02:20 INFO - u'web-platform': [u'target.common.tests.zip', 09:02:20 INFO - u'target.web-platform.tests.tar.gz'], 09:02:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:02:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:02:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.common.tests.zip 09:02:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:02:20 INFO - Fetch https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.common.tests.zip into memory 09:02:21 INFO - Content-Length response header: 54343676 09:02:21 INFO - Bytes received: 54343676 09:02:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:02:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.common.tests.zip 09:02:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:02:32 INFO - Fetch https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.common.tests.zip into memory 09:02:33 INFO - Content-Length response header: 54343676 09:02:33 INFO - Bytes received: 54343676 09:02:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.talos.tests.zip 09:02:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:02:45 INFO - Fetch https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.talos.tests.zip into memory 09:02:46 INFO - Content-Length response header: 14496008 09:02:46 INFO - Bytes received: 14496008 09:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:46 INFO - trying https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.tar.bz2 09:02:46 INFO - Downloading https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:02:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:02:48 INFO - Downloaded 61917923 bytes. 09:02:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.tar.bz2 09:02:48 INFO - mkdir: /builds/slave/test/properties 09:02:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:02:48 INFO - Writing to file /builds/slave/test/properties/build_url 09:02:48 INFO - Contents: 09:02:48 INFO - build_url:https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.tar.bz2 09:02:48 INFO - Running post-action listener: _resource_record_post_action 09:02:48 INFO - Running post-action listener: set_extra_try_arguments 09:02:48 INFO - [mozharness: 2017-08-10 16:02:48.786165Z] Finished download-and-extract step (success) 09:02:48 INFO - [mozharness: 2017-08-10 16:02:48.786295Z] Running populate-webroot step. 09:02:48 INFO - Running pre-action listener: _resource_record_pre_action 09:02:48 INFO - Running main action method: populate_webroot 09:02:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:02:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:02:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:02:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:02:48 INFO - 'tests': ['damp', 'tps']}, 09:02:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:02:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:02:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:02:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:02:48 INFO - 'other-e10s': {'tests': ['a11yr', 09:02:48 INFO - 'ts_paint', 09:02:48 INFO - 'tpaint', 09:02:48 INFO - 'sessionrestore', 09:02:48 INFO - 'sessionrestore_many_windows', 09:02:48 INFO - 'sessionrestore_no_auto_restore', 09:02:48 INFO - 'tabpaint']}, 09:02:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:02:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:02:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:02:48 INFO - 'tsvgr_opacity', 09:02:48 INFO - 'tart', 09:02:48 INFO - 'tscrollx', 09:02:48 INFO - 'tsvg_static']}, 09:02:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:02:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:02:48 INFO - 'talos_options': ['--mitmproxy', 09:02:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:02:48 INFO - '--firstNonBlankPaint'], 09:02:48 INFO - 'tests': ['tp6_google', 09:02:48 INFO - 'tp6_youtube', 09:02:48 INFO - 'tp6_amazon', 09:02:48 INFO - 'tp6_facebook']}, 09:02:48 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:02:48 INFO - 'talos_options': ['--stylo', 09:02:48 INFO - '--mitmproxy', 09:02:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:02:48 INFO - '--firstNonBlankPaint'], 09:02:48 INFO - 'tests': ['tp6_google', 09:02:48 INFO - 'tp6_youtube', 09:02:48 INFO - 'tp6_amazon', 09:02:48 INFO - 'tp6_facebook']}, 09:02:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:02:48 INFO - 'talos_options': ['--stylo', 09:02:48 INFO - '--stylo-threads=1', 09:02:48 INFO - '--mitmproxy', 09:02:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:02:48 INFO - '--firstNonBlankPaint'], 09:02:48 INFO - 'tests': ['tp6_google', 09:02:48 INFO - 'tp6_youtube', 09:02:48 INFO - 'tp6_amazon', 09:02:48 INFO - 'tp6_facebook']}, 09:02:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:02:48 INFO - 'talos_options': ['--xperf_path', 09:02:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:48 INFO - 'tests': ['tp5n']}}} 09:02:48 INFO - Running post-action listener: _resource_record_post_action 09:02:48 INFO - [mozharness: 2017-08-10 16:02:48.796742Z] Finished populate-webroot step (success) 09:02:48 INFO - [mozharness: 2017-08-10 16:02:48.796833Z] Running create-virtualenv step. 09:02:48 INFO - Running pre-action listener: _resource_record_pre_action 09:02:48 INFO - Running main action method: create_virtualenv 09:02:48 INFO - Creating virtualenv /builds/slave/test/build/venv 09:02:48 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 09:02:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:02:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:02:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:02:48 INFO - Using real prefix '/usr' 09:02:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:02:49 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:51 INFO - Installing pip.................done. 09:02:51 INFO - Return code: 0 09:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:02:51 INFO - Reading from file tmpfile_stdout 09:02:51 INFO - Output received: 09:02:51 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) 09:02:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:51 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/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 0x21741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2340a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e3700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e08a0>, '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 0x24e17a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1c30>, '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': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', '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 09:02:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:02:51 INFO - Using env: {'DISPLAY': ':0', 09:02:51 INFO - 'HOME': '/home/cltbld', 09:02:51 INFO - 'LANG': 'en_US.UTF-8', 09:02:51 INFO - 'LANGUAGE': 'en_US:en', 09:02:51 INFO - 'LOGNAME': 'cltbld', 09:02:51 INFO - 'MAIL': '/var/mail/cltbld', 09:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:51 INFO - 'MOZ_NO_REMOTE': '1', 09:02:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:51 INFO - 'NO_EM_RESTART': '1', 09:02:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:51 INFO - 'PWD': '/builds/slave/test', 09:02:51 INFO - 'SHELL': '/bin/bash', 09:02:51 INFO - 'SHLVL': '1', 09:02:51 INFO - 'TERM': 'linux', 09:02:51 INFO - 'TMOUT': '86400', 09:02:51 INFO - 'USER': 'cltbld', 09:02:51 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', 09:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:51 INFO - '_': '/tools/buildbot/bin/python'} 09:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:51 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 09:02:51 INFO - Cleaning up... 09:02:51 INFO - Return code: 0 09:02:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:51 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/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 0x21741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2340a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e3700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e08a0>, '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 0x24e17a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1c30>, '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': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', '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 09:02:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:02:51 INFO - Using env: {'DISPLAY': ':0', 09:02:51 INFO - 'HOME': '/home/cltbld', 09:02:51 INFO - 'LANG': 'en_US.UTF-8', 09:02:51 INFO - 'LANGUAGE': 'en_US:en', 09:02:51 INFO - 'LOGNAME': 'cltbld', 09:02:51 INFO - 'MAIL': '/var/mail/cltbld', 09:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:51 INFO - 'MOZ_NO_REMOTE': '1', 09:02:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:51 INFO - 'NO_EM_RESTART': '1', 09:02:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:51 INFO - 'PWD': '/builds/slave/test', 09:02:51 INFO - 'SHELL': '/bin/bash', 09:02:51 INFO - 'SHLVL': '1', 09:02:51 INFO - 'TERM': 'linux', 09:02:51 INFO - 'TMOUT': '86400', 09:02:51 INFO - 'USER': 'cltbld', 09:02:51 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', 09:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:51 INFO - '_': '/tools/buildbot/bin/python'} 09:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:51 INFO - Downloading/unpacking psutil>=3.1.1 09:02:52 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 09:02:52 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 09:02:52 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 09:02:52 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 09:02:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:53 INFO - Installing collected packages: psutil 09:02:53 INFO - Running setup.py install for psutil 09:02:53 INFO - building 'psutil._psutil_linux' extension 09:02:53 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 09:02:53 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 09:02:53 INFO - building 'psutil._psutil_posix' extension 09:02:53 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 09:02:53 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 09:02:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:53 INFO - Successfully installed psutil 09:02:53 INFO - Cleaning up... 09:02:53 INFO - Return code: 0 09:02:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x21741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2340a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e3700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e08a0>, '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 0x24e17a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1c30>, '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': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', '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 09:02:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:02:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:02:53 INFO - Using env: {'DISPLAY': ':0', 09:02:53 INFO - 'HOME': '/home/cltbld', 09:02:53 INFO - 'LANG': 'en_US.UTF-8', 09:02:53 INFO - 'LANGUAGE': 'en_US:en', 09:02:53 INFO - 'LOGNAME': 'cltbld', 09:02:53 INFO - 'MAIL': '/var/mail/cltbld', 09:02:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:53 INFO - 'MOZ_NO_REMOTE': '1', 09:02:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:53 INFO - 'NO_EM_RESTART': '1', 09:02:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:53 INFO - 'PWD': '/builds/slave/test', 09:02:53 INFO - 'SHELL': '/bin/bash', 09:02:53 INFO - 'SHLVL': '1', 09:02:53 INFO - 'TERM': 'linux', 09:02:53 INFO - 'TMOUT': '86400', 09:02:53 INFO - 'USER': 'cltbld', 09:02:53 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', 09:02:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:53 INFO - '_': '/tools/buildbot/bin/python'} 09:02:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:02:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:02:55 INFO - Installing collected packages: mozsystemmonitor 09:02:55 INFO - Running setup.py install for mozsystemmonitor 09:02:55 INFO - Successfully installed mozsystemmonitor 09:02:55 INFO - Cleaning up... 09:02:55 INFO - Return code: 0 09:02:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:02:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x21741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2340a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e3700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e08a0>, '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 0x24e17a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1c30>, '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': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', '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 09:02:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:02:55 INFO - Using env: {'DISPLAY': ':0', 09:02:55 INFO - 'HOME': '/home/cltbld', 09:02:55 INFO - 'LANG': 'en_US.UTF-8', 09:02:55 INFO - 'LANGUAGE': 'en_US:en', 09:02:55 INFO - 'LOGNAME': 'cltbld', 09:02:55 INFO - 'MAIL': '/var/mail/cltbld', 09:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:55 INFO - 'MOZ_NO_REMOTE': '1', 09:02:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:55 INFO - 'NO_EM_RESTART': '1', 09:02:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:55 INFO - 'PWD': '/builds/slave/test', 09:02:55 INFO - 'SHELL': '/bin/bash', 09:02:55 INFO - 'SHLVL': '1', 09:02:55 INFO - 'TERM': 'linux', 09:02:55 INFO - 'TMOUT': '86400', 09:02:55 INFO - 'USER': 'cltbld', 09:02:55 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', 09:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:55 INFO - '_': '/tools/buildbot/bin/python'} 09:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:55 INFO - Downloading/unpacking jsonschema==2.5.1 09:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:56 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:56 INFO - warning: no files found matching '*.txt' 09:02:56 INFO - no previously-included directories found matching 'build' 09:02:56 INFO - no previously-included directories found matching 'dist' 09:02:56 INFO - no previously-included directories found matching '.git*' 09:02:56 INFO - Installing collected packages: jsonschema, functools32 09:02:56 INFO - Running setup.py install for functools32 09:02:56 INFO - warning: no files found matching '*.txt' 09:02:56 INFO - no previously-included directories found matching 'build' 09:02:56 INFO - no previously-included directories found matching 'dist' 09:02:56 INFO - no previously-included directories found matching '.git*' 09:02:56 INFO - Successfully installed jsonschema functools32 09:02:56 INFO - Cleaning up... 09:02:56 INFO - Return code: 0 09:02:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x21741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2340a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e3700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e08a0>, '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 0x24e17a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1c30>, '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': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', '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 09:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:02:56 INFO - Using env: {'DISPLAY': ':0', 09:02:56 INFO - 'HOME': '/home/cltbld', 09:02:56 INFO - 'LANG': 'en_US.UTF-8', 09:02:56 INFO - 'LANGUAGE': 'en_US:en', 09:02:56 INFO - 'LOGNAME': 'cltbld', 09:02:56 INFO - 'MAIL': '/var/mail/cltbld', 09:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:56 INFO - 'MOZ_NO_REMOTE': '1', 09:02:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:56 INFO - 'NO_EM_RESTART': '1', 09:02:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:56 INFO - 'PWD': '/builds/slave/test', 09:02:56 INFO - 'SHELL': '/bin/bash', 09:02:56 INFO - 'SHLVL': '1', 09:02:56 INFO - 'TERM': 'linux', 09:02:56 INFO - 'TMOUT': '86400', 09:02:56 INFO - 'USER': 'cltbld', 09:02:56 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', 09:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:56 INFO - '_': '/tools/buildbot/bin/python'} 09:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:02:57 INFO - Cleaning up... 09:02:57 INFO - Return code: 0 09:02:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:57 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/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 0x21741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2340a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e3700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e08a0>, '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 0x24e17a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1c30>, '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': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', '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 09:02:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:02:57 INFO - Using env: {'DISPLAY': ':0', 09:02:57 INFO - 'HOME': '/home/cltbld', 09:02:57 INFO - 'LANG': 'en_US.UTF-8', 09:02:57 INFO - 'LANGUAGE': 'en_US:en', 09:02:57 INFO - 'LOGNAME': 'cltbld', 09:02:57 INFO - 'MAIL': '/var/mail/cltbld', 09:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:57 INFO - 'MOZ_NO_REMOTE': '1', 09:02:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:57 INFO - 'NO_EM_RESTART': '1', 09:02:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:57 INFO - 'PWD': '/builds/slave/test', 09:02:57 INFO - 'SHELL': '/bin/bash', 09:02:57 INFO - 'SHLVL': '1', 09:02:57 INFO - 'TERM': 'linux', 09:02:57 INFO - 'TMOUT': '86400', 09:02:57 INFO - 'USER': 'cltbld', 09:02:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', 09:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:57 INFO - '_': '/tools/buildbot/bin/python'} 09:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:57 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:57 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 09:02:57 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 09:02:57 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 09:02:57 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 09:02:58 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:58 INFO - Downloading docopt-0.6.1.tar.gz 09:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:59 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:59 INFO - Running setup.py install for blobuploader 09:02:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:59 INFO - Running setup.py install for requests 09:02:59 INFO - Running setup.py install for docopt 09:02:59 INFO - Successfully installed blobuploader requests docopt 09:02:59 INFO - Cleaning up... 09:02:59 INFO - Return code: 0 09:02:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x21741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2340a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e3700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e08a0>, '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 0x24e17a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1c30>, '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': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', '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 09:02:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:59 INFO - Using env: {'DISPLAY': ':0', 09:02:59 INFO - 'HOME': '/home/cltbld', 09:02:59 INFO - 'LANG': 'en_US.UTF-8', 09:02:59 INFO - 'LANGUAGE': 'en_US:en', 09:02:59 INFO - 'LOGNAME': 'cltbld', 09:02:59 INFO - 'MAIL': '/var/mail/cltbld', 09:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:59 INFO - 'MOZ_NO_REMOTE': '1', 09:02:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:02:59 INFO - 'NO_EM_RESTART': '1', 09:02:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:59 INFO - 'PWD': '/builds/slave/test', 09:02:59 INFO - 'SHELL': '/bin/bash', 09:02:59 INFO - 'SHLVL': '1', 09:02:59 INFO - 'TERM': 'linux', 09:02:59 INFO - 'TMOUT': '86400', 09:02:59 INFO - 'USER': 'cltbld', 09:02:59 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', 09:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:59 INFO - '_': '/tools/buildbot/bin/python'} 09:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:59 INFO - Running setup.py (path:/tmp/pip-DBqStz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:59 INFO - Running setup.py (path:/tmp/pip-Du1Yxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:00 INFO - Running setup.py (path:/tmp/pip-ljp7tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:00 INFO - Running setup.py (path:/tmp/pip-eqQl30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:00 INFO - Running setup.py (path:/tmp/pip-ZsZNPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:00 INFO - Running setup.py (path:/tmp/pip-LO8P5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:00 INFO - Running setup.py (path:/tmp/pip-F0gqit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:00 INFO - Running setup.py (path:/tmp/pip-UAPcY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:00 INFO - Running setup.py (path:/tmp/pip-0aV6h5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:00 INFO - Running setup.py (path:/tmp/pip-mZe3Mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:00 INFO - Running setup.py (path:/tmp/pip-mRW7Ru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:00 INFO - Running setup.py (path:/tmp/pip-E35ZVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:00 INFO - Running setup.py (path:/tmp/pip-WfbR4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:00 INFO - Running setup.py (path:/tmp/pip-gTh7wF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:00 INFO - Running setup.py (path:/tmp/pip-4CtY1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:00 INFO - Running setup.py (path:/tmp/pip-HVPg9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:01 INFO - Running setup.py (path:/tmp/pip-i9Bkop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:03:01 INFO - Running setup.py install for manifestparser 09:03:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:03:01 INFO - Running setup.py install for mozcrash 09:03:01 INFO - Running setup.py install for mozdebug 09:03:01 INFO - Running setup.py install for mozdevice 09:03:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:03:01 INFO - Running setup.py install for mozfile 09:03:01 INFO - Running setup.py install for mozhttpd 09:03:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:03:01 INFO - Running setup.py install for mozinfo 09:03:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:03:01 INFO - Running setup.py install for mozInstall 09:03:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:03:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:03:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:03:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:03:01 INFO - Running setup.py install for mozleak 09:03:02 INFO - Running setup.py install for mozlog 09:03:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:03:02 INFO - Running setup.py install for moznetwork 09:03:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:03:02 INFO - Running setup.py install for mozprocess 09:03:02 INFO - Running setup.py install for mozprofile 09:03:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:03:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:03:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:03:02 INFO - Running setup.py install for mozrunner 09:03:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:03:02 INFO - Running setup.py install for mozscreenshot 09:03:02 INFO - Running setup.py install for moztest 09:03:02 INFO - Running setup.py install for mozversion 09:03:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:03:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:03:02 INFO - Cleaning up... 09:03:02 INFO - Return code: 0 09:03:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:03:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x21741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2340a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e3700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e08a0>, '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 0x24e17a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1c30>, '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': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', '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 09:03:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:03:02 INFO - Using env: {'DISPLAY': ':0', 09:03:02 INFO - 'HOME': '/home/cltbld', 09:03:02 INFO - 'LANG': 'en_US.UTF-8', 09:03:02 INFO - 'LANGUAGE': 'en_US:en', 09:03:02 INFO - 'LOGNAME': 'cltbld', 09:03:02 INFO - 'MAIL': '/var/mail/cltbld', 09:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:02 INFO - 'MOZ_NO_REMOTE': '1', 09:03:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:02 INFO - 'NO_EM_RESTART': '1', 09:03:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:02 INFO - 'PWD': '/builds/slave/test', 09:03:02 INFO - 'SHELL': '/bin/bash', 09:03:02 INFO - 'SHLVL': '1', 09:03:02 INFO - 'TERM': 'linux', 09:03:02 INFO - 'TMOUT': '86400', 09:03:02 INFO - 'USER': 'cltbld', 09:03:02 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', 09:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:02 INFO - '_': '/tools/buildbot/bin/python'} 09:03:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:03:03 INFO - Running setup.py (path:/tmp/pip-j03giK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:03:03 INFO - Running setup.py (path:/tmp/pip-Cq4pXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:03:03 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)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:03:03 INFO - Running setup.py (path:/tmp/pip-dR1ygS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:03:03 INFO - Running setup.py (path:/tmp/pip-6HNNzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:03:03 INFO - Running setup.py (path:/tmp/pip-jQfQ1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:03:03 INFO - Running setup.py (path:/tmp/pip-vUhgO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:03:03 INFO - Running setup.py (path:/tmp/pip-6qaO1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:03:03 INFO - Running setup.py (path:/tmp/pip-k5nWzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:03:03 INFO - Running setup.py (path:/tmp/pip-4mowfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:03:03 INFO - Running setup.py (path:/tmp/pip-1ZCDVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:03:03 INFO - Running setup.py (path:/tmp/pip-QsuaWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:03:03 INFO - Running setup.py (path:/tmp/pip-4Y9uLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:03:03 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)) 09:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:03:04 INFO - Running setup.py (path:/tmp/pip-wPiUlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:03:04 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)) 09:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:03:04 INFO - Running setup.py (path:/tmp/pip-db3Puc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:03:04 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)) 09:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:04 INFO - Running setup.py (path:/tmp/pip-gh2JNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:03:04 INFO - Running setup.py (path:/tmp/pip-bGD1UU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:03:04 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)) 09:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:03:04 INFO - Running setup.py (path:/tmp/pip-lsruzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:03:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:03:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:03:05 INFO - Downloading blessings-1.6.tar.gz 09:03:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:03:05 INFO - Installing collected packages: blessings 09:03:05 INFO - Running setup.py install for blessings 09:03:05 INFO - Successfully installed blessings 09:03:05 INFO - Cleaning up... 09:03:05 INFO - Return code: 0 09:03:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:03:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:05 INFO - Reading from file tmpfile_stdout 09:03:05 INFO - Current package versions: 09:03:05 INFO - argparse == 1.2.1 09:03:05 INFO - blessings == 1.6 09:03:05 INFO - blobuploader == 1.2.4 09:03:05 INFO - docopt == 0.6.1 09:03:05 INFO - functools32 == 3.2.3-2 09:03:05 INFO - jsonschema == 2.5.1 09:03:05 INFO - manifestparser == 1.1 09:03:05 INFO - mozInstall == 1.13 09:03:05 INFO - mozcrash == 1.0 09:03:05 INFO - mozdebug == 0.1 09:03:05 INFO - mozdevice == 0.51 09:03:05 INFO - mozfile == 1.2 09:03:05 INFO - mozhttpd == 0.7 09:03:05 INFO - mozinfo == 0.10 09:03:05 INFO - mozleak == 0.1 09:03:05 INFO - mozlog == 3.5 09:03:05 INFO - moznetwork == 0.27 09:03:05 INFO - mozprocess == 0.25 09:03:05 INFO - mozprofile == 0.28 09:03:05 INFO - mozrunner == 6.13 09:03:05 INFO - mozscreenshot == 0.1 09:03:05 INFO - mozsystemmonitor == 0.3 09:03:05 INFO - moztest == 0.8 09:03:05 INFO - mozversion == 1.4 09:03:05 INFO - psutil == 3.1.1 09:03:05 INFO - requests == 1.2.3 09:03:05 INFO - wsgiref == 0.1.2 09:03:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:05 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/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 0x21741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2340a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e3700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e08a0>, '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 0x24e17a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1c30>, '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': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', '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 09:03:05 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:03:05 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:03:05 INFO - Using env: {'DISPLAY': ':0', 09:03:05 INFO - 'HOME': '/home/cltbld', 09:03:05 INFO - 'LANG': 'en_US.UTF-8', 09:03:05 INFO - 'LANGUAGE': 'en_US:en', 09:03:05 INFO - 'LOGNAME': 'cltbld', 09:03:05 INFO - 'MAIL': '/var/mail/cltbld', 09:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:05 INFO - 'MOZ_NO_REMOTE': '1', 09:03:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:05 INFO - 'NO_EM_RESTART': '1', 09:03:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:05 INFO - 'PWD': '/builds/slave/test', 09:03:05 INFO - 'SHELL': '/bin/bash', 09:03:05 INFO - 'SHLVL': '1', 09:03:05 INFO - 'TERM': 'linux', 09:03:05 INFO - 'TMOUT': '86400', 09:03:05 INFO - 'USER': 'cltbld', 09:03:05 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', 09:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:05 INFO - '_': '/tools/buildbot/bin/python'} 09:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:03:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:03:06 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 09:03:06 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 09:03:06 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 09:03:06 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 09:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:03:07 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)) 09:03:07 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)) 09:03:07 INFO - Installing collected packages: simplejson 09:03:07 INFO - Running setup.py install for simplejson 09:03:07 INFO - building 'simplejson._speedups' extension 09:03:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 09:03:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 09:03:08 INFO - Successfully installed simplejson 09:03:08 INFO - Cleaning up... 09:03:08 INFO - Return code: 0 09:03:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:03:08 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/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 0x21741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2340a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e3700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e08a0>, '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 0x24e17a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1c30>, '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': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', '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 09:03:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:03:08 INFO - Using env: {'DISPLAY': ':0', 09:03:08 INFO - 'HOME': '/home/cltbld', 09:03:08 INFO - 'LANG': 'en_US.UTF-8', 09:03:08 INFO - 'LANGUAGE': 'en_US:en', 09:03:08 INFO - 'LOGNAME': 'cltbld', 09:03:08 INFO - 'MAIL': '/var/mail/cltbld', 09:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:08 INFO - 'MOZ_NO_REMOTE': '1', 09:03:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:08 INFO - 'NO_EM_RESTART': '1', 09:03:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:08 INFO - 'PWD': '/builds/slave/test', 09:03:08 INFO - 'SHELL': '/bin/bash', 09:03:08 INFO - 'SHLVL': '1', 09:03:08 INFO - 'TERM': 'linux', 09:03:08 INFO - 'TMOUT': '86400', 09:03:08 INFO - 'USER': 'cltbld', 09:03:08 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', 09:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:08 INFO - '_': '/tools/buildbot/bin/python'} 09:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:03:08 INFO - Cleaning up... 09:03:08 INFO - Return code: 0 09:03:08 INFO - Running post-action listener: _resource_record_post_action 09:03:08 INFO - Running post-action listener: _start_resource_monitoring 09:03:08 INFO - Starting resource monitoring. 09:03:08 INFO - [mozharness: 2017-08-10 16:03:08.620903Z] Finished create-virtualenv step (success) 09:03:08 INFO - [mozharness: 2017-08-10 16:03:08.621553Z] Running install step. 09:03:08 INFO - Running pre-action listener: _resource_record_pre_action 09:03:08 INFO - Running main action method: install 09:03:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:03:08 INFO - Reading from file tmpfile_stdout 09:03:08 INFO - Detecting whether we're running mozinstall >=1.0... 09:03:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:03:08 INFO - Reading from file tmpfile_stdout 09:03:08 INFO - Output received: 09:03:08 INFO - Usage: mozinstall [options] installer 09:03:08 INFO - Options: 09:03:08 INFO - -h, --help show this help message and exit 09:03:08 INFO - -d DEST, --destination=DEST 09:03:08 INFO - Directory to install application into. [default: 09:03:08 INFO - "/builds/slave/test"] 09:03:08 INFO - --app=APP Application being installed. [default: firefox] 09:03:08 INFO - mkdir: /builds/slave/test/build/application 09:03:08 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'] 09:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:03:27 INFO - Reading from file tmpfile_stdout 09:03:27 INFO - Output received: 09:03:27 INFO - /builds/slave/test/build/application/firefox/firefox 09:03:27 INFO - Running post-action listener: _resource_record_post_action 09:03:27 INFO - [mozharness: 2017-08-10 16:03:27.048276Z] Finished install step (success) 09:03:27 INFO - [mozharness: 2017-08-10 16:03:27.048564Z] Running setup-mitmproxy step. 09:03:27 INFO - Running pre-action listener: _resource_record_pre_action 09:03:27 INFO - Running main action method: setup_mitmproxy 09:03:27 INFO - Skipping: mitmproxy is not required 09:03:27 INFO - Running post-action listener: _resource_record_post_action 09:03:27 INFO - [mozharness: 2017-08-10 16:03:27.049798Z] Finished setup-mitmproxy step (success) 09:03:27 INFO - [mozharness: 2017-08-10 16:03:27.050023Z] Running run-tests step. 09:03:27 INFO - Running pre-action listener: _resource_record_pre_action 09:03:27 INFO - Running pre-action listener: _set_gcov_prefix 09:03:27 INFO - Running main action method: run_tests 09:03:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:27 INFO - Python 2.7.3 09:03:27 INFO - Return code: 0 09:03:27 INFO - grabbing minidump binary from tooltool 09:03:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 09:03:27 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 0x24e08a0>, '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 0x24e17a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e1c30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:27 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 09:03:27 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 09:03:27 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 09:03:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:03:27 INFO - Return code: 0 09:03:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:03:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:03:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:03:27 INFO - ENV: RUST_BACKTRACE is now full 09:03:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:03:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:03:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-052 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:03:27 INFO - Using env: {'DISPLAY': ':0', 09:03:27 INFO - 'HOME': '/home/cltbld', 09:03:27 INFO - 'LANG': 'en_US.UTF-8', 09:03:27 INFO - 'LANGUAGE': 'en_US:en', 09:03:27 INFO - 'LOGNAME': 'cltbld', 09:03:27 INFO - 'MAIL': '/var/mail/cltbld', 09:03:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:03:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:27 INFO - 'MOZ_NO_REMOTE': '1', 09:03:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:03:27 INFO - 'NO_EM_RESTART': '1', 09:03:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:03:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:27 INFO - 'PWD': '/builds/slave/test', 09:03:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:27 INFO - 'RUST_BACKTRACE': 'full', 09:03:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:03:27 INFO - 'SHELL': '/bin/bash', 09:03:27 INFO - 'SHLVL': '1', 09:03:27 INFO - 'STYLO_FORCE_ENABLED': '1', 09:03:27 INFO - 'TERM': 'linux', 09:03:27 INFO - 'TMOUT': '86400', 09:03:27 INFO - 'USER': 'cltbld', 09:03:27 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416', 09:03:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:27 INFO - '_': '/tools/buildbot/bin/python'} 09:03:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:03:27 INFO - mozversion application_buildid: 20170810152033 09:03:27 INFO - mozversion application_changeset: 5322c03f4c8587fe526172d3f87160031faa6d75 09:03:27 INFO - mozversion application_display_name: Nightly 09:03:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:03:27 INFO - mozversion application_name: Firefox 09:03:27 INFO - mozversion application_remotingname: firefox 09:03:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:03:27 INFO - mozversion application_vendor: Mozilla 09:03:27 INFO - mozversion application_version: 57.0a1 09:03:27 INFO - mozversion platform_buildid: 20170810152033 09:03:27 INFO - mozversion platform_changeset: 5322c03f4c8587fe526172d3f87160031faa6d75 09:03:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:03:27 INFO - mozversion platform_version: 57.0a1 09:03:27 INFO - using testdate: 1502381007 09:03:27 INFO - actual date: 1502381007 09:03:27 INFO - starting webserver on 'localhost:48622' 09:03:27 INFO - SUITE-START | Running 2 tests 09:03:27 INFO - TEST-START | dromaeo_css 09:03:27 INFO - Initialising browser for dromaeo_css test... 09:03:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48622/getInfo.html -profile /tmp/tmpJFQHQb/profile 09:03:27 INFO - TEST-INFO | started process 20684 (/builds/slave/test/build/application/firefox/firefox http://localhost:48622/getInfo.html) 09:03:33 INFO - TEST-INFO | 20684: exit 0 09:03:33 INFO - Browser initialized. 09:03:33 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:33 INFO - TEST-INFO | started process 20921 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJFQHQb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:34 INFO - PID 20921 | 09:03:34 INFO - PID 20921 | (/builds/slave/test/build/application/firefox/firefox:20976): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:03:34 INFO - PID 20921 | 09:03:44 INFO - PID 20921 | RSS: Main: 168542208 09:03:44 INFO - PID 20921 | 09:06:43 INFO - PID 20921 | Cycle 1(1): loaded http://localhost:48622/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48622/tests/dromaeo/cssquery-ext.html) 09:06:45 INFO - PID 20921 | RSS: Main: 176263168 09:06:45 INFO - PID 20921 | 09:09:47 INFO - PID 20921 | Cycle 1(1): loaded http://localhost:48622/tests/dromaeo/cssquery-ext.html (next: http://localhost:48622/tests/dromaeo/cssquery-jquery.html) 09:09:47 INFO - PID 20921 | RSS: Main: 173588480 09:09:47 INFO - PID 20921 | 09:12:49 INFO - PID 20921 | Cycle 1(1): loaded http://localhost:48622/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48622/tests/dromaeo/cssquery-mootools.html) 09:12:50 INFO - PID 20921 | RSS: Main: 174936064 09:12:50 INFO - PID 20921 | 09:15:52 INFO - PID 20921 | Cycle 1(1): loaded http://localhost:48622/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48622/tests/dromaeo/cssquery-prototype.html) 09:15:52 INFO - PID 20921 | RSS: Main: 171446272 09:15:52 INFO - PID 20921 | 09:18:56 INFO - PID 20921 | Cycle 1(1): loaded http://localhost:48622/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48622/tests/dromaeo/cssquery-yui.html) 09:18:57 INFO - PID 20921 | RSS: Main: 177025024 09:18:57 INFO - PID 20921 | 09:21:58 INFO - PID 20921 | Cycle 1(1): loaded http://localhost:48622/tests/dromaeo/cssquery-yui.html (next: http://localhost:48622/tests/dromaeo/cssquery-dojo.html) 09:21:59 INFO - PID 20921 | RSS: Main: 177950720 09:21:59 INFO - PID 20921 | 09:21:59 INFO - PID 20921 | __start_tp_report 09:21:59 INFO - PID 20921 | _x_x_mozilla_page_load 09:21:59 INFO - PID 20921 | _x_x_mozilla_page_load_details 09:21:59 INFO - PID 20921 | |i|pagename|runs| 09:21:59 INFO - PID 20921 | |0;dojo.html;3102.100390886643;3166.6991635794598;3178.809271443712;3192.8084314941098;3210.6789321067895;6200.937990620092;6223.37766223378;6303.590266632666;6316.115743795866;6359.014352775315;6171.259448866131;6291.559590828644;6307.621542707429;6330.8100756977365;6347.936520634792;5743.224664670258;5764.682942438164;5767;5776.999999999989;5782.710864456782;5776.39347868474;5779.162021506879;5875.1187321901625;5904.114382842565;5913.113033045056;5755.884882302352;5816.563757718167;5830.591858569881;5861.589688721791;5878.323992740826;981.8512789262321;5718.714064296769;5767.048437007888;5841.678707671098;5852.853678658025;5553.222548843165;5701.543876489871;5714.45712657301;5752.705641230731;5758.683272420015;6137.846553836145;6149.046897730859;6171.074338849208;6259;6308.842278943063;6710.563813352165;6722.159730033746;6741.831454213635;6810.353016463428;6839.794806155824;6771.762988295435;6776.186857577122;6780.288069752654;6874.243833178396;6878.828029299258;6200.782972595937;6323.620582765003;6351.174347334817;6391.137196478462;6412.551121421502;6937.271586483346;7003.964980175168;7027.8594428111155;7043.92956070443;7048.612326322102;6701.698423570951;6729.966350168218;6733.427658648971;6750.616123694631;6756.324367563205;6761.121054262914;6766.966165169143;6829.726810927507;6862.142232220973;6868.381845633917;6704.899426508607;6735.831604209934;6760.18877734675;6829.248782633907;6838.316168383122;6658.7336506539195;6754.493412994045;6756.391924726799;6757.628330441873;6759.391654751096;5440;5461.6996065216;5501.889962200734;5513.393526712139;5521.834344969658;4715.7877895494785;4721.244600863845;4758.976205118952;4772.7136371817805;4781.2111001685025;5821.363645444197;5849.824505264849;5913.349531551526;5934.643921364732;5946.613470124428;6802.047713320139;6805.591664500047;6854.588724676535;6864.176298844171;6893.862122757517;5716.542676585863;5747.827565173052;5806.970965145232;5879.764809407576;5917.940820591825;6031.24609423822;6039.275286965592;6050;6079.665618391031;6098.115773212948;6546.869062618721;6581.40767330933;6636.601803891782;6646.20245570525;6657.434118100015;6786.117804685359;6815.829604259933;6909.136357955255;6911.550749201286;6915.273896240923;6439.967800160969;6549.508786840911;6556.213254409501;6557.96721016411;6559.376859198369;6661.16735408074;6675.0654908313845;6753.054572359775;6761.966190169018;6769.458443324621;6764.425023873026;6881.41507971828;6888.758893438706;6896.655167241718;6911.101556797584;6671.33286671329;6687.799366019027;6692.498062645223;6693.665316734046;6693.933060669331;6577.967110164419;6589.868202636017;6597.241317248578;6673.532852700265;6695.564788288746;6431.614103153825;6529.314421985623;6545.70544325516;6562.34376562359;6572.211334639874;6643.236027856665;6648.501362397743;6708.6310252936555;6718.294579069127;6725.798226053226;6007.128966299774;6438.3883531064475;6486.513511486752;6537.934620653733;6538.607683539003 09:21:59 INFO - PID 20921 | |1;ext.html;751.3425752466592;805.0621026504123;834.261678414603;850.9191626795456;863.4732812984079;39313.410298845505;39388.212235755265;39788.60211397885;40049.3992590111;40124.79937600312;35181.120471988215;35204.295914081704;35477;35575.93272201832;35576.82211588939;6671.4662826973845;6733.192016957947;6792.626405547693;6794.286599907013;6802.897956530656;6667.666616669171;6762.22234443038;6763.086983257246;6773.729050837986;6784.050232967388;7554.924450755477;7709.074911010686;7712.961435192816;7716.151223365453;7719.884201736951;7605.353544948686;7658.808529786744;7662;7667.501612395176;7670.961645191766;7486.9251307486775;7556.735514256975;7620.847583048363;7669.38644908406;7672.386209103258;37835;37979.240415191816;38196;38201.235975280615;38299.80850095732;3515.2090779574646;3517.43721004638;3535.5934067582302;3545.6099829018785;3552.236269202134;30557.08328750141;30674.84662576695;30709.078727638207;30819.229519261975;30839.53740693869;5005.774740136702;5022.598192144656;5058.671186372874;5061.468545802712;5072.492750724935;231204;235902;238851;239117;239314;48686.26970595444;48930;49083.26375104376;49195.50804491981;49276.75361623169;50407.24389134166;50630;50815.23777143346;50911.23633145506;50943.745281274096;16499.505014849332;16823.07473088992;16876.746848797276;16876.83123168777;16886.15569221534;13831.239281839395;13837.86162138386;13847.376868040761;13848.930755346159;13858.861411385958;14128.293585320693;14369.497067602793;14401.495947641992;14445.49440769568;14652.340644671016;5681.636407262227;5724.484796368263;5745.9425405745405;5817.0983497557945;5857.648541087548;987.3138168972495;1000.8148492528906;1013.7769690668041;1013.8783345998528;1022.3967858963248;22269.888650556644;22293.66559501609;22500.099996000303;22522.436939076655;22679;1095.59462998691;1110.1119104716195;1125.887411258884;1128.542940109257;1136.9545218191345;11896.762064758655;12029.879701203052;12036.63890083299;12037.157398981988;12055.638330850088;254686;255614;256184;256508;257274;51358.74320628373;51757.74121129446;51993.7400312996;56229.43770562324;56294.437055629736;1429.2496439369415;1504.0975414751063;1508.3137172586446;1508.6756347385365;1524.1083965879864;26707;26980.865095674395;27349.863250684415;27684.44631107326;27685.723142768315;36394.63605363912;36870;36877.63122368742;36889.631103689695;36898;215914;216904;217481;218271;218299;34279.65720342765;34459.4831077529;34502;34610.48084278788;34784.65215347814;39843.60156398399;40802.38796418116;41306;41408.37887431631;41415.58584414118;30973.225669357544;31118.22204444907;31363.215919602193;31504.842475787475;31618.525722114646;31163.37673246477;33578.3284334307;34688.132796680286;34979.65020349764;35059.824700876336;37515.249695006496;37897;37899.43150852684;37988.43017354687;38074.428883566216;32643.510347344334;32853;32893.50659740154;33020.1744956378;33144.33711325808;24175.274741757774;24277.51444971126;24305.51388972246;24539.631905521073;24910 09:21:59 INFO - PID 20921 | |2;jquery.html;571.2516603249743;574.8131857146426;575.8013485347559;583.2592607388615;597.871457636608;21066.473338166554;21608.3517494475;22615;22709;22784.43038924028;26843.597346039824;27228;27480.450390992217;27520.311992200153;27638.447231055416;6987.336203060714;7149.249328820477;7187.568745875238;7193.136823581178;7198.496105272633;6650.334966503335;6955.200151982511;7124.394426473756;7154.320339567733;7203.171635261933;7006.299370062979;7016;7036.753713620024;7037.120359955005;7037.788866334018;7014.894776578355;7020.859582808341;7023.473239507058;7035.085438892962;7036.261192574757;6876.312368763134;6877.724891004354;6898.862022759567;6901.6204108774;6913.10129683143;31258.21854453634;31681.84159079213;32439.83780081108;32445.188870278194;32755.18112047194;1937.7480927479337;1978.1592823050146;1980.0990549300177;1984.5435549823478;1992.2927360787003;20399.388018359474;21138;21245.256416025364;21310.680339794915;21375.251866184764;2447.115720894919;2516.584763514018;2562.923112306634;2575.3947822261753;2580.806439517025;135811.32094339564;136566;138561.3071934644;139447.99999999898;139876.30061849728;19420.25217730411;19998.90000550017;21430;22353.44116397103;22793;43995.34006989898;44013.779931100136;44234.77882610566;44327;44370;11758.70603234926;12049.578264760692;12067.57763478274;12072.698182545331;12260;10079.949600251952;10160.33957792756;10194.184465242763;10323.48382580868;10361.844572331422;7806.92193078062;11149.832752508719;11307.208495405243;11435.828462573068;11824.76350473003;3678.7424880258127;3731.850046935212;3731.962680373216;3741.970957986521;4550.158220729176;965.7537327981461;976.3117002513211;983.1937810995058;995.2286977592279;996.3274789517047;19584.314548991002;19874.205031798567;19939.60120797576;19954.90022549907;20555.691664625043;1000.9049140331657;1072.619220176826;1073.7906108308805;1077.7467295185625;1082.5453309609982;10733.409662468639;11192.328460292409;11483.196176267747;13299.800502992462;14326.641833954027;540877;543939;546024.3493912604;553666;561301;117949;118218;119141;119915;126610.36694816466;1263.728298415845;1328.3291937571464;1355.9932200338935;1356.5862411964445;1364.1814911053289;21778.237761678265;21841.563168736855;22023.22918697838;22094.779052209273;22226;55723.72138139283;56003.71998140146;58059.709701451226;58891;59047.40952590591;66878.66560667165;67284.3271567278;67618.9857152153;67797.66101169462;67959.66020169867;14922.25388730581;15075.321610527333;15159.696806064037;15247.771283430979;15319.846801531841;23734.287971360885;23973.160939367037;24010.51978960446;24053.278401647978;24298;18469;19388.224471021;19390;19581.118849651513;19637.901810490857;17257.654846903242;17671.734923975895;17681;18004.36984705529;18058.097095145455;19611;19654.80345196587;19907.303244386378;20049.398518044483;20176.29382971589;14907.031042982606;14932.402703891723;14968.02707823988;15158.166300853118;15183.316750746022;18684.439466816017;18855.528611784815;18882;18959.715604266225;19026.619467610846 09:21:59 INFO - PID 20921 | |3;mootools.html;1325.4698120751698;1350.4469859661388;1406.7538180818353;1408.9929550352247;1413.9363728632213;13738.931305343473;14297.213653249093;14319.570412887617;14324.140551566914;14329.856701432982;14068.22624755638;14177.43290268388;14177.78733319001;14237.359318830651;14269.857301426982;2176.8911554422293;2636.644053052837;2659.6675415573054;2663.73362663734;2694.312950197707;2288.5422915416984;2484.254723582927;2561.692596888376;2662.3211081174272;2668.159529748123;2662.068276103358;2692.219256415637;2692.4211294571664;2694.8248363856383;2696.7168447313043;2512.4975004998983;2560.705518865326;2600.427905860717;2611.869406529685;2614.686237651475;2526.507331070442;2556.7187609362954;2556.782673472757;2566.5123626510904;2571.845689258632;13664.863351366557;13717.314134293245;13846.861531384759;13872.583822485341;13872.861271387257;7241.855162896765;7394.260573942616;7502.849943001164;7504.662290196954;7514.135874374462;4860.392450943632;4974.054929563407;5123;5135.024345374367;5146.279520867081;1791.3192986665115;1791.480470663506;1801.2794882047156;1826.7807863056385;1827.1229809691422;22572.209972651042;23139.7686023141;23142.88428557863;23156.18953336625;23172.88413557938;6353.7140828661895;6554.410103090745;6578.144841170617;6756.594604323756;6758.189017317954;18311.175997080165;18447.262109515737;18451.354202602844;18472.630547388977;18534.44396668102;7406.629668516553;7488.063992001;7538.246175382418;7543.170251272356;7559.697612095454;6741.629210393377;6795.7961261162245;6873.381395674413;6993.055937448379;6994.370506654425;5383.192521121785;5522.392536820947;5551.278333816578;5557.221988921554;5628.521575666033;1661.9916900415421;1684.216839169792;1690.8055573608947;1716.3306310538899;1726.66330065636;840.8024114333127;855.4183155454351;867.8568036274071;872.7687162901836;883.3992884838372;7942.880856787153;8074.475159114639;8096.7166149184495;8178.1821817817745;8197.508149511048;723.2586598736337;732.6007326007339;735.0958321264767;739.5562662402515;747.8130467383154;4551.658625603093;4564.475085365159;4578.1301552705545;5089.720065396439;5373.8714869876985;16983.405580804614;17720.91139544294;18106.004169770527;18115.909420452812;18118.547036323933;17298.394556190473;17429.91285043592;17712.46862594124;17737.733933991;18033.18850651724;9993.750156246151;10030.799384012278;10113.898861011296;10123.44321062334;10124.088832005007;7485.363744081814;7522.360599348897;7556.811079722831;7597.126330472067;7633.160352361125;15034.548963531111;15503.302351393988;15725.44960926362;15805.209739512748;15824.208789560706;15656.530304090895;16087.67824643524;16140.596485087966;16161;16224.837751622805;10939.671809845717;11055.613053543088;11081.944590276997;11082.390468524309;11151.052160566443;12177.269363838199;12433.689157770767;12635.747285054063;12646.936765316115;12690.619281421572;10864.45677716127;10992.560297588007;11028.834567481334;11230.438478076227;11331.546738130712;10621.89378106209;10645.733856653645;10770.138388929023;10780.353178809297;10897;8864.911350886408;8940.46357218569;8968.461892286483;8979.775505612151;8988.235999940078;6675.365840245169;6809.353111454404;6921.58470491752;7123.893141602984;7160.033395491542;7211.67547460376;7297.562146271241;7321.341079302888;7347.5224110432655;7399.556026638204 09:21:59 INFO - PID 20921 | |4;prototype.html;497.863087650896;501.61125128025776;524.4519477146387;527.0855066459693;530.8368398774993;6247.219097612799;6276.529260305472;6315.210598675166;6324;6326.2092238470195;5959.553033522492;6084.5132389408855;6103.23709536308;6154.323024467305;6231.532635052378;4908.558229759321;4917.82787602434;4936.629752768539;4956.925646115311;5030.773615187316;4872.3909511311085;4947.282644016615;4959.462566604364;4965.453800081988;4974.875628109308;4840.128776820171;4856.660033797635;4912.36139301892;4918.237673160648;4954.380702412199;4891.192953162724;4917.262410638397;4959.156943319645;4961.925571116436;5060.2409638554145;4442.2226110430865;4442.577955094293;4465.68740188187;4472.284434490499;4507.729536227836;6167.691615419256;6170.96914515429;6171.25944886612;6198.287196972361;6199.3490683478485;5781.913271300934;5792.275965504312;5796.855078623026;5802.158686990363;5806.303243610751;6599.9670001650165;6608.933910660928;6650.467962562983;6650.900236496408;6652.234992975821;4861.951380486185;4891.144049791273;4903.828366007173;4917.262410638397;4927.876803079916;6770.289119642416;6913.343232392915;6926.298743489992;6931.168259808805;6932.168139823203;5009.423916249678;5025.120603894377;5038.924416133761;5107.591392688576;5113.565346945551;5042.773075211624;5097.566706829887;5107.846764597068;5128.948710512922;5161.66449180802;5744.913826292609;5751.309842818805;5763.135529670583;5768.596198266081;5781.595288329777;6574.088417391645;6625.668716564152;6672.165979252593;6683.431908287753;6721.462283017347;1667.7915260592426;1720.0883531728205;1774.1927423022753;1790.9910450447665;1915.2339064374082;1413.9787903181461;1421.6588018875395;1438.180237264749;1478.3569147420906;1484.5323723027211;5682.659040457585;5726.3700992890745;5763.740631671585;5772.237903729356;5825.70871456434;6485.5460117792245;6633.204015518072;6635.469162466991;6637.23671777742;6710;5810.680412577264;5812.6221795584;5819.709014549256;5824.563157763185;5845.649261044351;3556.6976806971697;3600.613763700994;3639.6906262967946;3642.307961487309;3644.835982380799;6398.680065996681;6419.74321027163;6448.097266382711;6494.805155845333;6523.380278873499;6849.760258390932;6918.411934985482;6919.688614012382;6920.9307906920285;6980.720771169096;6447.806565803034;6495.935040649533;6534.215894092739;6535.8692826143215;6628.602283862983;6656.966715166393;6672.366125218096;6769.153855768029;6794.218664853407;6856.6228857413325;6694.163229596301;6697.866042679022;6703.396694297537;6735.73057077731;6782.796516104525;6451.903221451588;6549.7380104795275;6558.409743123142;6582.407583317525;6591.791749763883;5074.112030394593;5083.949160508496;5084.161113416245;5124.256982737407;5126.7180305083575;6770.6614669265355;6772.390484856386;6772.627505487246;6781.423578995643;6837.794866154023;6235.8174638876035;6376.458001069961;6509.558915406723;6510.641914694737;6525.934740652533;6437.645929473924;6462.676866156579;6505.447037001908;6562.868742625216;6573.638449885111;6271.874562508816;6357.491400688026;6364.840878978062;6436.967815160894;6459.870802584016;6409.262934762562;6474.384933431316;6512.697460508012;6575.967120164369;6634.701438435379;6274.278457977209;6383.170187875547;6439.67801609927;6448.838779030561;6452.806415807533 09:21:59 INFO - PID 20921 | |5;yui.html;905.9954700226498;923.233716015707;926.754410081329;937.4141161773891;940.9106134917181;16993.745093823603;17563.912180439096;17575.82424175758;17576.29694812206;20489.795102048975;20783.792162078375;21937.561248775015;21968;22054.33836984889;22078.77921220787;1017.2116609627524;1057.5399701129995;1060.6499855047855;1062.2139616683676;1062.5909025025383;1028.804527139841;1065.664315740543;1066.1950227578168;1069.4973362519604;1079.4386918802256;1124.3085502415995;1125.2629527659396;1125.7467069909246;1127.5433449452985;1128.6670432222472;1115.0856297835778;1115.1246271676735;1115.3753897817166;1117.5697356517717;1119.764849381631;819.6598411659154;819.8401311744212;819.8565251081096;822.7079386817653;822.8560001999626;18221.18004689792;18272.086395680297;18416.63166736658;18980.810191898043;20479.38561843147;21606.351809445743;21750.782492175033;22062.448438788997;22275.331740047826;22286;16778.328866845313;16918.323267069303;17095.230714617872;17131.22909469077;17209.22558484871;3570.4644303354444;3577.5528058992627;3578.141246100943;3579.928401431957;3580.3197392495345;122971;123070.38464807619;124840.37579812134;125971;126007;17036.744448833277;17258;17259.482215533553;17512.912435437996;17534.29862805499;31520.211994700316;31682.207944801565;31686;31823.204419889687;31859;8426.199511046441;8526.360522960678;8530.146985301544;8541.914580854113;8552.786180345542;4106.609872062149;4170.145120250341;4205.789710514523;4211.284081706117;4236.279832428495;5140.974295128575;5279.52484276417;5303.4696530346655;5307.6019298552765;5310.601704872072;933.2580598424269;936.2369668719972;955.2644463762864;959.1367769007809;965.4110992294694;681.4786688183543;689.1420181873599;689.762032098925;694.7707256605308;700.9088818453671;11124.721881952853;11920.10599205063;11942.940285298519;12582.24506529611;13288.136271142344;690.4993879437368;691.1015679616479;696.4463251714798;698.2319448606594;702.0311969482082;6049.5462840287155;6177.722002509898;6273.96863015682;6611.86776264472;6641.351054262036;102720;104473;105630;109844;109957.45021275003;95448;96313.51843240739;98744;100898.49550752199;101506;81104.18895810966;83657;84051.15948840433;84715;84942.02702905369;12672.303023333805;12809.807852882028;12837.486500540248;13152.539661112198;13251.13867598603;37959.43060854144;38863.41704874373;38897.61102389053;39101.217975640895;40207.798961005006;68782;71243.64378178076;71699;73693.26306736864;73846;18927.810721893155;19369.225230990603;20294.188232470537;20332.69500957457;20349;18836.52908677294;19326.4202073938;19408.320708775125;19431.805681943;19710.50723731861;19397;19970.30103946355;20006.199752009758;20032.399028029184;20183.39449816508;19426;19469.415917522496;19616.901915490333;19681.901590491954;19935;16746.49760507187;17065.40271090506;17226.827731722522;17401.56496087608;19294.22823087061;14131;14148.22184779847;14148.292585370484;14387.352569134206;14456.85543144555;14033.649158771112;14062.085964412281;14629.70740585161;15236.771448428504;15654.765178522104 09:21:59 INFO - PID 20921 | __end_tp_report 09:21:59 INFO - PID 20921 | __start_cc_report 09:21:59 INFO - PID 20921 | _x_x_mozilla_cycle_collect,376 09:21:59 INFO - PID 20921 | __end_cc_report 09:21:59 INFO - PID 20921 | __startTimestamp1502382119094__endTimestamp 09:21:59 INFO - PID 20921 | 09:21:59 INFO - PID 20921 | ------- Summary: start ------- 09:21:59 INFO - PID 20921 | Number of tests: 6 09:21:59 INFO - PID 20921 | 09:21:59 INFO - PID 20921 | [#0] dojo.html Cycles:165 Average:6221.22 Median:6545.71 stddev:851.49 (13.0%) stddev-sans-first:818.19 09:21:59 INFO - PID 20921 | Values: 3102.1 3166.7 3178.8 3192.8 3210.7 6200.9 6223.4 6303.6 6316.1 6359.0 6171.3 6291.6 6307.6 6330.8 6347.9 5743.2 5764.7 5767.0 5777.0 5782.7 5776.4 5779.2 5875.1 5904.1 5913.1 5755.9 5816.6 5830.6 5861.6 5878.3 981.9 5718.7 5767.0 5841.7 5852.9 5553.2 5701.5 5714.5 5752.7 5758.7 6137.8 6149.0 6171.1 6259.0 6308.8 6710.6 6722.2 6741.8 6810.4 6839.8 6771.8 6776.2 6780.3 6874.2 6878.8 6200.8 6323.6 6351.2 6391.1 6412.6 6937.3 7004.0 7027.9 7043.9 7048.6 6701.7 6730.0 6733.4 6750.6 6756.3 6761.1 6767.0 6829.7 6862.1 6868.4 6704.9 6735.8 6760.2 6829.2 6838.3 6658.7 6754.5 6756.4 6757.6 6759.4 5440.0 5461.7 5501.9 5513.4 5521.8 4715.8 4721.2 4759.0 4772.7 4781.2 5821.4 5849.8 5913.3 5934.6 5946.6 6802.0 6805.6 6854.6 6864.2 6893.9 5716.5 5747.8 5807.0 5879.8 5917.9 6031.2 6039.3 6050.0 6079.7 6098.1 6546.9 6581.4 6636.6 6646.2 6657.4 6786.1 6815.8 6909.1 6911.6 6915.3 6440.0 6549.5 6556.2 6558.0 6559.4 6661.2 6675.1 6753.1 6762.0 6769.5 6764.4 6881.4 6888.8 6896.7 6911.1 6671.3 6687.8 6692.5 6693.7 6693.9 6578.0 6589.9 6597.2 6673.5 6695.6 6431.6 6529.3 6545.7 6562.3 6572.2 6643.2 6648.5 6708.6 6718.3 6725.8 6007.1 6438.4 6486.5 6537.9 6538.6 09:21:59 INFO - PID 20921 | 09:21:59 INFO - PID 20921 | [#1] ext.html Cycles:180 Average:40063.47 Median:19349.28 stddev:61661.75 (318.7%) stddev-sans-first:61764.07 09:21:59 INFO - PID 20921 | Values: 751.3 805.1 834.3 850.9 863.5 39313.4 39388.2 39788.6 40049.4 40124.8 35181.1 35204.3 35477.0 35575.9 35576.8 6671.5 6733.2 6792.6 6794.3 6802.9 6667.7 6762.2 6763.1 6773.7 6784.1 7554.9 7709.1 7713.0 7716.2 7719.9 7605.4 7658.8 7662.0 7667.5 7671.0 7486.9 7556.7 7620.8 7669.4 7672.4 37835.0 37979.2 38196.0 38201.2 38299.8 3515.2 3517.4 3535.6 3545.6 3552.2 30557.1 30674.8 30709.1 30819.2 30839.5 5005.8 5022.6 5058.7 5061.5 5072.5 231204.0 235902.0 238851.0 239117.0 239314.0 48686.3 48930.0 49083.3 49195.5 49276.8 50407.2 50630.0 50815.2 50911.2 50943.7 16499.5 16823.1 16876.7 16876.8 16886.2 13831.2 13837.9 13847.4 13848.9 13858.9 14128.3 14369.5 14401.5 14445.5 14652.3 5681.6 5724.5 5745.9 5817.1 5857.6 987.3 1000.8 1013.8 1013.9 1022.4 22269.9 22293.7 22500.1 22522.4 22679.0 1095.6 1110.1 1125.9 1128.5 1137.0 11896.8 12029.9 12036.6 12037.2 12055.6 254686.0 255614.0 256184.0 256508.0 257274.0 51358.7 51757.7 51993.7 56229.4 56294.4 1429.2 1504.1 1508.3 1508.7 1524.1 26707.0 26980.9 27349.9 27684.4 27685.7 36394.6 36870.0 36877.6 36889.6 36898.0 215914.0 216904.0 217481.0 218271.0 218299.0 34279.7 34459.5 34502.0 34610.5 34784.7 39843.6 40802.4 41306.0 41408.4 41415.6 30973.2 31118.2 31363.2 31504.8 31618.5 31163.4 33578.3 34688.1 34979.7 35059.8 37515.2 37897.0 37899.4 37988.4 38074.4 32643.5 32853.0 32893.5 33020.2 33144.3 24175.3 24277.5 24305.5 24539.6 24910.0 09:21:59 INFO - PID 20921 | 09:21:59 INFO - PID 20921 | [#2] jquery.html Cycles:180 Average:37925.67 Median:21932.40 stddev:91625.54 (417.8%) stddev-sans-first:91839.65 09:21:59 INFO - PID 20921 | Values: 571.3 574.8 575.8 583.3 597.9 21066.5 21608.4 22615.0 22709.0 22784.4 26843.6 27228.0 27480.5 27520.3 27638.4 6987.3 7149.2 7187.6 7193.1 7198.5 6650.3 6955.2 7124.4 7154.3 7203.2 7006.3 7016.0 7036.8 7037.1 7037.8 7014.9 7020.9 7023.5 7035.1 7036.3 6876.3 6877.7 6898.9 6901.6 6913.1 31258.2 31681.8 32439.8 32445.2 32755.2 1937.7 1978.2 1980.1 1984.5 1992.3 20399.4 21138.0 21245.3 21310.7 21375.3 2447.1 2516.6 2562.9 2575.4 2580.8 135811.3 136566.0 138561.3 139448.0 139876.3 19420.3 19998.9 21430.0 22353.4 22793.0 43995.3 44013.8 44234.8 44327.0 44370.0 11758.7 12049.6 12067.6 12072.7 12260.0 10079.9 10160.3 10194.2 10323.5 10361.8 7806.9 11149.8 11307.2 11435.8 11824.8 3678.7 3731.9 3732.0 3742.0 4550.2 965.8 976.3 983.2 995.2 996.3 19584.3 19874.2 19939.6 19954.9 20555.7 1000.9 1072.6 1073.8 1077.7 1082.5 10733.4 11192.3 11483.2 13299.8 14326.6 540877.0 543939.0 546024.3 553666.0 561301.0 117949.0 118218.0 119141.0 119915.0 126610.4 1263.7 1328.3 1356.0 1356.6 1364.2 21778.2 21841.6 22023.2 22094.8 22226.0 55723.7 56003.7 58059.7 58891.0 59047.4 66878.7 67284.3 67619.0 67797.7 67959.7 14922.3 15075.3 15159.7 15247.8 15319.8 23734.3 23973.2 24010.5 24053.3 24298.0 18469.0 19388.2 19390.0 19581.1 19637.9 17257.7 17671.7 17681.0 18004.4 18058.1 19611.0 19654.8 19907.3 20049.4 20176.3 14907.0 14932.4 14968.0 15158.2 15183.3 18684.4 18855.5 18882.0 18959.7 19026.6 09:21:59 INFO - PID 20921 | 09:21:59 INFO - PID 20921 | [#3] mootools.html Cycles:180 Average:8564.67 Median:2558.74 stddev:5792.34 (226.4%) stddev-sans-first:5783.05 09:21:59 INFO - PID 20921 | Values: 1325.5 1350.4 1406.8 1409.0 1413.9 13738.9 14297.2 14319.6 14324.1 14329.9 14068.2 14177.4 14177.8 14237.4 14269.9 2176.9 2636.6 2659.7 2663.7 2694.3 2288.5 2484.3 2561.7 2662.3 2668.2 2662.1 2692.2 2692.4 2694.8 2696.7 2512.5 2560.7 2600.4 2611.9 2614.7 2526.5 2556.7 2556.8 2566.5 2571.8 13664.9 13717.3 13846.9 13872.6 13872.9 7241.9 7394.3 7502.8 7504.7 7514.1 4860.4 4974.1 5123.0 5135.0 5146.3 1791.3 1791.5 1801.3 1826.8 1827.1 22572.2 23139.8 23142.9 23156.2 23172.9 6353.7 6554.4 6578.1 6756.6 6758.2 18311.2 18447.3 18451.4 18472.6 18534.4 7406.6 7488.1 7538.2 7543.2 7559.7 6741.6 6795.8 6873.4 6993.1 6994.4 5383.2 5522.4 5551.3 5557.2 5628.5 1662.0 1684.2 1690.8 1716.3 1726.7 840.8 855.4 867.9 872.8 883.4 7942.9 8074.5 8096.7 8178.2 8197.5 723.3 732.6 735.1 739.6 747.8 4551.7 4564.5 4578.1 5089.7 5373.9 16983.4 17720.9 18106.0 18115.9 18118.5 17298.4 17429.9 17712.5 17737.7 18033.2 9993.8 10030.8 10113.9 10123.4 10124.1 7485.4 7522.4 7556.8 7597.1 7633.2 15034.5 15503.3 15725.4 15805.2 15824.2 15656.5 16087.7 16140.6 16161.0 16224.8 10939.7 11055.6 11081.9 11082.4 11151.1 12177.3 12433.7 12635.7 12646.9 12690.6 10864.5 10992.6 11028.8 11230.4 11331.5 10621.9 10645.7 10770.1 10780.4 10897.0 8864.9 8940.5 8968.5 8979.8 8988.2 6675.4 6809.4 6921.6 7123.9 7160.0 7211.7 7297.6 7321.3 7347.5 7399.6 09:21:59 INFO - PID 20921 | 09:21:59 INFO - PID 20921 | [#4] prototype.html Cycles:180 Average:5533.53 Median:6171.11 stddev:1530.59 (24.8%) stddev-sans-first:1487.48 09:21:59 INFO - PID 20921 | Values: 497.9 501.6 524.5 527.1 530.8 6247.2 6276.5 6315.2 6324.0 6326.2 5959.6 6084.5 6103.2 6154.3 6231.5 4908.6 4917.8 4936.6 4956.9 5030.8 4872.4 4947.3 4959.5 4965.5 4974.9 4840.1 4856.7 4912.4 4918.2 4954.4 4891.2 4917.3 4959.2 4961.9 5060.2 4442.2 4442.6 4465.7 4472.3 4507.7 6167.7 6171.0 6171.3 6198.3 6199.3 5781.9 5792.3 5796.9 5802.2 5806.3 6600.0 6608.9 6650.5 6650.9 6652.2 4862.0 4891.1 4903.8 4917.3 4927.9 6770.3 6913.3 6926.3 6931.2 6932.2 5009.4 5025.1 5038.9 5107.6 5113.6 5042.8 5097.6 5107.8 5128.9 5161.7 5744.9 5751.3 5763.1 5768.6 5781.6 6574.1 6625.7 6672.2 6683.4 6721.5 1667.8 1720.1 1774.2 1791.0 1915.2 1414.0 1421.7 1438.2 1478.4 1484.5 5682.7 5726.4 5763.7 5772.2 5825.7 6485.5 6633.2 6635.5 6637.2 6710.0 5810.7 5812.6 5819.7 5824.6 5845.6 3556.7 3600.6 3639.7 3642.3 3644.8 6398.7 6419.7 6448.1 6494.8 6523.4 6849.8 6918.4 6919.7 6920.9 6980.7 6447.8 6495.9 6534.2 6535.9 6628.6 6657.0 6672.4 6769.2 6794.2 6856.6 6694.2 6697.9 6703.4 6735.7 6782.8 6451.9 6549.7 6558.4 6582.4 6591.8 5074.1 5083.9 5084.2 5124.3 5126.7 6770.7 6772.4 6772.6 6781.4 6837.8 6235.8 6376.5 6509.6 6510.6 6525.9 6437.6 6462.7 6505.4 6562.9 6573.6 6271.9 6357.5 6364.8 6437.0 6459.9 6409.3 6474.4 6512.7 6576.0 6634.7 6274.3 6383.2 6439.7 6448.8 6452.8 09:21:59 INFO - PID 20921 | 09:21:59 INFO - PID 20921 | [#5] yui.html Cycles:180 Average:23824.68 Median:20107.90 stddev:31655.28 (157.4%) stddev-sans-first:31697.30 09:21:59 INFO - PID 20921 | Values: 906.0 923.2 926.8 937.4 940.9 16993.7 17563.9 17575.8 17576.3 20489.8 20783.8 21937.6 21968.0 22054.3 22078.8 1017.2 1057.5 1060.6 1062.2 1062.6 1028.8 1065.7 1066.2 1069.5 1079.4 1124.3 1125.3 1125.7 1127.5 1128.7 1115.1 1115.1 1115.4 1117.6 1119.8 819.7 819.8 819.9 822.7 822.9 18221.2 18272.1 18416.6 18980.8 20479.4 21606.4 21750.8 22062.4 22275.3 22286.0 16778.3 16918.3 17095.2 17131.2 17209.2 3570.5 3577.6 3578.1 3579.9 3580.3 122971.0 123070.4 124840.4 125971.0 126007.0 17036.7 17258.0 17259.5 17512.9 17534.3 31520.2 31682.2 31686.0 31823.2 31859.0 8426.2 8526.4 8530.1 8541.9 8552.8 4106.6 4170.1 4205.8 4211.3 4236.3 5141.0 5279.5 5303.5 5307.6 5310.6 933.3 936.2 955.3 959.1 965.4 681.5 689.1 689.8 694.8 700.9 11124.7 11920.1 11942.9 12582.2 13288.1 690.5 691.1 696.4 698.2 702.0 6049.5 6177.7 6274.0 6611.9 6641.4 102720.0 104473.0 105630.0 109844.0 109957.5 95448.0 96313.5 98744.0 100898.5 101506.0 81104.2 83657.0 84051.2 84715.0 84942.0 12672.3 12809.8 12837.5 13152.5 13251.1 37959.4 38863.4 38897.6 39101.2 40207.8 68782.0 71243.6 71699.0 73693.3 73846.0 18927.8 19369.2 20294.2 20332.7 20349.0 18836.5 19326.4 19408.3 19431.8 19710.5 19397.0 19970.3 20006.2 20032.4 20183.4 19426.0 19469.4 19616.9 19681.9 19935.0 16746.5 17065.4 17226.8 17401.6 19294.2 14131.0 14148.2 14148.3 14387.4 14456.9 14033.6 14062.1 14629.7 15236.8 15654.8 09:21:59 INFO - PID 20921 | -------- Summary: end -------- 09:21:59 INFO - PID 20921 | 09:21:59 INFO - TEST-INFO | 20921: exit 0 09:21:59 INFO - TEST-OK | dromaeo_css | took 1112382ms 09:21:59 INFO - TEST-START | kraken 09:21:59 INFO - Initialising browser for kraken test... 09:21:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48622/getInfo.html -profile /tmp/tmppkWWKa/profile 09:21:59 INFO - TEST-INFO | started process 21306 (/builds/slave/test/build/application/firefox/firefox http://localhost:48622/getInfo.html) 09:22:05 INFO - TEST-INFO | 21306: exit 0 09:22:05 INFO - Browser initialized. 09:22:05 INFO - Running cycle 1/1 for kraken test... 09:22:05 INFO - TEST-INFO | started process 21536 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppkWWKa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:06 INFO - PID 21536 | 09:22:06 INFO - PID 21536 | (/builds/slave/test/build/application/firefox/firefox:21590): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:22:06 INFO - PID 21536 | 09:22:16 INFO - PID 21536 | RSS: Main: 168685568 09:22:16 INFO - PID 21536 | 09:22:19 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48622/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:20 INFO - PID 21536 | RSS: Main: 174923776 09:22:20 INFO - PID 21536 | 09:22:23 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48622/tests/kraken/driver.html?testName=audio-dft) 09:22:23 INFO - PID 21536 | RSS: Main: 178556928 09:22:23 INFO - PID 21536 | 09:22:26 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48622/tests/kraken/driver.html?testName=audio-fft) 09:22:27 INFO - PID 21536 | RSS: Main: 172810240 09:22:27 INFO - PID 21536 | 09:22:29 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48622/tests/kraken/driver.html?testName=audio-oscillator) 09:22:30 INFO - PID 21536 | RSS: Main: 173133824 09:22:30 INFO - PID 21536 | 09:22:32 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48622/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:33 INFO - PID 21536 | RSS: Main: 173645824 09:22:33 INFO - PID 21536 | 09:22:36 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48622/tests/kraken/driver.html?testName=imaging-darkroom) 09:22:37 INFO - PID 21536 | RSS: Main: 174624768 09:22:37 INFO - PID 21536 | 09:22:40 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48622/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:41 INFO - PID 21536 | RSS: Main: 173940736 09:22:41 INFO - PID 21536 | 09:22:44 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48622/tests/kraken/driver.html?testName=json-parse-financial) 09:22:44 INFO - PID 21536 | RSS: Main: 174678016 09:22:44 INFO - PID 21536 | 09:22:46 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48622/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:47 INFO - PID 21536 | RSS: Main: 175087616 09:22:47 INFO - PID 21536 | 09:22:48 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48622/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:49 INFO - PID 21536 | RSS: Main: 175321088 09:22:49 INFO - PID 21536 | 09:22:51 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48622/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:52 INFO - PID 21536 | RSS: Main: 174522368 09:22:52 INFO - PID 21536 | 09:22:55 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48622/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:55 INFO - PID 21536 | RSS: Main: 174784512 09:22:55 INFO - PID 21536 | 09:22:59 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48622/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:59 INFO - PID 21536 | RSS: Main: 174669824 09:22:59 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | Cycle 1(1): loaded http://localhost:48622/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48622/tests/kraken/driver.html?testName=ai-astar) 09:23:01 INFO - PID 21536 | RSS: Main: 176463872 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | __start_tp_report 09:23:01 INFO - PID 21536 | _x_x_mozilla_page_load 09:23:01 INFO - PID 21536 | _x_x_mozilla_page_load_details 09:23:01 INFO - PID 21536 | |i|pagename|runs| 09:23:01 INFO - PID 21536 | |0;ai-astar;99;110;107;108;111;112;112;98;111;108 09:23:01 INFO - PID 21536 | |1;audio-beat-detection;153;121;148;172;127;147;155;119;178;119 09:23:01 INFO - PID 21536 | |2;audio-dft;169;168;161;166;163;163;169;165;164;164 09:23:01 INFO - PID 21536 | |3;audio-fft;76;75;75;177;75;75;75;79;164;75 09:23:01 INFO - PID 21536 | |4;audio-oscillator;81;80;129;79;83;81;142;78;81;130 09:23:01 INFO - PID 21536 | |5;imaging-gaussian-blur;115;114;114;113;114;116;114;123;114;126 09:23:01 INFO - PID 21536 | |6;imaging-darkroom;151;153;152;151;152;159;150;151;151;150 09:23:01 INFO - PID 21536 | |7;imaging-desaturate;94;99;95;98;99;94;94;102;94;95 09:23:01 INFO - PID 21536 | |8;json-parse-financial;54;53;54;53;56;54;59;57;53;53 09:23:01 INFO - PID 21536 | |9;json-stringify-tinderbox;61;53;54;54;53;53;57;54;54;53 09:23:01 INFO - PID 21536 | |10;stanford-crypto-aes;89;80;79;97;76;76;89;95;76;78 09:23:01 INFO - PID 21536 | |11;stanford-crypto-ccm;132;138;128;128;123;128;127;123;130;169 09:23:01 INFO - PID 21536 | |12;stanford-crypto-pbkdf2;177;176;186;178;184;185;175;198;179;178 09:23:01 INFO - PID 21536 | |13;stanford-crypto-sha256-iterative;68;65;66;67;68;66;65;65;69;68 09:23:01 INFO - PID 21536 | __end_tp_report 09:23:01 INFO - PID 21536 | __start_cc_report 09:23:01 INFO - PID 21536 | _x_x_mozilla_cycle_collect,991 09:23:01 INFO - PID 21536 | __end_cc_report 09:23:01 INFO - PID 21536 | __startTimestamp1502382181757__endTimestamp 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | ------- Summary: start ------- 09:23:01 INFO - PID 21536 | Number of tests: 14 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#0] ai-astar Cycles:10 Average:107.60 Median:111.50 stddev:5.10 (4.6%) stddev-sans-first:4.36 09:23:01 INFO - PID 21536 | Values: 99.0 110.0 107.0 108.0 111.0 112.0 112.0 98.0 111.0 108.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#1] audio-beat-detection Cycles:10 Average:143.90 Median:150.50 stddev:21.69 (14.4%) stddev-sans-first:22.76 09:23:01 INFO - PID 21536 | Values: 153.0 121.0 148.0 172.0 127.0 147.0 155.0 119.0 178.0 119.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#2] audio-dft Cycles:10 Average:165.20 Median:165.50 stddev:2.74 (1.7%) stddev-sans-first:2.54 09:23:01 INFO - PID 21536 | Values: 169.0 168.0 161.0 166.0 163.0 163.0 169.0 165.0 164.0 164.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#3] audio-fft Cycles:10 Average:94.60 Median:75.00 stddev:40.14 (53.5%) stddev-sans-first:42.01 09:23:01 INFO - PID 21536 | Values: 76.0 75.0 75.0 177.0 75.0 75.0 75.0 79.0 164.0 75.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:80.50 stddev:25.98 (32.3%) stddev-sans-first:26.95 09:23:01 INFO - PID 21536 | Values: 81.0 80.0 129.0 79.0 83.0 81.0 142.0 78.0 81.0 130.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:114.50 stddev:4.45 (3.9%) stddev-sans-first:4.69 09:23:01 INFO - PID 21536 | Values: 115.0 114.0 114.0 113.0 114.0 116.0 114.0 123.0 114.0 126.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#6] imaging-darkroom Cycles:10 Average:152.00 Median:151.50 stddev:2.62 (1.7%) stddev-sans-first:2.76 09:23:01 INFO - PID 21536 | Values: 151.0 153.0 152.0 151.0 152.0 159.0 150.0 151.0 151.0 150.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:95.00 stddev:2.88 (3.0%) stddev-sans-first:2.92 09:23:01 INFO - PID 21536 | Values: 94.0 99.0 95.0 98.0 99.0 94.0 94.0 102.0 94.0 95.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:54.00 stddev:2.07 (3.8%) stddev-sans-first:2.18 09:23:01 INFO - PID 21536 | Values: 54.0 53.0 54.0 53.0 56.0 54.0 59.0 57.0 53.0 53.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#9] json-stringify-tinderbox Cycles:10 Average:54.60 Median:54.00 stddev:2.55 (4.7%) stddev-sans-first:1.27 09:23:01 INFO - PID 21536 | Values: 61.0 53.0 54.0 54.0 53.0 53.0 57.0 54.0 54.0 53.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.50 stddev:8.21 (9.7%) stddev-sans-first:8.46 09:23:01 INFO - PID 21536 | Values: 89.0 80.0 79.0 97.0 76.0 76.0 89.0 95.0 76.0 78.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:129.00 stddev:13.50 (10.5%) stddev-sans-first:14.32 09:23:01 INFO - PID 21536 | Values: 132.0 138.0 128.0 128.0 123.0 128.0 127.0 123.0 130.0 169.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.60 Median:181.50 stddev:6.95 (3.8%) stddev-sans-first:7.17 09:23:01 INFO - PID 21536 | Values: 177.0 176.0 186.0 178.0 184.0 185.0 175.0 198.0 179.0 178.0 09:23:01 INFO - PID 21536 | 09:23:01 INFO - PID 21536 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.50 stddev:1.49 (2.2%) stddev-sans-first:1.51 09:23:01 INFO - PID 21536 | Values: 68.0 65.0 66.0 67.0 68.0 66.0 65.0 65.0 69.0 68.0 09:23:01 INFO - PID 21536 | -------- Summary: end -------- 09:23:01 INFO - PID 21536 | 09:23:02 INFO - PID 21536 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:23:02 INFO - TEST-INFO | 21536: exit 0 09:23:02 INFO - TEST-OK | kraken | took 62674ms 09:23:02 INFO - SUITE-END | took 1175s 09:23:02 INFO - Completed test suite (00:19:35) 09:23:02 INFO - javascript benchmark 09:23:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8639.381627355266, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3102.100390886643, 3166.6991635794598, 3178.809271443712, 3192.8084314941098, 3210.6789321067895, 6200.937990620092, 6223.37766223378, 6303.590266632666, 6316.115743795866, 6359.014352775315, 6171.259448866131, 6291.559590828644, 6307.621542707429, 6330.8100756977365, 6347.936520634792, 5743.224664670258, 5764.682942438164, 5767.0, 5776.999999999989, 5782.710864456782, 5776.39347868474, 5779.162021506879, 5875.1187321901625, 5904.114382842565, 5913.113033045056, 5755.884882302352, 5816.563757718167, 5830.591858569881, 5861.589688721791, 5878.323992740826, 981.8512789262321, 5718.714064296769, 5767.048437007888, 5841.678707671098, 5852.853678658025, 5553.222548843165, 5701.543876489871, 5714.45712657301, 5752.705641230731, 5758.683272420015, 6137.846553836145, 6149.046897730859, 6171.074338849208, 6259.0, 6308.842278943063, 6710.563813352165, 6722.159730033746, 6741.831454213635, 6810.353016463428, 6839.794806155824, 6771.762988295435, 6776.186857577122, 6780.288069752654, 6874.243833178396, 6878.828029299258, 6200.782972595937, 6323.620582765003, 6351.174347334817, 6391.137196478462, 6412.551121421502, 6937.271586483346, 7003.964980175168, 7027.8594428111155, 7043.92956070443, 7048.612326322102, 6701.698423570951, 6729.966350168218, 6733.427658648971, 6750.616123694631, 6756.324367563205, 6761.121054262914, 6766.966165169143, 6829.726810927507, 6862.142232220973, 6868.381845633917, 6704.899426508607, 6735.831604209934, 6760.18877734675, 6829.248782633907, 6838.316168383122, 6658.7336506539195, 6754.493412994045, 6756.391924726799, 6757.628330441873, 6759.391654751096, 5440.0, 5461.6996065216, 5501.889962200734, 5513.393526712139, 5521.834344969658, 4715.7877895494785, 4721.244600863845, 4758.976205118952, 4772.7136371817805, 4781.2111001685025, 5821.363645444197, 5849.824505264849, 5913.349531551526, 5934.643921364732, 5946.613470124428, 6802.047713320139, 6805.591664500047, 6854.588724676535, 6864.176298844171, 6893.862122757517, 5716.542676585863, 5747.827565173052, 5806.970965145232, 5879.764809407576, 5917.940820591825, 6031.24609423822, 6039.275286965592, 6050.0, 6079.665618391031, 6098.115773212948, 6546.869062618721, 6581.40767330933, 6636.601803891782, 6646.20245570525, 6657.434118100015, 6786.117804685359, 6815.829604259933, 6909.136357955255, 6911.550749201286, 6915.273896240923, 6439.967800160969, 6549.508786840911, 6556.213254409501, 6557.96721016411, 6559.376859198369, 6661.16735408074, 6675.0654908313845, 6753.054572359775, 6761.966190169018, 6769.458443324621, 6764.425023873026, 6881.41507971828, 6888.758893438706, 6896.655167241718, 6911.101556797584, 6671.33286671329, 6687.799366019027, 6692.498062645223, 6693.665316734046, 6693.933060669331, 6577.967110164419, 6589.868202636017, 6597.241317248578, 6673.532852700265, 6695.564788288746, 6431.614103153825, 6529.314421985623, 6545.70544325516, 6562.34376562359, 6572.211334639874, 6643.236027856665, 6648.501362397743, 6708.6310252936555, 6718.294579069127, 6725.798226053226, 6007.128966299774, 6438.3883531064475, 6486.513511486752, 6537.934620653733, 6538.607683539003], "value": 6159.563131425036, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.3425752466592, 805.0621026504123, 834.261678414603, 850.9191626795456, 863.4732812984079, 39313.410298845505, 39388.212235755265, 39788.60211397885, 40049.3992590111, 40124.79937600312, 35181.120471988215, 35204.295914081704, 35477.0, 35575.93272201832, 35576.82211588939, 6671.4662826973845, 6733.192016957947, 6792.626405547693, 6794.286599907013, 6802.897956530656, 6667.666616669171, 6762.22234443038, 6763.086983257246, 6773.729050837986, 6784.050232967388, 7554.924450755477, 7709.074911010686, 7712.961435192816, 7716.151223365453, 7719.884201736951, 7605.353544948686, 7658.808529786744, 7662.0, 7667.501612395176, 7670.961645191766, 7486.9251307486775, 7556.735514256975, 7620.847583048363, 7669.38644908406, 7672.386209103258, 37835.0, 37979.240415191816, 38196.0, 38201.235975280615, 38299.80850095732, 3515.2090779574646, 3517.43721004638, 3535.5934067582302, 3545.6099829018785, 3552.236269202134, 30557.08328750141, 30674.84662576695, 30709.078727638207, 30819.229519261975, 30839.53740693869, 5005.774740136702, 5022.598192144656, 5058.671186372874, 5061.468545802712, 5072.492750724935, 231204.0, 235902.0, 238851.0, 239117.0, 239314.0, 48686.26970595444, 48930.0, 49083.26375104376, 49195.50804491981, 49276.75361623169, 50407.24389134166, 50630.0, 50815.23777143346, 50911.23633145506, 50943.745281274096, 16499.505014849332, 16823.07473088992, 16876.746848797276, 16876.83123168777, 16886.15569221534, 13831.239281839395, 13837.86162138386, 13847.376868040761, 13848.930755346159, 13858.861411385958, 14128.293585320693, 14369.497067602793, 14401.495947641992, 14445.49440769568, 14652.340644671016, 5681.636407262227, 5724.484796368263, 5745.9425405745405, 5817.0983497557945, 5857.648541087548, 987.3138168972495, 1000.8148492528906, 1013.7769690668041, 1013.8783345998528, 1022.3967858963248, 22269.888650556644, 22293.66559501609, 22500.099996000303, 22522.436939076655, 22679.0, 1095.59462998691, 1110.1119104716195, 1125.887411258884, 1128.542940109257, 1136.9545218191345, 11896.762064758655, 12029.879701203052, 12036.63890083299, 12037.157398981988, 12055.638330850088, 254686.0, 255614.0, 256184.0, 256508.0, 257274.0, 51358.74320628373, 51757.74121129446, 51993.7400312996, 56229.43770562324, 56294.437055629736, 1429.2496439369415, 1504.0975414751063, 1508.3137172586446, 1508.6756347385365, 1524.1083965879864, 26707.0, 26980.865095674395, 27349.863250684415, 27684.44631107326, 27685.723142768315, 36394.63605363912, 36870.0, 36877.63122368742, 36889.631103689695, 36898.0, 215914.0, 216904.0, 217481.0, 218271.0, 218299.0, 34279.65720342765, 34459.4831077529, 34502.0, 34610.48084278788, 34784.65215347814, 39843.60156398399, 40802.38796418116, 41306.0, 41408.37887431631, 41415.58584414118, 30973.225669357544, 31118.22204444907, 31363.215919602193, 31504.842475787475, 31618.525722114646, 31163.37673246477, 33578.3284334307, 34688.132796680286, 34979.65020349764, 35059.824700876336, 37515.249695006496, 37897.0, 37899.43150852684, 37988.43017354687, 38074.428883566216, 32643.510347344334, 32853.0, 32893.50659740154, 33020.1744956378, 33144.33711325808, 24175.274741757774, 24277.51444971126, 24305.51388972246, 24539.631905521073, 24910.0], "value": 17269.979777850316, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.2516603249743, 574.8131857146426, 575.8013485347559, 583.2592607388615, 597.871457636608, 21066.473338166554, 21608.3517494475, 22615.0, 22709.0, 22784.43038924028, 26843.597346039824, 27228.0, 27480.450390992217, 27520.311992200153, 27638.447231055416, 6987.336203060714, 7149.249328820477, 7187.568745875238, 7193.136823581178, 7198.496105272633, 6650.334966503335, 6955.200151982511, 7124.394426473756, 7154.320339567733, 7203.171635261933, 7006.299370062979, 7016.0, 7036.753713620024, 7037.120359955005, 7037.788866334018, 7014.894776578355, 7020.859582808341, 7023.473239507058, 7035.085438892962, 7036.261192574757, 6876.312368763134, 6877.724891004354, 6898.862022759567, 6901.6204108774, 6913.10129683143, 31258.21854453634, 31681.84159079213, 32439.83780081108, 32445.188870278194, 32755.18112047194, 1937.7480927479337, 1978.1592823050146, 1980.0990549300177, 1984.5435549823478, 1992.2927360787003, 20399.388018359474, 21138.0, 21245.256416025364, 21310.680339794915, 21375.251866184764, 2447.115720894919, 2516.584763514018, 2562.923112306634, 2575.3947822261753, 2580.806439517025, 135811.32094339564, 136566.0, 138561.3071934644, 139447.99999999898, 139876.30061849728, 19420.25217730411, 19998.90000550017, 21430.0, 22353.44116397103, 22793.0, 43995.34006989898, 44013.779931100136, 44234.77882610566, 44327.0, 44370.0, 11758.70603234926, 12049.578264760692, 12067.57763478274, 12072.698182545331, 12260.0, 10079.949600251952, 10160.33957792756, 10194.184465242763, 10323.48382580868, 10361.844572331422, 7806.92193078062, 11149.832752508719, 11307.208495405243, 11435.828462573068, 11824.76350473003, 3678.7424880258127, 3731.850046935212, 3731.962680373216, 3741.970957986521, 4550.158220729176, 965.7537327981461, 976.3117002513211, 983.1937810995058, 995.2286977592279, 996.3274789517047, 19584.314548991002, 19874.205031798567, 19939.60120797576, 19954.90022549907, 20555.691664625043, 1000.9049140331657, 1072.619220176826, 1073.7906108308805, 1077.7467295185625, 1082.5453309609982, 10733.409662468639, 11192.328460292409, 11483.196176267747, 13299.800502992462, 14326.641833954027, 540877.0, 543939.0, 546024.3493912604, 553666.0, 561301.0, 117949.0, 118218.0, 119141.0, 119915.0, 126610.36694816466, 1263.728298415845, 1328.3291937571464, 1355.9932200338935, 1356.5862411964445, 1364.1814911053289, 21778.237761678265, 21841.563168736855, 22023.22918697838, 22094.779052209273, 22226.0, 55723.72138139283, 56003.71998140146, 58059.709701451226, 58891.0, 59047.40952590591, 66878.66560667165, 67284.3271567278, 67618.9857152153, 67797.66101169462, 67959.66020169867, 14922.25388730581, 15075.321610527333, 15159.696806064037, 15247.771283430979, 15319.846801531841, 23734.287971360885, 23973.160939367037, 24010.51978960446, 24053.278401647978, 24298.0, 18469.0, 19388.224471021, 19390.0, 19581.118849651513, 19637.901810490857, 17257.654846903242, 17671.734923975895, 17681.0, 18004.36984705529, 18058.097095145455, 19611.0, 19654.80345196587, 19907.303244386378, 20049.398518044483, 20176.29382971589, 14907.031042982606, 14932.402703891723, 14968.02707823988, 15158.166300853118, 15183.316750746022, 18684.439466816017, 18855.528611784815, 18882.0, 18959.715604266225, 19026.619467610846], "value": 13133.05272535735, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.4698120751698, 1350.4469859661388, 1406.7538180818353, 1408.9929550352247, 1413.9363728632213, 13738.931305343473, 14297.213653249093, 14319.570412887617, 14324.140551566914, 14329.856701432982, 14068.22624755638, 14177.43290268388, 14177.78733319001, 14237.359318830651, 14269.857301426982, 2176.8911554422293, 2636.644053052837, 2659.6675415573054, 2663.73362663734, 2694.312950197707, 2288.5422915416984, 2484.254723582927, 2561.692596888376, 2662.3211081174272, 2668.159529748123, 2662.068276103358, 2692.219256415637, 2692.4211294571664, 2694.8248363856383, 2696.7168447313043, 2512.4975004998983, 2560.705518865326, 2600.427905860717, 2611.869406529685, 2614.686237651475, 2526.507331070442, 2556.7187609362954, 2556.782673472757, 2566.5123626510904, 2571.845689258632, 13664.863351366557, 13717.314134293245, 13846.861531384759, 13872.583822485341, 13872.861271387257, 7241.855162896765, 7394.260573942616, 7502.849943001164, 7504.662290196954, 7514.135874374462, 4860.392450943632, 4974.054929563407, 5123.0, 5135.024345374367, 5146.279520867081, 1791.3192986665115, 1791.480470663506, 1801.2794882047156, 1826.7807863056385, 1827.1229809691422, 22572.209972651042, 23139.7686023141, 23142.88428557863, 23156.18953336625, 23172.88413557938, 6353.7140828661895, 6554.410103090745, 6578.144841170617, 6756.594604323756, 6758.189017317954, 18311.175997080165, 18447.262109515737, 18451.354202602844, 18472.630547388977, 18534.44396668102, 7406.629668516553, 7488.063992001, 7538.246175382418, 7543.170251272356, 7559.697612095454, 6741.629210393377, 6795.7961261162245, 6873.381395674413, 6993.055937448379, 6994.370506654425, 5383.192521121785, 5522.392536820947, 5551.278333816578, 5557.221988921554, 5628.521575666033, 1661.9916900415421, 1684.216839169792, 1690.8055573608947, 1716.3306310538899, 1726.66330065636, 840.8024114333127, 855.4183155454351, 867.8568036274071, 872.7687162901836, 883.3992884838372, 7942.880856787153, 8074.475159114639, 8096.7166149184495, 8178.1821817817745, 8197.508149511048, 723.2586598736337, 732.6007326007339, 735.0958321264767, 739.5562662402515, 747.8130467383154, 4551.658625603093, 4564.475085365159, 4578.1301552705545, 5089.720065396439, 5373.8714869876985, 16983.405580804614, 17720.91139544294, 18106.004169770527, 18115.909420452812, 18118.547036323933, 17298.394556190473, 17429.91285043592, 17712.46862594124, 17737.733933991, 18033.18850651724, 9993.750156246151, 10030.799384012278, 10113.898861011296, 10123.44321062334, 10124.088832005007, 7485.363744081814, 7522.360599348897, 7556.811079722831, 7597.126330472067, 7633.160352361125, 15034.548963531111, 15503.302351393988, 15725.44960926362, 15805.209739512748, 15824.208789560706, 15656.530304090895, 16087.67824643524, 16140.596485087966, 16161.0, 16224.837751622805, 10939.671809845717, 11055.613053543088, 11081.944590276997, 11082.390468524309, 11151.052160566443, 12177.269363838199, 12433.689157770767, 12635.747285054063, 12646.936765316115, 12690.619281421572, 10864.45677716127, 10992.560297588007, 11028.834567481334, 11230.438478076227, 11331.546738130712, 10621.89378106209, 10645.733856653645, 10770.138388929023, 10780.353178809297, 10897.0, 8864.911350886408, 8940.46357218569, 8968.461892286483, 8979.775505612151, 8988.235999940078, 6675.365840245169, 6809.353111454404, 6921.58470491752, 7123.893141602984, 7160.033395491542, 7211.67547460376, 7297.562146271241, 7321.341079302888, 7347.5224110432655, 7399.556026638204], "value": 6252.77835597258, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.863087650896, 501.61125128025776, 524.4519477146387, 527.0855066459693, 530.8368398774993, 6247.219097612799, 6276.529260305472, 6315.210598675166, 6324.0, 6326.2092238470195, 5959.553033522492, 6084.5132389408855, 6103.23709536308, 6154.323024467305, 6231.532635052378, 4908.558229759321, 4917.82787602434, 4936.629752768539, 4956.925646115311, 5030.773615187316, 4872.3909511311085, 4947.282644016615, 4959.462566604364, 4965.453800081988, 4974.875628109308, 4840.128776820171, 4856.660033797635, 4912.36139301892, 4918.237673160648, 4954.380702412199, 4891.192953162724, 4917.262410638397, 4959.156943319645, 4961.925571116436, 5060.2409638554145, 4442.2226110430865, 4442.577955094293, 4465.68740188187, 4472.284434490499, 4507.729536227836, 6167.691615419256, 6170.96914515429, 6171.25944886612, 6198.287196972361, 6199.3490683478485, 5781.913271300934, 5792.275965504312, 5796.855078623026, 5802.158686990363, 5806.303243610751, 6599.9670001650165, 6608.933910660928, 6650.467962562983, 6650.900236496408, 6652.234992975821, 4861.951380486185, 4891.144049791273, 4903.828366007173, 4917.262410638397, 4927.876803079916, 6770.289119642416, 6913.343232392915, 6926.298743489992, 6931.168259808805, 6932.168139823203, 5009.423916249678, 5025.120603894377, 5038.924416133761, 5107.591392688576, 5113.565346945551, 5042.773075211624, 5097.566706829887, 5107.846764597068, 5128.948710512922, 5161.66449180802, 5744.913826292609, 5751.309842818805, 5763.135529670583, 5768.596198266081, 5781.595288329777, 6574.088417391645, 6625.668716564152, 6672.165979252593, 6683.431908287753, 6721.462283017347, 1667.7915260592426, 1720.0883531728205, 1774.1927423022753, 1790.9910450447665, 1915.2339064374082, 1413.9787903181461, 1421.6588018875395, 1438.180237264749, 1478.3569147420906, 1484.5323723027211, 5682.659040457585, 5726.3700992890745, 5763.740631671585, 5772.237903729356, 5825.70871456434, 6485.5460117792245, 6633.204015518072, 6635.469162466991, 6637.23671777742, 6710.0, 5810.680412577264, 5812.6221795584, 5819.709014549256, 5824.563157763185, 5845.649261044351, 3556.6976806971697, 3600.613763700994, 3639.6906262967946, 3642.307961487309, 3644.835982380799, 6398.680065996681, 6419.74321027163, 6448.097266382711, 6494.805155845333, 6523.380278873499, 6849.760258390932, 6918.411934985482, 6919.688614012382, 6920.9307906920285, 6980.720771169096, 6447.806565803034, 6495.935040649533, 6534.215894092739, 6535.8692826143215, 6628.602283862983, 6656.966715166393, 6672.366125218096, 6769.153855768029, 6794.218664853407, 6856.6228857413325, 6694.163229596301, 6697.866042679022, 6703.396694297537, 6735.73057077731, 6782.796516104525, 6451.903221451588, 6549.7380104795275, 6558.409743123142, 6582.407583317525, 6591.791749763883, 5074.112030394593, 5083.949160508496, 5084.161113416245, 5124.256982737407, 5126.7180305083575, 6770.6614669265355, 6772.390484856386, 6772.627505487246, 6781.423578995643, 6837.794866154023, 6235.8174638876035, 6376.458001069961, 6509.558915406723, 6510.641914694737, 6525.934740652533, 6437.645929473924, 6462.676866156579, 6505.447037001908, 6562.868742625216, 6573.638449885111, 6271.874562508816, 6357.491400688026, 6364.840878978062, 6436.967815160894, 6459.870802584016, 6409.262934762562, 6474.384933431316, 6512.697460508012, 6575.967120164369, 6634.701438435379, 6274.278457977209, 6383.170187875547, 6439.67801609927, 6448.838779030561, 6452.806415807533], "value": 5096.169047400367, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [905.9954700226498, 923.233716015707, 926.754410081329, 937.4141161773891, 940.9106134917181, 16993.745093823603, 17563.912180439096, 17575.82424175758, 17576.29694812206, 20489.795102048975, 20783.792162078375, 21937.561248775015, 21968.0, 22054.33836984889, 22078.77921220787, 1017.2116609627524, 1057.5399701129995, 1060.6499855047855, 1062.2139616683676, 1062.5909025025383, 1028.804527139841, 1065.664315740543, 1066.1950227578168, 1069.4973362519604, 1079.4386918802256, 1124.3085502415995, 1125.2629527659396, 1125.7467069909246, 1127.5433449452985, 1128.6670432222472, 1115.0856297835778, 1115.1246271676735, 1115.3753897817166, 1117.5697356517717, 1119.764849381631, 819.6598411659154, 819.8401311744212, 819.8565251081096, 822.7079386817653, 822.8560001999626, 18221.18004689792, 18272.086395680297, 18416.63166736658, 18980.810191898043, 20479.38561843147, 21606.351809445743, 21750.782492175033, 22062.448438788997, 22275.331740047826, 22286.0, 16778.328866845313, 16918.323267069303, 17095.230714617872, 17131.22909469077, 17209.22558484871, 3570.4644303354444, 3577.5528058992627, 3578.141246100943, 3579.928401431957, 3580.3197392495345, 122971.0, 123070.38464807619, 124840.37579812134, 125971.0, 126007.0, 17036.744448833277, 17258.0, 17259.482215533553, 17512.912435437996, 17534.29862805499, 31520.211994700316, 31682.207944801565, 31686.0, 31823.204419889687, 31859.0, 8426.199511046441, 8526.360522960678, 8530.146985301544, 8541.914580854113, 8552.786180345542, 4106.609872062149, 4170.145120250341, 4205.789710514523, 4211.284081706117, 4236.279832428495, 5140.974295128575, 5279.52484276417, 5303.4696530346655, 5307.6019298552765, 5310.601704872072, 933.2580598424269, 936.2369668719972, 955.2644463762864, 959.1367769007809, 965.4110992294694, 681.4786688183543, 689.1420181873599, 689.762032098925, 694.7707256605308, 700.9088818453671, 11124.721881952853, 11920.10599205063, 11942.940285298519, 12582.24506529611, 13288.136271142344, 690.4993879437368, 691.1015679616479, 696.4463251714798, 698.2319448606594, 702.0311969482082, 6049.5462840287155, 6177.722002509898, 6273.96863015682, 6611.86776264472, 6641.351054262036, 102720.0, 104473.0, 105630.0, 109844.0, 109957.45021275003, 95448.0, 96313.51843240739, 98744.0, 100898.49550752199, 101506.0, 81104.18895810966, 83657.0, 84051.15948840433, 84715.0, 84942.02702905369, 12672.303023333805, 12809.807852882028, 12837.486500540248, 13152.539661112198, 13251.13867598603, 37959.43060854144, 38863.41704874373, 38897.61102389053, 39101.217975640895, 40207.798961005006, 68782.0, 71243.64378178076, 71699.0, 73693.26306736864, 73846.0, 18927.810721893155, 19369.225230990603, 20294.188232470537, 20332.69500957457, 20349.0, 18836.52908677294, 19326.4202073938, 19408.320708775125, 19431.805681943, 19710.50723731861, 19397.0, 19970.30103946355, 20006.199752009758, 20032.399028029184, 20183.39449816508, 19426.0, 19469.415917522496, 19616.901915490333, 19681.901590491954, 19935.0, 16746.49760507187, 17065.40271090506, 17226.827731722522, 17401.56496087608, 19294.22823087061, 14131.0, 14148.22184779847, 14148.292585370484, 14387.352569134206, 14456.85543144555, 14033.649158771112, 14062.085964412281, 14629.70740585161, 15236.771448428504, 15654.765178522104], "value": 9339.924952514402, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.9999999999995, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 110.0, 107.0, 108.0, 111.0, 112.0, 112.0, 98.0, 111.0, 108.0], "value": 107.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 121.0, 148.0, 172.0, 127.0, 147.0, 155.0, 119.0, 178.0, 119.0], "value": 143.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 161.0, 166.0, 163.0, 163.0, 169.0, 165.0, 164.0, 164.0], "value": 165.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 177.0, 75.0, 75.0, 75.0, 79.0, 164.0, 75.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 129.0, 79.0, 83.0, 81.0, 142.0, 78.0, 81.0, 130.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 113.0, 114.0, 116.0, 114.0, 123.0, 114.0, 126.0], "value": 116.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 152.0, 151.0, 152.0, 159.0, 150.0, 151.0, 151.0, 150.0], "value": 152.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 99.0, 95.0, 98.0, 99.0, 94.0, 94.0, 102.0, 94.0, 95.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 53.0, 56.0, 54.0, 59.0, 57.0, 53.0, 53.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 53.0, 54.0, 54.0, 53.0, 53.0, 57.0, 54.0, 54.0, 53.0], "value": 54.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 80.0, 79.0, 97.0, 76.0, 76.0, 89.0, 95.0, 76.0, 78.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 138.0, 128.0, 128.0, 123.0, 128.0, 127.0, 123.0, 130.0, 169.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 176.0, 186.0, 178.0, 184.0, 185.0, 175.0, 198.0, 179.0, 178.0], "value": 181.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 66.0, 67.0, 68.0, 66.0, 65.0, 65.0, 69.0, 68.0], "value": 66.7, "unit": "score"}]}]} 09:23:02 INFO - Return code: 0 09:23:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:23:03 INFO - # TBPL SUCCESS # 09:23:03 INFO - Running post-action listener: _package_coverage_data 09:23:03 INFO - Running post-action listener: _resource_record_post_action 09:23:03 INFO - [mozharness: 2017-08-10 16:23:03.021078Z] Finished run-tests step (success) 09:23:03 INFO - Running post-run listener: _resource_record_post_run 09:23:03 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 12288; Write bytes: 437747712; Read time: 216; Write time: 465264 09:23:03 INFO - TinderboxPrint: CPU usage
13.2% 09:23:03 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 216 09:23:03 INFO - TinderboxPrint: I/O write bytes / time
437,747,712 / 465,264 09:23:03 INFO - TinderboxPrint: CPU idle
8,260.7 (86.8%) 09:23:03 INFO - TinderboxPrint: CPU user
1,206.1 (12.7%) 09:23:03 INFO - TinderboxPrint: Swap in / out
0 / 0 09:23:03 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 112406528; Read time: 0; Write time: 148356 09:23:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:23:03 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 8192; Write bytes: 325341184; Read time: 200; Write time: 316908 09:23:03 INFO - Running post-run listener: _upload_blobber_files 09:23:03 INFO - Blob upload gear active. 09:23:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:23:03 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:23:03 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:23:03 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:23:04 INFO - (blobuploader) - INFO - Open directory for files ... 09:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:23:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:23:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:05 INFO - (blobuploader) - INFO - Done attempting. 09:23:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:23:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:23:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:05 INFO - (blobuploader) - INFO - Done attempting. 09:23:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:09 INFO - (blobuploader) - INFO - Done attempting. 09:23:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:23:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:23:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:09 INFO - (blobuploader) - INFO - Done attempting. 09:23:09 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:09 INFO - Return code: 0 09:23:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:09 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfafa1eb50fc70d8dac40ec2b365db5f1d7ea211c6995fff28fd7213adc1e3853bea9a5b47f1458f74e23eaffc0f86bd767bbdd83178bf3345c13fbb8b40d8eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88dc29c79d7395493a968bae5120a2cf5a9c253cc7c8cd74b5141a733d32b193dad63069e65a96571110c7d783fa55ddfd700c88f6827a44e9ab4d3ff3728ab2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/313ae445ee56de062691a2f97443a3bfd79d0a926cf9d2d35a98cf1ebe73fa648b30c925c86a6c64c55d386237d307c5ae2bc4fc35393034de62e170a36dd520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32cda9a65b55e37c2a7dd8b82f526a76c5241ab3530b7cd076abb1d0aee9abc3b40ee0a641ca3db226f96277665a1364a1a12f56bce7b09d5e6fe12099c559f7"} 09:23:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:09 INFO - Contents: 09:23:09 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfafa1eb50fc70d8dac40ec2b365db5f1d7ea211c6995fff28fd7213adc1e3853bea9a5b47f1458f74e23eaffc0f86bd767bbdd83178bf3345c13fbb8b40d8eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88dc29c79d7395493a968bae5120a2cf5a9c253cc7c8cd74b5141a733d32b193dad63069e65a96571110c7d783fa55ddfd700c88f6827a44e9ab4d3ff3728ab2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/313ae445ee56de062691a2f97443a3bfd79d0a926cf9d2d35a98cf1ebe73fa648b30c925c86a6c64c55d386237d307c5ae2bc4fc35393034de62e170a36dd520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32cda9a65b55e37c2a7dd8b82f526a76c5241ab3530b7cd076abb1d0aee9abc3b40ee0a641ca3db226f96277665a1364a1a12f56bce7b09d5e6fe12099c559f7"} 09:23:09 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:09 INFO - Copying logs to upload dir... 09:23:09 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.368637 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-10 09:23:07.799442) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-10 09:23:07.804450) ========= 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=826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfafa1eb50fc70d8dac40ec2b365db5f1d7ea211c6995fff28fd7213adc1e3853bea9a5b47f1458f74e23eaffc0f86bd767bbdd83178bf3345c13fbb8b40d8eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88dc29c79d7395493a968bae5120a2cf5a9c253cc7c8cd74b5141a733d32b193dad63069e65a96571110c7d783fa55ddfd700c88f6827a44e9ab4d3ff3728ab2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/313ae445ee56de062691a2f97443a3bfd79d0a926cf9d2d35a98cf1ebe73fa648b30c925c86a6c64c55d386237d307c5ae2bc4fc35393034de62e170a36dd520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32cda9a65b55e37c2a7dd8b82f526a76c5241ab3530b7cd076abb1d0aee9abc3b40ee0a641ca3db226f96277665a1364a1a12f56bce7b09d5e6fe12099c559f7"} build_url:https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011219 build_url: 'https://queue.taskcluster.net/v1/task/LIC2wUu3Sb-3_r_z6QiqiA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfafa1eb50fc70d8dac40ec2b365db5f1d7ea211c6995fff28fd7213adc1e3853bea9a5b47f1458f74e23eaffc0f86bd767bbdd83178bf3345c13fbb8b40d8eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88dc29c79d7395493a968bae5120a2cf5a9c253cc7c8cd74b5141a733d32b193dad63069e65a96571110c7d783fa55ddfd700c88f6827a44e9ab4d3ff3728ab2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/313ae445ee56de062691a2f97443a3bfd79d0a926cf9d2d35a98cf1ebe73fa648b30c925c86a6c64c55d386237d307c5ae2bc4fc35393034de62e170a36dd520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32cda9a65b55e37c2a7dd8b82f526a76c5241ab3530b7cd076abb1d0aee9abc3b40ee0a641ca3db226f96277665a1364a1a12f56bce7b09d5e6fe12099c559f7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-10 09:23:07.831843) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 09:23:07.832180) ========= 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=826874ccc97bd7bf541b4875000001d5-1502380726.402704-1260568416 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 09:23:07.912948) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-10 09:23:07.914298) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-10 09:23:07.914623) ========= ========= Total master_lag: 0.24 =========