builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-010 starttime: 1502416000.05 results: success (0) revision: bb88ccb10454a78b8fa4a7d6c45bdf6fc47de3cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:40.048144) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:40.048552) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:40.048868) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:40.085850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:40.086281) ========= 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=eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-10 18:46:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.8M=0s 2017-08-10 18:46:40 (45.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.317721 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:40.424159) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:40.424484) ========= 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=eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017561 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:40.473765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:40.477113) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bb88ccb10454a78b8fa4a7d6c45bdf6fc47de3cd --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 bb88ccb10454a78b8fa4a7d6c45bdf6fc47de3cd --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=eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-10 18:46:40,926 truncating revision to first 12 chars 2017-08-10 18:46:40,926 Setting DEBUG logging. 2017-08-10 18:46:40,926 attempt 1/10 2017-08-10 18:46:40,926 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bb88ccb10454?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-10 18:46:41,353 unpacking tar archive at: mozilla-central-bb88ccb10454/testing/mozharness/ program finished with exit code 0 elapsedTime=0.779851 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:41.273084) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:41.273524) ========= script_repo_revision: bb88ccb10454a78b8fa4a7d6c45bdf6fc47de3cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:41.273961) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:41.274240) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-10 18:46:41.284037) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-10 18:46:41.284448) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:46:41 INFO - MultiFileLogger online at 20170810 18:46:41 in /builds/slave/test 18:46:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:46:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:46:41 INFO - {'append_to_log': False, 18:46:41 INFO - 'base_work_dir': '/builds/slave/test', 18:46:41 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:46:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:46:41 INFO - 'branch': 'Firefox-Non-PGO', 18:46:41 INFO - 'buildbot_json_path': 'buildprops.json', 18:46:41 INFO - 'code_coverage': False, 18:46:41 INFO - 'config_files': ('talos/linux_config.py',), 18:46:41 INFO - 'default_actions': ('clobber', 18:46:41 INFO - 'read-buildbot-config', 18:46:41 INFO - 'download-and-extract', 18:46:41 INFO - 'populate-webroot', 18:46:41 INFO - 'create-virtualenv', 18:46:41 INFO - 'install', 18:46:41 INFO - 'setup-mitmproxy', 18:46:41 INFO - 'run-tests'), 18:46:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:46:41 INFO - 'disable_ccov_upload': False, 18:46:41 INFO - 'download_minidump_stackwalk': True, 18:46:41 INFO - 'download_symbols': 'ondemand', 18:46:41 INFO - 'enable_stylo': False, 18:46:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:46:41 INFO - 'tooltool.py': '/tools/tooltool.py', 18:46:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:46:41 INFO - '/tools/misc-python/virtualenv.py')}, 18:46:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:46:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:46:41 INFO - 'gecko_profile': False, 18:46:41 INFO - 'gecko_profile_interval': 0, 18:46:41 INFO - 'installer_path': 'installer.exe', 18:46:41 INFO - 'log_level': 'info', 18:46:41 INFO - 'log_name': 'talos', 18:46:41 INFO - 'log_to_console': True, 18:46:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:46:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:46:41 INFO - 'opt_config_files': (), 18:46:41 INFO - 'pip_index': False, 18:46:41 INFO - 'suite': 'dromaeojs-e10s', 18:46:41 INFO - 'system_bits': '32', 18:46:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:46:41 INFO - 'title': 'talos-linux64-ix-010', 18:46:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:46:41 INFO - 'use_talos_json': True, 18:46:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:46:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:46:41 INFO - 'work_dir': 'build'} 18:46:41 INFO - [mozharness: 2017-08-11 01:46:41.836971Z] Running clobber step. 18:46:41 INFO - Running pre-action listener: _resource_record_pre_action 18:46:41 INFO - Running main action method: clobber 18:46:41 INFO - rmtree: /builds/slave/test/build 18:46:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:46:43 INFO - Running post-action listener: _resource_record_post_action 18:46:43 INFO - [mozharness: 2017-08-11 01:46:43.366221Z] Finished clobber step (success) 18:46:43 INFO - [mozharness: 2017-08-11 01:46:43.366323Z] Running read-buildbot-config step. 18:46:43 INFO - Running pre-action listener: _resource_record_pre_action 18:46:43 INFO - Running main action method: read_buildbot_config 18:46:43 INFO - Using buildbot properties: 18:46:43 INFO - { 18:46:43 INFO - "project": "", 18:46:43 INFO - "product": "firefox", 18:46:43 INFO - "who": "ffxbld@noreply.mozilla.org", 18:46:43 INFO - "installer_path": "public/build/target.tar.bz2", 18:46:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:46:43 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:46:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:46:43 INFO - "stage_platform": "linux64", 18:46:43 INFO - "basedir": "/builds/slave/test", 18:46:43 INFO - "buildnumber": 767, 18:46:43 INFO - "platform": "ubuntu64_hw", 18:46:43 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:46:43 INFO - "slavebuilddir": "test", 18:46:43 INFO - "taskId": "Zp2OwxgfQvyuvuQNvgAhrw", 18:46:43 INFO - "branch": "mozilla-central", 18:46:43 INFO - "script_repo_revision": "production", 18:46:43 INFO - "revision": "bb88ccb10454a78b8fa4a7d6c45bdf6fc47de3cd", 18:46:43 INFO - "slavename": "talos-linux64-ix-010", 18:46:43 INFO - "repo_path": "mozilla-central" 18:46:43 INFO - } 18:46:43 INFO - Finding installer, test and symbols from parent task. 18:46:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zp2OwxgfQvyuvuQNvgAhrw'}, attempt #1 18:46:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zp2OwxgfQvyuvuQNvgAhrw'}, attempt #1 18:46:44 INFO - Task dependencies: FQylf4SMTjqgA4sl_IbrWA 18:46:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA'}, attempt #1 18:46:44 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.tar.bz2 18:46:44 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.test_packages.json 18:46:44 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.crashreporter-symbols.zip 18:46:44 INFO - Running post-action listener: _resource_record_post_action 18:46:44 INFO - [mozharness: 2017-08-11 01:46:44.695245Z] Finished read-buildbot-config step (success) 18:46:44 INFO - [mozharness: 2017-08-11 01:46:44.695465Z] Running download-and-extract step. 18:46:44 INFO - Running pre-action listener: _resource_record_pre_action 18:46:44 INFO - Running main action method: download_and_extract 18:46:44 INFO - mkdir: /builds/slave/test/build/tests 18:46:44 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')]} 18:46:44 INFO - trying https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.test_packages.json 18:46:44 INFO - Downloading https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:46:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:46:45 INFO - Downloaded 989 bytes. 18:46:45 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:46:45 INFO - Using the following test package requirements: 18:46:45 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:46:45 INFO - u'common': [u'target.common.tests.zip'], 18:46:45 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:46:45 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:46:45 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:46:45 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:46:45 INFO - u'mozbase': [u'target.common.tests.zip'], 18:46:45 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:46:45 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:46:45 INFO - u'web-platform': [u'target.common.tests.zip', 18:46:45 INFO - u'target.web-platform.tests.tar.gz'], 18:46:45 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:46:45 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:46:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.common.tests.zip 18:46:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:46:45 INFO - Fetch https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.common.tests.zip into memory 18:46:46 INFO - Content-Length response header: 54343511 18:46:46 INFO - Bytes received: 54343511 18:46:57 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:46:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.common.tests.zip 18:46:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:46:57 INFO - Fetch https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.common.tests.zip into memory 18:46:58 INFO - Content-Length response header: 54343511 18:46:58 INFO - Bytes received: 54343511 18:47:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.talos.tests.zip 18:47:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:47:10 INFO - Fetch https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.talos.tests.zip into memory 18:47:11 INFO - Content-Length response header: 14496008 18:47:11 INFO - Bytes received: 14496008 18:47: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')]} 18:47:11 INFO - trying https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.tar.bz2 18:47:11 INFO - Downloading https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:47:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:47:12 INFO - Downloaded 61958491 bytes. 18:47:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.tar.bz2 18:47:12 INFO - mkdir: /builds/slave/test/properties 18:47:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:47:12 INFO - Writing to file /builds/slave/test/properties/build_url 18:47:12 INFO - Contents: 18:47:12 INFO - build_url:https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.tar.bz2 18:47:12 INFO - Running post-action listener: _resource_record_post_action 18:47:12 INFO - Running post-action listener: set_extra_try_arguments 18:47:12 INFO - [mozharness: 2017-08-11 01:47:12.567334Z] Finished download-and-extract step (success) 18:47:12 INFO - [mozharness: 2017-08-11 01:47:12.567432Z] Running populate-webroot step. 18:47:12 INFO - Running pre-action listener: _resource_record_pre_action 18:47:12 INFO - Running main action method: populate_webroot 18:47:12 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:47:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:47:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:47:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:47:12 INFO - 'tests': ['damp', 'tps']}, 18:47:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:47:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:47:12 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:47:12 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:47:12 INFO - 'other-e10s': {'tests': ['a11yr', 18:47:12 INFO - 'ts_paint', 18:47:12 INFO - 'tpaint', 18:47:12 INFO - 'sessionrestore', 18:47:12 INFO - 'sessionrestore_many_windows', 18:47:12 INFO - 'sessionrestore_no_auto_restore', 18:47:12 INFO - 'tabpaint']}, 18:47:12 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:47:12 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:47:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:47:12 INFO - 'tsvgr_opacity', 18:47:12 INFO - 'tart', 18:47:12 INFO - 'tscrollx', 18:47:12 INFO - 'tsvg_static']}, 18:47:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:47:12 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:47:12 INFO - 'talos_options': ['--mitmproxy', 18:47:12 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:47:12 INFO - '--firstNonBlankPaint'], 18:47:12 INFO - 'tests': ['tp6_google', 18:47:12 INFO - 'tp6_youtube', 18:47:12 INFO - 'tp6_amazon', 18:47:12 INFO - 'tp6_facebook']}, 18:47:12 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:47:12 INFO - 'talos_options': ['--stylo', 18:47:12 INFO - '--mitmproxy', 18:47:12 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:47:12 INFO - '--firstNonBlankPaint'], 18:47:12 INFO - 'tests': ['tp6_google', 18:47:12 INFO - 'tp6_youtube', 18:47:12 INFO - 'tp6_amazon', 18:47:12 INFO - 'tp6_facebook']}, 18:47:12 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:47:12 INFO - 'talos_options': ['--stylo', 18:47:12 INFO - '--stylo-threads=1', 18:47:12 INFO - '--mitmproxy', 18:47:12 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:47:12 INFO - '--firstNonBlankPaint'], 18:47:12 INFO - 'tests': ['tp6_google', 18:47:12 INFO - 'tp6_youtube', 18:47:12 INFO - 'tp6_amazon', 18:47:12 INFO - 'tp6_facebook']}, 18:47:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:47:12 INFO - 'talos_options': ['--xperf_path', 18:47:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:12 INFO - 'tests': ['tp5n']}}} 18:47:12 INFO - Running post-action listener: _resource_record_post_action 18:47:12 INFO - [mozharness: 2017-08-11 01:47:12.575756Z] Finished populate-webroot step (success) 18:47:12 INFO - [mozharness: 2017-08-11 01:47:12.575845Z] Running create-virtualenv step. 18:47:12 INFO - Running pre-action listener: _resource_record_pre_action 18:47:12 INFO - Running main action method: create_virtualenv 18:47:12 INFO - Creating virtualenv /builds/slave/test/build/venv 18:47:12 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 18:47:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:47:12 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:47:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:47:12 INFO - Using real prefix '/usr' 18:47:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:47:13 INFO - Installing distribute.............................................................................................................................................................................................done. 18:47:15 INFO - Installing pip.................done. 18:47:15 INFO - Return code: 0 18:47:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:47:15 INFO - Reading from file tmpfile_stdout 18:47:15 INFO - Output received: 18:47:15 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) 18:47:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:47:15 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')]} 18:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:15 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')]} 18:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1db71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2125b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2128f00>, '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 0x2123ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2124050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', '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 18:47:15 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 18:47:15 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 18:47:15 INFO - Using env: {'DISPLAY': ':0', 18:47:15 INFO - 'HOME': '/home/cltbld', 18:47:15 INFO - 'LANG': 'en_US.UTF-8', 18:47:15 INFO - 'LANGUAGE': 'en_US:en', 18:47:15 INFO - 'LOGNAME': 'cltbld', 18:47:15 INFO - 'MAIL': '/var/mail/cltbld', 18:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:15 INFO - 'MOZ_NO_REMOTE': '1', 18:47:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:15 INFO - 'NO_EM_RESTART': '1', 18:47:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:15 INFO - 'PWD': '/builds/slave/test', 18:47:15 INFO - 'SHELL': '/bin/bash', 18:47:15 INFO - 'SHLVL': '1', 18:47:15 INFO - 'TERM': 'linux', 18:47:15 INFO - 'TMOUT': '86400', 18:47:15 INFO - 'USER': 'cltbld', 18:47:15 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', 18:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:15 INFO - '_': '/tools/buildbot/bin/python'} 18:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:15 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 18:47:15 INFO - Cleaning up... 18:47:15 INFO - Return code: 0 18:47:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:47:15 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')]} 18:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:15 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')]} 18:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1db71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2125b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2128f00>, '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 0x2123ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2124050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', '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 18:47:15 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 18:47:15 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 18:47:15 INFO - Using env: {'DISPLAY': ':0', 18:47:15 INFO - 'HOME': '/home/cltbld', 18:47:15 INFO - 'LANG': 'en_US.UTF-8', 18:47:15 INFO - 'LANGUAGE': 'en_US:en', 18:47:15 INFO - 'LOGNAME': 'cltbld', 18:47:15 INFO - 'MAIL': '/var/mail/cltbld', 18:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:15 INFO - 'MOZ_NO_REMOTE': '1', 18:47:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:15 INFO - 'NO_EM_RESTART': '1', 18:47:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:15 INFO - 'PWD': '/builds/slave/test', 18:47:15 INFO - 'SHELL': '/bin/bash', 18:47:15 INFO - 'SHLVL': '1', 18:47:15 INFO - 'TERM': 'linux', 18:47:15 INFO - 'TMOUT': '86400', 18:47:15 INFO - 'USER': 'cltbld', 18:47:15 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', 18:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:15 INFO - '_': '/tools/buildbot/bin/python'} 18:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:15 INFO - Downloading/unpacking psutil>=3.1.1 18:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:47:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:47:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:47:17 INFO - Installing collected packages: psutil 18:47:17 INFO - Running setup.py install for psutil 18:47:17 INFO - building 'psutil._psutil_linux' extension 18:47:17 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 18:47:17 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 18:47:17 INFO - building 'psutil._psutil_posix' extension 18:47:17 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 18:47:17 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 18:47:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:47:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:47:17 INFO - Successfully installed psutil 18:47:17 INFO - Cleaning up... 18:47:17 INFO - Return code: 0 18:47:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:47:17 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')]} 18:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:17 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')]} 18:47:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:17 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 0x1db71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2125b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2128f00>, '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 0x2123ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2124050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', '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 18:47:17 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 18:47:17 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 18:47:17 INFO - Using env: {'DISPLAY': ':0', 18:47:17 INFO - 'HOME': '/home/cltbld', 18:47:17 INFO - 'LANG': 'en_US.UTF-8', 18:47:17 INFO - 'LANGUAGE': 'en_US:en', 18:47:17 INFO - 'LOGNAME': 'cltbld', 18:47:17 INFO - 'MAIL': '/var/mail/cltbld', 18:47:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:17 INFO - 'MOZ_NO_REMOTE': '1', 18:47:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:17 INFO - 'NO_EM_RESTART': '1', 18:47:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:17 INFO - 'PWD': '/builds/slave/test', 18:47:17 INFO - 'SHELL': '/bin/bash', 18:47:17 INFO - 'SHLVL': '1', 18:47:17 INFO - 'TERM': 'linux', 18:47:17 INFO - 'TMOUT': '86400', 18:47:17 INFO - 'USER': 'cltbld', 18:47:17 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', 18:47:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:17 INFO - '_': '/tools/buildbot/bin/python'} 18:47:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:47:17 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 18:47:17 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 18:47:17 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 18:47:17 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 18:47:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:47:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:47:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:47:18 INFO - Installing collected packages: mozsystemmonitor 18:47:18 INFO - Running setup.py install for mozsystemmonitor 18:47:19 INFO - Successfully installed mozsystemmonitor 18:47:19 INFO - Cleaning up... 18:47:19 INFO - Return code: 0 18:47:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:47:19 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')]} 18:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:19 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')]} 18:47:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1db71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2125b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2128f00>, '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 0x2123ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2124050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', '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 18:47:19 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 18:47:19 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 18:47:19 INFO - Using env: {'DISPLAY': ':0', 18:47:19 INFO - 'HOME': '/home/cltbld', 18:47:19 INFO - 'LANG': 'en_US.UTF-8', 18:47:19 INFO - 'LANGUAGE': 'en_US:en', 18:47:19 INFO - 'LOGNAME': 'cltbld', 18:47:19 INFO - 'MAIL': '/var/mail/cltbld', 18:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:19 INFO - 'MOZ_NO_REMOTE': '1', 18:47:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:19 INFO - 'NO_EM_RESTART': '1', 18:47:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:19 INFO - 'PWD': '/builds/slave/test', 18:47:19 INFO - 'SHELL': '/bin/bash', 18:47:19 INFO - 'SHLVL': '1', 18:47:19 INFO - 'TERM': 'linux', 18:47:19 INFO - 'TMOUT': '86400', 18:47:19 INFO - 'USER': 'cltbld', 18:47:19 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', 18:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:19 INFO - '_': '/tools/buildbot/bin/python'} 18:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:19 INFO - Downloading/unpacking jsonschema==2.5.1 18:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:47:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:20 INFO - Downloading functools32-3.2.3-2.tar.gz 18:47:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:47:20 INFO - warning: no files found matching '*.txt' 18:47:20 INFO - no previously-included directories found matching 'build' 18:47:20 INFO - no previously-included directories found matching 'dist' 18:47:20 INFO - no previously-included directories found matching '.git*' 18:47:20 INFO - Installing collected packages: jsonschema, functools32 18:47:20 INFO - Running setup.py install for functools32 18:47:20 INFO - warning: no files found matching '*.txt' 18:47:20 INFO - no previously-included directories found matching 'build' 18:47:20 INFO - no previously-included directories found matching 'dist' 18:47:20 INFO - no previously-included directories found matching '.git*' 18:47:20 INFO - Successfully installed jsonschema functools32 18:47:20 INFO - Cleaning up... 18:47:20 INFO - Return code: 0 18:47:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1db71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2125b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2128f00>, '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 0x2123ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2124050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', '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 18:47:20 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 18:47:20 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 18:47:20 INFO - Using env: {'DISPLAY': ':0', 18:47:20 INFO - 'HOME': '/home/cltbld', 18:47:20 INFO - 'LANG': 'en_US.UTF-8', 18:47:20 INFO - 'LANGUAGE': 'en_US:en', 18:47:20 INFO - 'LOGNAME': 'cltbld', 18:47:20 INFO - 'MAIL': '/var/mail/cltbld', 18:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:20 INFO - 'MOZ_NO_REMOTE': '1', 18:47:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:20 INFO - 'NO_EM_RESTART': '1', 18:47:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:20 INFO - 'PWD': '/builds/slave/test', 18:47:20 INFO - 'SHELL': '/bin/bash', 18:47:20 INFO - 'SHLVL': '1', 18:47:20 INFO - 'TERM': 'linux', 18:47:20 INFO - 'TMOUT': '86400', 18:47:20 INFO - 'USER': 'cltbld', 18:47:20 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', 18:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:20 INFO - '_': '/tools/buildbot/bin/python'} 18:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:47:20 INFO - Cleaning up... 18:47:20 INFO - Return code: 0 18:47:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1db71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2125b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2128f00>, '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 0x2123ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2124050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', '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 18:47:20 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 18:47:20 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 18:47:20 INFO - Using env: {'DISPLAY': ':0', 18:47:20 INFO - 'HOME': '/home/cltbld', 18:47:20 INFO - 'LANG': 'en_US.UTF-8', 18:47:20 INFO - 'LANGUAGE': 'en_US:en', 18:47:20 INFO - 'LOGNAME': 'cltbld', 18:47:20 INFO - 'MAIL': '/var/mail/cltbld', 18:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:20 INFO - 'MOZ_NO_REMOTE': '1', 18:47:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:20 INFO - 'NO_EM_RESTART': '1', 18:47:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:20 INFO - 'PWD': '/builds/slave/test', 18:47:20 INFO - 'SHELL': '/bin/bash', 18:47:20 INFO - 'SHLVL': '1', 18:47:20 INFO - 'TERM': 'linux', 18:47:20 INFO - 'TMOUT': '86400', 18:47:20 INFO - 'USER': 'cltbld', 18:47:20 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', 18:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:20 INFO - '_': '/tools/buildbot/bin/python'} 18:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:21 INFO - Downloading/unpacking blobuploader==1.2.4 18:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:22 INFO - Downloading blobuploader-1.2.4.tar.gz 18:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:47:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:47:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:22 INFO - Downloading docopt-0.6.1.tar.gz 18:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:47:22 INFO - Installing collected packages: blobuploader, requests, docopt 18:47:22 INFO - Running setup.py install for blobuploader 18:47:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:47:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:47:22 INFO - Running setup.py install for requests 18:47:23 INFO - Running setup.py install for docopt 18:47:23 INFO - Successfully installed blobuploader requests docopt 18:47:23 INFO - Cleaning up... 18:47:23 INFO - Return code: 0 18:47:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:23 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')]} 18:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:23 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')]} 18:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:23 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 0x1db71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2125b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2128f00>, '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 0x2123ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2124050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', '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 18:47:23 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 18:47:23 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 18:47:23 INFO - Using env: {'DISPLAY': ':0', 18:47:23 INFO - 'HOME': '/home/cltbld', 18:47:23 INFO - 'LANG': 'en_US.UTF-8', 18:47:23 INFO - 'LANGUAGE': 'en_US:en', 18:47:23 INFO - 'LOGNAME': 'cltbld', 18:47:23 INFO - 'MAIL': '/var/mail/cltbld', 18:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:23 INFO - 'MOZ_NO_REMOTE': '1', 18:47:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:23 INFO - 'NO_EM_RESTART': '1', 18:47:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:23 INFO - 'PWD': '/builds/slave/test', 18:47:23 INFO - 'SHELL': '/bin/bash', 18:47:23 INFO - 'SHLVL': '1', 18:47:23 INFO - 'TERM': 'linux', 18:47:23 INFO - 'TMOUT': '86400', 18:47:23 INFO - 'USER': 'cltbld', 18:47:23 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', 18:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:23 INFO - '_': '/tools/buildbot/bin/python'} 18:47:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:23 INFO - Running setup.py (path:/tmp/pip-y74PnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:23 INFO - Running setup.py (path:/tmp/pip-LpsvE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:23 INFO - Running setup.py (path:/tmp/pip-lK1l7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:23 INFO - Running setup.py (path:/tmp/pip-wxMyoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:23 INFO - Running setup.py (path:/tmp/pip-VVjC7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:24 INFO - Running setup.py (path:/tmp/pip-G9tGOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:24 INFO - Running setup.py (path:/tmp/pip-4VFjM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:24 INFO - Running setup.py (path:/tmp/pip-KWAMWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:24 INFO - Running setup.py (path:/tmp/pip-9GRTRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:24 INFO - Running setup.py (path:/tmp/pip-2wrVHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:24 INFO - Running setup.py (path:/tmp/pip-fRXbvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:24 INFO - Running setup.py (path:/tmp/pip-ZzhPso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:24 INFO - Running setup.py (path:/tmp/pip-lwnxhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:24 INFO - Running setup.py (path:/tmp/pip-QvgmzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:24 INFO - Running setup.py (path:/tmp/pip-wLqnr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:24 INFO - Running setup.py (path:/tmp/pip-7pyeKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:24 INFO - Running setup.py (path:/tmp/pip-2pKOpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:47:24 INFO - Running setup.py install for manifestparser 18:47:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:47:25 INFO - Running setup.py install for mozcrash 18:47:25 INFO - Running setup.py install for mozdebug 18:47:25 INFO - Running setup.py install for mozdevice 18:47:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:47:25 INFO - Running setup.py install for mozfile 18:47:25 INFO - Running setup.py install for mozhttpd 18:47:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:47:25 INFO - Running setup.py install for mozinfo 18:47:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:47:25 INFO - Running setup.py install for mozInstall 18:47:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:47:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:47:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:47:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:47:25 INFO - Running setup.py install for mozleak 18:47:25 INFO - Running setup.py install for mozlog 18:47:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:47:25 INFO - Running setup.py install for moznetwork 18:47:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:47:26 INFO - Running setup.py install for mozprocess 18:47:26 INFO - Running setup.py install for mozprofile 18:47:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:47:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:47:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:47:26 INFO - Running setup.py install for mozrunner 18:47:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:47:26 INFO - Running setup.py install for mozscreenshot 18:47:26 INFO - Running setup.py install for moztest 18:47:26 INFO - Running setup.py install for mozversion 18:47:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:47:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:47:26 INFO - Cleaning up... 18:47:26 INFO - Return code: 0 18:47:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47:26 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')]} 18:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:26 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')]} 18:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:26 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 0x1db71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2125b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2128f00>, '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 0x2123ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2124050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', '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 18:47:26 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 18:47:26 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 18:47:26 INFO - Using env: {'DISPLAY': ':0', 18:47:26 INFO - 'HOME': '/home/cltbld', 18:47:26 INFO - 'LANG': 'en_US.UTF-8', 18:47:26 INFO - 'LANGUAGE': 'en_US:en', 18:47:26 INFO - 'LOGNAME': 'cltbld', 18:47:26 INFO - 'MAIL': '/var/mail/cltbld', 18:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:26 INFO - 'MOZ_NO_REMOTE': '1', 18:47:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:26 INFO - 'NO_EM_RESTART': '1', 18:47:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:26 INFO - 'PWD': '/builds/slave/test', 18:47:26 INFO - 'SHELL': '/bin/bash', 18:47:26 INFO - 'SHLVL': '1', 18:47:26 INFO - 'TERM': 'linux', 18:47:26 INFO - 'TMOUT': '86400', 18:47:26 INFO - 'USER': 'cltbld', 18:47:26 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', 18:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:26 INFO - '_': '/tools/buildbot/bin/python'} 18:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:26 INFO - Running setup.py (path:/tmp/pip-V_vUuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47:26 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)) 18:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:26 INFO - Running setup.py (path:/tmp/pip-R1px_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:27 INFO - Running setup.py (path:/tmp/pip-TlsYsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:27 INFO - Running setup.py (path:/tmp/pip-Q0rh0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:27 INFO - Running setup.py (path:/tmp/pip-RQnlt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:27 INFO - Running setup.py (path:/tmp/pip-FeTQyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:27 INFO - Running setup.py (path:/tmp/pip-ihUStr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:27 INFO - Running setup.py (path:/tmp/pip-zdibC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:27 INFO - Running setup.py (path:/tmp/pip-ZnQZrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:27 INFO - Running setup.py (path:/tmp/pip-ywWGoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:27 INFO - Running setup.py (path:/tmp/pip-htR8w7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:27 INFO - Running setup.py (path:/tmp/pip-bF0qsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:27 INFO - Running setup.py (path:/tmp/pip-heUm91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:27 INFO - Running setup.py (path:/tmp/pip-XahKXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:27 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)) 18:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:27 INFO - Running setup.py (path:/tmp/pip-uzbQsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:28 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)) 18:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:28 INFO - Running setup.py (path:/tmp/pip-nXqhTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47:28 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)) 18:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:28 INFO - Running setup.py (path:/tmp/pip-NHbklT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47:28 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)) 18:47:28 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)) 18:47:28 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)) 18:47:28 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)) 18:47:28 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)) 18:47:28 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)) 18:47:28 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)) 18:47:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:47:28 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 18:47:28 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 18:47:28 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 18:47:28 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 18:47:29 INFO - Downloading blessings-1.6.tar.gz 18:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:47:29 INFO - Installing collected packages: blessings 18:47:29 INFO - Running setup.py install for blessings 18:47:29 INFO - Successfully installed blessings 18:47:29 INFO - Cleaning up... 18:47:29 INFO - Return code: 0 18:47:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:47:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:47:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:47:29 INFO - Reading from file tmpfile_stdout 18:47:29 INFO - Current package versions: 18:47:29 INFO - argparse == 1.2.1 18:47:29 INFO - blessings == 1.6 18:47:29 INFO - blobuploader == 1.2.4 18:47:29 INFO - docopt == 0.6.1 18:47:29 INFO - functools32 == 3.2.3-2 18:47:29 INFO - jsonschema == 2.5.1 18:47:29 INFO - manifestparser == 1.1 18:47:29 INFO - mozInstall == 1.13 18:47:29 INFO - mozcrash == 1.0 18:47:29 INFO - mozdebug == 0.1 18:47:29 INFO - mozdevice == 0.51 18:47:29 INFO - mozfile == 1.2 18:47:29 INFO - mozhttpd == 0.7 18:47:29 INFO - mozinfo == 0.10 18:47:29 INFO - mozleak == 0.1 18:47:29 INFO - mozlog == 3.5 18:47:29 INFO - moznetwork == 0.27 18:47:29 INFO - mozprocess == 0.25 18:47:29 INFO - mozprofile == 0.28 18:47:29 INFO - mozrunner == 6.13 18:47:29 INFO - mozscreenshot == 0.1 18:47:29 INFO - mozsystemmonitor == 0.3 18:47:29 INFO - moztest == 0.8 18:47:29 INFO - mozversion == 1.4 18:47:29 INFO - psutil == 3.1.1 18:47:29 INFO - requests == 1.2.3 18:47:29 INFO - wsgiref == 0.1.2 18:47:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47: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')]} 18:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47: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')]} 18:47:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:29 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 0x1db71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2125b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2128f00>, '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 0x2123ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2124050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', '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 18:47:29 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 18:47:29 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 18:47:29 INFO - Using env: {'DISPLAY': ':0', 18:47:29 INFO - 'HOME': '/home/cltbld', 18:47:29 INFO - 'LANG': 'en_US.UTF-8', 18:47:29 INFO - 'LANGUAGE': 'en_US:en', 18:47:29 INFO - 'LOGNAME': 'cltbld', 18:47:29 INFO - 'MAIL': '/var/mail/cltbld', 18:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:29 INFO - 'MOZ_NO_REMOTE': '1', 18:47:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:29 INFO - 'NO_EM_RESTART': '1', 18:47:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:29 INFO - 'PWD': '/builds/slave/test', 18:47:29 INFO - 'SHELL': '/bin/bash', 18:47:29 INFO - 'SHLVL': '1', 18:47:29 INFO - 'TERM': 'linux', 18:47:29 INFO - 'TMOUT': '86400', 18:47:29 INFO - 'USER': 'cltbld', 18:47:29 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', 18:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:29 INFO - '_': '/tools/buildbot/bin/python'} 18:47:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:29 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)) 18:47:29 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)) 18:47:29 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)) 18:47:29 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)) 18:47:29 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)) 18:47:29 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)) 18:47:29 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)) 18:47:29 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)) 18:47:29 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)) 18:47:29 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:47:31 INFO - Installing collected packages: simplejson 18:47:31 INFO - Running setup.py install for simplejson 18:47:31 INFO - building 'simplejson._speedups' extension 18:47:31 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 18:47:32 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 18:47:32 INFO - Successfully installed simplejson 18:47:32 INFO - Cleaning up... 18:47:32 INFO - Return code: 0 18:47:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:47:32 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')]} 18:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:32 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')]} 18:47:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:32 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 0x1db71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f84a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2125b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2128f00>, '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 0x2123ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2124050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', '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 18:47:32 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 18:47:32 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 18:47:32 INFO - Using env: {'DISPLAY': ':0', 18:47:32 INFO - 'HOME': '/home/cltbld', 18:47:32 INFO - 'LANG': 'en_US.UTF-8', 18:47:32 INFO - 'LANGUAGE': 'en_US:en', 18:47:32 INFO - 'LOGNAME': 'cltbld', 18:47:32 INFO - 'MAIL': '/var/mail/cltbld', 18:47:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:32 INFO - 'MOZ_NO_REMOTE': '1', 18:47:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:32 INFO - 'NO_EM_RESTART': '1', 18:47:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:32 INFO - 'PWD': '/builds/slave/test', 18:47:32 INFO - 'SHELL': '/bin/bash', 18:47:32 INFO - 'SHLVL': '1', 18:47:32 INFO - 'TERM': 'linux', 18:47:32 INFO - 'TMOUT': '86400', 18:47:32 INFO - 'USER': 'cltbld', 18:47:32 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', 18:47:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:32 INFO - '_': '/tools/buildbot/bin/python'} 18:47:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:47:32 INFO - Cleaning up... 18:47:32 INFO - Return code: 0 18:47:32 INFO - Running post-action listener: _resource_record_post_action 18:47:32 INFO - Running post-action listener: _start_resource_monitoring 18:47:32 INFO - Starting resource monitoring. 18:47:32 INFO - [mozharness: 2017-08-11 01:47:32.419087Z] Finished create-virtualenv step (success) 18:47:32 INFO - [mozharness: 2017-08-11 01:47:32.419556Z] Running install step. 18:47:32 INFO - Running pre-action listener: _resource_record_pre_action 18:47:32 INFO - Running main action method: install 18:47:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:47:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:47:32 INFO - Reading from file tmpfile_stdout 18:47:32 INFO - Detecting whether we're running mozinstall >=1.0... 18:47:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:47:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:47:32 INFO - Reading from file tmpfile_stdout 18:47:32 INFO - Output received: 18:47:32 INFO - Usage: mozinstall [options] installer 18:47:32 INFO - Options: 18:47:32 INFO - -h, --help show this help message and exit 18:47:32 INFO - -d DEST, --destination=DEST 18:47:32 INFO - Directory to install application into. [default: 18:47:32 INFO - "/builds/slave/test"] 18:47:32 INFO - --app=APP Application being installed. [default: firefox] 18:47:32 INFO - mkdir: /builds/slave/test/build/application 18:47:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:47:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:47:50 INFO - Reading from file tmpfile_stdout 18:47:50 INFO - Output received: 18:47:50 INFO - /builds/slave/test/build/application/firefox/firefox 18:47:50 INFO - Running post-action listener: _resource_record_post_action 18:47:50 INFO - [mozharness: 2017-08-11 01:47:50.759337Z] Finished install step (success) 18:47:50 INFO - [mozharness: 2017-08-11 01:47:50.759488Z] Running setup-mitmproxy step. 18:47:50 INFO - Running pre-action listener: _resource_record_pre_action 18:47:50 INFO - Running main action method: setup_mitmproxy 18:47:50 INFO - Skipping: mitmproxy is not required 18:47:50 INFO - Running post-action listener: _resource_record_post_action 18:47:50 INFO - [mozharness: 2017-08-11 01:47:50.760029Z] Finished setup-mitmproxy step (success) 18:47:50 INFO - [mozharness: 2017-08-11 01:47:50.760132Z] Running run-tests step. 18:47:50 INFO - Running pre-action listener: _resource_record_pre_action 18:47:50 INFO - Running pre-action listener: _set_gcov_prefix 18:47:50 INFO - Running main action method: run_tests 18:47:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:47:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:47:50 INFO - Python 2.7.3 18:47:50 INFO - Return code: 0 18:47:50 INFO - grabbing minidump binary from tooltool 18:47:50 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')]} 18:47:50 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 0x2128f00>, '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 0x2123ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2124050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:47:50 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 18:47:50 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 18:47:50 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 18:47:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:47:50 INFO - Return code: 0 18:47:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:47:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:47:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:47:50 INFO - ENV: RUST_BACKTRACE is now full 18:47:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:47:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:47:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 18:47:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 18:47:50 INFO - Using env: {'DISPLAY': ':0', 18:47:50 INFO - 'HOME': '/home/cltbld', 18:47:50 INFO - 'LANG': 'en_US.UTF-8', 18:47:50 INFO - 'LANGUAGE': 'en_US:en', 18:47:50 INFO - 'LOGNAME': 'cltbld', 18:47:50 INFO - 'MAIL': '/var/mail/cltbld', 18:47:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:47:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:50 INFO - 'MOZ_NO_REMOTE': '1', 18:47:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:47:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:47:50 INFO - 'NO_EM_RESTART': '1', 18:47:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:50 INFO - 'PWD': '/builds/slave/test', 18:47:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:47:50 INFO - 'RUST_BACKTRACE': 'full', 18:47:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:47:50 INFO - 'SHELL': '/bin/bash', 18:47:50 INFO - 'SHLVL': '1', 18:47:50 INFO - 'TERM': 'linux', 18:47:50 INFO - 'TMOUT': '86400', 18:47:50 INFO - 'USER': 'cltbld', 18:47:50 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486', 18:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:50 INFO - '_': '/tools/buildbot/bin/python'} 18:47:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 18:47:51 INFO - mozversion application_buildid: 20170811005950 18:47:51 INFO - mozversion application_changeset: bb88ccb10454a78b8fa4a7d6c45bdf6fc47de3cd 18:47:51 INFO - mozversion application_display_name: Nightly 18:47:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:47:51 INFO - mozversion application_name: Firefox 18:47:51 INFO - mozversion application_remotingname: firefox 18:47:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:47:51 INFO - mozversion application_vendor: Mozilla 18:47:51 INFO - mozversion application_version: 57.0a1 18:47:51 INFO - mozversion platform_buildid: 20170811005950 18:47:51 INFO - mozversion platform_changeset: bb88ccb10454a78b8fa4a7d6c45bdf6fc47de3cd 18:47:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:47:51 INFO - mozversion platform_version: 57.0a1 18:47:51 INFO - using testdate: 1502416071 18:47:51 INFO - actual date: 1502416071 18:47:51 INFO - starting webserver on 'localhost:38145' 18:47:51 INFO - SUITE-START | Running 2 tests 18:47:51 INFO - TEST-START | dromaeo_css 18:47:51 INFO - Initialising browser for dromaeo_css test... 18:47:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38145/getInfo.html -profile /tmp/tmpPPcSsR/profile 18:47:51 INFO - TEST-INFO | started process 23279 (/builds/slave/test/build/application/firefox/firefox http://localhost:38145/getInfo.html) 18:47:58 INFO - TEST-INFO | 23279: exit 0 18:47:58 INFO - Browser initialized. 18:47:58 INFO - Running cycle 1/1 for dromaeo_css test... 18:47:58 INFO - TEST-INFO | started process 23489 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPPcSsR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:47:59 INFO - PID 23489 | 18:47:59 INFO - PID 23489 | (/builds/slave/test/build/application/firefox/firefox:23537): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:47:59 INFO - PID 23489 | 18:48:09 INFO - PID 23489 | RSS: Main: 168472576 18:48:09 INFO - PID 23489 | 18:51:08 INFO - PID 23489 | Cycle 1(1): loaded http://localhost:38145/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38145/tests/dromaeo/cssquery-ext.html) 18:51:10 INFO - PID 23489 | RSS: Main: 173178880 18:51:10 INFO - PID 23489 | 18:54:11 INFO - PID 23489 | Cycle 1(1): loaded http://localhost:38145/tests/dromaeo/cssquery-ext.html (next: http://localhost:38145/tests/dromaeo/cssquery-jquery.html) 18:54:12 INFO - PID 23489 | RSS: Main: 170323968 18:54:12 INFO - PID 23489 | 18:57:14 INFO - PID 23489 | Cycle 1(1): loaded http://localhost:38145/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38145/tests/dromaeo/cssquery-mootools.html) 18:57:14 INFO - PID 23489 | RSS: Main: 171851776 18:57:14 INFO - PID 23489 | 19:00:16 INFO - PID 23489 | Cycle 1(1): loaded http://localhost:38145/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38145/tests/dromaeo/cssquery-prototype.html) 19:00:17 INFO - PID 23489 | RSS: Main: 168792064 19:00:17 INFO - PID 23489 | 19:03:20 INFO - PID 23489 | Cycle 1(1): loaded http://localhost:38145/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38145/tests/dromaeo/cssquery-yui.html) 19:03:21 INFO - PID 23489 | RSS: Main: 174014464 19:03:21 INFO - PID 23489 | 19:06:23 INFO - PID 23489 | Cycle 1(1): loaded http://localhost:38145/tests/dromaeo/cssquery-yui.html (next: http://localhost:38145/tests/dromaeo/cssquery-dojo.html) 19:06:23 INFO - PID 23489 | RSS: Main: 174895104 19:06:23 INFO - PID 23489 | 19:06:23 INFO - PID 23489 | __start_tp_report 19:06:23 INFO - PID 23489 | _x_x_mozilla_page_load 19:06:23 INFO - PID 23489 | _x_x_mozilla_page_load_details 19:06:23 INFO - PID 23489 | |i|pagename|runs| 19:06:23 INFO - PID 23489 | |0;dojo.html;3118.236032172118;3146.763992700548;3158.1315138336963;3174.5555622212896;3181.093388384311;6241.21984751906;6252.906206406896;6265.185525881635;6272.905906411402;6306.463950564202;6187.907181392283;6297.748090076391;6302.905456418146;6306.274778400485;6321.968390158055;5733.885322293562;5751.223584816038;5773;5784.4504772046585;5794.362620111784;5776.82669519913;5786.82639520813;5812.738426770784;5816.563757718188;5885.970570147143;5805;5817.941820581804;5865.560082993771;5869.589128760988;5941.465268125868;5627.6904770237625;5737.254156959569;5820.476157145835;5846.678432686201;5867.647941123546;5715.599908006441;5761.683107429089;5767.250257466502;5773.595848290622;5781.248437703114;6205.937940620581;6237.656928868911;6246.87506249877;6300.30696623371;6325.430711236011;6733.663316834188;6750.291219421939;6764.120664313607;6827.726890924357;6843.8973415398805;6812.080369150151;6945.305469453065;6952.374286314256;6955;6967.233604303523;6292.056191571255;6296.275928268216;6375.8406039848915;6377.936220637827;6379.744810207632;6862.691178896962;6950.965245173843;7033.542819716702;7050.259722729143;7060.046893669289;6535.477161827042;6591.274959754423;6643.534952553275;6670.83322916931;6708.563943343667;6703.899441508382;6714.697838597275;6835.282295358916;6835.282295359015;6841.589504629638;6589.406953374123;6594.076829243813;6705.228898676617;6718.126643536407;6719.395254427027;6637.800865973932;6687.130673012574;6748.831279218059;6749.190097188369;6800.217974932847;5422.783088676488;5435;5518.558515318765;5520.530754885799;5520.944790552124;4705.411823522059;4754.78603462845;4769.594584460359;4776.546228108324;4792.113459010095;5768.625039372428;5832.941670583325;5928.466438020599;5931.495822855106;5937;6679.899801502981;6765.830854228684;6779.457643388517;6781.355771201728;6781.898271525931;5827.708614569255;5828.88342233153;5844.064949608042;5845.1232315153065;5850.0932355484965;6030.366811484819;6076.149339092531;6094.908576371358;6107.358727333655;6115.724792384264;6616.338366163442;6627.569208001464;6701.5979041256705;6718.966405168041;6725.831854203586;6743.224529179011;6838;6850.691718872563;6935.722571097201;6942.028116063654;6599.142111525471;6629.303923088007;6629.867402651824;6634.071230027704;6637.336266373517;6672.132622759011;6684.766033188815;6720.0591917132615;6749.055132281386;6791.286914874065;6464.773732919325;6886.449084073162;6887.7244910203035;6972.6513674315065;6989;6628.436582890508;6637.402633762985;6713.12729345182;6721.361470660279;6722.764703235559;6503.902441463477;6554.80335589933;6612.272650008399;6633.70148343316;6637.2699003108655;6509.544331896722;6535.44448721864;6589.835254118685;6590.604563726191;6598.043283723737;6616.66916654175;6630.403263706289;6676.132102826601;6692.966535167293;6718.630475323904;6350.396712312323;6359.300476947439;6440.420362167428;6462.838429039311;6492.610443373413 19:06:23 INFO - PID 23489 | |1;ext.html;802.5345299726159;835.8787975743521;874.772559134625;890.7639475538988;896.6906417286039;40937.79531102344;41701.7914910425;41732.16535669289;41734;41782.58217417825;37071.44392834103;37197.62802371976;37560.62439375606;37572.43641345375;37630;6867.278935711765;6962.582245065312;6985.091938048072;6991.545549539289;6998.755043573476;6896.48276379271;7486.139094004177;7629.122650895135;7643;7669.769906902774;7686.385089192879;7707.537547747125;7717.922820771804;7725.652345644459;7731.265529774662;7560.773176804704;7638.541687498768;7776.222377762178;7800.921990780076;7803.453758236926;7626.313631773111;7653.15815260321;7702.306792388656;7707.306342429152;7708.614569271514;37684.057898552484;37956.05109872248;37959.81020094882;38012.61987380119;38041.42937855935;3590.7127429805555;3609.7292703047115;3610.8916732497805;3619.927601447983;3637.872674456408;30737;30803.691963080306;30987.535186972214;30996.380072398424;31040;4901.338319326881;5047.671901326402;5048.899022019576;5049.798008079672;5066;189527;192548;192790;194808;195202;43142;48937.75531122321;49529.50470495321;49650.503494964585;49654.751726241135;51871.221931671054;51947.22079168815;51970.22044669333;52007.73996129995;52019;14731.337089830984;14789.186594737177;14842.925785371004;14852.331645075998;14853.405863765545;11739.823902641467;11822.527098915947;11861.762764744657;11863.76272474563;11867.525298988117;14180.361883715257;14259.07316024455;14262.286885655883;14318.14091154534;14572.052816567102;5783.595148339576;5868.618539794899;5932.555058370639;5982.222311099529;5992.34084250732;997.7256254530063;1026.7535791410007;1029.6447725534679;1031.1493018259966;1039.6880935719255;22896.65655015176;22961.88519057394;23029.76970230276;23200.883995579916;23259;1098.5715570927346;1099.6865893220395;1117.4915413486844;1120.971975700614;1121.332806979862;12304.217018001526;12393.62819115428;12412.441440135215;12444.191127576678;12467.625971220878;233161;233552;235788;235893;236848;51832;51834.48165518372;51922.48077519252;52007;52081;1460.2260801775076;1484.3172140815345;1487.516557118919;1511.093343993595;1513.8788896888223;21419.571608568054;21465.420038516768;21999.560008800054;22091.226807061677;22165.113395464647;36928.44607330838;36978.6302136986;36996;37011.6298837019;37040.259194815415;192736;193381;193971;194076;194142;31288;32626.184345391554;34477.655223447444;34562;34657.48013779745;43264.13471730485;43497.56502435062;43582.56417435785;43682;44558.77720611376;32473;32946.5058024125;33011;33437.33125337528;33549.66450335563;32391.676083238865;32600.347993039533;32735.18162045968;32807.75624371597;32950.67049329476;35717.82141089382;35893.28213435769;35960.640393596776;36037.09907252339;36110.4583431254;30441.695583043886;31318.843405782824;31369.215769605944;31937.520937185494;32481.512777307886;23186.652200217348;23393.06427742871;23509;23615.64576531319;23991.160309389088 19:06:23 INFO - PID 23489 | |2;jquery.html;586.8356860079176;588.1119509540595;592.193808882907;596.9134475501053;598.8892054969831;22561.435964100885;23139.958701858413;23302.300930972076;23328.416789580268;23345.76654233457;26882.462350753023;27300.45399092017;27320.180394588144;27374.31564210896;27525.724742752565;6624.470042396621;6765.661716914159;6766.492513061516;6782.660866956657;6785.7964261072;6623.105880706115;6684.46524278059;6691.933080669204;6762.5942443453305;7061.470389720792;7074.434045276391;7075.150981882181;7080.504364694473;7082.256363081854;7082.575045497261;7079.716811327541;7091.468139889503;7094.1841688206005;7094.822629434279;7096.148462184545;6917.239103698589;6931.2375638679705;6944.340287672663;6946.756863509803;6972.616506092163;30973.84513077443;31320.37359252825;32091;32357.352852943044;32780.1804954878;1973.605278944217;2000.0699674651212;2004.4387571479863;2007.3175120459114;2008.2770202727006;20695.482612934647;21382.144714211412;21404;21407.357779266647;21413.14347426101;2472.1594657816427;2492.987535062331;2494.077191439179;2555.1312553731636;2605.7654811067096;148074;152109.9999999989;152409;152486;152897;19673.704894426595;21382;21516.190628530523;23046.695330467155;23534.64698029531;45694.314585281245;45775;46001.30998035032;46110.538894611294;46311.536884630725;10966.506507207196;11106.722331941604;11167.553297868157;11189.776204475866;11195.832062519068;9081.636734530677;9142;9142.268618510503;9158.450492970444;9329.440233586007;9167.908320916838;11684.298942063504;11833.171677982631;11849.20107356522;11860.22908510944;3780.984313255915;4022.959770402317;4043.8180281887385;4179.644730197906;4282.957170428318;909.4270609516095;949.378157306965;953.1897886796168;955.47926380123;964.3827950111934;19876.801231987785;20086.799132008786;20127.993600320217;20264.49338766513;20663.690044649342;999.550202408919;1006.572206812102;1008.2287050406445;1011.5346940407347;1022.1005515146624;10872.402017889091;11246.1565382595;11292;11320.377379244055;11344.602938897264;422135;432695;443029;443496;446614;108246.45876770566;117630;118031;118125;118663;1169.3159501691605;1205.6563879294358;1205.6624145239348;1206.5173930427723;1206.7164216409137;17758.733618995466;18022.729659055385;18235.908820456345;18249.543761406072;18522.351717689817;55895;57705.71147144237;58285.417145827996;58980.705096474245;59065.70467147637;67705.32294677188;67884;68139.65930170317;68218;69562;14837.554873353816;15219.096828355536;15248.085114893142;15279.465218717292;15412;23559.646605301277;23822.8088595573;23873.641895371235;23956.52086958286;24112;18351.449456516326;18598.349057782914;19009.144588493273;19075;19139.904300478407;15643.608909777347;16294.348226070824;16334.346626134822;16532.256048478088;16884.831151688326;19149.234030638618;19396.2241510338;19411.902940485772;19579.70630440573;19700.113494893052;15016.849831501546;15038.6992260152;15118.470853520073;15150.16674082936;15235.238238088274;18906.621867562295;19044.333448329242;19197.712034319775;19227.230910763974;19401.126949287034 19:06:23 INFO - PID 23489 | |3;mootools.html;1332.4936524120835;1354.4988354308907;1369.7671395862708;1374.9450021999116;1378.6966867289193;13529.391177397016;14121.223332716696;14202.857971420282;14240.430382784689;14289.928550357246;13486.932565337183;14025.298735063232;14448.638784030405;14471.78292325616;14487.78268325976;2622.082271205082;2683.07433935292;2692.044324264887;2694;2694.932626684329;2544.3257536752776;2550.1966880432697;2588.0812311629384;2675.1573254424893;2676.170387179971;2633.3285012321926;2638.907638232662;2653.429512654779;2653.8938442462377;2665.5335316319665;2548.3374322676236;2555.246202370305;2572.0354866924904;2573.961390579143;2576.8196226264263;2485.155047283932;2509.9834567000394;2521.760432758885;2526.305266051832;2530.72162062173;13597.86402135976;13934.233617151052;13937.233452160128;13942.23317717525;13946;6929.3070692930305;7652.808679782995;7678.923210767876;7699.961500192463;7706.653200605986;4888.560029597354;5046.34397528319;5103.617228707824;5113.948860511385;5142.974285128588;1764.6382491589322;1780.3946658136297;1791.6237590106093;1831.569581148429;1839.6872531669649;22468.887655561783;22495.10019599214;22546.887265563568;22584.43538911524;22645.207417740465;6182.412670796267;6198.783042593487;6225.688715564203;6393.360663933569;6417.06952491886;15700.528984130495;15705.21473926322;15709.293081811345;15711.450099246471;15720.29258683362;7005.824854378579;7357.59533225667;7380.9630951845975;7384.926150738531;7394.445416593777;6719.428848547928;6814.93185068143;6863.485238607124;6887.035814985806;6895.379415852597;4544.636429085665;4678.508756580578;4807.6153907688;4877.195262781602;4877.365942427533;1266.5377137344988;1351.3783659516537;1369.5480491437802;1391.5825252424231;1391.7981892625512;858.6822875536092;872.1540106097076;879.8680197970227;882.8984666763276;897.0804924951846;7890.8816367754525;7914.327282180964;8061.113277539466;8075.596220188967;8129.430939834273;941.8540126280437;949.6106596295485;958.1903291718487;965.5413678502655;972.3728195313982;4664.533546645308;4817.710937343771;4836.5888899443235;4903.387076615423;5040.445550989388;19089.427317180507;19125.2349906005;19806.60386792256;19841.60316793685;19868.304609338604;19010.144543495575;19243;19404;19858.304959326353;19884.60230795376;10303.330283531397;10767.084797792118;10877.184211184192;11045.116390688725;11077.556897724162;7672.923270767221;7897.170797066455;7982.281594656509;8010.036286574005;8143.267105960255;16802.495925122264;16873.156342183087;16944;17152.656946860745;17636.64726705433;17244.137793110545;17249.741253881453;17342.653146937242;17351.913240433718;17459.38892138769;10458.424786636808;10573.04842564173;10577.153827693612;10585.629502967358;10688.091512221548;12802.67993300175;12952.870471295166;13044.5434409799;13093.869061309266;13110;8888.64445422176;10245.795084098425;10366.740831479274;10487.895121048998;10499.422531760827;10230.488475576043;10324.845127323246;10383.428911409945;10458.63394781179;10617.681469555617;8444.535550544533;8493.57532123379;8645.049044605214;8645.870311945451;8674.52290124024;6703.564268322543;6752.628605426552;6789.762358317435;6819.318068193141;6868.416184624363;6923.723051077901;6960.895586566105;6971.965140174267;6999.6850141742725;7056.823579410556 19:06:23 INFO - PID 23489 | |4;prototype.html;501.5461007787951;502.68046378257077;522.9921551176732;528.4715284715285;531.1846315905084;6266.370743606667;6276.497880169587;6278.372162783719;6330.4935605151595;6356.872862542747;5962.165296858443;6085.604435711675;6121.234845644294;6130.540209484284;6149.846253843645;4863.659543831933;4995.175795993675;5007.649464537493;5008.549230569248;5072.137736584771;4929.940062886479;4976.850694479172;4982.181503751123;5008.771096861898;5032.723200223986;4817.892459363708;4893.388326459192;4971.477994810547;4976.925346119793;4987.875303117433;4974.527419895104;4988.950110498885;4994.275830004647;5016.3980322361185;5037.4458809530925;4472.508024117345;4491.797869095866;4497.9325310120375;4500.977495112536;4504.635066590034;5994.640321580676;6017.247844019497;6042.848928776772;6046.486048685867;6058.212432383806;5782.392848750863;5782.7397767100165;5795.652260864362;5817.447342502455;5820.476157145878;6345.746170153188;6371.171747672819;6475.805725828232;6509.772157974448;6513.902291465632;4882.3408839806525;4893.314935908976;4895.755212239374;4899.632527560448;4935.235038569028;6693.397594216543;6925.3074692530345;6935.202451718117;6951.096357473496;6952.443804495628;4940.580050695659;4999.125153098194;4999.7250701071725;5004.799808007639;5022.89954200914;5088.287639730441;5089.745512724423;5102.591792656579;5116.667416617908;5138.897222055538;5704.372519022904;5710.914926164016;5717.885642287214;5722.740996980658;5751.741171647286;6513.934860651428;6528.738850446024;6554.606723596599;6556.541042127101;6673.432758215509;1682.3691115831564;1713.6572685462957;1726.1973182470213;1769.0889192066097;1775.6359946210996;1205.2949024820402;1217.3487184356327;1233.3894722112611;1237.189640785287;1246.1339369138361;5565.443455654402;5584.499268220799;5632.817108407198;5633.6619802811965;5657.236273103161;6447.709853056624;6448.935510644834;6457.192850893638;6492.67536623167;6527.160685101996;5628.212050312959;5628.493435590816;5675.772969081273;5754.251947246915;5760.279965004374;3575.80333081683;3581.1405262737276;3587.8564857405413;3593.6226696197045;3611.5485564304463;6559.311272316338;6608.702608382634;6610.669466526654;6611.471082313499;6688.264290927994;6844.93155068443;6870.251899240344;6929.861402771916;6951.7914462566205;6969.7909062728195;6530.608163510205;6548.705308261045;6556.868862622721;6564.868702626016;6604.3395660434535;6748;6748.089007984056;6822.556533825285;6831.6242606657115;6839.69221385029;6720;6762.256151823394;6791.0153027811675;6791.185057793096;6813.591184528944;6597;6628.613699068428;6635.9004614929845;6636.402723754885;6640.933590664032;4969.850904472872;4974.801007959641;5010.924836127534;5033.446320904624;5035.370578677665;6745.898811517733;6750.932490675031;6835;6901.861962760817;6907.585544867324;6391.296957334596;6499.707513161825;6527.67361631926;6540.313267106885;6566.179227596551;6369.235691717024;6382.712777925098;6407.5010745810105;6459.450946669585;6481.4814814815645;6200.697853450826;6369.936300636934;6384.3934826191435;6409.3270206629495;6529.269743518066;6374.21521973848;6582.17722784652;6596.142501474777;6644.568103073285;6664.8000559983275;6217.906731398942;6310.7791227306825;6359;6431.324710905287;6457.128287681103 19:06:23 INFO - PID 23489 | |5;yui.html;890.7060669978907;902.454015320731;903.9457632542052;907.3512438606396;916.1296768070333;18539.72190417145;18925.52686182846;19490;19547.41357759266;19558.21767129315;21105.47236319093;21774.564508709816;21774.564508709816;21787;21946.561068778654;1021.6475316015963;1051.647698021164;1061.0238580505913;1061.8194906865851;1062.659948816379;1017.2929813779426;1037.1702637889696;1041.0422411381546;1049.3703777733376;1061.029158320136;1113.259682311262;1119.1774046076127;1120.714217874445;1120.7926533591271;1121.2711737370732;1109.6449136276394;1113.9610113645983;1114.225613198819;1114.3481063577817;1115.0577761791274;807.1444269074799;808.720991258015;812.2202685422002;814.6822739131743;818.5088946632033;19276.710849337273;19443.513912152168;19487.512812179666;19887.403377898685;19913.203471861107;21069;21421.357359279245;21836.126554937782;21870.562588748136;22017.009234584482;16295.837041629551;16348.673026539522;16435.506934791974;16454;16696.248668809934;3566.7503274770775;3592.2276710507367;3604.4953706481115;3605.9098522536883;3607.783532988029;128818.35590821986;129910;134450;134975.32512337473;136198;16415.343386264656;16568.502944911674;16895.408660697063;16972.74540881888;16998.830011699723;29945.550816737767;30626.54060189099;30647.693523064485;30970.22574435612;31162.844185778926;7500.737474188377;7767.572783496849;8252.21603947624;8358.958205208935;8728.956355218183;4248.468941382327;4351.369051487517;4382.824687012548;4390.07808360248;4394.912101757947;5323.946760532423;5439.5376393006245;5463.945360546344;5477.233187353773;5491.697956612345;1083.3824719909576;1108.2463924531235;1122.1583812140893;1129.4239937631867;1131.032966813379;683.736761346885;695.7530076822757;697.2852825853439;702.9929700703029;706.6784613001072;11515.251508652078;11746.29522228669;11891.940540297242;12052.156349055482;12288.938555307344;672.2739441403272;692.0829900381914;692.5706062241443;700.9579425234502;701.7999870037015;5389.83830485086;5475.863103422446;5576.97211513948;5784.161296611967;5823.184754134425;109112;109267.45366273118;112008;112548.43725781482;114854.4257278725;97938;98014;98144;98574;101068.49465752771;88937;89400.10599894047;89428.55285723529;90326.54836725774;90600.5469972646;12902.161359511978;12932;12983.091183617056;13101.148425352321;13218.537351192663;40735.796321018206;40877.79561102176;41089.58910410858;41217;41434.79282603568;72950;76356.13207547285;77092.22907771076;78007;78449;14493.565193044225;19877.30429434963;19998.90000549988;20017.699734504284;20301.695474568187;21484.785152148903;21699.023543940246;21760.12959481603;22483.21308754186;22834.42913927165;18666.906665466584;18906.338278160198;19058.142383592494;19207.51981200481;19666.410007699793;19743.506412339808;19794.40616781499;19798.70301945443;19820.40538783839;20057.899710501355;18529.258829646664;18640.254389824255;18859.528511787314;18876;18916.716249256548;13202.471901124232;13275;13327.533536326182;13633.045686801828;13787.172769633855;14192.92903535517;14515.92742036283;14563.563093107225;14622.41510339617;14719.411223551366 19:06:23 INFO - PID 23489 | __end_tp_report 19:06:23 INFO - PID 23489 | __start_cc_report 19:06:23 INFO - PID 23489 | _x_x_mozilla_cycle_collect,386 19:06:23 INFO - PID 23489 | __end_cc_report 19:06:23 INFO - PID 23489 | __startTimestamp1502417183545__endTimestamp 19:06:23 INFO - PID 23489 | 19:06:23 INFO - PID 23489 | ------- Summary: start ------- 19:06:23 INFO - PID 23489 | Number of tests: 6 19:06:23 INFO - PID 23489 | 19:06:23 INFO - PID 23489 | [#0] dojo.html Cycles:165 Average:6250.46 Median:6509.54 stddev:743.46 (11.4%) stddev-sans-first:703.97 19:06:23 INFO - PID 23489 | Values: 3118.2 3146.8 3158.1 3174.6 3181.1 6241.2 6252.9 6265.2 6272.9 6306.5 6187.9 6297.7 6302.9 6306.3 6322.0 5733.9 5751.2 5773.0 5784.5 5794.4 5776.8 5786.8 5812.7 5816.6 5886.0 5805.0 5817.9 5865.6 5869.6 5941.5 5627.7 5737.3 5820.5 5846.7 5867.6 5715.6 5761.7 5767.3 5773.6 5781.2 6205.9 6237.7 6246.9 6300.3 6325.4 6733.7 6750.3 6764.1 6827.7 6843.9 6812.1 6945.3 6952.4 6955.0 6967.2 6292.1 6296.3 6375.8 6377.9 6379.7 6862.7 6951.0 7033.5 7050.3 7060.0 6535.5 6591.3 6643.5 6670.8 6708.6 6703.9 6714.7 6835.3 6835.3 6841.6 6589.4 6594.1 6705.2 6718.1 6719.4 6637.8 6687.1 6748.8 6749.2 6800.2 5422.8 5435.0 5518.6 5520.5 5520.9 4705.4 4754.8 4769.6 4776.5 4792.1 5768.6 5832.9 5928.5 5931.5 5937.0 6679.9 6765.8 6779.5 6781.4 6781.9 5827.7 5828.9 5844.1 5845.1 5850.1 6030.4 6076.1 6094.9 6107.4 6115.7 6616.3 6627.6 6701.6 6719.0 6725.8 6743.2 6838.0 6850.7 6935.7 6942.0 6599.1 6629.3 6629.9 6634.1 6637.3 6672.1 6684.8 6720.1 6749.1 6791.3 6464.8 6886.4 6887.7 6972.7 6989.0 6628.4 6637.4 6713.1 6721.4 6722.8 6503.9 6554.8 6612.3 6633.7 6637.3 6509.5 6535.4 6589.8 6590.6 6598.0 6616.7 6630.4 6676.1 6693.0 6718.6 6350.4 6359.3 6440.4 6462.8 6492.6 19:06:23 INFO - PID 23489 | 19:06:23 INFO - PID 23489 | [#1] ext.html Cycles:180 Average:37428.74 Median:19742.00 stddev:53978.54 (273.4%) stddev-sans-first:54059.90 19:06:23 INFO - PID 23489 | Values: 802.5 835.9 874.8 890.8 896.7 40937.8 41701.8 41732.2 41734.0 41782.6 37071.4 37197.6 37560.6 37572.4 37630.0 6867.3 6962.6 6985.1 6991.5 6998.8 6896.5 7486.1 7629.1 7643.0 7669.8 7686.4 7707.5 7717.9 7725.7 7731.3 7560.8 7638.5 7776.2 7800.9 7803.5 7626.3 7653.2 7702.3 7707.3 7708.6 37684.1 37956.1 37959.8 38012.6 38041.4 3590.7 3609.7 3610.9 3619.9 3637.9 30737.0 30803.7 30987.5 30996.4 31040.0 4901.3 5047.7 5048.9 5049.8 5066.0 189527.0 192548.0 192790.0 194808.0 195202.0 43142.0 48937.8 49529.5 49650.5 49654.8 51871.2 51947.2 51970.2 52007.7 52019.0 14731.3 14789.2 14842.9 14852.3 14853.4 11739.8 11822.5 11861.8 11863.8 11867.5 14180.4 14259.1 14262.3 14318.1 14572.1 5783.6 5868.6 5932.6 5982.2 5992.3 997.7 1026.8 1029.6 1031.1 1039.7 22896.7 22961.9 23029.8 23200.9 23259.0 1098.6 1099.7 1117.5 1121.0 1121.3 12304.2 12393.6 12412.4 12444.2 12467.6 233161.0 233552.0 235788.0 235893.0 236848.0 51832.0 51834.5 51922.5 52007.0 52081.0 1460.2 1484.3 1487.5 1511.1 1513.9 21419.6 21465.4 21999.6 22091.2 22165.1 36928.4 36978.6 36996.0 37011.6 37040.3 192736.0 193381.0 193971.0 194076.0 194142.0 31288.0 32626.2 34477.7 34562.0 34657.5 43264.1 43497.6 43582.6 43682.0 44558.8 32473.0 32946.5 33011.0 33437.3 33549.7 32391.7 32600.3 32735.2 32807.8 32950.7 35717.8 35893.3 35960.6 36037.1 36110.5 30441.7 31318.8 31369.2 31937.5 32481.5 23186.7 23393.1 23509.0 23615.6 23991.2 19:06:23 INFO - PID 23489 | 19:06:23 INFO - PID 23489 | [#2] jquery.html Cycles:180 Average:34974.02 Median:23337.09 stddev:74979.02 (321.3%) stddev-sans-first:75144.90 19:06:23 INFO - PID 23489 | Values: 586.8 588.1 592.2 596.9 598.9 22561.4 23140.0 23302.3 23328.4 23345.8 26882.5 27300.5 27320.2 27374.3 27525.7 6624.5 6765.7 6766.5 6782.7 6785.8 6623.1 6684.5 6691.9 6762.6 7061.5 7074.4 7075.2 7080.5 7082.3 7082.6 7079.7 7091.5 7094.2 7094.8 7096.1 6917.2 6931.2 6944.3 6946.8 6972.6 30973.8 31320.4 32091.0 32357.4 32780.2 1973.6 2000.1 2004.4 2007.3 2008.3 20695.5 21382.1 21404.0 21407.4 21413.1 2472.2 2493.0 2494.1 2555.1 2605.8 148074.0 152110.0 152409.0 152486.0 152897.0 19673.7 21382.0 21516.2 23046.7 23534.6 45694.3 45775.0 46001.3 46110.5 46311.5 10966.5 11106.7 11167.6 11189.8 11195.8 9081.6 9142.0 9142.3 9158.5 9329.4 9167.9 11684.3 11833.2 11849.2 11860.2 3781.0 4023.0 4043.8 4179.6 4283.0 909.4 949.4 953.2 955.5 964.4 19876.8 20086.8 20128.0 20264.5 20663.7 999.6 1006.6 1008.2 1011.5 1022.1 10872.4 11246.2 11292.0 11320.4 11344.6 422135.0 432695.0 443029.0 443496.0 446614.0 108246.5 117630.0 118031.0 118125.0 118663.0 1169.3 1205.7 1205.7 1206.5 1206.7 17758.7 18022.7 18235.9 18249.5 18522.4 55895.0 57705.7 58285.4 58980.7 59065.7 67705.3 67884.0 68139.7 68218.0 69562.0 14837.6 15219.1 15248.1 15279.5 15412.0 23559.6 23822.8 23873.6 23956.5 24112.0 18351.4 18598.3 19009.1 19075.0 19139.9 15643.6 16294.3 16334.3 16532.3 16884.8 19149.2 19396.2 19411.9 19579.7 19700.1 15016.8 15038.7 15118.5 15150.2 15235.2 18906.6 19044.3 19197.7 19227.2 19401.1 19:06:23 INFO - PID 23489 | 19:06:23 INFO - PID 23489 | [#3] mootools.html Cycles:180 Average:8583.83 Median:2549.27 stddev:5941.27 (233.1%) stddev-sans-first:5932.95 19:06:23 INFO - PID 23489 | Values: 1332.5 1354.5 1369.8 1374.9 1378.7 13529.4 14121.2 14202.9 14240.4 14289.9 13486.9 14025.3 14448.6 14471.8 14487.8 2622.1 2683.1 2692.0 2694.0 2694.9 2544.3 2550.2 2588.1 2675.2 2676.2 2633.3 2638.9 2653.4 2653.9 2665.5 2548.3 2555.2 2572.0 2574.0 2576.8 2485.2 2510.0 2521.8 2526.3 2530.7 13597.9 13934.2 13937.2 13942.2 13946.0 6929.3 7652.8 7678.9 7700.0 7706.7 4888.6 5046.3 5103.6 5113.9 5143.0 1764.6 1780.4 1791.6 1831.6 1839.7 22468.9 22495.1 22546.9 22584.4 22645.2 6182.4 6198.8 6225.7 6393.4 6417.1 15700.5 15705.2 15709.3 15711.5 15720.3 7005.8 7357.6 7381.0 7384.9 7394.4 6719.4 6814.9 6863.5 6887.0 6895.4 4544.6 4678.5 4807.6 4877.2 4877.4 1266.5 1351.4 1369.5 1391.6 1391.8 858.7 872.2 879.9 882.9 897.1 7890.9 7914.3 8061.1 8075.6 8129.4 941.9 949.6 958.2 965.5 972.4 4664.5 4817.7 4836.6 4903.4 5040.4 19089.4 19125.2 19806.6 19841.6 19868.3 19010.1 19243.0 19404.0 19858.3 19884.6 10303.3 10767.1 10877.2 11045.1 11077.6 7672.9 7897.2 7982.3 8010.0 8143.3 16802.5 16873.2 16944.0 17152.7 17636.6 17244.1 17249.7 17342.7 17351.9 17459.4 10458.4 10573.0 10577.2 10585.6 10688.1 12802.7 12952.9 13044.5 13093.9 13110.0 8888.6 10245.8 10366.7 10487.9 10499.4 10230.5 10324.8 10383.4 10458.6 10617.7 8444.5 8493.6 8645.0 8645.9 8674.5 6703.6 6752.6 6789.8 6819.3 6868.4 6923.7 6960.9 6972.0 6999.7 7056.8 19:06:23 INFO - PID 23489 | 19:06:23 INFO - PID 23489 | [#4] prototype.html Cycles:180 Average:5512.54 Median:6071.91 stddev:1547.43 (25.5%) stddev-sans-first:1505.37 19:06:23 INFO - PID 23489 | Values: 501.5 502.7 523.0 528.5 531.2 6266.4 6276.5 6278.4 6330.5 6356.9 5962.2 6085.6 6121.2 6130.5 6149.8 4863.7 4995.2 5007.6 5008.5 5072.1 4929.9 4976.9 4982.2 5008.8 5032.7 4817.9 4893.4 4971.5 4976.9 4987.9 4974.5 4989.0 4994.3 5016.4 5037.4 4472.5 4491.8 4497.9 4501.0 4504.6 5994.6 6017.2 6042.8 6046.5 6058.2 5782.4 5782.7 5795.7 5817.4 5820.5 6345.7 6371.2 6475.8 6509.8 6513.9 4882.3 4893.3 4895.8 4899.6 4935.2 6693.4 6925.3 6935.2 6951.1 6952.4 4940.6 4999.1 4999.7 5004.8 5022.9 5088.3 5089.7 5102.6 5116.7 5138.9 5704.4 5710.9 5717.9 5722.7 5751.7 6513.9 6528.7 6554.6 6556.5 6673.4 1682.4 1713.7 1726.2 1769.1 1775.6 1205.3 1217.3 1233.4 1237.2 1246.1 5565.4 5584.5 5632.8 5633.7 5657.2 6447.7 6448.9 6457.2 6492.7 6527.2 5628.2 5628.5 5675.8 5754.3 5760.3 3575.8 3581.1 3587.9 3593.6 3611.5 6559.3 6608.7 6610.7 6611.5 6688.3 6844.9 6870.3 6929.9 6951.8 6969.8 6530.6 6548.7 6556.9 6564.9 6604.3 6748.0 6748.1 6822.6 6831.6 6839.7 6720.0 6762.3 6791.0 6791.2 6813.6 6597.0 6628.6 6635.9 6636.4 6640.9 4969.9 4974.8 5010.9 5033.4 5035.4 6745.9 6750.9 6835.0 6901.9 6907.6 6391.3 6499.7 6527.7 6540.3 6566.2 6369.2 6382.7 6407.5 6459.5 6481.5 6200.7 6369.9 6384.4 6409.3 6529.3 6374.2 6582.2 6596.1 6644.6 6664.8 6217.9 6310.8 6359.0 6431.3 6457.1 19:06:23 INFO - PID 23489 | 19:06:23 INFO - PID 23489 | [#5] yui.html Cycles:180 Average:24551.00 Median:19900.30 stddev:33338.47 (167.5%) stddev-sans-first:33384.66 19:06:23 INFO - PID 23489 | Values: 890.7 902.5 903.9 907.4 916.1 18539.7 18925.5 19490.0 19547.4 19558.2 21105.5 21774.6 21774.6 21787.0 21946.6 1021.6 1051.6 1061.0 1061.8 1062.7 1017.3 1037.2 1041.0 1049.4 1061.0 1113.3 1119.2 1120.7 1120.8 1121.3 1109.6 1114.0 1114.2 1114.3 1115.1 807.1 808.7 812.2 814.7 818.5 19276.7 19443.5 19487.5 19887.4 19913.2 21069.0 21421.4 21836.1 21870.6 22017.0 16295.8 16348.7 16435.5 16454.0 16696.2 3566.8 3592.2 3604.5 3605.9 3607.8 128818.4 129910.0 134450.0 134975.3 136198.0 16415.3 16568.5 16895.4 16972.7 16998.8 29945.6 30626.5 30647.7 30970.2 31162.8 7500.7 7767.6 8252.2 8359.0 8729.0 4248.5 4351.4 4382.8 4390.1 4394.9 5323.9 5439.5 5463.9 5477.2 5491.7 1083.4 1108.2 1122.2 1129.4 1131.0 683.7 695.8 697.3 703.0 706.7 11515.3 11746.3 11891.9 12052.2 12288.9 672.3 692.1 692.6 701.0 701.8 5389.8 5475.9 5577.0 5784.2 5823.2 109112.0 109267.5 112008.0 112548.4 114854.4 97938.0 98014.0 98144.0 98574.0 101068.5 88937.0 89400.1 89428.6 90326.5 90600.5 12902.2 12932.0 12983.1 13101.1 13218.5 40735.8 40877.8 41089.6 41217.0 41434.8 72950.0 76356.1 77092.2 78007.0 78449.0 14493.6 19877.3 19998.9 20017.7 20301.7 21484.8 21699.0 21760.1 22483.2 22834.4 18666.9 18906.3 19058.1 19207.5 19666.4 19743.5 19794.4 19798.7 19820.4 20057.9 18529.3 18640.3 18859.5 18876.0 18916.7 13202.5 13275.0 13327.5 13633.0 13787.2 14192.9 14515.9 14563.6 14622.4 14719.4 19:06:23 INFO - PID 23489 | -------- Summary: end -------- 19:06:23 INFO - PID 23489 | 19:06:24 INFO - TEST-INFO | 23489: exit 0 19:06:24 INFO - TEST-OK | dromaeo_css | took 1113221ms 19:06:24 INFO - TEST-START | kraken 19:06:24 INFO - Initialising browser for kraken test... 19:06:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38145/getInfo.html -profile /tmp/tmpZQrn9V/profile 19:06:24 INFO - TEST-INFO | started process 23858 (/builds/slave/test/build/application/firefox/firefox http://localhost:38145/getInfo.html) 19:06:30 INFO - TEST-INFO | 23858: exit 0 19:06:30 INFO - Browser initialized. 19:06:30 INFO - Running cycle 1/1 for kraken test... 19:06:30 INFO - TEST-INFO | started process 24073 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZQrn9V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:06:31 INFO - PID 24073 | 19:06:31 INFO - PID 24073 | (/builds/slave/test/build/application/firefox/firefox:24122): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:06:31 INFO - PID 24073 | 19:06:42 INFO - PID 24073 | RSS: Main: 165478400 19:06:42 INFO - PID 24073 | 19:06:44 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38145/tests/kraken/driver.html?testName=audio-beat-detection) 19:06:45 INFO - PID 24073 | RSS: Main: 171655168 19:06:45 INFO - PID 24073 | 19:06:48 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38145/tests/kraken/driver.html?testName=audio-dft) 19:06:49 INFO - PID 24073 | RSS: Main: 172060672 19:06:49 INFO - PID 24073 | 19:06:52 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38145/tests/kraken/driver.html?testName=audio-fft) 19:06:52 INFO - PID 24073 | RSS: Main: 166895616 19:06:52 INFO - PID 24073 | 19:06:54 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38145/tests/kraken/driver.html?testName=audio-oscillator) 19:06:55 INFO - PID 24073 | RSS: Main: 167170048 19:06:55 INFO - PID 24073 | 19:06:57 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38145/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:06:58 INFO - PID 24073 | RSS: Main: 167239680 19:06:58 INFO - PID 24073 | 19:07:01 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38145/tests/kraken/driver.html?testName=imaging-darkroom) 19:07:02 INFO - PID 24073 | RSS: Main: 170229760 19:07:02 INFO - PID 24073 | 19:07:05 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38145/tests/kraken/driver.html?testName=imaging-desaturate) 19:07:06 INFO - PID 24073 | RSS: Main: 170622976 19:07:06 INFO - PID 24073 | 19:07:08 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38145/tests/kraken/driver.html?testName=json-parse-financial) 19:07:09 INFO - PID 24073 | RSS: Main: 171298816 19:07:09 INFO - PID 24073 | 19:07:11 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38145/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:07:11 INFO - PID 24073 | RSS: Main: 171159552 19:07:11 INFO - PID 24073 | 19:07:13 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38145/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:07:14 INFO - PID 24073 | RSS: Main: 170991616 19:07:14 INFO - PID 24073 | 19:07:16 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38145/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:07:17 INFO - PID 24073 | RSS: Main: 170971136 19:07:17 INFO - PID 24073 | 19:07:19 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:07:20 INFO - PID 24073 | RSS: Main: 171294720 19:07:20 INFO - PID 24073 | 19:07:23 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:07:24 INFO - PID 24073 | RSS: Main: 171278336 19:07:24 INFO - PID 24073 | 19:07:25 INFO - PID 24073 | Cycle 1(1): loaded http://localhost:38145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38145/tests/kraken/driver.html?testName=ai-astar) 19:07:26 INFO - PID 24073 | RSS: Main: 172978176 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | __start_tp_report 19:07:26 INFO - PID 24073 | _x_x_mozilla_page_load 19:07:26 INFO - PID 24073 | _x_x_mozilla_page_load_details 19:07:26 INFO - PID 24073 | |i|pagename|runs| 19:07:26 INFO - PID 24073 | |0;ai-astar;98;108;100;106;108;110;111;100;109;111 19:07:26 INFO - PID 24073 | |1;audio-beat-detection;145;118;144;188;119;148;145;120;172;121 19:07:26 INFO - PID 24073 | |2;audio-dft;164;166;163;164;163;163;166;167;163;165 19:07:26 INFO - PID 24073 | |3;audio-fft;76;74;80;181;76;75;73;89;95;75 19:07:26 INFO - PID 24073 | |4;audio-oscillator;78;79;127;79;78;79;131;78;79;133 19:07:26 INFO - PID 24073 | |5;imaging-gaussian-blur;114;114;113;115;114;114;114;114;114;114 19:07:26 INFO - PID 24073 | |6;imaging-darkroom;151;151;152;151;149;149;151;150;151;151 19:07:26 INFO - PID 24073 | |7;imaging-desaturate;94;93;94;94;94;94;94;93;94;94 19:07:26 INFO - PID 24073 | |8;json-parse-financial;57;55;56;56;56;57;56;56;55;56 19:07:26 INFO - PID 24073 | |9;json-stringify-tinderbox;54;54;55;54;55;55;54;54;53;54 19:07:26 INFO - PID 24073 | |10;stanford-crypto-aes;76;78;81;78;77;80;85;78;77;86 19:07:26 INFO - PID 24073 | |11;stanford-crypto-ccm;129;132;123;140;124;125;123;127;134;173 19:07:26 INFO - PID 24073 | |12;stanford-crypto-pbkdf2;177;172;176;180;172;191;190;173;184;176 19:07:26 INFO - PID 24073 | |13;stanford-crypto-sha256-iterative;72;68;66;66;66;67;67;66;66;66 19:07:26 INFO - PID 24073 | __end_tp_report 19:07:26 INFO - PID 24073 | __start_cc_report 19:07:26 INFO - PID 24073 | _x_x_mozilla_cycle_collect,1008 19:07:26 INFO - PID 24073 | __end_cc_report 19:07:26 INFO - PID 24073 | __startTimestamp1502417246217__endTimestamp 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | ------- Summary: start ------- 19:07:26 INFO - PID 24073 | Number of tests: 14 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#0] ai-astar Cycles:10 Average:106.10 Median:109.50 stddev:4.93 (4.5%) stddev-sans-first:4.27 19:07:26 INFO - PID 24073 | Values: 98.0 108.0 100.0 106.0 108.0 110.0 111.0 100.0 109.0 111.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:145.00 stddev:23.81 (16.4%) stddev-sans-first:25.23 19:07:26 INFO - PID 24073 | Values: 145.0 118.0 144.0 188.0 119.0 148.0 145.0 120.0 172.0 121.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.50 stddev:1.51 (0.9%) stddev-sans-first:1.59 19:07:26 INFO - PID 24073 | Values: 164.0 166.0 163.0 164.0 163.0 163.0 166.0 167.0 163.0 165.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#3] audio-fft Cycles:10 Average:89.40 Median:76.00 stddev:32.98 (43.4%) stddev-sans-first:34.62 19:07:26 INFO - PID 24073 | Values: 76.0 74.0 80.0 181.0 76.0 75.0 73.0 89.0 95.0 75.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:78.50 stddev:25.05 (31.9%) stddev-sans-first:25.88 19:07:26 INFO - PID 24073 | Values: 78.0 79.0 127.0 79.0 78.0 79.0 131.0 78.0 79.0 133.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 19:07:26 INFO - PID 24073 | Values: 114.0 114.0 113.0 115.0 114.0 114.0 114.0 114.0 114.0 114.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:151.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 19:07:26 INFO - PID 24073 | Values: 151.0 151.0 152.0 151.0 149.0 149.0 151.0 150.0 151.0 151.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:07:26 INFO - PID 24073 | Values: 94.0 93.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.00 stddev:0.67 (1.2%) stddev-sans-first:0.60 19:07:26 INFO - PID 24073 | Values: 57.0 55.0 56.0 56.0 56.0 57.0 56.0 56.0 55.0 56.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#9] json-stringify-tinderbox Cycles:10 Average:54.20 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 19:07:26 INFO - PID 24073 | Values: 54.0 54.0 55.0 54.0 55.0 55.0 54.0 54.0 53.0 54.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:79.00 stddev:3.44 (4.4%) stddev-sans-first:3.39 19:07:26 INFO - PID 24073 | Values: 76.0 78.0 81.0 78.0 77.0 80.0 85.0 78.0 77.0 86.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#11] stanford-crypto-ccm Cycles:10 Average:133.00 Median:130.50 stddev:15.08 (11.6%) stddev-sans-first:15.93 19:07:26 INFO - PID 24073 | Values: 129.0 132.0 123.0 140.0 124.0 125.0 123.0 127.0 134.0 173.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:178.50 stddev:7.05 (3.9%) stddev-sans-first:7.43 19:07:26 INFO - PID 24073 | Values: 177.0 172.0 176.0 180.0 172.0 191.0 190.0 173.0 184.0 176.0 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:66.50 stddev:1.89 (2.8%) stddev-sans-first:0.73 19:07:26 INFO - PID 24073 | Values: 72.0 68.0 66.0 66.0 66.0 67.0 67.0 66.0 66.0 66.0 19:07:26 INFO - PID 24073 | -------- Summary: end -------- 19:07:26 INFO - PID 24073 | 19:07:26 INFO - PID 24073 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:07:26 INFO - TEST-INFO | 24073: exit 0 19:07:26 INFO - TEST-OK | kraken | took 62573ms 19:07:26 INFO - SUITE-END | took 1175s 19:07:27 INFO - Completed test suite (00:19:36) 19:07:27 INFO - javascript benchmark 19:07:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8591.285133264419, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3118.236032172118, 3146.763992700548, 3158.1315138336963, 3174.5555622212896, 3181.093388384311, 6241.21984751906, 6252.906206406896, 6265.185525881635, 6272.905906411402, 6306.463950564202, 6187.907181392283, 6297.748090076391, 6302.905456418146, 6306.274778400485, 6321.968390158055, 5733.885322293562, 5751.223584816038, 5773.0, 5784.4504772046585, 5794.362620111784, 5776.82669519913, 5786.82639520813, 5812.738426770784, 5816.563757718188, 5885.970570147143, 5805.0, 5817.941820581804, 5865.560082993771, 5869.589128760988, 5941.465268125868, 5627.6904770237625, 5737.254156959569, 5820.476157145835, 5846.678432686201, 5867.647941123546, 5715.599908006441, 5761.683107429089, 5767.250257466502, 5773.595848290622, 5781.248437703114, 6205.937940620581, 6237.656928868911, 6246.87506249877, 6300.30696623371, 6325.430711236011, 6733.663316834188, 6750.291219421939, 6764.120664313607, 6827.726890924357, 6843.8973415398805, 6812.080369150151, 6945.305469453065, 6952.374286314256, 6955.0, 6967.233604303523, 6292.056191571255, 6296.275928268216, 6375.8406039848915, 6377.936220637827, 6379.744810207632, 6862.691178896962, 6950.965245173843, 7033.542819716702, 7050.259722729143, 7060.046893669289, 6535.477161827042, 6591.274959754423, 6643.534952553275, 6670.83322916931, 6708.563943343667, 6703.899441508382, 6714.697838597275, 6835.282295358916, 6835.282295359015, 6841.589504629638, 6589.406953374123, 6594.076829243813, 6705.228898676617, 6718.126643536407, 6719.395254427027, 6637.800865973932, 6687.130673012574, 6748.831279218059, 6749.190097188369, 6800.217974932847, 5422.783088676488, 5435.0, 5518.558515318765, 5520.530754885799, 5520.944790552124, 4705.411823522059, 4754.78603462845, 4769.594584460359, 4776.546228108324, 4792.113459010095, 5768.625039372428, 5832.941670583325, 5928.466438020599, 5931.495822855106, 5937.0, 6679.899801502981, 6765.830854228684, 6779.457643388517, 6781.355771201728, 6781.898271525931, 5827.708614569255, 5828.88342233153, 5844.064949608042, 5845.1232315153065, 5850.0932355484965, 6030.366811484819, 6076.149339092531, 6094.908576371358, 6107.358727333655, 6115.724792384264, 6616.338366163442, 6627.569208001464, 6701.5979041256705, 6718.966405168041, 6725.831854203586, 6743.224529179011, 6838.0, 6850.691718872563, 6935.722571097201, 6942.028116063654, 6599.142111525471, 6629.303923088007, 6629.867402651824, 6634.071230027704, 6637.336266373517, 6672.132622759011, 6684.766033188815, 6720.0591917132615, 6749.055132281386, 6791.286914874065, 6464.773732919325, 6886.449084073162, 6887.7244910203035, 6972.6513674315065, 6989.0, 6628.436582890508, 6637.402633762985, 6713.12729345182, 6721.361470660279, 6722.764703235559, 6503.902441463477, 6554.80335589933, 6612.272650008399, 6633.70148343316, 6637.2699003108655, 6509.544331896722, 6535.44448721864, 6589.835254118685, 6590.604563726191, 6598.043283723737, 6616.66916654175, 6630.403263706289, 6676.132102826601, 6692.966535167293, 6718.630475323904, 6350.396712312323, 6359.300476947439, 6440.420362167428, 6462.838429039311, 6492.610443373413], "value": 6193.816864720911, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.5345299726159, 835.8787975743521, 874.772559134625, 890.7639475538988, 896.6906417286039, 40937.79531102344, 41701.7914910425, 41732.16535669289, 41734.0, 41782.58217417825, 37071.44392834103, 37197.62802371976, 37560.62439375606, 37572.43641345375, 37630.0, 6867.278935711765, 6962.582245065312, 6985.091938048072, 6991.545549539289, 6998.755043573476, 6896.48276379271, 7486.139094004177, 7629.122650895135, 7643.0, 7669.769906902774, 7686.385089192879, 7707.537547747125, 7717.922820771804, 7725.652345644459, 7731.265529774662, 7560.773176804704, 7638.541687498768, 7776.222377762178, 7800.921990780076, 7803.453758236926, 7626.313631773111, 7653.15815260321, 7702.306792388656, 7707.306342429152, 7708.614569271514, 37684.057898552484, 37956.05109872248, 37959.81020094882, 38012.61987380119, 38041.42937855935, 3590.7127429805555, 3609.7292703047115, 3610.8916732497805, 3619.927601447983, 3637.872674456408, 30737.0, 30803.691963080306, 30987.535186972214, 30996.380072398424, 31040.0, 4901.338319326881, 5047.671901326402, 5048.899022019576, 5049.798008079672, 5066.0, 189527.0, 192548.0, 192790.0, 194808.0, 195202.0, 43142.0, 48937.75531122321, 49529.50470495321, 49650.503494964585, 49654.751726241135, 51871.221931671054, 51947.22079168815, 51970.22044669333, 52007.73996129995, 52019.0, 14731.337089830984, 14789.186594737177, 14842.925785371004, 14852.331645075998, 14853.405863765545, 11739.823902641467, 11822.527098915947, 11861.762764744657, 11863.76272474563, 11867.525298988117, 14180.361883715257, 14259.07316024455, 14262.286885655883, 14318.14091154534, 14572.052816567102, 5783.595148339576, 5868.618539794899, 5932.555058370639, 5982.222311099529, 5992.34084250732, 997.7256254530063, 1026.7535791410007, 1029.6447725534679, 1031.1493018259966, 1039.6880935719255, 22896.65655015176, 22961.88519057394, 23029.76970230276, 23200.883995579916, 23259.0, 1098.5715570927346, 1099.6865893220395, 1117.4915413486844, 1120.971975700614, 1121.332806979862, 12304.217018001526, 12393.62819115428, 12412.441440135215, 12444.191127576678, 12467.625971220878, 233161.0, 233552.0, 235788.0, 235893.0, 236848.0, 51832.0, 51834.48165518372, 51922.48077519252, 52007.0, 52081.0, 1460.2260801775076, 1484.3172140815345, 1487.516557118919, 1511.093343993595, 1513.8788896888223, 21419.571608568054, 21465.420038516768, 21999.560008800054, 22091.226807061677, 22165.113395464647, 36928.44607330838, 36978.6302136986, 36996.0, 37011.6298837019, 37040.259194815415, 192736.0, 193381.0, 193971.0, 194076.0, 194142.0, 31288.0, 32626.184345391554, 34477.655223447444, 34562.0, 34657.48013779745, 43264.13471730485, 43497.56502435062, 43582.56417435785, 43682.0, 44558.77720611376, 32473.0, 32946.5058024125, 33011.0, 33437.33125337528, 33549.66450335563, 32391.676083238865, 32600.347993039533, 32735.18162045968, 32807.75624371597, 32950.67049329476, 35717.82141089382, 35893.28213435769, 35960.640393596776, 36037.09907252339, 36110.4583431254, 30441.695583043886, 31318.843405782824, 31369.215769605944, 31937.520937185494, 32481.512777307886, 23186.652200217348, 23393.06427742871, 23509.0, 23615.64576531319, 23991.160309389088], "value": 16983.242750361715, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.8356860079176, 588.1119509540595, 592.193808882907, 596.9134475501053, 598.8892054969831, 22561.435964100885, 23139.958701858413, 23302.300930972076, 23328.416789580268, 23345.76654233457, 26882.462350753023, 27300.45399092017, 27320.180394588144, 27374.31564210896, 27525.724742752565, 6624.470042396621, 6765.661716914159, 6766.492513061516, 6782.660866956657, 6785.7964261072, 6623.105880706115, 6684.46524278059, 6691.933080669204, 6762.5942443453305, 7061.470389720792, 7074.434045276391, 7075.150981882181, 7080.504364694473, 7082.256363081854, 7082.575045497261, 7079.716811327541, 7091.468139889503, 7094.1841688206005, 7094.822629434279, 7096.148462184545, 6917.239103698589, 6931.2375638679705, 6944.340287672663, 6946.756863509803, 6972.616506092163, 30973.84513077443, 31320.37359252825, 32091.0, 32357.352852943044, 32780.1804954878, 1973.605278944217, 2000.0699674651212, 2004.4387571479863, 2007.3175120459114, 2008.2770202727006, 20695.482612934647, 21382.144714211412, 21404.0, 21407.357779266647, 21413.14347426101, 2472.1594657816427, 2492.987535062331, 2494.077191439179, 2555.1312553731636, 2605.7654811067096, 148074.0, 152109.9999999989, 152409.0, 152486.0, 152897.0, 19673.704894426595, 21382.0, 21516.190628530523, 23046.695330467155, 23534.64698029531, 45694.314585281245, 45775.0, 46001.30998035032, 46110.538894611294, 46311.536884630725, 10966.506507207196, 11106.722331941604, 11167.553297868157, 11189.776204475866, 11195.832062519068, 9081.636734530677, 9142.0, 9142.268618510503, 9158.450492970444, 9329.440233586007, 9167.908320916838, 11684.298942063504, 11833.171677982631, 11849.20107356522, 11860.22908510944, 3780.984313255915, 4022.959770402317, 4043.8180281887385, 4179.644730197906, 4282.957170428318, 909.4270609516095, 949.378157306965, 953.1897886796168, 955.47926380123, 964.3827950111934, 19876.801231987785, 20086.799132008786, 20127.993600320217, 20264.49338766513, 20663.690044649342, 999.550202408919, 1006.572206812102, 1008.2287050406445, 1011.5346940407347, 1022.1005515146624, 10872.402017889091, 11246.1565382595, 11292.0, 11320.377379244055, 11344.602938897264, 422135.0, 432695.0, 443029.0, 443496.0, 446614.0, 108246.45876770566, 117630.0, 118031.0, 118125.0, 118663.0, 1169.3159501691605, 1205.6563879294358, 1205.6624145239348, 1206.5173930427723, 1206.7164216409137, 17758.733618995466, 18022.729659055385, 18235.908820456345, 18249.543761406072, 18522.351717689817, 55895.0, 57705.71147144237, 58285.417145827996, 58980.705096474245, 59065.70467147637, 67705.32294677188, 67884.0, 68139.65930170317, 68218.0, 69562.0, 14837.554873353816, 15219.096828355536, 15248.085114893142, 15279.465218717292, 15412.0, 23559.646605301277, 23822.8088595573, 23873.641895371235, 23956.52086958286, 24112.0, 18351.449456516326, 18598.349057782914, 19009.144588493273, 19075.0, 19139.904300478407, 15643.608909777347, 16294.348226070824, 16334.346626134822, 16532.256048478088, 16884.831151688326, 19149.234030638618, 19396.2241510338, 19411.902940485772, 19579.70630440573, 19700.113494893052, 15016.849831501546, 15038.6992260152, 15118.470853520073, 15150.16674082936, 15235.238238088274, 18906.621867562295, 19044.333448329242, 19197.712034319775, 19227.230910763974, 19401.126949287034], "value": 12864.371756246079, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.4936524120835, 1354.4988354308907, 1369.7671395862708, 1374.9450021999116, 1378.6966867289193, 13529.391177397016, 14121.223332716696, 14202.857971420282, 14240.430382784689, 14289.928550357246, 13486.932565337183, 14025.298735063232, 14448.638784030405, 14471.78292325616, 14487.78268325976, 2622.082271205082, 2683.07433935292, 2692.044324264887, 2694.0, 2694.932626684329, 2544.3257536752776, 2550.1966880432697, 2588.0812311629384, 2675.1573254424893, 2676.170387179971, 2633.3285012321926, 2638.907638232662, 2653.429512654779, 2653.8938442462377, 2665.5335316319665, 2548.3374322676236, 2555.246202370305, 2572.0354866924904, 2573.961390579143, 2576.8196226264263, 2485.155047283932, 2509.9834567000394, 2521.760432758885, 2526.305266051832, 2530.72162062173, 13597.86402135976, 13934.233617151052, 13937.233452160128, 13942.23317717525, 13946.0, 6929.3070692930305, 7652.808679782995, 7678.923210767876, 7699.961500192463, 7706.653200605986, 4888.560029597354, 5046.34397528319, 5103.617228707824, 5113.948860511385, 5142.974285128588, 1764.6382491589322, 1780.3946658136297, 1791.6237590106093, 1831.569581148429, 1839.6872531669649, 22468.887655561783, 22495.10019599214, 22546.887265563568, 22584.43538911524, 22645.207417740465, 6182.412670796267, 6198.783042593487, 6225.688715564203, 6393.360663933569, 6417.06952491886, 15700.528984130495, 15705.21473926322, 15709.293081811345, 15711.450099246471, 15720.29258683362, 7005.824854378579, 7357.59533225667, 7380.9630951845975, 7384.926150738531, 7394.445416593777, 6719.428848547928, 6814.93185068143, 6863.485238607124, 6887.035814985806, 6895.379415852597, 4544.636429085665, 4678.508756580578, 4807.6153907688, 4877.195262781602, 4877.365942427533, 1266.5377137344988, 1351.3783659516537, 1369.5480491437802, 1391.5825252424231, 1391.7981892625512, 858.6822875536092, 872.1540106097076, 879.8680197970227, 882.8984666763276, 897.0804924951846, 7890.8816367754525, 7914.327282180964, 8061.113277539466, 8075.596220188967, 8129.430939834273, 941.8540126280437, 949.6106596295485, 958.1903291718487, 965.5413678502655, 972.3728195313982, 4664.533546645308, 4817.710937343771, 4836.5888899443235, 4903.387076615423, 5040.445550989388, 19089.427317180507, 19125.2349906005, 19806.60386792256, 19841.60316793685, 19868.304609338604, 19010.144543495575, 19243.0, 19404.0, 19858.304959326353, 19884.60230795376, 10303.330283531397, 10767.084797792118, 10877.184211184192, 11045.116390688725, 11077.556897724162, 7672.923270767221, 7897.170797066455, 7982.281594656509, 8010.036286574005, 8143.267105960255, 16802.495925122264, 16873.156342183087, 16944.0, 17152.656946860745, 17636.64726705433, 17244.137793110545, 17249.741253881453, 17342.653146937242, 17351.913240433718, 17459.38892138769, 10458.424786636808, 10573.04842564173, 10577.153827693612, 10585.629502967358, 10688.091512221548, 12802.67993300175, 12952.870471295166, 13044.5434409799, 13093.869061309266, 13110.0, 8888.64445422176, 10245.795084098425, 10366.740831479274, 10487.895121048998, 10499.422531760827, 10230.488475576043, 10324.845127323246, 10383.428911409945, 10458.63394781179, 10617.681469555617, 8444.535550544533, 8493.57532123379, 8645.049044605214, 8645.870311945451, 8674.52290124024, 6703.564268322543, 6752.628605426552, 6789.762358317435, 6819.318068193141, 6868.416184624363, 6923.723051077901, 6960.895586566105, 6971.965140174267, 6999.6850141742725, 7056.823579410556], "value": 6235.524583393747, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.5461007787951, 502.68046378257077, 522.9921551176732, 528.4715284715285, 531.1846315905084, 6266.370743606667, 6276.497880169587, 6278.372162783719, 6330.4935605151595, 6356.872862542747, 5962.165296858443, 6085.604435711675, 6121.234845644294, 6130.540209484284, 6149.846253843645, 4863.659543831933, 4995.175795993675, 5007.649464537493, 5008.549230569248, 5072.137736584771, 4929.940062886479, 4976.850694479172, 4982.181503751123, 5008.771096861898, 5032.723200223986, 4817.892459363708, 4893.388326459192, 4971.477994810547, 4976.925346119793, 4987.875303117433, 4974.527419895104, 4988.950110498885, 4994.275830004647, 5016.3980322361185, 5037.4458809530925, 4472.508024117345, 4491.797869095866, 4497.9325310120375, 4500.977495112536, 4504.635066590034, 5994.640321580676, 6017.247844019497, 6042.848928776772, 6046.486048685867, 6058.212432383806, 5782.392848750863, 5782.7397767100165, 5795.652260864362, 5817.447342502455, 5820.476157145878, 6345.746170153188, 6371.171747672819, 6475.805725828232, 6509.772157974448, 6513.902291465632, 4882.3408839806525, 4893.314935908976, 4895.755212239374, 4899.632527560448, 4935.235038569028, 6693.397594216543, 6925.3074692530345, 6935.202451718117, 6951.096357473496, 6952.443804495628, 4940.580050695659, 4999.125153098194, 4999.7250701071725, 5004.799808007639, 5022.89954200914, 5088.287639730441, 5089.745512724423, 5102.591792656579, 5116.667416617908, 5138.897222055538, 5704.372519022904, 5710.914926164016, 5717.885642287214, 5722.740996980658, 5751.741171647286, 6513.934860651428, 6528.738850446024, 6554.606723596599, 6556.541042127101, 6673.432758215509, 1682.3691115831564, 1713.6572685462957, 1726.1973182470213, 1769.0889192066097, 1775.6359946210996, 1205.2949024820402, 1217.3487184356327, 1233.3894722112611, 1237.189640785287, 1246.1339369138361, 5565.443455654402, 5584.499268220799, 5632.817108407198, 5633.6619802811965, 5657.236273103161, 6447.709853056624, 6448.935510644834, 6457.192850893638, 6492.67536623167, 6527.160685101996, 5628.212050312959, 5628.493435590816, 5675.772969081273, 5754.251947246915, 5760.279965004374, 3575.80333081683, 3581.1405262737276, 3587.8564857405413, 3593.6226696197045, 3611.5485564304463, 6559.311272316338, 6608.702608382634, 6610.669466526654, 6611.471082313499, 6688.264290927994, 6844.93155068443, 6870.251899240344, 6929.861402771916, 6951.7914462566205, 6969.7909062728195, 6530.608163510205, 6548.705308261045, 6556.868862622721, 6564.868702626016, 6604.3395660434535, 6748.0, 6748.089007984056, 6822.556533825285, 6831.6242606657115, 6839.69221385029, 6720.0, 6762.256151823394, 6791.0153027811675, 6791.185057793096, 6813.591184528944, 6597.0, 6628.613699068428, 6635.9004614929845, 6636.402723754885, 6640.933590664032, 4969.850904472872, 4974.801007959641, 5010.924836127534, 5033.446320904624, 5035.370578677665, 6745.898811517733, 6750.932490675031, 6835.0, 6901.861962760817, 6907.585544867324, 6391.296957334596, 6499.707513161825, 6527.67361631926, 6540.313267106885, 6566.179227596551, 6369.235691717024, 6382.712777925098, 6407.5010745810105, 6459.450946669585, 6481.4814814815645, 6200.697853450826, 6369.936300636934, 6384.3934826191435, 6409.3270206629495, 6529.269743518066, 6374.21521973848, 6582.17722784652, 6596.142501474777, 6644.568103073285, 6664.8000559983275, 6217.906731398942, 6310.7791227306825, 6359.0, 6431.324710905287, 6457.128287681103], "value": 5057.862280642681, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [890.7060669978907, 902.454015320731, 903.9457632542052, 907.3512438606396, 916.1296768070333, 18539.72190417145, 18925.52686182846, 19490.0, 19547.41357759266, 19558.21767129315, 21105.47236319093, 21774.564508709816, 21774.564508709816, 21787.0, 21946.561068778654, 1021.6475316015963, 1051.647698021164, 1061.0238580505913, 1061.8194906865851, 1062.659948816379, 1017.2929813779426, 1037.1702637889696, 1041.0422411381546, 1049.3703777733376, 1061.029158320136, 1113.259682311262, 1119.1774046076127, 1120.714217874445, 1120.7926533591271, 1121.2711737370732, 1109.6449136276394, 1113.9610113645983, 1114.225613198819, 1114.3481063577817, 1115.0577761791274, 807.1444269074799, 808.720991258015, 812.2202685422002, 814.6822739131743, 818.5088946632033, 19276.710849337273, 19443.513912152168, 19487.512812179666, 19887.403377898685, 19913.203471861107, 21069.0, 21421.357359279245, 21836.126554937782, 21870.562588748136, 22017.009234584482, 16295.837041629551, 16348.673026539522, 16435.506934791974, 16454.0, 16696.248668809934, 3566.7503274770775, 3592.2276710507367, 3604.4953706481115, 3605.9098522536883, 3607.783532988029, 128818.35590821986, 129910.0, 134450.0, 134975.32512337473, 136198.0, 16415.343386264656, 16568.502944911674, 16895.408660697063, 16972.74540881888, 16998.830011699723, 29945.550816737767, 30626.54060189099, 30647.693523064485, 30970.22574435612, 31162.844185778926, 7500.737474188377, 7767.572783496849, 8252.21603947624, 8358.958205208935, 8728.956355218183, 4248.468941382327, 4351.369051487517, 4382.824687012548, 4390.07808360248, 4394.912101757947, 5323.946760532423, 5439.5376393006245, 5463.945360546344, 5477.233187353773, 5491.697956612345, 1083.3824719909576, 1108.2463924531235, 1122.1583812140893, 1129.4239937631867, 1131.032966813379, 683.736761346885, 695.7530076822757, 697.2852825853439, 702.9929700703029, 706.6784613001072, 11515.251508652078, 11746.29522228669, 11891.940540297242, 12052.156349055482, 12288.938555307344, 672.2739441403272, 692.0829900381914, 692.5706062241443, 700.9579425234502, 701.7999870037015, 5389.83830485086, 5475.863103422446, 5576.97211513948, 5784.161296611967, 5823.184754134425, 109112.0, 109267.45366273118, 112008.0, 112548.43725781482, 114854.4257278725, 97938.0, 98014.0, 98144.0, 98574.0, 101068.49465752771, 88937.0, 89400.10599894047, 89428.55285723529, 90326.54836725774, 90600.5469972646, 12902.161359511978, 12932.0, 12983.091183617056, 13101.148425352321, 13218.537351192663, 40735.796321018206, 40877.79561102176, 41089.58910410858, 41217.0, 41434.79282603568, 72950.0, 76356.13207547285, 77092.22907771076, 78007.0, 78449.0, 14493.565193044225, 19877.30429434963, 19998.90000549988, 20017.699734504284, 20301.695474568187, 21484.785152148903, 21699.023543940246, 21760.12959481603, 22483.21308754186, 22834.42913927165, 18666.906665466584, 18906.338278160198, 19058.142383592494, 19207.51981200481, 19666.410007699793, 19743.506412339808, 19794.40616781499, 19798.70301945443, 19820.40538783839, 20057.899710501355, 18529.258829646664, 18640.254389824255, 18859.528511787314, 18876.0, 18916.716249256548, 13202.471901124232, 13275.0, 13327.533536326182, 13633.045686801828, 13787.172769633855, 14192.92903535517, 14515.92742036283, 14563.563093107225, 14622.41510339617, 14719.411223551366], "value": 9421.357709493375, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1523.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 100.0, 106.0, 108.0, 110.0, 111.0, 100.0, 109.0, 111.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 118.0, 144.0, 188.0, 119.0, 148.0, 145.0, 120.0, 172.0, 121.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 163.0, 164.0, 163.0, 163.0, 166.0, 167.0, 163.0, 165.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 80.0, 181.0, 76.0, 75.0, 73.0, 89.0, 95.0, 75.0], "value": 89.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 127.0, 79.0, 78.0, 79.0, 131.0, 78.0, 79.0, 133.0], "value": 94.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 152.0, 151.0, 149.0, 149.0, 151.0, 150.0, 151.0, 151.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 56.0, 56.0, 56.0, 57.0, 56.0, 56.0, 55.0, 56.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 54.0, 55.0, 55.0, 54.0, 54.0, 53.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 81.0, 78.0, 77.0, 80.0, 85.0, 78.0, 77.0, 86.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 132.0, 123.0, 140.0, 124.0, 125.0, 123.0, 127.0, 134.0, 173.0], "value": 133.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 172.0, 176.0, 180.0, 172.0, 191.0, 190.0, 173.0, 184.0, 176.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 66.0, 66.0, 66.0, 67.0, 67.0, 66.0, 66.0, 66.0], "value": 67.0, "unit": "score"}]}]} 19:07:27 INFO - Return code: 0 19:07:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:07:27 INFO - # TBPL SUCCESS # 19:07:27 INFO - Running post-action listener: _package_coverage_data 19:07:27 INFO - Running post-action listener: _resource_record_post_action 19:07:27 INFO - [mozharness: 2017-08-11 02:07:27.495540Z] Finished run-tests step (success) 19:07:27 INFO - Running post-run listener: _resource_record_post_run 19:07:27 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 16384; Write bytes: 430825472; Read time: 356; Write time: 649168 19:07:27 INFO - TinderboxPrint: CPU usage
13.2% 19:07:27 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 356 19:07:27 INFO - TinderboxPrint: I/O write bytes / time
430,825,472 / 649,168 19:07:27 INFO - TinderboxPrint: CPU idle
8,271.8 (86.8%) 19:07:27 INFO - TinderboxPrint: CPU user
1,200.4 (12.6%) 19:07:27 INFO - TinderboxPrint: Swap in / out
0 / 0 19:07:27 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 4096; Write bytes: 106754048; Read time: 100; Write time: 200432 19:07:27 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:07:27 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 8192; Write bytes: 324071424; Read time: 240; Write time: 448736 19:07:28 INFO - Running post-run listener: _upload_blobber_files 19:07:28 INFO - Blob upload gear active. 19:07:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:07:28 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 19:07:28 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:07:28 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:07:28 INFO - (blobuploader) - INFO - Open directory for files ... 19:07:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:07:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:07:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:35 INFO - (blobuploader) - INFO - Done attempting. 19:07:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:07:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:07:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:36 INFO - (blobuploader) - INFO - Done attempting. 19:07:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:07:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:07:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:37 INFO - (blobuploader) - INFO - Done attempting. 19:07:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:07:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:07:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:38 INFO - (blobuploader) - INFO - Done attempting. 19:07:38 INFO - (blobuploader) - INFO - Iteration through files over. 19:07:38 INFO - Return code: 0 19:07:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:07:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:07:38 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7712392f3df8687d35a28ab0ca75b5297aa5d8dc2f16e6a7148ad8ffc30817244303b0845a99150210dbeccabc121e6a240028132285447dafb355b689470659", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/507ae0fbfc16be51f1ac6ede7977b328120e72c1c3ac6a2a512d5fe8a4d4831171ba26b4804c74c205ec3ccce2a8fbb27a6a5b766831c754b2393a0bf0936fef", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c16c7b00b03a6bdfd15d1d81dafbdf285bb057ffd581c89f34bf6c6ef867d418c169371f027425a340560c249bd9e18dff5a93f0daa41be2dff8177c455dd1dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/75bfd54f809426f167733470eadf2bdaad244b47818fb3d486194a46eef97aaf4f353aaacf0c2bab8c27af88d272176ac64cd918c119fd8dc677f62b68c66fc9"} 19:07:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:07:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:07:38 INFO - Contents: 19:07:38 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7712392f3df8687d35a28ab0ca75b5297aa5d8dc2f16e6a7148ad8ffc30817244303b0845a99150210dbeccabc121e6a240028132285447dafb355b689470659", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/507ae0fbfc16be51f1ac6ede7977b328120e72c1c3ac6a2a512d5fe8a4d4831171ba26b4804c74c205ec3ccce2a8fbb27a6a5b766831c754b2393a0bf0936fef", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c16c7b00b03a6bdfd15d1d81dafbdf285bb057ffd581c89f34bf6c6ef867d418c169371f027425a340560c249bd9e18dff5a93f0daa41be2dff8177c455dd1dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/75bfd54f809426f167733470eadf2bdaad244b47818fb3d486194a46eef97aaf4f353aaacf0c2bab8c27af88d272176ac64cd918c119fd8dc677f62b68c66fc9"} 19:07:38 INFO - Running post-run listener: copy_logs_to_upload_dir 19:07:38 INFO - Copying logs to upload dir... 19:07:38 INFO - mkdir: /builds/slave/test/build/upload/logs 19:07:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.497187 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-10 19:07:37.783967) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-10 19:07:37.791427) ========= 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=eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486 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-Non-PGO/sha512/7712392f3df8687d35a28ab0ca75b5297aa5d8dc2f16e6a7148ad8ffc30817244303b0845a99150210dbeccabc121e6a240028132285447dafb355b689470659", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/507ae0fbfc16be51f1ac6ede7977b328120e72c1c3ac6a2a512d5fe8a4d4831171ba26b4804c74c205ec3ccce2a8fbb27a6a5b766831c754b2393a0bf0936fef", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c16c7b00b03a6bdfd15d1d81dafbdf285bb057ffd581c89f34bf6c6ef867d418c169371f027425a340560c249bd9e18dff5a93f0daa41be2dff8177c455dd1dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/75bfd54f809426f167733470eadf2bdaad244b47818fb3d486194a46eef97aaf4f353aaacf0c2bab8c27af88d272176ac64cd918c119fd8dc677f62b68c66fc9"} build_url:https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/FQylf4SMTjqgA4sl_IbrWA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7712392f3df8687d35a28ab0ca75b5297aa5d8dc2f16e6a7148ad8ffc30817244303b0845a99150210dbeccabc121e6a240028132285447dafb355b689470659", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/507ae0fbfc16be51f1ac6ede7977b328120e72c1c3ac6a2a512d5fe8a4d4831171ba26b4804c74c205ec3ccce2a8fbb27a6a5b766831c754b2393a0bf0936fef", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c16c7b00b03a6bdfd15d1d81dafbdf285bb057ffd581c89f34bf6c6ef867d418c169371f027425a340560c249bd9e18dff5a93f0daa41be2dff8177c455dd1dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/75bfd54f809426f167733470eadf2bdaad244b47818fb3d486194a46eef97aaf4f353aaacf0c2bab8c27af88d272176ac64cd918c119fd8dc677f62b68c66fc9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-10 19:07:37.818900) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 19:07:37.819230) ========= 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=eca2ab57546de7b41f256b19000001ef-1502415413.672697-2137392486 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004595 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-10 19:07:37.898848) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-10 19:07:37.902180) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-10 19:07:37.902481) ========= ========= Total master_lag: 0.16 =========