builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-077 starttime: 1502579943.19 results: success (0) revision: 30e6b0fd69ff4b3599eb6744ba12ccb75ea28598 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:03.188454) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:03.188879) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:03.189153) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:03.274379) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:03.274665) ========= 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=20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-12 16:19:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 107M=0s 2017-08-12 16:19:03 (107 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.164894 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:03.453231) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:03.456559) ========= 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=20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018041 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:03.507308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-08-12 16:19:03.509687) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 30e6b0fd69ff4b3599eb6744ba12ccb75ea28598 --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 30e6b0fd69ff4b3599eb6744ba12ccb75ea28598 --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=20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-12 16:19:04,073 truncating revision to first 12 chars 2017-08-12 16:19:04,073 Setting DEBUG logging. 2017-08-12 16:19:04,074 attempt 1/10 2017-08-12 16:19:04,074 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/30e6b0fd69ff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-12 16:19:04,861 attempt 1/10 2017-08-12 16:19:05,651 current task status: no status available at this point. state: STARTED 2017-08-12 16:19:05,652 sleeping for 9.00s (attempt 1/10) 2017-08-12 16:19:14,661 attempt 2/10 2017-08-12 16:19:15,079 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-12 16:19:15,312 unpacking tar archive at: mozilla-central-30e6b0fd69ff/testing/mozharness/ program finished with exit code 0 elapsedTime=11.567130 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-08-12 16:19:15.096691) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:15.097001) ========= script_repo_revision: 30e6b0fd69ff4b3599eb6744ba12ccb75ea28598 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:15.097426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:15.097737) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 16:19:15.109846) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-12 16:19:15.110107) ========= /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=20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:19:15 INFO - MultiFileLogger online at 20170812 16:19:15 in /builds/slave/test 16:19:15 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 16:19:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:19:15 INFO - {'append_to_log': False, 16:19:15 INFO - 'base_work_dir': '/builds/slave/test', 16:19:15 INFO - 'blob_upload_branch': 'Firefox', 16:19:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:19:15 INFO - 'branch': 'Firefox', 16:19:15 INFO - 'buildbot_json_path': 'buildprops.json', 16:19:15 INFO - 'code_coverage': False, 16:19:15 INFO - 'config_files': ('talos/linux_config.py',), 16:19:15 INFO - 'default_actions': ('clobber', 16:19:15 INFO - 'read-buildbot-config', 16:19:15 INFO - 'download-and-extract', 16:19:15 INFO - 'populate-webroot', 16:19:15 INFO - 'create-virtualenv', 16:19:15 INFO - 'install', 16:19:15 INFO - 'setup-mitmproxy', 16:19:15 INFO - 'run-tests'), 16:19:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:19:15 INFO - 'disable_ccov_upload': False, 16:19:15 INFO - 'download_minidump_stackwalk': True, 16:19:15 INFO - 'download_symbols': 'ondemand', 16:19:15 INFO - 'enable_stylo': False, 16:19:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:19:15 INFO - 'tooltool.py': '/tools/tooltool.py', 16:19:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:19:15 INFO - '/tools/misc-python/virtualenv.py')}, 16:19:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:19:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:19:15 INFO - 'gecko_profile': False, 16:19:15 INFO - 'gecko_profile_interval': 0, 16:19:15 INFO - 'installer_path': 'installer.exe', 16:19:15 INFO - 'log_level': 'info', 16:19:15 INFO - 'log_name': 'talos', 16:19:15 INFO - 'log_to_console': True, 16:19:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:19:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:19:15 INFO - 'opt_config_files': (), 16:19:15 INFO - 'pip_index': False, 16:19:15 INFO - 'suite': 'dromaeojs-e10s', 16:19:15 INFO - 'system_bits': '32', 16:19:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:19:15 INFO - 'title': 'talos-linux64-ix-077', 16:19:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:19:15 INFO - 'use_talos_json': True, 16:19:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:19:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:19:15 INFO - 'work_dir': 'build'} 16:19:15 INFO - [mozharness: 2017-08-12 23:19:15.770715Z] Running clobber step. 16:19:15 INFO - Running pre-action listener: _resource_record_pre_action 16:19:15 INFO - Running main action method: clobber 16:19:15 INFO - rmtree: /builds/slave/test/build 16:19:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:19:16 INFO - Running post-action listener: _resource_record_post_action 16:19:16 INFO - [mozharness: 2017-08-12 23:19:16.787007Z] Finished clobber step (success) 16:19:16 INFO - [mozharness: 2017-08-12 23:19:16.787109Z] Running read-buildbot-config step. 16:19:16 INFO - Running pre-action listener: _resource_record_pre_action 16:19:16 INFO - Running main action method: read_buildbot_config 16:19:16 INFO - Using buildbot properties: 16:19:16 INFO - { 16:19:16 INFO - "project": "", 16:19:16 INFO - "product": "firefox", 16:19:16 INFO - "who": "jdolske@mozilla.com", 16:19:16 INFO - "installer_path": "public/build/target.tar.bz2", 16:19:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 16:19:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:19:16 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 16:19:16 INFO - "stage_platform": "linux64-stylo", 16:19:16 INFO - "basedir": "/builds/slave/test", 16:19:16 INFO - "buildnumber": 26, 16:19:16 INFO - "platform": "ubuntu64_hw_stylo", 16:19:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:19:16 INFO - "slavebuilddir": "test", 16:19:16 INFO - "taskId": "KHXG1bSeST6vTI8O2kMdPg", 16:19:16 INFO - "branch": "mozilla-central", 16:19:16 INFO - "script_repo_revision": "production", 16:19:16 INFO - "revision": "30e6b0fd69ff4b3599eb6744ba12ccb75ea28598", 16:19:16 INFO - "slavename": "talos-linux64-ix-077", 16:19:16 INFO - "repo_path": "mozilla-central" 16:19:16 INFO - } 16:19:16 INFO - Finding installer, test and symbols from parent task. 16:19:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KHXG1bSeST6vTI8O2kMdPg'}, attempt #1 16:19:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KHXG1bSeST6vTI8O2kMdPg'}, attempt #1 16:19:17 INFO - Task dependencies: eRGUWGbpQu2kTLRetgvcig 16:19:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig'}, attempt #1 16:19:18 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.tar.bz2 16:19:18 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.test_packages.json 16:19:18 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.crashreporter-symbols.zip 16:19:18 INFO - Running post-action listener: _resource_record_post_action 16:19:18 INFO - [mozharness: 2017-08-12 23:19:18.131424Z] Finished read-buildbot-config step (success) 16:19:18 INFO - [mozharness: 2017-08-12 23:19:18.131647Z] Running download-and-extract step. 16:19:18 INFO - Running pre-action listener: _resource_record_pre_action 16:19:18 INFO - Running main action method: download_and_extract 16:19:18 INFO - mkdir: /builds/slave/test/build/tests 16:19:18 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')]} 16:19:18 INFO - trying https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.test_packages.json 16:19:18 INFO - Downloading https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:19:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:19:18 INFO - Downloaded 989 bytes. 16:19:18 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:19:18 INFO - Using the following test package requirements: 16:19:18 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:19:18 INFO - u'common': [u'target.common.tests.zip'], 16:19:18 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:19:18 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:19:18 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:19:18 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:19:18 INFO - u'mozbase': [u'target.common.tests.zip'], 16:19:18 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:19:18 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:19:18 INFO - u'web-platform': [u'target.common.tests.zip', 16:19:18 INFO - u'target.web-platform.tests.tar.gz'], 16:19:18 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:19:18 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:19:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.common.tests.zip 16:19:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:19:18 INFO - Fetch https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.common.tests.zip into memory 16:19:20 INFO - Content-Length response header: 54034059 16:19:20 INFO - Bytes received: 54034059 16:19:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:19:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.common.tests.zip 16:19:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:19:31 INFO - Fetch https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.common.tests.zip into memory 16:19:32 INFO - Content-Length response header: 54034059 16:19:32 INFO - Bytes received: 54034059 16:19:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.talos.tests.zip 16:19:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:19:44 INFO - Fetch https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.talos.tests.zip into memory 16:19:45 INFO - Content-Length response header: 14496050 16:19:45 INFO - Bytes received: 14496050 16:19: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')]} 16:19:46 INFO - trying https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.tar.bz2 16:19:46 INFO - Downloading https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:19:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:19:51 INFO - Downloaded 61821479 bytes. 16:19:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.tar.bz2 16:19:51 INFO - mkdir: /builds/slave/test/properties 16:19:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:19:51 INFO - Writing to file /builds/slave/test/properties/build_url 16:19:51 INFO - Contents: 16:19:51 INFO - build_url:https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.tar.bz2 16:19:51 INFO - Running post-action listener: _resource_record_post_action 16:19:51 INFO - Running post-action listener: set_extra_try_arguments 16:19:51 INFO - [mozharness: 2017-08-12 23:19:51.316656Z] Finished download-and-extract step (success) 16:19:51 INFO - [mozharness: 2017-08-12 23:19:51.316888Z] Running populate-webroot step. 16:19:51 INFO - Running pre-action listener: _resource_record_pre_action 16:19:51 INFO - Running main action method: populate_webroot 16:19:51 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:19:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:19:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:19:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:19:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:19:51 INFO - 'tests': ['damp', 'tps']}, 16:19:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:19:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:19:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:19:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:19:51 INFO - 'other-e10s': {'tests': ['a11yr', 16:19:51 INFO - 'ts_paint', 16:19:51 INFO - 'tpaint', 16:19:51 INFO - 'sessionrestore', 16:19:51 INFO - 'sessionrestore_many_windows', 16:19:51 INFO - 'sessionrestore_no_auto_restore', 16:19:51 INFO - 'tabpaint']}, 16:19:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:19:51 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:19:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:19:51 INFO - 'tsvgr_opacity', 16:19:51 INFO - 'tart', 16:19:51 INFO - 'tscrollx', 16:19:51 INFO - 'tsvg_static']}, 16:19:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:19:51 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:19:51 INFO - 'talos_options': ['--mitmproxy', 16:19:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:19:51 INFO - '--firstNonBlankPaint'], 16:19:51 INFO - 'tests': ['tp6_google', 16:19:51 INFO - 'tp6_youtube', 16:19:51 INFO - 'tp6_amazon', 16:19:51 INFO - 'tp6_facebook']}, 16:19:51 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:19:51 INFO - 'talos_options': ['--stylo', 16:19:51 INFO - '--mitmproxy', 16:19:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:19:51 INFO - '--firstNonBlankPaint'], 16:19:51 INFO - 'tests': ['tp6_google', 16:19:51 INFO - 'tp6_youtube', 16:19:51 INFO - 'tp6_amazon', 16:19:51 INFO - 'tp6_facebook']}, 16:19:51 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:19:51 INFO - 'talos_options': ['--stylo', 16:19:51 INFO - '--stylo-threads=1', 16:19:51 INFO - '--mitmproxy', 16:19:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:19:51 INFO - '--firstNonBlankPaint'], 16:19:51 INFO - 'tests': ['tp6_google', 16:19:51 INFO - 'tp6_youtube', 16:19:51 INFO - 'tp6_amazon', 16:19:51 INFO - 'tp6_facebook']}, 16:19:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:19:51 INFO - 'talos_options': ['--xperf_path', 16:19:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:51 INFO - 'tests': ['tp5n']}}} 16:19:51 INFO - Running post-action listener: _resource_record_post_action 16:19:51 INFO - [mozharness: 2017-08-12 23:19:51.330764Z] Finished populate-webroot step (success) 16:19:51 INFO - [mozharness: 2017-08-12 23:19:51.330854Z] Running create-virtualenv step. 16:19:51 INFO - Running pre-action listener: _resource_record_pre_action 16:19:51 INFO - Running main action method: create_virtualenv 16:19:51 INFO - Creating virtualenv /builds/slave/test/build/venv 16:19:51 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 16:19:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:19:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:19:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:19:51 INFO - Using real prefix '/usr' 16:19:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:19:51 INFO - Installing distribute.............................................................................................................................................................................................done. 16:19:53 INFO - Installing pip.................done. 16:19:53 INFO - Return code: 0 16:19:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:19:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:19:54 INFO - Reading from file tmpfile_stdout 16:19:54 INFO - Output received: 16:19:54 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) 16:19:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:19:54 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')]} 16:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:54 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')]} 16:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:54 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 0x29971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d04b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d07f00>, '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 0x2d02ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d03050>, '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': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', '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 16:19:54 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 16:19:54 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 16:19:54 INFO - Using env: {'DISPLAY': ':0', 16:19:54 INFO - 'HOME': '/home/cltbld', 16:19:54 INFO - 'LANG': 'en_US.UTF-8', 16:19:54 INFO - 'LANGUAGE': 'en_US:en', 16:19:54 INFO - 'LOGNAME': 'cltbld', 16:19:54 INFO - 'MAIL': '/var/mail/cltbld', 16:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:54 INFO - 'MOZ_NO_REMOTE': '1', 16:19:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:54 INFO - 'NO_EM_RESTART': '1', 16:19:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:54 INFO - 'PWD': '/builds/slave/test', 16:19:54 INFO - 'SHELL': '/bin/bash', 16:19:54 INFO - 'SHLVL': '1', 16:19:54 INFO - 'TERM': 'linux', 16:19:54 INFO - 'TMOUT': '86400', 16:19:54 INFO - 'USER': 'cltbld', 16:19:54 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', 16:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:54 INFO - '_': '/tools/buildbot/bin/python'} 16:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:54 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 16:19:54 INFO - Cleaning up... 16:19:54 INFO - Return code: 0 16:19:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:19:54 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')]} 16:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:54 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')]} 16:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:54 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 0x29971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d04b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d07f00>, '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 0x2d02ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d03050>, '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': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', '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 16:19:54 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 16:19:54 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 16:19:54 INFO - Using env: {'DISPLAY': ':0', 16:19:54 INFO - 'HOME': '/home/cltbld', 16:19:54 INFO - 'LANG': 'en_US.UTF-8', 16:19:54 INFO - 'LANGUAGE': 'en_US:en', 16:19:54 INFO - 'LOGNAME': 'cltbld', 16:19:54 INFO - 'MAIL': '/var/mail/cltbld', 16:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:54 INFO - 'MOZ_NO_REMOTE': '1', 16:19:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:54 INFO - 'NO_EM_RESTART': '1', 16:19:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:54 INFO - 'PWD': '/builds/slave/test', 16:19:54 INFO - 'SHELL': '/bin/bash', 16:19:54 INFO - 'SHLVL': '1', 16:19:54 INFO - 'TERM': 'linux', 16:19:54 INFO - 'TMOUT': '86400', 16:19:54 INFO - 'USER': 'cltbld', 16:19:54 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', 16:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:54 INFO - '_': '/tools/buildbot/bin/python'} 16:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:54 INFO - Downloading/unpacking psutil>=3.1.1 16:19:54 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 16:19:54 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 16:19:54 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 16:19:54 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 16:19:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:19:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:55 INFO - Installing collected packages: psutil 16:19:55 INFO - Running setup.py install for psutil 16:19:55 INFO - building 'psutil._psutil_linux' extension 16:19:55 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 16:19:55 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 16:19:56 INFO - building 'psutil._psutil_posix' extension 16:19:56 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 16:19:56 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 16:19:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:56 INFO - Successfully installed psutil 16:19:56 INFO - Cleaning up... 16:19:56 INFO - Return code: 0 16:19:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19: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')]} 16:19:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d04b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d07f00>, '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 0x2d02ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d03050>, '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': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', '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 16:19: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:19: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 mozsystemmonitor==0.3 16:19:56 INFO - Using env: {'DISPLAY': ':0', 16:19:56 INFO - 'HOME': '/home/cltbld', 16:19:56 INFO - 'LANG': 'en_US.UTF-8', 16:19:56 INFO - 'LANGUAGE': 'en_US:en', 16:19:56 INFO - 'LOGNAME': 'cltbld', 16:19:56 INFO - 'MAIL': '/var/mail/cltbld', 16:19:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:56 INFO - 'MOZ_NO_REMOTE': '1', 16:19:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:56 INFO - 'NO_EM_RESTART': '1', 16:19:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:56 INFO - 'PWD': '/builds/slave/test', 16:19:56 INFO - 'SHELL': '/bin/bash', 16:19:56 INFO - 'SHLVL': '1', 16:19:56 INFO - 'TERM': 'linux', 16:19:56 INFO - 'TMOUT': '86400', 16:19:56 INFO - 'USER': 'cltbld', 16:19:56 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', 16:19:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:56 INFO - '_': '/tools/buildbot/bin/python'} 16:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:19: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 16:19: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 16:19: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 16:19: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 16:19:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:19:57 INFO - Installing collected packages: mozsystemmonitor 16:19:57 INFO - Running setup.py install for mozsystemmonitor 16:19:57 INFO - Successfully installed mozsystemmonitor 16:19:57 INFO - Cleaning up... 16:19:57 INFO - Return code: 0 16:19:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19: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')]} 16:19:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19: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', '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 0x29971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d04b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d07f00>, '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 0x2d02ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d03050>, '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': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', '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 16:19: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:19: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 jsonschema==2.5.1 16:19:57 INFO - Using env: {'DISPLAY': ':0', 16:19:57 INFO - 'HOME': '/home/cltbld', 16:19:57 INFO - 'LANG': 'en_US.UTF-8', 16:19:57 INFO - 'LANGUAGE': 'en_US:en', 16:19:57 INFO - 'LOGNAME': 'cltbld', 16:19:57 INFO - 'MAIL': '/var/mail/cltbld', 16:19:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:57 INFO - 'MOZ_NO_REMOTE': '1', 16:19:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:57 INFO - 'NO_EM_RESTART': '1', 16:19:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:57 INFO - 'PWD': '/builds/slave/test', 16:19:57 INFO - 'SHELL': '/bin/bash', 16:19:57 INFO - 'SHLVL': '1', 16:19:57 INFO - 'TERM': 'linux', 16:19:57 INFO - 'TMOUT': '86400', 16:19:57 INFO - 'USER': 'cltbld', 16:19:57 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', 16:19:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:57 INFO - '_': '/tools/buildbot/bin/python'} 16:19:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:58 INFO - Downloading/unpacking jsonschema==2.5.1 16:19: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 16:19: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 16:19: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 16:19: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 16:19:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:19:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:19:59 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 16:19:59 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 16:19:59 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 16:19:59 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 16:19:59 INFO - Downloading functools32-3.2.3-2.tar.gz 16:19:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:19:59 INFO - warning: no files found matching '*.txt' 16:19:59 INFO - no previously-included directories found matching 'build' 16:19:59 INFO - no previously-included directories found matching 'dist' 16:19:59 INFO - no previously-included directories found matching '.git*' 16:19:59 INFO - Installing collected packages: jsonschema, functools32 16:19:59 INFO - Running setup.py install for functools32 16:19:59 INFO - warning: no files found matching '*.txt' 16:19:59 INFO - no previously-included directories found matching 'build' 16:19:59 INFO - no previously-included directories found matching 'dist' 16:19:59 INFO - no previously-included directories found matching '.git*' 16:19:59 INFO - Successfully installed jsonschema functools32 16:19:59 INFO - Cleaning up... 16:19:59 INFO - Return code: 0 16:19:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19: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')]} 16:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:59 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 0x29971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d04b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d07f00>, '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 0x2d02ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d03050>, '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': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', '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 16:19:59 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 16:19:59 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 16:19:59 INFO - Using env: {'DISPLAY': ':0', 16:19:59 INFO - 'HOME': '/home/cltbld', 16:19:59 INFO - 'LANG': 'en_US.UTF-8', 16:19:59 INFO - 'LANGUAGE': 'en_US:en', 16:19:59 INFO - 'LOGNAME': 'cltbld', 16:19:59 INFO - 'MAIL': '/var/mail/cltbld', 16:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:59 INFO - 'MOZ_NO_REMOTE': '1', 16:19:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:59 INFO - 'NO_EM_RESTART': '1', 16:19:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:59 INFO - 'PWD': '/builds/slave/test', 16:19:59 INFO - 'SHELL': '/bin/bash', 16:19:59 INFO - 'SHLVL': '1', 16:19:59 INFO - 'TERM': 'linux', 16:19:59 INFO - 'TMOUT': '86400', 16:19:59 INFO - 'USER': 'cltbld', 16:19:59 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', 16:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:59 INFO - '_': '/tools/buildbot/bin/python'} 16:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:19:59 INFO - Cleaning up... 16:19:59 INFO - Return code: 0 16:19:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19: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')]} 16:19:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:59 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 0x29971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d04b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d07f00>, '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 0x2d02ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d03050>, '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': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', '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 16:19:59 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 16:19:59 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 16:19:59 INFO - Using env: {'DISPLAY': ':0', 16:19:59 INFO - 'HOME': '/home/cltbld', 16:19:59 INFO - 'LANG': 'en_US.UTF-8', 16:19:59 INFO - 'LANGUAGE': 'en_US:en', 16:19:59 INFO - 'LOGNAME': 'cltbld', 16:19:59 INFO - 'MAIL': '/var/mail/cltbld', 16:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:59 INFO - 'MOZ_NO_REMOTE': '1', 16:19:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:19:59 INFO - 'NO_EM_RESTART': '1', 16:19:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:59 INFO - 'PWD': '/builds/slave/test', 16:19:59 INFO - 'SHELL': '/bin/bash', 16:19:59 INFO - 'SHLVL': '1', 16:19:59 INFO - 'TERM': 'linux', 16:19:59 INFO - 'TMOUT': '86400', 16:19:59 INFO - 'USER': 'cltbld', 16:19:59 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', 16:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:59 INFO - '_': '/tools/buildbot/bin/python'} 16:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:59 INFO - Downloading/unpacking blobuploader==1.2.4 16:19:59 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 16:19:59 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 16:19:59 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 16:19:59 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 16:20:00 INFO - Downloading blobuploader-1.2.4.tar.gz 16:20:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:20:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:20:01 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 16:20:01 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 16:20:01 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 16:20:01 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 16:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:20:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:20:01 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 16:20:01 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 16:20:01 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 16:20:01 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 16:20:01 INFO - Downloading docopt-0.6.1.tar.gz 16:20:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:20:01 INFO - Installing collected packages: blobuploader, requests, docopt 16:20:01 INFO - Running setup.py install for blobuploader 16:20:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:20:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:20:01 INFO - Running setup.py install for requests 16:20:02 INFO - Running setup.py install for docopt 16:20:02 INFO - Successfully installed blobuploader requests docopt 16:20:02 INFO - Cleaning up... 16:20:02 INFO - Return code: 0 16:20:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:20: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')]} 16:20:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20: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')]} 16:20:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20:02 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 0x29971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d04b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d07f00>, '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 0x2d02ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d03050>, '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': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', '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 16:20:02 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 16:20:02 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 16:20:02 INFO - Using env: {'DISPLAY': ':0', 16:20:02 INFO - 'HOME': '/home/cltbld', 16:20:02 INFO - 'LANG': 'en_US.UTF-8', 16:20:02 INFO - 'LANGUAGE': 'en_US:en', 16:20:02 INFO - 'LOGNAME': 'cltbld', 16:20:02 INFO - 'MAIL': '/var/mail/cltbld', 16:20:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:02 INFO - 'MOZ_NO_REMOTE': '1', 16:20:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:02 INFO - 'NO_EM_RESTART': '1', 16:20:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:02 INFO - 'PWD': '/builds/slave/test', 16:20:02 INFO - 'SHELL': '/bin/bash', 16:20:02 INFO - 'SHLVL': '1', 16:20:02 INFO - 'TERM': 'linux', 16:20:02 INFO - 'TMOUT': '86400', 16:20:02 INFO - 'USER': 'cltbld', 16:20:02 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', 16:20:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:02 INFO - '_': '/tools/buildbot/bin/python'} 16:20:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:20:02 INFO - Running setup.py (path:/tmp/pip-nU6Fpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:20:02 INFO - Running setup.py (path:/tmp/pip-oXPR6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:20:02 INFO - Running setup.py (path:/tmp/pip-SjtDCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:20:02 INFO - Running setup.py (path:/tmp/pip-gW5cxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:20:02 INFO - Running setup.py (path:/tmp/pip-qlQTxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:20:02 INFO - Running setup.py (path:/tmp/pip-M0Dviw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:20:02 INFO - Running setup.py (path:/tmp/pip-Aw0Qqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:20:02 INFO - Running setup.py (path:/tmp/pip-QssR5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:20:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:20:02 INFO - Running setup.py (path:/tmp/pip-jQI6WR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:20:03 INFO - Running setup.py (path:/tmp/pip-vPfRGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:20:03 INFO - Running setup.py (path:/tmp/pip-LkOCFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:20:03 INFO - Running setup.py (path:/tmp/pip-PIeYSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:20:03 INFO - Running setup.py (path:/tmp/pip-tJ9MU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:20:03 INFO - Running setup.py (path:/tmp/pip-n2rL2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:20:03 INFO - Running setup.py (path:/tmp/pip-999Lf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:20:03 INFO - Running setup.py (path:/tmp/pip-GZJ7go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:20:03 INFO - Running setup.py (path:/tmp/pip-Nxo6Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:20:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:20:03 INFO - Running setup.py install for manifestparser 16:20:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:20:03 INFO - Running setup.py install for mozcrash 16:20:03 INFO - Running setup.py install for mozdebug 16:20:03 INFO - Running setup.py install for mozdevice 16:20:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Running setup.py install for mozfile 16:20:04 INFO - Running setup.py install for mozhttpd 16:20:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Running setup.py install for mozinfo 16:20:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Running setup.py install for mozInstall 16:20:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Running setup.py install for mozleak 16:20:04 INFO - Running setup.py install for mozlog 16:20:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Running setup.py install for moznetwork 16:20:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Running setup.py install for mozprocess 16:20:04 INFO - Running setup.py install for mozprofile 16:20:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:20:04 INFO - Running setup.py install for mozrunner 16:20:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:20:05 INFO - Running setup.py install for mozscreenshot 16:20:05 INFO - Running setup.py install for moztest 16:20:05 INFO - Running setup.py install for mozversion 16:20:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:20:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:20:05 INFO - Cleaning up... 16:20:05 INFO - Return code: 0 16:20:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:20: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')]} 16:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20: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')]} 16:20:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20:05 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 0x29971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d04b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d07f00>, '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 0x2d02ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d03050>, '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': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', '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 16:20:05 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 16:20:05 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 16:20:05 INFO - Using env: {'DISPLAY': ':0', 16:20:05 INFO - 'HOME': '/home/cltbld', 16:20:05 INFO - 'LANG': 'en_US.UTF-8', 16:20:05 INFO - 'LANGUAGE': 'en_US:en', 16:20:05 INFO - 'LOGNAME': 'cltbld', 16:20:05 INFO - 'MAIL': '/var/mail/cltbld', 16:20:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:05 INFO - 'MOZ_NO_REMOTE': '1', 16:20:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:05 INFO - 'NO_EM_RESTART': '1', 16:20:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:05 INFO - 'PWD': '/builds/slave/test', 16:20:05 INFO - 'SHELL': '/bin/bash', 16:20:05 INFO - 'SHLVL': '1', 16:20:05 INFO - 'TERM': 'linux', 16:20:05 INFO - 'TMOUT': '86400', 16:20:05 INFO - 'USER': 'cltbld', 16:20:05 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', 16:20:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:05 INFO - '_': '/tools/buildbot/bin/python'} 16:20:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:20:05 INFO - Running setup.py (path:/tmp/pip-S6GRsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:20:05 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)) 16:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:20:05 INFO - Running setup.py (path:/tmp/pip-6Ht_4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:20:05 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)) 16:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:20:05 INFO - Running setup.py (path:/tmp/pip-RMs2pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:20:05 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)) 16:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:20:05 INFO - Running setup.py (path:/tmp/pip-RNgLTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:20:05 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)) 16:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:20:05 INFO - Running setup.py (path:/tmp/pip-p8Th0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:20:05 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)) 16:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:20:05 INFO - Running setup.py (path:/tmp/pip-HpvEhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:20:06 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)) 16:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:20:06 INFO - Running setup.py (path:/tmp/pip-pIT9JM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:20:06 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)) 16:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:20:06 INFO - Running setup.py (path:/tmp/pip-P1LVMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:20:06 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)) 16:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:20:06 INFO - Running setup.py (path:/tmp/pip-Abs8kK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:20:06 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)) 16:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:20:06 INFO - Running setup.py (path:/tmp/pip-CEeRYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:20:06 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)) 16:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:20:06 INFO - Running setup.py (path:/tmp/pip-aPsLrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:20:06 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)) 16:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:20:06 INFO - Running setup.py (path:/tmp/pip-cYV1vX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:20:06 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)) 16:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:20:06 INFO - Running setup.py (path:/tmp/pip-rkIoAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:20:06 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)) 16:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:20:06 INFO - Running setup.py (path:/tmp/pip-ajKebe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:20:06 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)) 16:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:20:06 INFO - Running setup.py (path:/tmp/pip-DMl61L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:20:06 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)) 16:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:20:06 INFO - Running setup.py (path:/tmp/pip-DqNTe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:20:06 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)) 16:20:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:20:06 INFO - Running setup.py (path:/tmp/pip-IHRBrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:20:06 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)) 16:20:06 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)) 16:20:06 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)) 16:20:06 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)) 16:20:06 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)) 16:20:06 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)) 16:20:06 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)) 16:20:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:20: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 16:20: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 16:20: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 16:20: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 16:20:07 INFO - Downloading blessings-1.6.tar.gz 16:20:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:20:08 INFO - Installing collected packages: blessings 16:20:08 INFO - Running setup.py install for blessings 16:20:08 INFO - Successfully installed blessings 16:20:08 INFO - Cleaning up... 16:20:08 INFO - Return code: 0 16:20:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:20:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:20:08 INFO - Reading from file tmpfile_stdout 16:20:08 INFO - Current package versions: 16:20:08 INFO - argparse == 1.2.1 16:20:08 INFO - blessings == 1.6 16:20:08 INFO - blobuploader == 1.2.4 16:20:08 INFO - docopt == 0.6.1 16:20:08 INFO - functools32 == 3.2.3-2 16:20:08 INFO - jsonschema == 2.5.1 16:20:08 INFO - manifestparser == 1.1 16:20:08 INFO - mozInstall == 1.13 16:20:08 INFO - mozcrash == 1.0 16:20:08 INFO - mozdebug == 0.1 16:20:08 INFO - mozdevice == 0.51 16:20:08 INFO - mozfile == 1.2 16:20:08 INFO - mozhttpd == 0.7 16:20:08 INFO - mozinfo == 0.10 16:20:08 INFO - mozleak == 0.1 16:20:08 INFO - mozlog == 3.5 16:20:08 INFO - moznetwork == 0.27 16:20:08 INFO - mozprocess == 0.25 16:20:08 INFO - mozprofile == 0.28 16:20:08 INFO - mozrunner == 6.13 16:20:08 INFO - mozscreenshot == 0.1 16:20:08 INFO - mozsystemmonitor == 0.3 16:20:08 INFO - moztest == 0.8 16:20:08 INFO - mozversion == 1.4 16:20:08 INFO - psutil == 3.1.1 16:20:08 INFO - requests == 1.2.3 16:20:08 INFO - wsgiref == 0.1.2 16:20:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:20: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')]} 16:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20: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')]} 16:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20:08 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 0x29971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d04b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d07f00>, '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 0x2d02ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d03050>, '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': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', '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 16:20:08 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 16:20:08 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 16:20:08 INFO - Using env: {'DISPLAY': ':0', 16:20:08 INFO - 'HOME': '/home/cltbld', 16:20:08 INFO - 'LANG': 'en_US.UTF-8', 16:20:08 INFO - 'LANGUAGE': 'en_US:en', 16:20:08 INFO - 'LOGNAME': 'cltbld', 16:20:08 INFO - 'MAIL': '/var/mail/cltbld', 16:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:08 INFO - 'MOZ_NO_REMOTE': '1', 16:20:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:08 INFO - 'NO_EM_RESTART': '1', 16:20:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:08 INFO - 'PWD': '/builds/slave/test', 16:20:08 INFO - 'SHELL': '/bin/bash', 16:20:08 INFO - 'SHLVL': '1', 16:20:08 INFO - 'TERM': 'linux', 16:20:08 INFO - 'TMOUT': '86400', 16:20:08 INFO - 'USER': 'cltbld', 16:20:08 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', 16:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:08 INFO - '_': '/tools/buildbot/bin/python'} 16:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:08 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)) 16:20:08 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)) 16:20:08 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)) 16:20:08 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)) 16:20:08 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)) 16:20:08 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)) 16:20:08 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)) 16:20:08 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)) 16:20:08 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)) 16:20:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:20:08 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 16:20:08 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 16:20:08 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 16:20:08 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 16:20:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:20:09 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)) 16:20:09 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)) 16:20:09 INFO - Installing collected packages: simplejson 16:20:09 INFO - Running setup.py install for simplejson 16:20:10 INFO - building 'simplejson._speedups' extension 16:20:10 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 16:20:10 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 16:20:10 INFO - Successfully installed simplejson 16:20:10 INFO - Cleaning up... 16:20:11 INFO - Return code: 0 16:20:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:20:11 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')]} 16:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20:11 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')]} 16:20:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20:11 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 0x29971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b63a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d04b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d07f00>, '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 0x2d02ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d03050>, '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': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', '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 16:20:11 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 16:20:11 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 16:20:11 INFO - Using env: {'DISPLAY': ':0', 16:20:11 INFO - 'HOME': '/home/cltbld', 16:20:11 INFO - 'LANG': 'en_US.UTF-8', 16:20:11 INFO - 'LANGUAGE': 'en_US:en', 16:20:11 INFO - 'LOGNAME': 'cltbld', 16:20:11 INFO - 'MAIL': '/var/mail/cltbld', 16:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:11 INFO - 'MOZ_NO_REMOTE': '1', 16:20:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:11 INFO - 'NO_EM_RESTART': '1', 16:20:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:11 INFO - 'PWD': '/builds/slave/test', 16:20:11 INFO - 'SHELL': '/bin/bash', 16:20:11 INFO - 'SHLVL': '1', 16:20:11 INFO - 'TERM': 'linux', 16:20:11 INFO - 'TMOUT': '86400', 16:20:11 INFO - 'USER': 'cltbld', 16:20:11 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', 16:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:11 INFO - '_': '/tools/buildbot/bin/python'} 16:20:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:20:11 INFO - Cleaning up... 16:20:11 INFO - Return code: 0 16:20:11 INFO - Running post-action listener: _resource_record_post_action 16:20:11 INFO - Running post-action listener: _start_resource_monitoring 16:20:11 INFO - Starting resource monitoring. 16:20:11 INFO - [mozharness: 2017-08-12 23:20:11.296376Z] Finished create-virtualenv step (success) 16:20:11 INFO - [mozharness: 2017-08-12 23:20:11.296843Z] Running install step. 16:20:11 INFO - Running pre-action listener: _resource_record_pre_action 16:20:11 INFO - Running main action method: install 16:20:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:20:11 INFO - Reading from file tmpfile_stdout 16:20:11 INFO - Detecting whether we're running mozinstall >=1.0... 16:20:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:20:11 INFO - Reading from file tmpfile_stdout 16:20:11 INFO - Output received: 16:20:11 INFO - Usage: mozinstall [options] installer 16:20:11 INFO - Options: 16:20:11 INFO - -h, --help show this help message and exit 16:20:11 INFO - -d DEST, --destination=DEST 16:20:11 INFO - Directory to install application into. [default: 16:20:11 INFO - "/builds/slave/test"] 16:20:11 INFO - --app=APP Application being installed. [default: firefox] 16:20:11 INFO - mkdir: /builds/slave/test/build/application 16:20:11 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'] 16:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:20:29 INFO - Reading from file tmpfile_stdout 16:20:29 INFO - Output received: 16:20:29 INFO - /builds/slave/test/build/application/firefox/firefox 16:20:29 INFO - Running post-action listener: _resource_record_post_action 16:20:29 INFO - [mozharness: 2017-08-12 23:20:29.554990Z] Finished install step (success) 16:20:29 INFO - [mozharness: 2017-08-12 23:20:29.555337Z] Running setup-mitmproxy step. 16:20:29 INFO - Running pre-action listener: _resource_record_pre_action 16:20:29 INFO - Running main action method: setup_mitmproxy 16:20:29 INFO - Skipping: mitmproxy is not required 16:20:29 INFO - Running post-action listener: _resource_record_post_action 16:20:29 INFO - [mozharness: 2017-08-12 23:20:29.556965Z] Finished setup-mitmproxy step (success) 16:20:29 INFO - [mozharness: 2017-08-12 23:20:29.557275Z] Running run-tests step. 16:20:29 INFO - Running pre-action listener: _resource_record_pre_action 16:20:29 INFO - Running pre-action listener: _set_gcov_prefix 16:20:29 INFO - Running main action method: run_tests 16:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:20:29 INFO - Python 2.7.3 16:20:29 INFO - Return code: 0 16:20:29 INFO - grabbing minidump binary from tooltool 16:20:29 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')]} 16:20:29 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 0x2d07f00>, '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 0x2d02ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d03050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:20:29 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 16:20:29 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 16:20:29 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 16:20:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:20:29 INFO - Return code: 0 16:20:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:20:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:20:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:20:29 INFO - ENV: RUST_BACKTRACE is now full 16:20:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:20:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:20:29 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/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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 16:20:29 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/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-077 --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 16:20:29 INFO - Using env: {'DISPLAY': ':0', 16:20:29 INFO - 'HOME': '/home/cltbld', 16:20:29 INFO - 'LANG': 'en_US.UTF-8', 16:20:29 INFO - 'LANGUAGE': 'en_US:en', 16:20:29 INFO - 'LOGNAME': 'cltbld', 16:20:29 INFO - 'MAIL': '/var/mail/cltbld', 16:20:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:20:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:29 INFO - 'MOZ_NO_REMOTE': '1', 16:20:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:20:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:20:29 INFO - 'NO_EM_RESTART': '1', 16:20:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:29 INFO - 'PWD': '/builds/slave/test', 16:20:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:20:29 INFO - 'RUST_BACKTRACE': 'full', 16:20:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:20:29 INFO - 'SHELL': '/bin/bash', 16:20:29 INFO - 'SHLVL': '1', 16:20:29 INFO - 'STYLO_FORCE_ENABLED': '1', 16:20:29 INFO - 'TERM': 'linux', 16:20:29 INFO - 'TMOUT': '86400', 16:20:29 INFO - 'USER': 'cltbld', 16:20:29 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426', 16:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:29 INFO - '_': '/tools/buildbot/bin/python'} 16:20:29 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/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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 16:20:29 INFO - mozversion application_buildid: 20170812223654 16:20:29 INFO - mozversion application_changeset: 30e6b0fd69ff4b3599eb6744ba12ccb75ea28598 16:20:29 INFO - mozversion application_display_name: Nightly 16:20:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:20:29 INFO - mozversion application_name: Firefox 16:20:29 INFO - mozversion application_remotingname: firefox 16:20:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:20:29 INFO - mozversion application_vendor: Mozilla 16:20:29 INFO - mozversion application_version: 57.0a1 16:20:29 INFO - mozversion platform_buildid: 20170812223654 16:20:29 INFO - mozversion platform_changeset: 30e6b0fd69ff4b3599eb6744ba12ccb75ea28598 16:20:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:20:29 INFO - mozversion platform_version: 57.0a1 16:20:29 INFO - using testdate: 1502580029 16:20:29 INFO - actual date: 1502580029 16:20:29 INFO - starting webserver on 'localhost:39842' 16:20:29 INFO - SUITE-START | Running 2 tests 16:20:29 INFO - TEST-START | dromaeo_css 16:20:29 INFO - Initialising browser for dromaeo_css test... 16:20:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39842/getInfo.html -profile /tmp/tmpAfyMX_/profile 16:20:29 INFO - TEST-INFO | started process 11774 (/builds/slave/test/build/application/firefox/firefox http://localhost:39842/getInfo.html) 16:20:36 INFO - TEST-INFO | 11774: exit 0 16:20:36 INFO - Browser initialized. 16:20:36 INFO - Running cycle 1/1 for dromaeo_css test... 16:20:36 INFO - TEST-INFO | started process 12006 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAfyMX_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:37 INFO - PID 12006 | 16:20:37 INFO - PID 12006 | (/builds/slave/test/build/application/firefox/firefox:12061): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:20:37 INFO - PID 12006 | 16:20:47 INFO - PID 12006 | RSS: Main: 167956480 16:20:47 INFO - PID 12006 | 16:23:46 INFO - PID 12006 | Cycle 1(1): loaded http://localhost:39842/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39842/tests/dromaeo/cssquery-ext.html) 16:23:48 INFO - PID 12006 | RSS: Main: 175579136 16:23:48 INFO - PID 12006 | 16:26:49 INFO - PID 12006 | Cycle 1(1): loaded http://localhost:39842/tests/dromaeo/cssquery-ext.html (next: http://localhost:39842/tests/dromaeo/cssquery-jquery.html) 16:26:50 INFO - PID 12006 | RSS: Main: 172912640 16:26:50 INFO - PID 12006 | 16:29:52 INFO - PID 12006 | Cycle 1(1): loaded http://localhost:39842/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39842/tests/dromaeo/cssquery-mootools.html) 16:29:53 INFO - PID 12006 | RSS: Main: 172982272 16:29:53 INFO - PID 12006 | 16:32:54 INFO - PID 12006 | Cycle 1(1): loaded http://localhost:39842/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39842/tests/dromaeo/cssquery-prototype.html) 16:32:55 INFO - PID 12006 | RSS: Main: 173174784 16:32:55 INFO - PID 12006 | 16:35:59 INFO - PID 12006 | Cycle 1(1): loaded http://localhost:39842/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39842/tests/dromaeo/cssquery-yui.html) 16:35:59 INFO - PID 12006 | RSS: Main: 176349184 16:35:59 INFO - PID 12006 | 16:39:01 INFO - PID 12006 | Cycle 1(1): loaded http://localhost:39842/tests/dromaeo/cssquery-yui.html (next: http://localhost:39842/tests/dromaeo/cssquery-dojo.html) 16:39:02 INFO - PID 12006 | RSS: Main: 177197056 16:39:02 INFO - PID 12006 | 16:39:02 INFO - PID 12006 | __start_tp_report 16:39:02 INFO - PID 12006 | _x_x_mozilla_page_load 16:39:02 INFO - PID 12006 | _x_x_mozilla_page_load_details 16:39:02 INFO - PID 12006 | |i|pagename|runs| 16:39:02 INFO - PID 12006 | |0;dojo.html;3128.9674407445546;3151.4800057990433;3158.5420114083454;3159.573457583226;3194.7923384979977;6112.174856394391;6140.508759299257;6179.505639548836;6275.905861412071;6335.873282534347;6205.348438413969;6250.843728906768;6296.842578935518;6299.055141728743;6355.904661430082;5543.25166102575;5611;5698.059820129673;5722.1416787482;5773.3937936516695;5649.46330098642;5681.687507187103;5731.254936858202;5757.625754325976;5764.827055188351;5682.943170568283;5712.742926568294;5735.942640573603;5741.511971482429;5749.281339832521;5530;5663.915041274384;5668.603197776158;5746.540276777848;5746.9712651436475;5602.24369710088;5616.157576363578;5665.886682266373;5680.6591604503865;5683.488486036276;6177.907331390033;6185.938140618626;6225.533085018642;6245.781397651061;6256.843578910518;6689.531732778707;6689.832754181136;6703.966480167616;6765.492588055916;6774.593524388552;6559.2128944526485;6710.765123220664;6737.292584278629;6740.696668649923;6858.10844590205;6216.067589861512;6216.7824126155365;6232.127502149703;6234.688265586702;6246.0630905364105;6745.494087943423;6817.5568588041615;6833.282505336868;6842.247352791189;6842.384185423236;6610.10763546915;6610.206775187008;6621.635810030398;6656.400923916871;6671.099401580725;6669.199696036507;6699.698513566901;6750.29121942189;6777.49168812341;6778.966105169541;6499.2850786413455;6658.533902626867;6682.699278532477;6741;6756.0879281297375;6631.734730610818;6640.568363056386;6725.865482690417;6730.663466826639;6745.392914637706;5429.4027656957705;5450.373207081237;5455.1271796512365;5457.426970168154;5467.289252397163;4614.792719060221;4654.976725116353;4684.484706682262;4685.906281874411;4701.882452938704;5751.194832723422;5764.510016648632;5827.096799996008;5829.8251052468495;5849.298084229919;6570.967145164243;6659.101021362151;6690.732370705117;6727.596344219362;6791.93208067913;5753.33836608787;5755.453231942959;5763.8559036024435;5767.192593036978;5783.450572195635;5875.500582450454;5891.293044834561;5922.3485416604135;5959.880802384015;5972.104184372292;6377.489800815923;6392.360763923571;6479.2224933006455;6489.286178520359;6514.088027676129;6633.6019838809825;6650.767223147167;6672.5329226953645;6733.797986060426;6735.225449073223;6484.157059582224;6505.902411463737;6507.967460162668;6521.576097553644;6522.249941256816;6601.570897891622;6677.065210870579;6686.030525573668;6701.32986701326;6705.1283333166375;6773.119494465688;6788.558743681645;6839.350261725128;6862.519623626298;6866.896996544956;6485.092087107714;6557.082008518906;6581.835454113685;6604.867902642017;6607;6355.110284560073;6469.644169570719;6478.190226221723;6557.049227861838;6563.737450501926;6502.382273683992;6514.3811337922825;6521.054447105238;6522.771702990372;6524.575902566318;6563.7702680405955;6566.9014964774615;6570.047343135314;6647.335266473314;6647.734090636321;6360.618362898241;6364.6181229126405;6442.516811239082;6450.967745161244;6458.289588145206 16:39:02 INFO - PID 12006 | |1;ext.html;796.4385108498506;862.7239283429303;884.7920738626424;1150.1029197226162;1172.9120315976304;30080.849595752017;30494.39011219777;39536.802315988454;39895;39990.20019599606;40338.394924076165;40643.79678101613;40715.796421017854;40741;40820.79589602055;7166.534175278616;7542.05724284463;7659.885101723478;7681.9615901920415;7701.0758708955;7456.7762967111075;7561.848763024736;7565.205653406396;7571.280728330799;7572.242775722411;7539.132999705022;7555.508891922035;7577.090749110114;7593.696252149907;7594.8481030379635;7519.2104828992715;7533.623318834037;7557.58433286169;7559.5464272143845;7565.848683026336;7480.214577469396;7513.774586762406;7529.435292353095;7530.472866899319;7542.396608271325;36687.816560917025;36817.26365472702;36891.81554092239;36951.63048369536;37041.444378334345;3590.0486371111674;3609.530761001053;3613.5663720353728;3617.330793803155;3634.636536346344;29818;29876.70123298761;29917.25206869824;29977.100686979426;30093.699063009528;5006.499350065001;5033.84898453047;5091.261767043795;5093.490650934914;5114.616403769695;191711;193309;194273;194425.02787486115;194797;49080.26379604309;49142.508574913794;49214;49252.26121608179;49425.25862112071;49788;50222;50225;50286.74856625694;50324.245136322985;14830.036047656868;15239.847601524065;15272.160031198391;15286.38854445832;15362.692746145238;11160.776784464266;11337;12465.813012804816;12487.438065286902;12679;14072.788908166385;14123.71752564943;14158.079724817853;14161.362738676784;14170.504032358818;5611.354694210137;5665;5741.282339707536;5799.5650326225705;5917.556183286185;993.101243374747;1009.4750729620556;1009.7980403919246;1009.9798004039878;1028.3829702178782;22716.318510444715;22858.542829143324;22990.310290691305;23088.19191328329;23110.884445577663;1104.3318792130644;1117.4692021289823;1125.5216532973454;1128.6275529075385;1129.6498085593491;12100.213486123374;12224.816627750592;15990.280437380346;15993.920030400006;16498.917505412395;229737;230973;231131;232034;232606;55779.721101395044;56147.71926140343;56148.157777633365;56214.718926405105;56289;1428.6142741459748;1458.762478212631;1468.544751127154;1482.384810303712;1501.9774703379458;26786.59820102661;26892;27235.72764272332;27815.582766258925;27840;37483.81258093692;37853.43219851759;37918.620813792615;37965.43051854169;38011.809940950116;204745;205689;206427;206717;207568;33559.66440335565;35791.64208357883;37913.24173516569;38016.239675206896;38032.429513556766;46969;47214.527854722386;47555.52444475511;47833.28250076321;47866.52133478621;34339.82830085834;34347.65652343544;34449.48325775166;34565.30869382648;34972.47541286934;33411;33948.49077263794;33952;33995.980120596425;34268.48597271093;37329.25341493209;37403;37501.62498374981;37698.62301376951;37728.245435091696;32944.505832413015;33026.33947320992;33229.83385083059;33238.5014224782;33599;23632.29103126909;24327;24906;25208;25432.491350172524 16:39:02 INFO - PID 12006 | |2;jquery.html;558.7541481748028;568.894754470423;577.0968434400165;579.6348300570639;584.9298084229893;21806.672899906494;22598.88700556497;22610.66084008741;22679;22843.200487982926;25740.48519029623;25900.74099259007;26389.340266493346;26391.604125938127;26392.60411093835;6381.298057213714;6508.707108180143;6512.185976752906;6514.869702605945;6516.706748196342;5769.798057068004;6234.127222188898;6283.937160628404;6294.402031806994;6357.523185761063;6253.49972002241;6256.311805701369;6269.467095296905;6273.905891411609;6278.968605156968;6266.060090986343;6275.466585340251;6284.308726040178;6287.245530536319;6292.276388215345;5844.883102337972;5845.532357411397;5847.561432892508;5855.180274761537;5862.912056319158;29163;29264.268393290124;29826.10521684353;29840.403191936257;29973.100806975825;1941.0585865855048;1959.6276707425543;1961.6370971370343;1962.2445358536922;1967.0558132096673;18817.62364752711;19675.704864427044;19729.40811775649;19743.506412339662;19776.505587360287;2394.928152155438;2462.3105530451503;2475.8390704604144;2485.192312498445;2508.2099138771227;130694;133339;134147;134200;134315.32842335824;13890.930545347208;14767.48313809033;14948.626284342978;14987.625309367135;15306.923465382824;22955.770442295696;23506.88246558756;24103.638445423334;24161.516769664508;24203.51592968166;9559.522023898917;9619.615215391446;9652.710418687451;9694;9725.173360264314;8544.102869198645;8575.656973720981;8642.913570864212;8677.6528938843;8729.170728780755;9075.50084745332;9340.392874463138;9346.813063738688;9520.333576649706;9621.759456013515;2588.171785028773;2640.6435131257035;2753.2703833484143;2791.260316016257;2808.9297767555568;954.4416516337881;979.8432250839832;993.6025589764008;999.4503023337136;1010.0455069958965;19976.90011549933;20134.19463221455;20236;20641.483962900747;20730.378088657064;1033.49358814182;1038.2835843268122;1046.2310202001522;1047.669983955067;1048.0829274384914;11258.774824503627;11266.54933802655;11301;11306.547738090549;11444.54221831134;466268;471263;480846;484638;491452;118638;119803;120824;120902;121807;1192.3442106841203;1213.7026428525069;1223.7674841780033;1228.3858070964518;1234.3581337704343;17925.910370448066;18021.819781802013;21305.25431609886;22590.435239118495;22942.42643933915;51324.48675513197;52605.21092183544;58787.70606146942;61312;63603.6819815898;71746.64126679333;75353.24646753463;77195.2280477188;77386.61306693431;77679;21237.68143477818;21829.235976740736;21991.120355185612;22126.77873221247;22466.663000054687;23872.88063559671;24424.26727198187;24507;24733.50532989294;24812.751872481043;18772.3429679966;19003.61992760165;19136.712949305493;19395.903020485373;19408.320708775125;16178.191090445665;16648;16773.077480738677;17229.827701723323;17832.55418614545;19649.90175049173;19842.900785495982;19981.700274495604;20045.699314510002;20248.89875550613;21101.261455848973;21364.465888352915;21406.25078122258;21520.784792151877;21963.23128690552;19473.123709433392;19535.12091955894;19549.315773947845;19652.50868728293;19692.11385487628 16:39:02 INFO - PID 12006 | |3;mootools.html;1353.3774463746677;1378.2901805570127;1398.9090709103907;1410.1468611490047;1421.1970236816192;12128.939355303222;12716.809247861278;12803.167794093377;14366.640833979129;15032.774508382383;14529.128252304869;14767.040142390737;14780.408783648669;14781.408743650241;14801.851981480182;2552.4512229870575;2656.4023094803706;2709.254954887402;2711.769499592537;2714.4978179036843;2664.214056853223;2683.449892771987;2694.5015172193207;2694.91915242543;2698.892044318225;2693.4747724193694;2697.1908427471776;2711.8372897626205;2720.3063218879256;2728.5361488546896;2524.684414448194;2532.189699296226;2540.745925407454;2542.4152444937677;2563.858987755673;2562.3466016165944;2574.691037075544;2579.7549232822853;2591.403977085262;2592.8444293342272;14254.786178207336;14332.49836255726;14380.35288412024;14421.639459013504;14427.567172984827;7288.4169266459085;7591.089069311718;7823.178566250519;7861.68553257869;7868.25251601097;5368.194770784375;5463.31708536433;5581.274434323512;5595.328560572757;5615.943840561583;1911.2737159879296;1928.3250862198258;1946.6496030714466;1989.532459871929;1997.6703843865744;24718.13486527963;24858.37854053645;24897.128600498894;24899.004039838386;24946;6364.1408409865;6457.935420645827;6669.566478178903;6691.464682825363;6694.631795251211;16471.176441177893;16613.41853035138;16628.58428539296;16633.50099497017;16655.167241637868;7472.215417381205;7684.961575192088;7688.730894418781;7689.615519224017;7689.692412303557;6845.863082738318;7001;7039.507234493536;7103.715851365888;7132.322429369202;5130.7947682093045;5276.894462110737;5284.154535274414;5308.256844041837;5332.040232758063;1507.9019863708825;1525.832737955465;1537.3158944269917;1540.8151021877447;1549.1789351643647;872.1148034744739;887.8535041718173;901.3510272603714;912.5710915869531;912.749533139603;7462.4776265660885;7470.589117598584;7483.70065197386;7484.663190156456;7521.811954701177;702.3362922038624;729.3326606155415;736.3777607921298;736.5580651608992;738.084774879146;5614.266183676055;5681.318241811009;5702.942970570241;5854.443827836434;5945.702714864239;20337.49156271105;20550.28074017402;20845.374638760863;20876.895615521826;20902.895485522477;20086;20157.395278141677;20704.48238794042;20786.792132078786;20921.267755628785;10109.64616238428;10849.511771970281;11045.28205666629;11071.173256412601;11126.38804865724;8816.559172041243;8844.46933184011;8854.158854908774;8863.556822158995;8963.641454342014;17432.91283543574;17748.822511775234;17754.201060952546;18150.546236343667;18561.628767424307;17618.38335658297;18110.09449527492;18181.09094545241;18206.544836379195;18309.54226144357;11831.17181797266;12110.455029523517;12112.697182570506;12112.878871211175;12116.27302361861;13054.608361749162;13061.477540898637;13192.934035330145;13193.934030329787;13231.404586793426;11130.499127539442;11137;11233;11270.380129092979;11501.597444089417;10431.321964072311;10438.947805260925;10586.735331616772;10613.204009699459;10688.89311106879;9600.135987761136;9688.079632434907;9693.273004524548;9699.854502182332;9719;7352.757408990309;7602.6198690066385;7665.118511371264;7680.692772289046;7691.730789422567;7745.845083098419;7767;7861.960690196513;7905.93269908555;8002.479838810459 16:39:02 INFO - PID 12006 | |4;prototype.html;497.65164384930546;498.7531172069826;521.1531261699738;528.1101344234962;533.8024930775614;6264.749410023605;6323.304436511986;6332.588381755183;6334.80995570133;6371.139896114023;6147;6162.383761623835;6162.568620196588;6190.535709821759;6201.379862013797;4880.341153944208;4897.33885925399;4917.901641967159;4968.826091086813;4969.378827646544;4997.350344455234;4998.7250701211415;5000.799968001275;5013.122703526887;5033.245013248005;5162.586993040566;5181.378234611851;5193.143131383317;5197.558207552343;5209.583233341343;5159.38087429509;5170.146925757245;5174.327337446146;5206.921896171561;5221.738913054332;4807.206810876201;4827.106985207713;4828.324034635154;4843.15244832153;4862.927056094161;6214;6222.906656400158;6224.844378890519;6229.888273739356;6249.750089967608;5997.490213331872;6016.368281330441;6040.667763273018;6061.787837425669;6080.96959515204;6695.12963314766;6709.899351509731;6721.932780672179;6730.596164230162;6750.696218670171;5304.9390121975375;5309.256704061435;5326.094563924142;5328.333958255218;5332.706701131437;7033.68348424317;7139.785806425815;7143.785686429415;7144.214136445039;7147.42820574358;5481.451854814566;5481.588880833874;5483.150111732688;5507.531859791962;5516.613837031449;5563.388027316992;5575.247341608912;5584.162375643686;5602.607817452739;5605.383407825136;6039.8792024159275;6059.424354686298;6075.058365953284;6075.908861367083;6081.726322315506;6704.597724136567;6706;6710.6309152997055;6768.458523318122;6770.864582708318;1845.385287873111;1888.2447021191633;1891.6311319292606;1921.5780322561204;1961.4507937777446;1411.922344271075;1433.8422773494908;1436.633658417097;1445.2051371745497;1460.8466111058399;5761.308642962871;5776.42235776419;5776.884462310731;5786.189933409326;5826.854328641733;6698.12924319845;6715.56348837324;6733.350329169287;6744.62904540255;6891.620960847202;5764.567657425626;5771;5811.506021988178;5835.252276507636;5856.657337065208;3612.458131280329;3645.927081458356;3653.1415117447377;3660.762050466711;3667.5232219811255;6564.934350656527;6594.087714562819;6620.205575330895;6625.674865026917;6628.602283862983;6991.762635956547;7029.5430796998035;7047.238190452387;7058.188308344504;7074.787756367317;6506.60960342381;6583.210014798159;6604.537682362188;6609.4712423005085;6630.602163870183;6811.148606424197;6859.382655561023;6868.793936181923;6878.518503704692;6880.931190688229;6757.3242675732035;6773.356531129535;6789.728410863708;6797.72809087631;6823.249442561215;6562.671866406756;6570.5400621957915;6580.605163690194;6606.867862642816;6625.569337993015;5016.54851063406;5022.422421421583;5033.748312584429;5064.518870707277;5064.696118232918;6894.3795058442975;6920.688569014507;6938.5143039986715;6949.270326615632;6950.7174547343375;6569.507286953402;6596.406323430914;6608.636524991171;6618.312330355729;6662.367075127855;6609.570377925419;6623.569467984566;6628.867422651617;6680.6993685283305;6685.298043705341;6344;6403.391677790602;6498.512611554058;6511.772087976898;6545.181852268466;6347.699670774955;6617.7683781067435;6652.83367915806;6656.434203092791;6660.001999400258;6438.195225596801;6443.903341449975;6455.709493072729;6465.3534646536855;6496.480281577368 16:39:02 INFO - PID 12006 | |5;yui.html;916.4730280088951;927.8144371125775;932.8927173375064;943.221841980366;947.2658689515622;18842.62314753704;19883.204671813128;19895.30366437175;19928.70106948397;22453;21288.935553222316;21386.46533836655;21680;21792;21796.564068718617;1035.7048241251225;1072.0244577434537;1073.366713638953;1074.1997212076974;1077.4074259157471;1001.8397056470966;1033.7725700345911;1055.6358056470544;1060.5598676549223;1064.09551880901;1097.9011888929995;1102.178876736832;1103.0954617213847;1103.2277405815921;1104.1498046504187;1089.8256278995323;1096.780643871225;1098.1324753444849;1101.2731597145926;1102.5865300512307;801.3949468151508;805.0661232969727;806.5684858600679;808.7654580171802;810.2626609785067;17290.654186916316;17336;17363.045032523205;17575.912120439443;20681;20585.485362865897;20744.5851082979;21182.152713891424;21287.14851405942;21294.680579791315;17201.56996075108;17309.653806923918;17439.825601743945;17598.648027039388;17640.647187056315;3639.70882329413;3673.1735359544155;3673.2837096765875;3674.5590529136407;3675.724320675933;130039.34980325038;130215.34892325477;132183.33908330396;133248;136030;16394.508164755076;16990.91504542494;17041.40355087566;17076.743848842278;17112.315507379564;31147.68852311493;31492;31715.682843171733;32006;32044.679553204634;7340.37606803417;7376.225496322916;7744.92255077442;8034.517928924166;8489.023762267418;4068.5524592294823;4175.144095460423;4178.435911152016;4181.811818468176;4183.49798024239;5249.685018898802;5329.280547126166;5333.57331413486;5363.061464243741;5377;1079.9622013229657;1099.2525082943675;1103.6688993301977;1107.06453047175;1108.5621179634;660.4353277947382;671.5232185148501;672.3478226120656;673.7574473189648;686.6017709728445;11691.824622630667;11770.705732356588;12095.21381110225;12265.26408415498;12395.876041239653;685.8765422224047;687.2920891481782;688.6281408039603;703.2756261051126;712.262807993724;5915.432410411245;5926.377730338339;5933.110033494924;5935.614185077956;6036.034234522455;114405.42797286127;115540.42229788797;117492;117887;120144;101460.49269753604;101679.49160254152;102462.48768756108;103594.48202758937;104754;82863;88231.99999999872;89137;91021.54489227511;91221;11935.582254621047;12043.879561204276;12045.63863084109;12197.207181533173;12789;40344.596554034084;40765;40807.38788918228;41280.1743965125;41447.37828932508;74385.62807185929;76131;76864.61567692125;78274;80162;19109.808901911358;19907.900460497603;19946.102425390603;20003.399898003085;20260.89869550643;22597.2090976815;22754.65868012014;22775.430614234778;22834.42913927165;23215.071397144602;18053;18281.908590456962;18627.627447450704;18712.25150993945;18964.336248231244;18321.541961451072;18738.906305468387;18797.530061748017;19268.32560860363;19429.222831086598;17988.460346189113;18397.54006149857;18440.446786596425;18500;18604.069796509848;11361.318320900773;11645.126615503701;11650.475728592402;11920.642380728592;12208.084393670333;13987.860121399062;14022.579322620337;14123.223222722849;14398.712025759636;14437.278136092944 16:39:02 INFO - PID 12006 | __end_tp_report 16:39:02 INFO - PID 12006 | __start_cc_report 16:39:02 INFO - PID 12006 | _x_x_mozilla_cycle_collect,384 16:39:02 INFO - PID 12006 | __end_cc_report 16:39:02 INFO - PID 12006 | __startTimestamp1502581142053__endTimestamp 16:39:02 INFO - PID 12006 | 16:39:02 INFO - PID 12006 | ------- Summary: start ------- 16:39:02 INFO - PID 12006 | Number of tests: 6 16:39:02 INFO - PID 12006 | 16:39:02 INFO - PID 12006 | [#0] dojo.html Cycles:165 Average:6168.23 Median:6458.29 stddev:732.35 (11.3%) stddev-sans-first:694.71 16:39:02 INFO - PID 12006 | Values: 3129.0 3151.5 3158.5 3159.6 3194.8 6112.2 6140.5 6179.5 6275.9 6335.9 6205.3 6250.8 6296.8 6299.1 6355.9 5543.3 5611.0 5698.1 5722.1 5773.4 5649.5 5681.7 5731.3 5757.6 5764.8 5682.9 5712.7 5735.9 5741.5 5749.3 5530.0 5663.9 5668.6 5746.5 5747.0 5602.2 5616.2 5665.9 5680.7 5683.5 6177.9 6185.9 6225.5 6245.8 6256.8 6689.5 6689.8 6704.0 6765.5 6774.6 6559.2 6710.8 6737.3 6740.7 6858.1 6216.1 6216.8 6232.1 6234.7 6246.1 6745.5 6817.6 6833.3 6842.2 6842.4 6610.1 6610.2 6621.6 6656.4 6671.1 6669.2 6699.7 6750.3 6777.5 6779.0 6499.3 6658.5 6682.7 6741.0 6756.1 6631.7 6640.6 6725.9 6730.7 6745.4 5429.4 5450.4 5455.1 5457.4 5467.3 4614.8 4655.0 4684.5 4685.9 4701.9 5751.2 5764.5 5827.1 5829.8 5849.3 6571.0 6659.1 6690.7 6727.6 6791.9 5753.3 5755.5 5763.9 5767.2 5783.5 5875.5 5891.3 5922.3 5959.9 5972.1 6377.5 6392.4 6479.2 6489.3 6514.1 6633.6 6650.8 6672.5 6733.8 6735.2 6484.2 6505.9 6508.0 6521.6 6522.2 6601.6 6677.1 6686.0 6701.3 6705.1 6773.1 6788.6 6839.4 6862.5 6866.9 6485.1 6557.1 6581.8 6604.9 6607.0 6355.1 6469.6 6478.2 6557.0 6563.7 6502.4 6514.4 6521.1 6522.8 6524.6 6563.8 6566.9 6570.0 6647.3 6647.7 6360.6 6364.6 6442.5 6451.0 6458.3 16:39:02 INFO - PID 12006 | 16:39:02 INFO - PID 12006 | [#1] ext.html Cycles:180 Average:38305.98 Median:3599.79 stddev:54662.57 (1518.5%) stddev-sans-first:54743.35 16:39:02 INFO - PID 12006 | Values: 796.4 862.7 884.8 1150.1 1172.9 30080.8 30494.4 39536.8 39895.0 39990.2 40338.4 40643.8 40715.8 40741.0 40820.8 7166.5 7542.1 7659.9 7682.0 7701.1 7456.8 7561.8 7565.2 7571.3 7572.2 7539.1 7555.5 7577.1 7593.7 7594.8 7519.2 7533.6 7557.6 7559.5 7565.8 7480.2 7513.8 7529.4 7530.5 7542.4 36687.8 36817.3 36891.8 36951.6 37041.4 3590.0 3609.5 3613.6 3617.3 3634.6 29818.0 29876.7 29917.3 29977.1 30093.7 5006.5 5033.8 5091.3 5093.5 5114.6 191711.0 193309.0 194273.0 194425.0 194797.0 49080.3 49142.5 49214.0 49252.3 49425.3 49788.0 50222.0 50225.0 50286.7 50324.2 14830.0 15239.8 15272.2 15286.4 15362.7 11160.8 11337.0 12465.8 12487.4 12679.0 14072.8 14123.7 14158.1 14161.4 14170.5 5611.4 5665.0 5741.3 5799.6 5917.6 993.1 1009.5 1009.8 1010.0 1028.4 22716.3 22858.5 22990.3 23088.2 23110.9 1104.3 1117.5 1125.5 1128.6 1129.6 12100.2 12224.8 15990.3 15993.9 16498.9 229737.0 230973.0 231131.0 232034.0 232606.0 55779.7 56147.7 56148.2 56214.7 56289.0 1428.6 1458.8 1468.5 1482.4 1502.0 26786.6 26892.0 27235.7 27815.6 27840.0 37483.8 37853.4 37918.6 37965.4 38011.8 204745.0 205689.0 206427.0 206717.0 207568.0 33559.7 35791.6 37913.2 38016.2 38032.4 46969.0 47214.5 47555.5 47833.3 47866.5 34339.8 34347.7 34449.5 34565.3 34972.5 33411.0 33948.5 33952.0 33996.0 34268.5 37329.3 37403.0 37501.6 37698.6 37728.2 32944.5 33026.3 33229.8 33238.5 33599.0 23632.3 24327.0 24906.0 25208.0 25432.5 16:39:02 INFO - PID 12006 | 16:39:02 INFO - PID 12006 | [#2] jquery.html Cycles:180 Average:35217.34 Median:24132.58 stddev:80811.92 (334.9%) stddev-sans-first:80996.72 16:39:02 INFO - PID 12006 | Values: 558.8 568.9 577.1 579.6 584.9 21806.7 22598.9 22610.7 22679.0 22843.2 25740.5 25900.7 26389.3 26391.6 26392.6 6381.3 6508.7 6512.2 6514.9 6516.7 5769.8 6234.1 6283.9 6294.4 6357.5 6253.5 6256.3 6269.5 6273.9 6279.0 6266.1 6275.5 6284.3 6287.2 6292.3 5844.9 5845.5 5847.6 5855.2 5862.9 29163.0 29264.3 29826.1 29840.4 29973.1 1941.1 1959.6 1961.6 1962.2 1967.1 18817.6 19675.7 19729.4 19743.5 19776.5 2394.9 2462.3 2475.8 2485.2 2508.2 130694.0 133339.0 134147.0 134200.0 134315.3 13890.9 14767.5 14948.6 14987.6 15306.9 22955.8 23506.9 24103.6 24161.5 24203.5 9559.5 9619.6 9652.7 9694.0 9725.2 8544.1 8575.7 8642.9 8677.7 8729.2 9075.5 9340.4 9346.8 9520.3 9621.8 2588.2 2640.6 2753.3 2791.3 2808.9 954.4 979.8 993.6 999.5 1010.0 19976.9 20134.2 20236.0 20641.5 20730.4 1033.5 1038.3 1046.2 1047.7 1048.1 11258.8 11266.5 11301.0 11306.5 11444.5 466268.0 471263.0 480846.0 484638.0 491452.0 118638.0 119803.0 120824.0 120902.0 121807.0 1192.3 1213.7 1223.8 1228.4 1234.4 17925.9 18021.8 21305.3 22590.4 22942.4 51324.5 52605.2 58787.7 61312.0 63603.7 71746.6 75353.2 77195.2 77386.6 77679.0 21237.7 21829.2 21991.1 22126.8 22466.7 23872.9 24424.3 24507.0 24733.5 24812.8 18772.3 19003.6 19136.7 19395.9 19408.3 16178.2 16648.0 16773.1 17229.8 17832.6 19649.9 19842.9 19981.7 20045.7 20248.9 21101.3 21364.5 21406.3 21520.8 21963.2 19473.1 19535.1 19549.3 19652.5 19692.1 16:39:02 INFO - PID 12006 | 16:39:02 INFO - PID 12006 | [#3] mootools.html Cycles:180 Average:9048.09 Median:2563.10 stddev:6292.80 (245.5%) stddev-sans-first:6283.89 16:39:02 INFO - PID 12006 | Values: 1353.4 1378.3 1398.9 1410.1 1421.2 12128.9 12716.8 12803.2 14366.6 15032.8 14529.1 14767.0 14780.4 14781.4 14801.9 2552.5 2656.4 2709.3 2711.8 2714.5 2664.2 2683.4 2694.5 2694.9 2698.9 2693.5 2697.2 2711.8 2720.3 2728.5 2524.7 2532.2 2540.7 2542.4 2563.9 2562.3 2574.7 2579.8 2591.4 2592.8 14254.8 14332.5 14380.4 14421.6 14427.6 7288.4 7591.1 7823.2 7861.7 7868.3 5368.2 5463.3 5581.3 5595.3 5615.9 1911.3 1928.3 1946.6 1989.5 1997.7 24718.1 24858.4 24897.1 24899.0 24946.0 6364.1 6457.9 6669.6 6691.5 6694.6 16471.2 16613.4 16628.6 16633.5 16655.2 7472.2 7685.0 7688.7 7689.6 7689.7 6845.9 7001.0 7039.5 7103.7 7132.3 5130.8 5276.9 5284.2 5308.3 5332.0 1507.9 1525.8 1537.3 1540.8 1549.2 872.1 887.9 901.4 912.6 912.7 7462.5 7470.6 7483.7 7484.7 7521.8 702.3 729.3 736.4 736.6 738.1 5614.3 5681.3 5702.9 5854.4 5945.7 20337.5 20550.3 20845.4 20876.9 20902.9 20086.0 20157.4 20704.5 20786.8 20921.3 10109.6 10849.5 11045.3 11071.2 11126.4 8816.6 8844.5 8854.2 8863.6 8963.6 17432.9 17748.8 17754.2 18150.5 18561.6 17618.4 18110.1 18181.1 18206.5 18309.5 11831.2 12110.5 12112.7 12112.9 12116.3 13054.6 13061.5 13192.9 13193.9 13231.4 11130.5 11137.0 11233.0 11270.4 11501.6 10431.3 10438.9 10586.7 10613.2 10688.9 9600.1 9688.1 9693.3 9699.9 9719.0 7352.8 7602.6 7665.1 7680.7 7691.7 7745.8 7767.0 7862.0 7905.9 8002.5 16:39:02 INFO - PID 12006 | 16:39:02 INFO - PID 12006 | [#4] prototype.html Cycles:180 Average:5667.01 Median:6218.45 stddev:1540.78 (24.8%) stddev-sans-first:1495.45 16:39:02 INFO - PID 12006 | Values: 497.7 498.8 521.2 528.1 533.8 6264.7 6323.3 6332.6 6334.8 6371.1 6147.0 6162.4 6162.6 6190.5 6201.4 4880.3 4897.3 4917.9 4968.8 4969.4 4997.4 4998.7 5000.8 5013.1 5033.2 5162.6 5181.4 5193.1 5197.6 5209.6 5159.4 5170.1 5174.3 5206.9 5221.7 4807.2 4827.1 4828.3 4843.2 4862.9 6214.0 6222.9 6224.8 6229.9 6249.8 5997.5 6016.4 6040.7 6061.8 6081.0 6695.1 6709.9 6721.9 6730.6 6750.7 5304.9 5309.3 5326.1 5328.3 5332.7 7033.7 7139.8 7143.8 7144.2 7147.4 5481.5 5481.6 5483.2 5507.5 5516.6 5563.4 5575.2 5584.2 5602.6 5605.4 6039.9 6059.4 6075.1 6075.9 6081.7 6704.6 6706.0 6710.6 6768.5 6770.9 1845.4 1888.2 1891.6 1921.6 1961.5 1411.9 1433.8 1436.6 1445.2 1460.8 5761.3 5776.4 5776.9 5786.2 5826.9 6698.1 6715.6 6733.4 6744.6 6891.6 5764.6 5771.0 5811.5 5835.3 5856.7 3612.5 3645.9 3653.1 3660.8 3667.5 6564.9 6594.1 6620.2 6625.7 6628.6 6991.8 7029.5 7047.2 7058.2 7074.8 6506.6 6583.2 6604.5 6609.5 6630.6 6811.1 6859.4 6868.8 6878.5 6880.9 6757.3 6773.4 6789.7 6797.7 6823.2 6562.7 6570.5 6580.6 6606.9 6625.6 5016.5 5022.4 5033.7 5064.5 5064.7 6894.4 6920.7 6938.5 6949.3 6950.7 6569.5 6596.4 6608.6 6618.3 6662.4 6609.6 6623.6 6628.9 6680.7 6685.3 6344.0 6403.4 6498.5 6511.8 6545.2 6347.7 6617.8 6652.8 6656.4 6660.0 6438.2 6443.9 6455.7 6465.4 6496.5 16:39:02 INFO - PID 12006 | 16:39:02 INFO - PID 12006 | [#5] yui.html Cycles:180 Average:24717.69 Median:19656.21 stddev:33954.03 (172.7%) stddev-sans-first:34002.24 16:39:02 INFO - PID 12006 | Values: 916.5 927.8 932.9 943.2 947.3 18842.6 19883.2 19895.3 19928.7 22453.0 21288.9 21386.5 21680.0 21792.0 21796.6 1035.7 1072.0 1073.4 1074.2 1077.4 1001.8 1033.8 1055.6 1060.6 1064.1 1097.9 1102.2 1103.1 1103.2 1104.1 1089.8 1096.8 1098.1 1101.3 1102.6 801.4 805.1 806.6 808.8 810.3 17290.7 17336.0 17363.0 17575.9 20681.0 20585.5 20744.6 21182.2 21287.1 21294.7 17201.6 17309.7 17439.8 17598.6 17640.6 3639.7 3673.2 3673.3 3674.6 3675.7 130039.3 130215.3 132183.3 133248.0 136030.0 16394.5 16990.9 17041.4 17076.7 17112.3 31147.7 31492.0 31715.7 32006.0 32044.7 7340.4 7376.2 7744.9 8034.5 8489.0 4068.6 4175.1 4178.4 4181.8 4183.5 5249.7 5329.3 5333.6 5363.1 5377.0 1080.0 1099.3 1103.7 1107.1 1108.6 660.4 671.5 672.3 673.8 686.6 11691.8 11770.7 12095.2 12265.3 12395.9 685.9 687.3 688.6 703.3 712.3 5915.4 5926.4 5933.1 5935.6 6036.0 114405.4 115540.4 117492.0 117887.0 120144.0 101460.5 101679.5 102462.5 103594.5 104754.0 82863.0 88232.0 89137.0 91021.5 91221.0 11935.6 12043.9 12045.6 12197.2 12789.0 40344.6 40765.0 40807.4 41280.2 41447.4 74385.6 76131.0 76864.6 78274.0 80162.0 19109.8 19907.9 19946.1 20003.4 20260.9 22597.2 22754.7 22775.4 22834.4 23215.1 18053.0 18281.9 18627.6 18712.3 18964.3 18321.5 18738.9 18797.5 19268.3 19429.2 17988.5 18397.5 18440.4 18500.0 18604.1 11361.3 11645.1 11650.5 11920.6 12208.1 13987.9 14022.6 14123.2 14398.7 14437.3 16:39:02 INFO - PID 12006 | -------- Summary: end -------- 16:39:02 INFO - PID 12006 | 16:39:02 INFO - TEST-INFO | 12006: exit 0 16:39:02 INFO - TEST-OK | dromaeo_css | took 1112842ms 16:39:02 INFO - TEST-START | kraken 16:39:02 INFO - Initialising browser for kraken test... 16:39:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39842/getInfo.html -profile /tmp/tmp62A8Ci/profile 16:39:02 INFO - TEST-INFO | started process 12385 (/builds/slave/test/build/application/firefox/firefox http://localhost:39842/getInfo.html) 16:39:08 INFO - TEST-INFO | 12385: exit 0 16:39:08 INFO - Browser initialized. 16:39:08 INFO - Running cycle 1/1 for kraken test... 16:39:08 INFO - TEST-INFO | started process 12621 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp62A8Ci/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:39:09 INFO - PID 12621 | 16:39:09 INFO - PID 12621 | (/builds/slave/test/build/application/firefox/firefox:12675): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:39:09 INFO - PID 12621 | 16:39:20 INFO - PID 12621 | RSS: Main: 167845888 16:39:20 INFO - PID 12621 | 16:39:22 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39842/tests/kraken/driver.html?testName=audio-beat-detection) 16:39:23 INFO - PID 12621 | RSS: Main: 172982272 16:39:23 INFO - PID 12621 | 16:39:26 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39842/tests/kraken/driver.html?testName=audio-dft) 16:39:27 INFO - PID 12621 | RSS: Main: 174587904 16:39:27 INFO - PID 12621 | 16:39:30 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39842/tests/kraken/driver.html?testName=audio-fft) 16:39:30 INFO - PID 12621 | RSS: Main: 169230336 16:39:30 INFO - PID 12621 | 16:39:33 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39842/tests/kraken/driver.html?testName=audio-oscillator) 16:39:33 INFO - PID 12621 | RSS: Main: 169693184 16:39:33 INFO - PID 12621 | 16:39:35 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39842/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:39:36 INFO - PID 12621 | RSS: Main: 170377216 16:39:36 INFO - PID 12621 | 16:39:39 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39842/tests/kraken/driver.html?testName=imaging-darkroom) 16:39:40 INFO - PID 12621 | RSS: Main: 173293568 16:39:40 INFO - PID 12621 | 16:39:43 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39842/tests/kraken/driver.html?testName=imaging-desaturate) 16:39:44 INFO - PID 12621 | RSS: Main: 172490752 16:39:44 INFO - PID 12621 | 16:39:47 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39842/tests/kraken/driver.html?testName=json-parse-financial) 16:39:47 INFO - PID 12621 | RSS: Main: 173068288 16:39:47 INFO - PID 12621 | 16:39:49 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39842/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:39:50 INFO - PID 12621 | RSS: Main: 173400064 16:39:50 INFO - PID 12621 | 16:39:52 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39842/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:39:52 INFO - PID 12621 | RSS: Main: 173731840 16:39:52 INFO - PID 12621 | 16:39:54 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39842/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:39:55 INFO - PID 12621 | RSS: Main: 173801472 16:39:55 INFO - PID 12621 | 16:39:58 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39842/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:39:59 INFO - PID 12621 | RSS: Main: 173903872 16:39:59 INFO - PID 12621 | 16:40:02 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39842/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:40:02 INFO - PID 12621 | RSS: Main: 174358528 16:40:02 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | Cycle 1(1): loaded http://localhost:39842/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39842/tests/kraken/driver.html?testName=ai-astar) 16:40:04 INFO - PID 12621 | RSS: Main: 175742976 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | __start_tp_report 16:40:04 INFO - PID 12621 | _x_x_mozilla_page_load 16:40:04 INFO - PID 12621 | _x_x_mozilla_page_load_details 16:40:04 INFO - PID 12621 | |i|pagename|runs| 16:40:04 INFO - PID 12621 | |0;ai-astar;98;107;102;111;110;115;107;99;107;114 16:40:04 INFO - PID 12621 | |1;audio-beat-detection;144;120;147;178;117;144;146;122;170;121 16:40:04 INFO - PID 12621 | |2;audio-dft;167;164;170;161;162;164;162;170;162;164 16:40:04 INFO - PID 12621 | |3;audio-fft;81;75;73;188;77;82;77;92;100;74 16:40:04 INFO - PID 12621 | |4;audio-oscillator;81;82;137;79;79;85;130;85;80;123 16:40:04 INFO - PID 12621 | |5;imaging-gaussian-blur;114;114;115;114;114;114;114;115;117;114 16:40:04 INFO - PID 12621 | |6;imaging-darkroom;149;151;151;150;150;154;158;150;152;151 16:40:04 INFO - PID 12621 | |7;imaging-desaturate;99;96;98;99;95;95;93;94;94;94 16:40:04 INFO - PID 12621 | |8;json-parse-financial;53;54;53;53;53;53;54;64;54;57 16:40:04 INFO - PID 12621 | |9;json-stringify-tinderbox;54;54;56;55;54;57;54;57;58;56 16:40:04 INFO - PID 12621 | |10;stanford-crypto-aes;83;76;77;76;77;79;78;92;82;77 16:40:04 INFO - PID 12621 | |11;stanford-crypto-ccm;130;133;119;132;132;130;122;126;120;160 16:40:04 INFO - PID 12621 | |12;stanford-crypto-pbkdf2;176;188;182;174;182;176;180;183;196;181 16:40:04 INFO - PID 12621 | |13;stanford-crypto-sha256-iterative;69;67;68;69;70;67;73;68;70;66 16:40:04 INFO - PID 12621 | __end_tp_report 16:40:04 INFO - PID 12621 | __start_cc_report 16:40:04 INFO - PID 12621 | _x_x_mozilla_cycle_collect,954 16:40:04 INFO - PID 12621 | __end_cc_report 16:40:04 INFO - PID 12621 | __startTimestamp1502581204926__endTimestamp 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | ------- Summary: start ------- 16:40:04 INFO - PID 12621 | Number of tests: 14 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#0] ai-astar Cycles:10 Average:107.00 Median:112.50 stddev:5.85 (5.2%) stddev-sans-first:5.22 16:40:04 INFO - PID 12621 | Values: 98.0 107.0 102.0 111.0 110.0 115.0 107.0 99.0 107.0 114.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#1] audio-beat-detection Cycles:10 Average:140.90 Median:145.00 stddev:21.26 (14.7%) stddev-sans-first:22.52 16:40:04 INFO - PID 12621 | Values: 144.0 120.0 147.0 178.0 117.0 144.0 146.0 122.0 170.0 121.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#2] audio-dft Cycles:10 Average:164.60 Median:164.00 stddev:3.31 (2.0%) stddev-sans-first:3.39 16:40:04 INFO - PID 12621 | Values: 167.0 164.0 170.0 161.0 162.0 164.0 162.0 170.0 162.0 164.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#3] audio-fft Cycles:10 Average:91.90 Median:77.00 stddev:34.84 (45.2%) stddev-sans-first:36.73 16:40:04 INFO - PID 12621 | Values: 81.0 75.0 73.0 188.0 77.0 82.0 77.0 92.0 100.0 74.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:80.50 stddev:23.72 (29.5%) stddev-sans-first:24.52 16:40:04 INFO - PID 12621 | Values: 81.0 82.0 137.0 79.0 79.0 85.0 130.0 85.0 80.0 123.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:114.00 stddev:0.97 (0.9%) stddev-sans-first:1.01 16:40:04 INFO - PID 12621 | Values: 114.0 114.0 115.0 114.0 114.0 114.0 114.0 115.0 117.0 114.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#6] imaging-darkroom Cycles:10 Average:151.60 Median:151.00 stddev:2.63 (1.7%) stddev-sans-first:2.62 16:40:04 INFO - PID 12621 | Values: 149.0 151.0 151.0 150.0 150.0 154.0 158.0 150.0 152.0 151.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:95.50 stddev:2.21 (2.3%) stddev-sans-first:2.00 16:40:04 INFO - PID 12621 | Values: 99.0 96.0 98.0 99.0 95.0 95.0 93.0 94.0 94.0 94.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:54.00 stddev:3.46 (6.4%) stddev-sans-first:3.61 16:40:04 INFO - PID 12621 | Values: 53.0 54.0 53.0 53.0 53.0 53.0 54.0 64.0 54.0 57.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#9] json-stringify-tinderbox Cycles:10 Average:55.50 Median:56.00 stddev:1.51 (2.7%) stddev-sans-first:1.50 16:40:04 INFO - PID 12621 | Values: 54.0 54.0 56.0 55.0 54.0 57.0 54.0 57.0 58.0 56.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:78.50 stddev:4.95 (6.3%) stddev-sans-first:5.10 16:40:04 INFO - PID 12621 | Values: 83.0 76.0 77.0 76.0 77.0 79.0 78.0 92.0 82.0 77.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#11] stanford-crypto-ccm Cycles:10 Average:130.40 Median:131.00 stddev:11.63 (8.9%) stddev-sans-first:12.33 16:40:04 INFO - PID 12621 | Values: 130.0 133.0 119.0 132.0 132.0 130.0 122.0 126.0 120.0 160.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.80 Median:182.00 stddev:6.44 (3.5%) stddev-sans-first:6.48 16:40:04 INFO - PID 12621 | Values: 176.0 188.0 182.0 174.0 182.0 176.0 180.0 183.0 196.0 181.0 16:40:04 INFO - PID 12621 | 16:40:04 INFO - PID 12621 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:2.00 (2.9%) stddev-sans-first:2.12 16:40:04 INFO - PID 12621 | Values: 69.0 67.0 68.0 69.0 70.0 67.0 73.0 68.0 70.0 66.0 16:40:04 INFO - PID 12621 | -------- Summary: end -------- 16:40:04 INFO - PID 12621 | 16:40:05 INFO - PID 12621 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:40:05 INFO - TEST-INFO | 12621: exit 0 16:40:05 INFO - TEST-OK | kraken | took 62874ms 16:40:05 INFO - SUITE-END | took 1175s 16:40:06 INFO - Completed test suite (00:19:36) 16:40:06 INFO - javascript benchmark 16:40:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8651.105651479762, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3128.9674407445546, 3151.4800057990433, 3158.5420114083454, 3159.573457583226, 3194.7923384979977, 6112.174856394391, 6140.508759299257, 6179.505639548836, 6275.905861412071, 6335.873282534347, 6205.348438413969, 6250.843728906768, 6296.842578935518, 6299.055141728743, 6355.904661430082, 5543.25166102575, 5611.0, 5698.059820129673, 5722.1416787482, 5773.3937936516695, 5649.46330098642, 5681.687507187103, 5731.254936858202, 5757.625754325976, 5764.827055188351, 5682.943170568283, 5712.742926568294, 5735.942640573603, 5741.511971482429, 5749.281339832521, 5530.0, 5663.915041274384, 5668.603197776158, 5746.540276777848, 5746.9712651436475, 5602.24369710088, 5616.157576363578, 5665.886682266373, 5680.6591604503865, 5683.488486036276, 6177.907331390033, 6185.938140618626, 6225.533085018642, 6245.781397651061, 6256.843578910518, 6689.531732778707, 6689.832754181136, 6703.966480167616, 6765.492588055916, 6774.593524388552, 6559.2128944526485, 6710.765123220664, 6737.292584278629, 6740.696668649923, 6858.10844590205, 6216.067589861512, 6216.7824126155365, 6232.127502149703, 6234.688265586702, 6246.0630905364105, 6745.494087943423, 6817.5568588041615, 6833.282505336868, 6842.247352791189, 6842.384185423236, 6610.10763546915, 6610.206775187008, 6621.635810030398, 6656.400923916871, 6671.099401580725, 6669.199696036507, 6699.698513566901, 6750.29121942189, 6777.49168812341, 6778.966105169541, 6499.2850786413455, 6658.533902626867, 6682.699278532477, 6741.0, 6756.0879281297375, 6631.734730610818, 6640.568363056386, 6725.865482690417, 6730.663466826639, 6745.392914637706, 5429.4027656957705, 5450.373207081237, 5455.1271796512365, 5457.426970168154, 5467.289252397163, 4614.792719060221, 4654.976725116353, 4684.484706682262, 4685.906281874411, 4701.882452938704, 5751.194832723422, 5764.510016648632, 5827.096799996008, 5829.8251052468495, 5849.298084229919, 6570.967145164243, 6659.101021362151, 6690.732370705117, 6727.596344219362, 6791.93208067913, 5753.33836608787, 5755.453231942959, 5763.8559036024435, 5767.192593036978, 5783.450572195635, 5875.500582450454, 5891.293044834561, 5922.3485416604135, 5959.880802384015, 5972.104184372292, 6377.489800815923, 6392.360763923571, 6479.2224933006455, 6489.286178520359, 6514.088027676129, 6633.6019838809825, 6650.767223147167, 6672.5329226953645, 6733.797986060426, 6735.225449073223, 6484.157059582224, 6505.902411463737, 6507.967460162668, 6521.576097553644, 6522.249941256816, 6601.570897891622, 6677.065210870579, 6686.030525573668, 6701.32986701326, 6705.1283333166375, 6773.119494465688, 6788.558743681645, 6839.350261725128, 6862.519623626298, 6866.896996544956, 6485.092087107714, 6557.082008518906, 6581.835454113685, 6604.867902642017, 6607.0, 6355.110284560073, 6469.644169570719, 6478.190226221723, 6557.049227861838, 6563.737450501926, 6502.382273683992, 6514.3811337922825, 6521.054447105238, 6522.771702990372, 6524.575902566318, 6563.7702680405955, 6566.9014964774615, 6570.047343135314, 6647.335266473314, 6647.734090636321, 6360.618362898241, 6364.6181229126405, 6442.516811239082, 6450.967745161244, 6458.289588145206], "value": 6112.966312272553, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.4385108498506, 862.7239283429303, 884.7920738626424, 1150.1029197226162, 1172.9120315976304, 30080.849595752017, 30494.39011219777, 39536.802315988454, 39895.0, 39990.20019599606, 40338.394924076165, 40643.79678101613, 40715.796421017854, 40741.0, 40820.79589602055, 7166.534175278616, 7542.05724284463, 7659.885101723478, 7681.9615901920415, 7701.0758708955, 7456.7762967111075, 7561.848763024736, 7565.205653406396, 7571.280728330799, 7572.242775722411, 7539.132999705022, 7555.508891922035, 7577.090749110114, 7593.696252149907, 7594.8481030379635, 7519.2104828992715, 7533.623318834037, 7557.58433286169, 7559.5464272143845, 7565.848683026336, 7480.214577469396, 7513.774586762406, 7529.435292353095, 7530.472866899319, 7542.396608271325, 36687.816560917025, 36817.26365472702, 36891.81554092239, 36951.63048369536, 37041.444378334345, 3590.0486371111674, 3609.530761001053, 3613.5663720353728, 3617.330793803155, 3634.636536346344, 29818.0, 29876.70123298761, 29917.25206869824, 29977.100686979426, 30093.699063009528, 5006.499350065001, 5033.84898453047, 5091.261767043795, 5093.490650934914, 5114.616403769695, 191711.0, 193309.0, 194273.0, 194425.02787486115, 194797.0, 49080.26379604309, 49142.508574913794, 49214.0, 49252.26121608179, 49425.25862112071, 49788.0, 50222.0, 50225.0, 50286.74856625694, 50324.245136322985, 14830.036047656868, 15239.847601524065, 15272.160031198391, 15286.38854445832, 15362.692746145238, 11160.776784464266, 11337.0, 12465.813012804816, 12487.438065286902, 12679.0, 14072.788908166385, 14123.71752564943, 14158.079724817853, 14161.362738676784, 14170.504032358818, 5611.354694210137, 5665.0, 5741.282339707536, 5799.5650326225705, 5917.556183286185, 993.101243374747, 1009.4750729620556, 1009.7980403919246, 1009.9798004039878, 1028.3829702178782, 22716.318510444715, 22858.542829143324, 22990.310290691305, 23088.19191328329, 23110.884445577663, 1104.3318792130644, 1117.4692021289823, 1125.5216532973454, 1128.6275529075385, 1129.6498085593491, 12100.213486123374, 12224.816627750592, 15990.280437380346, 15993.920030400006, 16498.917505412395, 229737.0, 230973.0, 231131.0, 232034.0, 232606.0, 55779.721101395044, 56147.71926140343, 56148.157777633365, 56214.718926405105, 56289.0, 1428.6142741459748, 1458.762478212631, 1468.544751127154, 1482.384810303712, 1501.9774703379458, 26786.59820102661, 26892.0, 27235.72764272332, 27815.582766258925, 27840.0, 37483.81258093692, 37853.43219851759, 37918.620813792615, 37965.43051854169, 38011.809940950116, 204745.0, 205689.0, 206427.0, 206717.0, 207568.0, 33559.66440335565, 35791.64208357883, 37913.24173516569, 38016.239675206896, 38032.429513556766, 46969.0, 47214.527854722386, 47555.52444475511, 47833.28250076321, 47866.52133478621, 34339.82830085834, 34347.65652343544, 34449.48325775166, 34565.30869382648, 34972.47541286934, 33411.0, 33948.49077263794, 33952.0, 33995.980120596425, 34268.48597271093, 37329.25341493209, 37403.0, 37501.62498374981, 37698.62301376951, 37728.245435091696, 32944.505832413015, 33026.33947320992, 33229.83385083059, 33238.5014224782, 33599.0, 23632.29103126909, 24327.0, 24906.0, 25208.0, 25432.491350172524], "value": 17429.046259065526, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.7541481748028, 568.894754470423, 577.0968434400165, 579.6348300570639, 584.9298084229893, 21806.672899906494, 22598.88700556497, 22610.66084008741, 22679.0, 22843.200487982926, 25740.48519029623, 25900.74099259007, 26389.340266493346, 26391.604125938127, 26392.60411093835, 6381.298057213714, 6508.707108180143, 6512.185976752906, 6514.869702605945, 6516.706748196342, 5769.798057068004, 6234.127222188898, 6283.937160628404, 6294.402031806994, 6357.523185761063, 6253.49972002241, 6256.311805701369, 6269.467095296905, 6273.905891411609, 6278.968605156968, 6266.060090986343, 6275.466585340251, 6284.308726040178, 6287.245530536319, 6292.276388215345, 5844.883102337972, 5845.532357411397, 5847.561432892508, 5855.180274761537, 5862.912056319158, 29163.0, 29264.268393290124, 29826.10521684353, 29840.403191936257, 29973.100806975825, 1941.0585865855048, 1959.6276707425543, 1961.6370971370343, 1962.2445358536922, 1967.0558132096673, 18817.62364752711, 19675.704864427044, 19729.40811775649, 19743.506412339662, 19776.505587360287, 2394.928152155438, 2462.3105530451503, 2475.8390704604144, 2485.192312498445, 2508.2099138771227, 130694.0, 133339.0, 134147.0, 134200.0, 134315.32842335824, 13890.930545347208, 14767.48313809033, 14948.626284342978, 14987.625309367135, 15306.923465382824, 22955.770442295696, 23506.88246558756, 24103.638445423334, 24161.516769664508, 24203.51592968166, 9559.522023898917, 9619.615215391446, 9652.710418687451, 9694.0, 9725.173360264314, 8544.102869198645, 8575.656973720981, 8642.913570864212, 8677.6528938843, 8729.170728780755, 9075.50084745332, 9340.392874463138, 9346.813063738688, 9520.333576649706, 9621.759456013515, 2588.171785028773, 2640.6435131257035, 2753.2703833484143, 2791.260316016257, 2808.9297767555568, 954.4416516337881, 979.8432250839832, 993.6025589764008, 999.4503023337136, 1010.0455069958965, 19976.90011549933, 20134.19463221455, 20236.0, 20641.483962900747, 20730.378088657064, 1033.49358814182, 1038.2835843268122, 1046.2310202001522, 1047.669983955067, 1048.0829274384914, 11258.774824503627, 11266.54933802655, 11301.0, 11306.547738090549, 11444.54221831134, 466268.0, 471263.0, 480846.0, 484638.0, 491452.0, 118638.0, 119803.0, 120824.0, 120902.0, 121807.0, 1192.3442106841203, 1213.7026428525069, 1223.7674841780033, 1228.3858070964518, 1234.3581337704343, 17925.910370448066, 18021.819781802013, 21305.25431609886, 22590.435239118495, 22942.42643933915, 51324.48675513197, 52605.21092183544, 58787.70606146942, 61312.0, 63603.6819815898, 71746.64126679333, 75353.24646753463, 77195.2280477188, 77386.61306693431, 77679.0, 21237.68143477818, 21829.235976740736, 21991.120355185612, 22126.77873221247, 22466.663000054687, 23872.88063559671, 24424.26727198187, 24507.0, 24733.50532989294, 24812.751872481043, 18772.3429679966, 19003.61992760165, 19136.712949305493, 19395.903020485373, 19408.320708775125, 16178.191090445665, 16648.0, 16773.077480738677, 17229.827701723323, 17832.55418614545, 19649.90175049173, 19842.900785495982, 19981.700274495604, 20045.699314510002, 20248.89875550613, 21101.261455848973, 21364.465888352915, 21406.25078122258, 21520.784792151877, 21963.23128690552, 19473.123709433392, 19535.12091955894, 19549.315773947845, 19652.50868728293, 19692.11385487628], "value": 12333.937366957658, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.3774463746677, 1378.2901805570127, 1398.9090709103907, 1410.1468611490047, 1421.1970236816192, 12128.939355303222, 12716.809247861278, 12803.167794093377, 14366.640833979129, 15032.774508382383, 14529.128252304869, 14767.040142390737, 14780.408783648669, 14781.408743650241, 14801.851981480182, 2552.4512229870575, 2656.4023094803706, 2709.254954887402, 2711.769499592537, 2714.4978179036843, 2664.214056853223, 2683.449892771987, 2694.5015172193207, 2694.91915242543, 2698.892044318225, 2693.4747724193694, 2697.1908427471776, 2711.8372897626205, 2720.3063218879256, 2728.5361488546896, 2524.684414448194, 2532.189699296226, 2540.745925407454, 2542.4152444937677, 2563.858987755673, 2562.3466016165944, 2574.691037075544, 2579.7549232822853, 2591.403977085262, 2592.8444293342272, 14254.786178207336, 14332.49836255726, 14380.35288412024, 14421.639459013504, 14427.567172984827, 7288.4169266459085, 7591.089069311718, 7823.178566250519, 7861.68553257869, 7868.25251601097, 5368.194770784375, 5463.31708536433, 5581.274434323512, 5595.328560572757, 5615.943840561583, 1911.2737159879296, 1928.3250862198258, 1946.6496030714466, 1989.532459871929, 1997.6703843865744, 24718.13486527963, 24858.37854053645, 24897.128600498894, 24899.004039838386, 24946.0, 6364.1408409865, 6457.935420645827, 6669.566478178903, 6691.464682825363, 6694.631795251211, 16471.176441177893, 16613.41853035138, 16628.58428539296, 16633.50099497017, 16655.167241637868, 7472.215417381205, 7684.961575192088, 7688.730894418781, 7689.615519224017, 7689.692412303557, 6845.863082738318, 7001.0, 7039.507234493536, 7103.715851365888, 7132.322429369202, 5130.7947682093045, 5276.894462110737, 5284.154535274414, 5308.256844041837, 5332.040232758063, 1507.9019863708825, 1525.832737955465, 1537.3158944269917, 1540.8151021877447, 1549.1789351643647, 872.1148034744739, 887.8535041718173, 901.3510272603714, 912.5710915869531, 912.749533139603, 7462.4776265660885, 7470.589117598584, 7483.70065197386, 7484.663190156456, 7521.811954701177, 702.3362922038624, 729.3326606155415, 736.3777607921298, 736.5580651608992, 738.084774879146, 5614.266183676055, 5681.318241811009, 5702.942970570241, 5854.443827836434, 5945.702714864239, 20337.49156271105, 20550.28074017402, 20845.374638760863, 20876.895615521826, 20902.895485522477, 20086.0, 20157.395278141677, 20704.48238794042, 20786.792132078786, 20921.267755628785, 10109.64616238428, 10849.511771970281, 11045.28205666629, 11071.173256412601, 11126.38804865724, 8816.559172041243, 8844.46933184011, 8854.158854908774, 8863.556822158995, 8963.641454342014, 17432.91283543574, 17748.822511775234, 17754.201060952546, 18150.546236343667, 18561.628767424307, 17618.38335658297, 18110.09449527492, 18181.09094545241, 18206.544836379195, 18309.54226144357, 11831.17181797266, 12110.455029523517, 12112.697182570506, 12112.878871211175, 12116.27302361861, 13054.608361749162, 13061.477540898637, 13192.934035330145, 13193.934030329787, 13231.404586793426, 11130.499127539442, 11137.0, 11233.0, 11270.380129092979, 11501.597444089417, 10431.321964072311, 10438.947805260925, 10586.735331616772, 10613.204009699459, 10688.89311106879, 9600.135987761136, 9688.079632434907, 9693.273004524548, 9699.854502182332, 9719.0, 7352.757408990309, 7602.6198690066385, 7665.118511371264, 7680.692772289046, 7691.730789422567, 7745.845083098419, 7767.0, 7861.960690196513, 7905.93269908555, 8002.479838810459], "value": 6515.349811663123, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.65164384930546, 498.7531172069826, 521.1531261699738, 528.1101344234962, 533.8024930775614, 6264.749410023605, 6323.304436511986, 6332.588381755183, 6334.80995570133, 6371.139896114023, 6147.0, 6162.383761623835, 6162.568620196588, 6190.535709821759, 6201.379862013797, 4880.341153944208, 4897.33885925399, 4917.901641967159, 4968.826091086813, 4969.378827646544, 4997.350344455234, 4998.7250701211415, 5000.799968001275, 5013.122703526887, 5033.245013248005, 5162.586993040566, 5181.378234611851, 5193.143131383317, 5197.558207552343, 5209.583233341343, 5159.38087429509, 5170.146925757245, 5174.327337446146, 5206.921896171561, 5221.738913054332, 4807.206810876201, 4827.106985207713, 4828.324034635154, 4843.15244832153, 4862.927056094161, 6214.0, 6222.906656400158, 6224.844378890519, 6229.888273739356, 6249.750089967608, 5997.490213331872, 6016.368281330441, 6040.667763273018, 6061.787837425669, 6080.96959515204, 6695.12963314766, 6709.899351509731, 6721.932780672179, 6730.596164230162, 6750.696218670171, 5304.9390121975375, 5309.256704061435, 5326.094563924142, 5328.333958255218, 5332.706701131437, 7033.68348424317, 7139.785806425815, 7143.785686429415, 7144.214136445039, 7147.42820574358, 5481.451854814566, 5481.588880833874, 5483.150111732688, 5507.531859791962, 5516.613837031449, 5563.388027316992, 5575.247341608912, 5584.162375643686, 5602.607817452739, 5605.383407825136, 6039.8792024159275, 6059.424354686298, 6075.058365953284, 6075.908861367083, 6081.726322315506, 6704.597724136567, 6706.0, 6710.6309152997055, 6768.458523318122, 6770.864582708318, 1845.385287873111, 1888.2447021191633, 1891.6311319292606, 1921.5780322561204, 1961.4507937777446, 1411.922344271075, 1433.8422773494908, 1436.633658417097, 1445.2051371745497, 1460.8466111058399, 5761.308642962871, 5776.42235776419, 5776.884462310731, 5786.189933409326, 5826.854328641733, 6698.12924319845, 6715.56348837324, 6733.350329169287, 6744.62904540255, 6891.620960847202, 5764.567657425626, 5771.0, 5811.506021988178, 5835.252276507636, 5856.657337065208, 3612.458131280329, 3645.927081458356, 3653.1415117447377, 3660.762050466711, 3667.5232219811255, 6564.934350656527, 6594.087714562819, 6620.205575330895, 6625.674865026917, 6628.602283862983, 6991.762635956547, 7029.5430796998035, 7047.238190452387, 7058.188308344504, 7074.787756367317, 6506.60960342381, 6583.210014798159, 6604.537682362188, 6609.4712423005085, 6630.602163870183, 6811.148606424197, 6859.382655561023, 6868.793936181923, 6878.518503704692, 6880.931190688229, 6757.3242675732035, 6773.356531129535, 6789.728410863708, 6797.72809087631, 6823.249442561215, 6562.671866406756, 6570.5400621957915, 6580.605163690194, 6606.867862642816, 6625.569337993015, 5016.54851063406, 5022.422421421583, 5033.748312584429, 5064.518870707277, 5064.696118232918, 6894.3795058442975, 6920.688569014507, 6938.5143039986715, 6949.270326615632, 6950.7174547343375, 6569.507286953402, 6596.406323430914, 6608.636524991171, 6618.312330355729, 6662.367075127855, 6609.570377925419, 6623.569467984566, 6628.867422651617, 6680.6993685283305, 6685.298043705341, 6344.0, 6403.391677790602, 6498.512611554058, 6511.772087976898, 6545.181852268466, 6347.699670774955, 6617.7683781067435, 6652.83367915806, 6656.434203092791, 6660.001999400258, 6438.195225596801, 6443.903341449975, 6455.709493072729, 6465.3534646536855, 6496.480281577368], "value": 5222.69317197222, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [916.4730280088951, 927.8144371125775, 932.8927173375064, 943.221841980366, 947.2658689515622, 18842.62314753704, 19883.204671813128, 19895.30366437175, 19928.70106948397, 22453.0, 21288.935553222316, 21386.46533836655, 21680.0, 21792.0, 21796.564068718617, 1035.7048241251225, 1072.0244577434537, 1073.366713638953, 1074.1997212076974, 1077.4074259157471, 1001.8397056470966, 1033.7725700345911, 1055.6358056470544, 1060.5598676549223, 1064.09551880901, 1097.9011888929995, 1102.178876736832, 1103.0954617213847, 1103.2277405815921, 1104.1498046504187, 1089.8256278995323, 1096.780643871225, 1098.1324753444849, 1101.2731597145926, 1102.5865300512307, 801.3949468151508, 805.0661232969727, 806.5684858600679, 808.7654580171802, 810.2626609785067, 17290.654186916316, 17336.0, 17363.045032523205, 17575.912120439443, 20681.0, 20585.485362865897, 20744.5851082979, 21182.152713891424, 21287.14851405942, 21294.680579791315, 17201.56996075108, 17309.653806923918, 17439.825601743945, 17598.648027039388, 17640.647187056315, 3639.70882329413, 3673.1735359544155, 3673.2837096765875, 3674.5590529136407, 3675.724320675933, 130039.34980325038, 130215.34892325477, 132183.33908330396, 133248.0, 136030.0, 16394.508164755076, 16990.91504542494, 17041.40355087566, 17076.743848842278, 17112.315507379564, 31147.68852311493, 31492.0, 31715.682843171733, 32006.0, 32044.679553204634, 7340.37606803417, 7376.225496322916, 7744.92255077442, 8034.517928924166, 8489.023762267418, 4068.5524592294823, 4175.144095460423, 4178.435911152016, 4181.811818468176, 4183.49798024239, 5249.685018898802, 5329.280547126166, 5333.57331413486, 5363.061464243741, 5377.0, 1079.9622013229657, 1099.2525082943675, 1103.6688993301977, 1107.06453047175, 1108.5621179634, 660.4353277947382, 671.5232185148501, 672.3478226120656, 673.7574473189648, 686.6017709728445, 11691.824622630667, 11770.705732356588, 12095.21381110225, 12265.26408415498, 12395.876041239653, 685.8765422224047, 687.2920891481782, 688.6281408039603, 703.2756261051126, 712.262807993724, 5915.432410411245, 5926.377730338339, 5933.110033494924, 5935.614185077956, 6036.034234522455, 114405.42797286127, 115540.42229788797, 117492.0, 117887.0, 120144.0, 101460.49269753604, 101679.49160254152, 102462.48768756108, 103594.48202758937, 104754.0, 82863.0, 88231.99999999872, 89137.0, 91021.54489227511, 91221.0, 11935.582254621047, 12043.879561204276, 12045.63863084109, 12197.207181533173, 12789.0, 40344.596554034084, 40765.0, 40807.38788918228, 41280.1743965125, 41447.37828932508, 74385.62807185929, 76131.0, 76864.61567692125, 78274.0, 80162.0, 19109.808901911358, 19907.900460497603, 19946.102425390603, 20003.399898003085, 20260.89869550643, 22597.2090976815, 22754.65868012014, 22775.430614234778, 22834.42913927165, 23215.071397144602, 18053.0, 18281.908590456962, 18627.627447450704, 18712.25150993945, 18964.336248231244, 18321.541961451072, 18738.906305468387, 18797.530061748017, 19268.32560860363, 19429.222831086598, 17988.460346189113, 18397.54006149857, 18440.446786596425, 18500.0, 18604.069796509848, 11361.318320900773, 11645.126615503701, 11650.475728592402, 11920.642380728592, 12208.084393670333, 13987.860121399062, 14022.579322620337, 14123.223222722849, 14398.712025759636, 14437.278136092944], "value": 9374.410764808857, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1533.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 102.0, 111.0, 110.0, 115.0, 107.0, 99.0, 107.0, 114.0], "value": 107.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 120.0, 147.0, 178.0, 117.0, 144.0, 146.0, 122.0, 170.0, 121.0], "value": 140.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 170.0, 161.0, 162.0, 164.0, 162.0, 170.0, 162.0, 164.0], "value": 164.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 75.0, 73.0, 188.0, 77.0, 82.0, 77.0, 92.0, 100.0, 74.0], "value": 91.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 137.0, 79.0, 79.0, 85.0, 130.0, 85.0, 80.0, 123.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 115.0, 117.0, 114.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 151.0, 150.0, 150.0, 154.0, 158.0, 150.0, 152.0, 151.0], "value": 151.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 96.0, 98.0, 99.0, 95.0, 95.0, 93.0, 94.0, 94.0, 94.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 53.0, 53.0, 53.0, 53.0, 54.0, 64.0, 54.0, 57.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 55.0, 54.0, 57.0, 54.0, 57.0, 58.0, 56.0], "value": 55.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 76.0, 77.0, 76.0, 77.0, 79.0, 78.0, 92.0, 82.0, 77.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 133.0, 119.0, 132.0, 132.0, 130.0, 122.0, 126.0, 120.0, 160.0], "value": 130.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 188.0, 182.0, 174.0, 182.0, 176.0, 180.0, 183.0, 196.0, 181.0], "value": 181.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 69.0, 70.0, 67.0, 73.0, 68.0, 70.0, 66.0], "value": 68.7, "unit": "score"}]}]} 16:40:06 INFO - Return code: 0 16:40:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:40:06 INFO - # TBPL SUCCESS # 16:40:06 INFO - Running post-action listener: _package_coverage_data 16:40:06 INFO - Running post-action listener: _resource_record_post_action 16:40:06 INFO - [mozharness: 2017-08-12 23:40:06.191050Z] Finished run-tests step (success) 16:40:06 INFO - Running post-run listener: _resource_record_post_run 16:40:06 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 8192; Write bytes: 436736000; Read time: 36; Write time: 346520 16:40:06 INFO - TinderboxPrint: CPU usage
13.2% 16:40:06 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 36 16:40:06 INFO - TinderboxPrint: I/O write bytes / time
436,736,000 / 346,520 16:40:06 INFO - TinderboxPrint: CPU idle
8,266.4 (86.8%) 16:40:06 INFO - TinderboxPrint: CPU user
1,202.8 (12.6%) 16:40:06 INFO - TinderboxPrint: Swap in / out
0 / 0 16:40:06 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 112803840; Read time: 0; Write time: 90556 16:40:06 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:40:06 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 323932160; Read time: 24; Write time: 255964 16:40:06 INFO - Running post-run listener: _upload_blobber_files 16:40:06 INFO - Blob upload gear active. 16:40:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:40:06 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 16:40:06 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'] 16:40:06 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 16:40:07 INFO - (blobuploader) - INFO - Open directory for files ... 16:40:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:40:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:40:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:40:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:40:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:40:08 INFO - (blobuploader) - INFO - Done attempting. 16:40:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:40:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:40:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:40:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:40:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:40:11 INFO - (blobuploader) - INFO - Done attempting. 16:40:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:40:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:40:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:40:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:40:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:40:12 INFO - (blobuploader) - INFO - Done attempting. 16:40:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:40:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:40:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:40:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:40:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:40:13 INFO - (blobuploader) - INFO - Done attempting. 16:40:13 INFO - (blobuploader) - INFO - Iteration through files over. 16:40:13 INFO - Return code: 0 16:40:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:40:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:40:13 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/669cbb57beff9e0032471dc8afe622d20f4a4132759be6dfe0289e50d103066363507765a0741a16e830486acafebc923cb534edb5a918dcdb6ff0dfd8395bff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f665439d9fa82f4d57544321282b2bb484e01665b9b019666e7911c017e5d167785c9eb04fd9f6bc05fd1d7d742d9e2b8fb8872ead670a9022aa990c61798be", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da2b1d2b3c66638e5c45bc4f7194d4b03a1aadc209618cc796c435d1f8a7e6a859a7052410fbc97d0624501e912ad34c981c169732f36dd5e1338ee76eacaf4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f61b38aeeb4826e0a38e1cb9fab775d0b0f408cb4457d5d6accef267fcd62e4eafa99f7c9d8f246631564ec465889e38bfbde65382435aeaa9548a683f2bcb5"} 16:40:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:40:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:40:13 INFO - Contents: 16:40:13 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/669cbb57beff9e0032471dc8afe622d20f4a4132759be6dfe0289e50d103066363507765a0741a16e830486acafebc923cb534edb5a918dcdb6ff0dfd8395bff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f665439d9fa82f4d57544321282b2bb484e01665b9b019666e7911c017e5d167785c9eb04fd9f6bc05fd1d7d742d9e2b8fb8872ead670a9022aa990c61798be", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da2b1d2b3c66638e5c45bc4f7194d4b03a1aadc209618cc796c435d1f8a7e6a859a7052410fbc97d0624501e912ad34c981c169732f36dd5e1338ee76eacaf4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f61b38aeeb4826e0a38e1cb9fab775d0b0f408cb4457d5d6accef267fcd62e4eafa99f7c9d8f246631564ec465889e38bfbde65382435aeaa9548a683f2bcb5"} 16:40:13 INFO - Running post-run listener: copy_logs_to_upload_dir 16:40:13 INFO - Copying logs to upload dir... 16:40:13 INFO - mkdir: /builds/slave/test/build/upload/logs 16:40:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.514775 ========= master_lag: 0.38 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-08-12 16:40:13.003217) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 16:40:13.006252) ========= 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=20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426 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/669cbb57beff9e0032471dc8afe622d20f4a4132759be6dfe0289e50d103066363507765a0741a16e830486acafebc923cb534edb5a918dcdb6ff0dfd8395bff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f665439d9fa82f4d57544321282b2bb484e01665b9b019666e7911c017e5d167785c9eb04fd9f6bc05fd1d7d742d9e2b8fb8872ead670a9022aa990c61798be", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da2b1d2b3c66638e5c45bc4f7194d4b03a1aadc209618cc796c435d1f8a7e6a859a7052410fbc97d0624501e912ad34c981c169732f36dd5e1338ee76eacaf4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f61b38aeeb4826e0a38e1cb9fab775d0b0f408cb4457d5d6accef267fcd62e4eafa99f7c9d8f246631564ec465889e38bfbde65382435aeaa9548a683f2bcb5"} build_url:https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011156 build_url: 'https://queue.taskcluster.net/v1/task/eRGUWGbpQu2kTLRetgvcig/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/669cbb57beff9e0032471dc8afe622d20f4a4132759be6dfe0289e50d103066363507765a0741a16e830486acafebc923cb534edb5a918dcdb6ff0dfd8395bff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f665439d9fa82f4d57544321282b2bb484e01665b9b019666e7911c017e5d167785c9eb04fd9f6bc05fd1d7d742d9e2b8fb8872ead670a9022aa990c61798be", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da2b1d2b3c66638e5c45bc4f7194d4b03a1aadc209618cc796c435d1f8a7e6a859a7052410fbc97d0624501e912ad34c981c169732f36dd5e1338ee76eacaf4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f61b38aeeb4826e0a38e1cb9fab775d0b0f408cb4457d5d6accef267fcd62e4eafa99f7c9d8f246631564ec465889e38bfbde65382435aeaa9548a683f2bcb5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 16:40:13.030355) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 16:40:13.030646) ========= 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=20e20d796406ba5117c4a6f1000001ba-1502574538.222090-1043517426 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 16:40:13.119831) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 16:40:13.120142) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 16:40:13.120444) ========= ========= Total master_lag: 0.54 =========