builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1502482687.56 results: success (0) revision: d0afe5d6350426e338c1acff28a4a0c38ba1965f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:07.563463) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:07.563971) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:07.564288) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:07.577928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:07.578262) ========= 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=03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-11 13:18:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 98.4M=0s 2017-08-11 13:18:07 (98.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.215592 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:07.805825) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:07.806188) ========= 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=03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019982 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:07.857026) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-11 13:18:07.857467) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d0afe5d6350426e338c1acff28a4a0c38ba1965f --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 d0afe5d6350426e338c1acff28a4a0c38ba1965f --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=03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-11 13:18:08,081 truncating revision to first 12 chars 2017-08-11 13:18:08,081 Setting DEBUG logging. 2017-08-11 13:18:08,081 attempt 1/10 2017-08-11 13:18:08,081 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d0afe5d63504?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-11 13:18:09,291 unpacking tar archive at: mozilla-central-d0afe5d63504/testing/mozharness/ program finished with exit code 0 elapsedTime=1.543635 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-11 13:18:09.414318) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:09.414700) ========= script_repo_revision: d0afe5d6350426e338c1acff28a4a0c38ba1965f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:09.415151) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:09.415470) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 13:18:09.424357) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-11 13:18:09.424732) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:18:09 INFO - MultiFileLogger online at 20170811 13:18:09 in /builds/slave/test 13:18:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 13:18:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:18:09 INFO - {'append_to_log': False, 13:18:09 INFO - 'base_work_dir': '/builds/slave/test', 13:18:09 INFO - 'blob_upload_branch': 'Firefox', 13:18:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:18:09 INFO - 'branch': 'Firefox', 13:18:09 INFO - 'buildbot_json_path': 'buildprops.json', 13:18:09 INFO - 'code_coverage': False, 13:18:09 INFO - 'config_files': ('talos/linux_config.py',), 13:18:09 INFO - 'default_actions': ('clobber', 13:18:09 INFO - 'read-buildbot-config', 13:18:09 INFO - 'download-and-extract', 13:18:09 INFO - 'populate-webroot', 13:18:09 INFO - 'create-virtualenv', 13:18:09 INFO - 'install', 13:18:09 INFO - 'setup-mitmproxy', 13:18:09 INFO - 'run-tests'), 13:18:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:18:09 INFO - 'disable_ccov_upload': False, 13:18:09 INFO - 'download_minidump_stackwalk': True, 13:18:09 INFO - 'download_symbols': 'ondemand', 13:18:09 INFO - 'enable_stylo': False, 13:18:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:18:09 INFO - 'tooltool.py': '/tools/tooltool.py', 13:18:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:18:09 INFO - '/tools/misc-python/virtualenv.py')}, 13:18:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:18:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:18:09 INFO - 'gecko_profile': False, 13:18:09 INFO - 'gecko_profile_interval': 0, 13:18:09 INFO - 'installer_path': 'installer.exe', 13:18:09 INFO - 'log_level': 'info', 13:18:09 INFO - 'log_name': 'talos', 13:18:09 INFO - 'log_to_console': True, 13:18:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:18:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:18:09 INFO - 'opt_config_files': (), 13:18:09 INFO - 'pip_index': False, 13:18:09 INFO - 'suite': 'dromaeojs-e10s', 13:18:09 INFO - 'system_bits': '32', 13:18:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:18:09 INFO - 'title': 'talos-linux64-ix-047', 13:18:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:18:09 INFO - 'use_talos_json': True, 13:18:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:18:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:18:09 INFO - 'work_dir': 'build'} 13:18:09 INFO - [mozharness: 2017-08-11 20:18:09.761739Z] Running clobber step. 13:18:09 INFO - Running pre-action listener: _resource_record_pre_action 13:18:09 INFO - Running main action method: clobber 13:18:09 INFO - rmtree: /builds/slave/test/build 13:18:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:18:10 INFO - Running post-action listener: _resource_record_post_action 13:18:10 INFO - [mozharness: 2017-08-11 20:18:10.844383Z] Finished clobber step (success) 13:18:10 INFO - [mozharness: 2017-08-11 20:18:10.844485Z] Running read-buildbot-config step. 13:18:10 INFO - Running pre-action listener: _resource_record_pre_action 13:18:10 INFO - Running main action method: read_buildbot_config 13:18:10 INFO - Using buildbot properties: 13:18:10 INFO - { 13:18:10 INFO - "project": "", 13:18:10 INFO - "product": "firefox", 13:18:10 INFO - "who": "kwierso@gmail.com", 13:18:10 INFO - "installer_path": "public/build/target.tar.bz2", 13:18:10 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 13:18:10 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 13:18:10 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 13:18:10 INFO - "stage_platform": "linux64-stylosequential", 13:18:10 INFO - "basedir": "/builds/slave/test", 13:18:10 INFO - "buildnumber": 182, 13:18:10 INFO - "platform": "ubuntu64_hw_styloseq", 13:18:10 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 13:18:10 INFO - "slavebuilddir": "test", 13:18:10 INFO - "taskId": "TAVSkdkzS6-4mcR7mHB-iQ", 13:18:10 INFO - "branch": "mozilla-central", 13:18:10 INFO - "script_repo_revision": "production", 13:18:10 INFO - "revision": "d0afe5d6350426e338c1acff28a4a0c38ba1965f", 13:18:10 INFO - "slavename": "talos-linux64-ix-047", 13:18:10 INFO - "repo_path": "mozilla-central" 13:18:10 INFO - } 13:18:10 INFO - Finding installer, test and symbols from parent task. 13:18:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TAVSkdkzS6-4mcR7mHB-iQ'}, attempt #1 13:18:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TAVSkdkzS6-4mcR7mHB-iQ'}, attempt #1 13:18:11 INFO - Task dependencies: DuKjEa7WSn-Ah0CktatpHg 13:18:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg'}, attempt #1 13:18:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.tar.bz2 13:18:12 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.test_packages.json 13:18:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.crashreporter-symbols.zip 13:18:12 INFO - Running post-action listener: _resource_record_post_action 13:18:12 INFO - [mozharness: 2017-08-11 20:18:12.194273Z] Finished read-buildbot-config step (success) 13:18:12 INFO - [mozharness: 2017-08-11 20:18:12.194493Z] Running download-and-extract step. 13:18:12 INFO - Running pre-action listener: _resource_record_pre_action 13:18:12 INFO - Running main action method: download_and_extract 13:18:12 INFO - mkdir: /builds/slave/test/build/tests 13:18:12 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')]} 13:18:12 INFO - trying https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.test_packages.json 13:18:12 INFO - Downloading https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:18:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:18:12 INFO - Downloaded 989 bytes. 13:18:12 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:18:12 INFO - Using the following test package requirements: 13:18:12 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 13:18:12 INFO - u'common': [u'target.common.tests.zip'], 13:18:12 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:18:12 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:18:12 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:18:12 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:18:12 INFO - u'mozbase': [u'target.common.tests.zip'], 13:18:12 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:18:12 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:18:12 INFO - u'web-platform': [u'target.common.tests.zip', 13:18:12 INFO - u'target.web-platform.tests.tar.gz'], 13:18:12 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:18:12 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 13:18:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.common.tests.zip 13:18:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:18:12 INFO - Fetch https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.common.tests.zip into memory 13:18:13 INFO - Content-Length response header: 54036625 13:18:13 INFO - Bytes received: 54036625 13:18:24 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 13:18:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.common.tests.zip 13:18:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:18:24 INFO - Fetch https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.common.tests.zip into memory 13:18:25 INFO - Content-Length response header: 54036625 13:18:25 INFO - Bytes received: 54036625 13:18:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.talos.tests.zip 13:18:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 13:18:37 INFO - Fetch https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.talos.tests.zip into memory 13:18:38 INFO - Content-Length response header: 14496050 13:18:38 INFO - Bytes received: 14496050 13:18:38 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')]} 13:18:38 INFO - trying https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.tar.bz2 13:18:38 INFO - Downloading https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 13:18:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 13:18:39 INFO - Downloaded 61767710 bytes. 13:18:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.tar.bz2 13:18:39 INFO - mkdir: /builds/slave/test/properties 13:18:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:18:39 INFO - Writing to file /builds/slave/test/properties/build_url 13:18:39 INFO - Contents: 13:18:39 INFO - build_url:https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.tar.bz2 13:18:39 INFO - Running post-action listener: _resource_record_post_action 13:18:39 INFO - Running post-action listener: set_extra_try_arguments 13:18:39 INFO - [mozharness: 2017-08-11 20:18:39.546761Z] Finished download-and-extract step (success) 13:18:39 INFO - [mozharness: 2017-08-11 20:18:39.546859Z] Running populate-webroot step. 13:18:39 INFO - Running pre-action listener: _resource_record_pre_action 13:18:39 INFO - Running main action method: populate_webroot 13:18:39 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:18:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:18:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:18:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:18:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:18:39 INFO - 'tests': ['damp', 'tps']}, 13:18:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:18:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 13:18:39 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 13:18:39 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 13:18:39 INFO - 'other-e10s': {'tests': ['a11yr', 13:18:39 INFO - 'ts_paint', 13:18:39 INFO - 'tpaint', 13:18:39 INFO - 'sessionrestore', 13:18:39 INFO - 'sessionrestore_many_windows', 13:18:39 INFO - 'sessionrestore_no_auto_restore', 13:18:39 INFO - 'tabpaint']}, 13:18:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 13:18:39 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 13:18:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:18:39 INFO - 'tsvgr_opacity', 13:18:39 INFO - 'tart', 13:18:39 INFO - 'tscrollx', 13:18:39 INFO - 'tsvg_static']}, 13:18:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:18:39 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:18:39 INFO - 'talos_options': ['--mitmproxy', 13:18:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:18:39 INFO - '--firstNonBlankPaint'], 13:18:39 INFO - 'tests': ['tp6_google', 13:18:39 INFO - 'tp6_youtube', 13:18:39 INFO - 'tp6_amazon', 13:18:39 INFO - 'tp6_facebook']}, 13:18:39 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:18:39 INFO - 'talos_options': ['--stylo', 13:18:39 INFO - '--mitmproxy', 13:18:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:18:39 INFO - '--firstNonBlankPaint'], 13:18:39 INFO - 'tests': ['tp6_google', 13:18:39 INFO - 'tp6_youtube', 13:18:39 INFO - 'tp6_amazon', 13:18:39 INFO - 'tp6_facebook']}, 13:18:39 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:18:39 INFO - 'talos_options': ['--stylo', 13:18:39 INFO - '--stylo-threads=1', 13:18:39 INFO - '--mitmproxy', 13:18:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:18:39 INFO - '--firstNonBlankPaint'], 13:18:39 INFO - 'tests': ['tp6_google', 13:18:39 INFO - 'tp6_youtube', 13:18:39 INFO - 'tp6_amazon', 13:18:39 INFO - 'tp6_facebook']}, 13:18:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:18:39 INFO - 'talos_options': ['--xperf_path', 13:18:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:18:39 INFO - 'tests': ['tp5n']}}} 13:18:39 INFO - Running post-action listener: _resource_record_post_action 13:18:39 INFO - [mozharness: 2017-08-11 20:18:39.555246Z] Finished populate-webroot step (success) 13:18:39 INFO - [mozharness: 2017-08-11 20:18:39.555337Z] Running create-virtualenv step. 13:18:39 INFO - Running pre-action listener: _resource_record_pre_action 13:18:39 INFO - Running main action method: create_virtualenv 13:18:39 INFO - Creating virtualenv /builds/slave/test/build/venv 13:18:39 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 13:18:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:18:39 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 13:18:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:18:39 INFO - Using real prefix '/usr' 13:18:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:18:40 INFO - Installing distribute.............................................................................................................................................................................................done. 13:18:42 INFO - Installing pip.................done. 13:18:42 INFO - Return code: 0 13:18:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:18:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:18:42 INFO - Reading from file tmpfile_stdout 13:18:42 INFO - Output received: 13:18:42 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) 13:18:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:18:42 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')]} 13:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:42 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')]} 13:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:42 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 0xe291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1199f00>, '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 0x1194ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195050>, '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': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', '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 13:18:42 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 13:18:42 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 13:18:42 INFO - Using env: {'DISPLAY': ':0', 13:18:42 INFO - 'HOME': '/home/cltbld', 13:18:42 INFO - 'LANG': 'en_US.UTF-8', 13:18:42 INFO - 'LANGUAGE': 'en_US:en', 13:18:42 INFO - 'LOGNAME': 'cltbld', 13:18:42 INFO - 'MAIL': '/var/mail/cltbld', 13:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:42 INFO - 'MOZ_NO_REMOTE': '1', 13:18:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:42 INFO - 'NO_EM_RESTART': '1', 13:18:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:42 INFO - 'PWD': '/builds/slave/test', 13:18:42 INFO - 'SHELL': '/bin/bash', 13:18:42 INFO - 'SHLVL': '1', 13:18:42 INFO - 'TERM': 'linux', 13:18:42 INFO - 'TMOUT': '86400', 13:18:42 INFO - 'USER': 'cltbld', 13:18:42 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', 13:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:42 INFO - '_': '/tools/buildbot/bin/python'} 13:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:42 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 13:18:42 INFO - Cleaning up... 13:18:42 INFO - Return code: 0 13:18:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:18:42 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')]} 13:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:42 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')]} 13:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:42 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 0xe291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1199f00>, '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 0x1194ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195050>, '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': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', '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 13:18:42 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 13:18:42 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 13:18:42 INFO - Using env: {'DISPLAY': ':0', 13:18:42 INFO - 'HOME': '/home/cltbld', 13:18:42 INFO - 'LANG': 'en_US.UTF-8', 13:18:42 INFO - 'LANGUAGE': 'en_US:en', 13:18:42 INFO - 'LOGNAME': 'cltbld', 13:18:42 INFO - 'MAIL': '/var/mail/cltbld', 13:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:42 INFO - 'MOZ_NO_REMOTE': '1', 13:18:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:42 INFO - 'NO_EM_RESTART': '1', 13:18:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:42 INFO - 'PWD': '/builds/slave/test', 13:18:42 INFO - 'SHELL': '/bin/bash', 13:18:42 INFO - 'SHLVL': '1', 13:18:42 INFO - 'TERM': 'linux', 13:18:42 INFO - 'TMOUT': '86400', 13:18:42 INFO - 'USER': 'cltbld', 13:18:42 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', 13:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:42 INFO - '_': '/tools/buildbot/bin/python'} 13:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:42 INFO - Downloading/unpacking psutil>=3.1.1 13:18:42 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 13:18:42 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 13:18:42 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 13:18:42 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 13:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:18:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:18:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:18:44 INFO - Installing collected packages: psutil 13:18:44 INFO - Running setup.py install for psutil 13:18:44 INFO - building 'psutil._psutil_linux' extension 13:18:44 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 13:18:44 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 13:18:44 INFO - building 'psutil._psutil_posix' extension 13:18:44 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 13:18:44 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 13:18:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:18:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:18:44 INFO - Successfully installed psutil 13:18:44 INFO - Cleaning up... 13:18:44 INFO - Return code: 0 13:18:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:18: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')]} 13:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18: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')]} 13:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:44 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 0xe291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1199f00>, '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 0x1194ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195050>, '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': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', '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 13:18:44 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 13:18:44 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 13:18:44 INFO - Using env: {'DISPLAY': ':0', 13:18:44 INFO - 'HOME': '/home/cltbld', 13:18:44 INFO - 'LANG': 'en_US.UTF-8', 13:18:44 INFO - 'LANGUAGE': 'en_US:en', 13:18:44 INFO - 'LOGNAME': 'cltbld', 13:18:44 INFO - 'MAIL': '/var/mail/cltbld', 13:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:44 INFO - 'MOZ_NO_REMOTE': '1', 13:18:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:44 INFO - 'NO_EM_RESTART': '1', 13:18:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:44 INFO - 'PWD': '/builds/slave/test', 13:18:44 INFO - 'SHELL': '/bin/bash', 13:18:44 INFO - 'SHLVL': '1', 13:18:44 INFO - 'TERM': 'linux', 13:18:44 INFO - 'TMOUT': '86400', 13:18:44 INFO - 'USER': 'cltbld', 13:18:44 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', 13:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:44 INFO - '_': '/tools/buildbot/bin/python'} 13:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:18:44 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 13:18:44 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 13:18:44 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 13:18:44 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 13:18:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:18:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:18:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:18:45 INFO - Installing collected packages: mozsystemmonitor 13:18:45 INFO - Running setup.py install for mozsystemmonitor 13:18:45 INFO - Successfully installed mozsystemmonitor 13:18:45 INFO - Cleaning up... 13:18:46 INFO - Return code: 0 13:18:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:46 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 0xe291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1199f00>, '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 0x1194ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195050>, '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': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', '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 13:18:46 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 13:18:46 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 13:18:46 INFO - Using env: {'DISPLAY': ':0', 13:18:46 INFO - 'HOME': '/home/cltbld', 13:18:46 INFO - 'LANG': 'en_US.UTF-8', 13:18:46 INFO - 'LANGUAGE': 'en_US:en', 13:18:46 INFO - 'LOGNAME': 'cltbld', 13:18:46 INFO - 'MAIL': '/var/mail/cltbld', 13:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:46 INFO - 'MOZ_NO_REMOTE': '1', 13:18:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:46 INFO - 'NO_EM_RESTART': '1', 13:18:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:46 INFO - 'PWD': '/builds/slave/test', 13:18:46 INFO - 'SHELL': '/bin/bash', 13:18:46 INFO - 'SHLVL': '1', 13:18:46 INFO - 'TERM': 'linux', 13:18:46 INFO - 'TMOUT': '86400', 13:18:46 INFO - 'USER': 'cltbld', 13:18:46 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', 13:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:46 INFO - '_': '/tools/buildbot/bin/python'} 13:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:46 INFO - Downloading/unpacking jsonschema==2.5.1 13:18:46 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 13:18:46 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 13:18:46 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 13:18:46 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 13:18:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:18:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:18:47 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 13:18:47 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 13:18:47 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 13:18:47 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 13:18:47 INFO - Downloading functools32-3.2.3-2.tar.gz 13:18:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:18:47 INFO - warning: no files found matching '*.txt' 13:18:47 INFO - no previously-included directories found matching 'build' 13:18:47 INFO - no previously-included directories found matching 'dist' 13:18:47 INFO - no previously-included directories found matching '.git*' 13:18:47 INFO - Installing collected packages: jsonschema, functools32 13:18:47 INFO - Running setup.py install for functools32 13:18:47 INFO - warning: no files found matching '*.txt' 13:18:47 INFO - no previously-included directories found matching 'build' 13:18:47 INFO - no previously-included directories found matching 'dist' 13:18:47 INFO - no previously-included directories found matching '.git*' 13:18:47 INFO - Successfully installed jsonschema functools32 13:18:47 INFO - Cleaning up... 13:18:47 INFO - Return code: 0 13:18:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:18:47 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')]} 13:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:47 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')]} 13:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:47 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 0xe291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1199f00>, '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 0x1194ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195050>, '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': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', '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 13:18:47 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 13:18:47 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 13:18:47 INFO - Using env: {'DISPLAY': ':0', 13:18:47 INFO - 'HOME': '/home/cltbld', 13:18:47 INFO - 'LANG': 'en_US.UTF-8', 13:18:47 INFO - 'LANGUAGE': 'en_US:en', 13:18:47 INFO - 'LOGNAME': 'cltbld', 13:18:47 INFO - 'MAIL': '/var/mail/cltbld', 13:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:47 INFO - 'MOZ_NO_REMOTE': '1', 13:18:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:47 INFO - 'NO_EM_RESTART': '1', 13:18:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:47 INFO - 'PWD': '/builds/slave/test', 13:18:47 INFO - 'SHELL': '/bin/bash', 13:18:47 INFO - 'SHLVL': '1', 13:18:47 INFO - 'TERM': 'linux', 13:18:47 INFO - 'TMOUT': '86400', 13:18:47 INFO - 'USER': 'cltbld', 13:18:47 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', 13:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:47 INFO - '_': '/tools/buildbot/bin/python'} 13:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:18:47 INFO - Cleaning up... 13:18:47 INFO - Return code: 0 13:18:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:18:47 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')]} 13:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:47 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')]} 13:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:47 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 0xe291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1199f00>, '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 0x1194ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195050>, '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': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', '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 13:18:47 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 13:18:47 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 13:18:47 INFO - Using env: {'DISPLAY': ':0', 13:18:47 INFO - 'HOME': '/home/cltbld', 13:18:47 INFO - 'LANG': 'en_US.UTF-8', 13:18:47 INFO - 'LANGUAGE': 'en_US:en', 13:18:47 INFO - 'LOGNAME': 'cltbld', 13:18:47 INFO - 'MAIL': '/var/mail/cltbld', 13:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:47 INFO - 'MOZ_NO_REMOTE': '1', 13:18:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:47 INFO - 'NO_EM_RESTART': '1', 13:18:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:47 INFO - 'PWD': '/builds/slave/test', 13:18:47 INFO - 'SHELL': '/bin/bash', 13:18:47 INFO - 'SHLVL': '1', 13:18:47 INFO - 'TERM': 'linux', 13:18:47 INFO - 'TMOUT': '86400', 13:18:47 INFO - 'USER': 'cltbld', 13:18:47 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', 13:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:47 INFO - '_': '/tools/buildbot/bin/python'} 13:18:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:48 INFO - Downloading/unpacking blobuploader==1.2.4 13:18:48 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 13:18:48 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 13:18:48 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 13:18:48 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 13:18:49 INFO - Downloading blobuploader-1.2.4.tar.gz 13:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:18:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:18:49 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 13:18:49 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 13:18:49 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 13:18:49 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 13:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:18:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:18:49 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 13:18:49 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 13:18:49 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 13:18:49 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 13:18:49 INFO - Downloading docopt-0.6.1.tar.gz 13:18:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:18:49 INFO - Installing collected packages: blobuploader, requests, docopt 13:18:49 INFO - Running setup.py install for blobuploader 13:18:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:18:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:18:50 INFO - Running setup.py install for requests 13:18:50 INFO - Running setup.py install for docopt 13:18:50 INFO - Successfully installed blobuploader requests docopt 13:18:50 INFO - Cleaning up... 13:18:50 INFO - Return code: 0 13:18:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:18: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')]} 13:18:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18: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')]} 13:18:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:50 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 0xe291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1199f00>, '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 0x1194ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195050>, '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': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', '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 13:18:50 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 13:18:50 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 13:18:50 INFO - Using env: {'DISPLAY': ':0', 13:18:50 INFO - 'HOME': '/home/cltbld', 13:18:50 INFO - 'LANG': 'en_US.UTF-8', 13:18:50 INFO - 'LANGUAGE': 'en_US:en', 13:18:50 INFO - 'LOGNAME': 'cltbld', 13:18:50 INFO - 'MAIL': '/var/mail/cltbld', 13:18:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:50 INFO - 'MOZ_NO_REMOTE': '1', 13:18:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:50 INFO - 'NO_EM_RESTART': '1', 13:18:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:50 INFO - 'PWD': '/builds/slave/test', 13:18:50 INFO - 'SHELL': '/bin/bash', 13:18:50 INFO - 'SHLVL': '1', 13:18:50 INFO - 'TERM': 'linux', 13:18:50 INFO - 'TMOUT': '86400', 13:18:50 INFO - 'USER': 'cltbld', 13:18:50 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', 13:18:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:50 INFO - '_': '/tools/buildbot/bin/python'} 13:18:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:18:50 INFO - Running setup.py (path:/tmp/pip-2H9Ftr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:18:50 INFO - Running setup.py (path:/tmp/pip-7RcG_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:18:50 INFO - Running setup.py (path:/tmp/pip-eqNN3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:18:50 INFO - Running setup.py (path:/tmp/pip-k0JX0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:18:51 INFO - Running setup.py (path:/tmp/pip-uePB21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:18:51 INFO - Running setup.py (path:/tmp/pip-jlpwk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:18:51 INFO - Running setup.py (path:/tmp/pip-irdovE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:18:51 INFO - Running setup.py (path:/tmp/pip-8RK7mX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:18:51 INFO - Running setup.py (path:/tmp/pip-eBolEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:18:51 INFO - Running setup.py (path:/tmp/pip-IvIAdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:18:51 INFO - Running setup.py (path:/tmp/pip-9Ms1m2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:18:51 INFO - Running setup.py (path:/tmp/pip-cVYFwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:18:51 INFO - Running setup.py (path:/tmp/pip-S8F92N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:18:51 INFO - Running setup.py (path:/tmp/pip-JnXjOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:18:51 INFO - Running setup.py (path:/tmp/pip-zGHnR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:18:51 INFO - Running setup.py (path:/tmp/pip-aFObo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:18:51 INFO - Running setup.py (path:/tmp/pip-M1PUGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:18:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:18:51 INFO - Running setup.py install for manifestparser 13:18:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:18:52 INFO - Running setup.py install for mozcrash 13:18:52 INFO - Running setup.py install for mozdebug 13:18:52 INFO - Running setup.py install for mozdevice 13:18:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:18:52 INFO - Running setup.py install for mozfile 13:18:52 INFO - Running setup.py install for mozhttpd 13:18:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:18:52 INFO - Running setup.py install for mozinfo 13:18:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:18:52 INFO - Running setup.py install for mozInstall 13:18:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:18:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:18:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:18:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:18:52 INFO - Running setup.py install for mozleak 13:18:52 INFO - Running setup.py install for mozlog 13:18:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:18:52 INFO - Running setup.py install for moznetwork 13:18:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:18:53 INFO - Running setup.py install for mozprocess 13:18:53 INFO - Running setup.py install for mozprofile 13:18:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:18:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:18:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:18:53 INFO - Running setup.py install for mozrunner 13:18:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:18:53 INFO - Running setup.py install for mozscreenshot 13:18:53 INFO - Running setup.py install for moztest 13:18:53 INFO - Running setup.py install for mozversion 13:18:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:18:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:18:53 INFO - Cleaning up... 13:18:53 INFO - Return code: 0 13:18:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:53 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 0xe291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1199f00>, '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 0x1194ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195050>, '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': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', '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 13:18:53 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 13:18:53 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 13:18:53 INFO - Using env: {'DISPLAY': ':0', 13:18:53 INFO - 'HOME': '/home/cltbld', 13:18:53 INFO - 'LANG': 'en_US.UTF-8', 13:18:53 INFO - 'LANGUAGE': 'en_US:en', 13:18:53 INFO - 'LOGNAME': 'cltbld', 13:18:53 INFO - 'MAIL': '/var/mail/cltbld', 13:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:53 INFO - 'MOZ_NO_REMOTE': '1', 13:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:53 INFO - 'NO_EM_RESTART': '1', 13:18:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:53 INFO - 'PWD': '/builds/slave/test', 13:18:53 INFO - 'SHELL': '/bin/bash', 13:18:53 INFO - 'SHLVL': '1', 13:18:53 INFO - 'TERM': 'linux', 13:18:53 INFO - 'TMOUT': '86400', 13:18:53 INFO - 'USER': 'cltbld', 13:18:53 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', 13:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:53 INFO - '_': '/tools/buildbot/bin/python'} 13:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:18:53 INFO - Running setup.py (path:/tmp/pip-k14oZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:18:54 INFO - Running setup.py (path:/tmp/pip-f2IFGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:18:54 INFO - Running setup.py (path:/tmp/pip-fJw04L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:18:54 INFO - Running setup.py (path:/tmp/pip-lBZzbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:18:54 INFO - Running setup.py (path:/tmp/pip-HRsYYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:18:54 INFO - Running setup.py (path:/tmp/pip-bcv9Tj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:18:54 INFO - Running setup.py (path:/tmp/pip-Ivcava-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:18:54 INFO - Running setup.py (path:/tmp/pip-wcdgyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:18:54 INFO - Running setup.py (path:/tmp/pip-1UbvNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:18:54 INFO - Running setup.py (path:/tmp/pip-Tgggy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:18:54 INFO - Running setup.py (path:/tmp/pip-gh3rid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:18:54 INFO - Running setup.py (path:/tmp/pip-ap4KOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:18:54 INFO - Running setup.py (path:/tmp/pip-6CokpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:18:54 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)) 13:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:18:54 INFO - Running setup.py (path:/tmp/pip-X7yv2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:18:54 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)) 13:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:18:55 INFO - Running setup.py (path:/tmp/pip-PO61Nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:18:55 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)) 13:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:18:55 INFO - Running setup.py (path:/tmp/pip-WZFERe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:18:55 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)) 13:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:18:55 INFO - Running setup.py (path:/tmp/pip-eNOkPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:18:55 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)) 13:18:55 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)) 13:18:55 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)) 13:18:55 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)) 13:18:55 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)) 13:18:55 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)) 13:18:55 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)) 13:18:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:18:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:18:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:18:56 INFO - Downloading blessings-1.6.tar.gz 13:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:18:56 INFO - Installing collected packages: blessings 13:18:56 INFO - Running setup.py install for blessings 13:18:56 INFO - Successfully installed blessings 13:18:56 INFO - Cleaning up... 13:18:56 INFO - Return code: 0 13:18:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:18:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:18:56 INFO - Reading from file tmpfile_stdout 13:18:56 INFO - Current package versions: 13:18:56 INFO - argparse == 1.2.1 13:18:56 INFO - blessings == 1.6 13:18:56 INFO - blobuploader == 1.2.4 13:18:56 INFO - docopt == 0.6.1 13:18:56 INFO - functools32 == 3.2.3-2 13:18:56 INFO - jsonschema == 2.5.1 13:18:56 INFO - manifestparser == 1.1 13:18:56 INFO - mozInstall == 1.13 13:18:56 INFO - mozcrash == 1.0 13:18:56 INFO - mozdebug == 0.1 13:18:56 INFO - mozdevice == 0.51 13:18:56 INFO - mozfile == 1.2 13:18:56 INFO - mozhttpd == 0.7 13:18:56 INFO - mozinfo == 0.10 13:18:56 INFO - mozleak == 0.1 13:18:56 INFO - mozlog == 3.5 13:18:56 INFO - moznetwork == 0.27 13:18:56 INFO - mozprocess == 0.25 13:18:56 INFO - mozprofile == 0.28 13:18:56 INFO - mozrunner == 6.13 13:18:56 INFO - mozscreenshot == 0.1 13:18:56 INFO - mozsystemmonitor == 0.3 13:18:56 INFO - moztest == 0.8 13:18:56 INFO - mozversion == 1.4 13:18:56 INFO - psutil == 3.1.1 13:18:56 INFO - requests == 1.2.3 13:18:56 INFO - wsgiref == 0.1.2 13:18:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:56 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 0xe291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1199f00>, '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 0x1194ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195050>, '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': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', '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 13:18:56 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 13:18:56 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 13:18:56 INFO - Using env: {'DISPLAY': ':0', 13:18:56 INFO - 'HOME': '/home/cltbld', 13:18:56 INFO - 'LANG': 'en_US.UTF-8', 13:18:56 INFO - 'LANGUAGE': 'en_US:en', 13:18:56 INFO - 'LOGNAME': 'cltbld', 13:18:56 INFO - 'MAIL': '/var/mail/cltbld', 13:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:56 INFO - 'MOZ_NO_REMOTE': '1', 13:18:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:56 INFO - 'NO_EM_RESTART': '1', 13:18:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:56 INFO - 'PWD': '/builds/slave/test', 13:18:56 INFO - 'SHELL': '/bin/bash', 13:18:56 INFO - 'SHLVL': '1', 13:18:56 INFO - 'TERM': 'linux', 13:18:56 INFO - 'TMOUT': '86400', 13:18:56 INFO - 'USER': 'cltbld', 13:18:56 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', 13:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:56 INFO - '_': '/tools/buildbot/bin/python'} 13:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:56 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)) 13:18:56 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)) 13:18:56 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)) 13:18:56 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)) 13:18:56 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)) 13:18:56 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)) 13:18:56 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)) 13:18:56 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)) 13:18:56 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)) 13:18:56 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 13:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 13:18:58 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)) 13:18:58 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)) 13:18:58 INFO - Installing collected packages: simplejson 13:18:58 INFO - Running setup.py install for simplejson 13:18:58 INFO - building 'simplejson._speedups' extension 13:18:58 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 13:18:59 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 13:18:59 INFO - Successfully installed simplejson 13:18:59 INFO - Cleaning up... 13:18:59 INFO - Return code: 0 13:18:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:18:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1199f00>, '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 0x1194ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195050>, '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': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', '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 13:18:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:18:59 INFO - Using env: {'DISPLAY': ':0', 13:18:59 INFO - 'HOME': '/home/cltbld', 13:18:59 INFO - 'LANG': 'en_US.UTF-8', 13:18:59 INFO - 'LANGUAGE': 'en_US:en', 13:18:59 INFO - 'LOGNAME': 'cltbld', 13:18:59 INFO - 'MAIL': '/var/mail/cltbld', 13:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:59 INFO - 'MOZ_NO_REMOTE': '1', 13:18:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:18:59 INFO - 'NO_EM_RESTART': '1', 13:18:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:59 INFO - 'PWD': '/builds/slave/test', 13:18:59 INFO - 'SHELL': '/bin/bash', 13:18:59 INFO - 'SHLVL': '1', 13:18:59 INFO - 'TERM': 'linux', 13:18:59 INFO - 'TMOUT': '86400', 13:18:59 INFO - 'USER': 'cltbld', 13:18:59 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', 13:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:59 INFO - '_': '/tools/buildbot/bin/python'} 13:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:18:59 INFO - Cleaning up... 13:18:59 INFO - Return code: 0 13:18:59 INFO - Running post-action listener: _resource_record_post_action 13:18:59 INFO - Running post-action listener: _start_resource_monitoring 13:18:59 INFO - Starting resource monitoring. 13:18:59 INFO - [mozharness: 2017-08-11 20:18:59.456480Z] Finished create-virtualenv step (success) 13:18:59 INFO - [mozharness: 2017-08-11 20:18:59.456989Z] Running install step. 13:18:59 INFO - Running pre-action listener: _resource_record_pre_action 13:18:59 INFO - Running main action method: install 13:18:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:18:59 INFO - Reading from file tmpfile_stdout 13:18:59 INFO - Detecting whether we're running mozinstall >=1.0... 13:18:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:18:59 INFO - Reading from file tmpfile_stdout 13:18:59 INFO - Output received: 13:18:59 INFO - Usage: mozinstall [options] installer 13:18:59 INFO - Options: 13:18:59 INFO - -h, --help show this help message and exit 13:18:59 INFO - -d DEST, --destination=DEST 13:18:59 INFO - Directory to install application into. [default: 13:18:59 INFO - "/builds/slave/test"] 13:18:59 INFO - --app=APP Application being installed. [default: firefox] 13:18:59 INFO - mkdir: /builds/slave/test/build/application 13:18:59 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'] 13:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 13:19:17 INFO - Reading from file tmpfile_stdout 13:19:17 INFO - Output received: 13:19:17 INFO - /builds/slave/test/build/application/firefox/firefox 13:19:17 INFO - Running post-action listener: _resource_record_post_action 13:19:17 INFO - [mozharness: 2017-08-11 20:19:17.876309Z] Finished install step (success) 13:19:17 INFO - [mozharness: 2017-08-11 20:19:17.876454Z] Running setup-mitmproxy step. 13:19:17 INFO - Running pre-action listener: _resource_record_pre_action 13:19:17 INFO - Running main action method: setup_mitmproxy 13:19:17 INFO - Skipping: mitmproxy is not required 13:19:17 INFO - Running post-action listener: _resource_record_post_action 13:19:17 INFO - [mozharness: 2017-08-11 20:19:17.876986Z] Finished setup-mitmproxy step (success) 13:19:17 INFO - [mozharness: 2017-08-11 20:19:17.877088Z] Running run-tests step. 13:19:17 INFO - Running pre-action listener: _resource_record_pre_action 13:19:17 INFO - Running pre-action listener: _set_gcov_prefix 13:19:17 INFO - Running main action method: run_tests 13:19:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:19:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:19:17 INFO - Python 2.7.3 13:19:17 INFO - Return code: 0 13:19:17 INFO - grabbing minidump binary from tooltool 13:19: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')]} 13:19:17 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 0x1199f00>, '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 0x1194ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:19:17 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 13:19:17 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 13:19:17 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 13:19:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:19:18 INFO - Return code: 0 13:19:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:19:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:19:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:19:18 INFO - ENV: RUST_BACKTRACE is now full 13:19:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:19:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:19:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 13:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 13:19:18 INFO - Using env: {'DISPLAY': ':0', 13:19:18 INFO - 'HOME': '/home/cltbld', 13:19:18 INFO - 'LANG': 'en_US.UTF-8', 13:19:18 INFO - 'LANGUAGE': 'en_US:en', 13:19:18 INFO - 'LOGNAME': 'cltbld', 13:19:18 INFO - 'MAIL': '/var/mail/cltbld', 13:19:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:19:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:19:18 INFO - 'MOZ_NO_REMOTE': '1', 13:19:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:19:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:19:18 INFO - 'NO_EM_RESTART': '1', 13:19:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:19:18 INFO - 'PWD': '/builds/slave/test', 13:19:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:19:18 INFO - 'RUST_BACKTRACE': 'full', 13:19:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:19:18 INFO - 'SHELL': '/bin/bash', 13:19:18 INFO - 'SHLVL': '1', 13:19:18 INFO - 'STYLO_FORCE_ENABLED': '1', 13:19:18 INFO - 'TERM': 'linux', 13:19:18 INFO - 'TMOUT': '86400', 13:19:18 INFO - 'USER': 'cltbld', 13:19:18 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083', 13:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:19:18 INFO - '_': '/tools/buildbot/bin/python'} 13:19:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 13:19:18 INFO - mozversion application_buildid: 20170811193030 13:19:18 INFO - mozversion application_changeset: d0afe5d6350426e338c1acff28a4a0c38ba1965f 13:19:18 INFO - mozversion application_display_name: Nightly 13:19:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:19:18 INFO - mozversion application_name: Firefox 13:19:18 INFO - mozversion application_remotingname: firefox 13:19:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:19:18 INFO - mozversion application_vendor: Mozilla 13:19:18 INFO - mozversion application_version: 57.0a1 13:19:18 INFO - mozversion platform_buildid: 20170811193030 13:19:18 INFO - mozversion platform_changeset: d0afe5d6350426e338c1acff28a4a0c38ba1965f 13:19:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:19:18 INFO - mozversion platform_version: 57.0a1 13:19:18 INFO - using testdate: 1502482758 13:19:18 INFO - actual date: 1502482758 13:19:18 INFO - starting webserver on 'localhost:56508' 13:19:18 INFO - SUITE-START | Running 2 tests 13:19:18 INFO - TEST-START | dromaeo_css 13:19:18 INFO - Initialising browser for dromaeo_css test... 13:19:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56508/getInfo.html -profile /tmp/tmpoyTf08/profile 13:19:18 INFO - TEST-INFO | started process 19340 (/builds/slave/test/build/application/firefox/firefox http://localhost:56508/getInfo.html) 13:19:24 INFO - TEST-INFO | 19340: exit 0 13:19:24 INFO - Browser initialized. 13:19:24 INFO - Running cycle 1/1 for dromaeo_css test... 13:19:24 INFO - TEST-INFO | started process 19575 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoyTf08/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:19:25 INFO - PID 19575 | 13:19:25 INFO - PID 19575 | (/builds/slave/test/build/application/firefox/firefox:19629): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:19:25 INFO - PID 19575 | 13:19:36 INFO - PID 19575 | RSS: Main: 167972864 13:19:36 INFO - PID 19575 | 13:22:35 INFO - PID 19575 | Cycle 1(1): loaded http://localhost:56508/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56508/tests/dromaeo/cssquery-ext.html) 13:22:37 INFO - PID 19575 | RSS: Main: 175222784 13:22:37 INFO - PID 19575 | 13:25:39 INFO - PID 19575 | Cycle 1(1): loaded http://localhost:56508/tests/dromaeo/cssquery-ext.html (next: http://localhost:56508/tests/dromaeo/cssquery-jquery.html) 13:25:39 INFO - PID 19575 | RSS: Main: 172789760 13:25:39 INFO - PID 19575 | 13:28:41 INFO - PID 19575 | Cycle 1(1): loaded http://localhost:56508/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56508/tests/dromaeo/cssquery-mootools.html) 13:28:42 INFO - PID 19575 | RSS: Main: 174616576 13:28:42 INFO - PID 19575 | 13:31:43 INFO - PID 19575 | Cycle 1(1): loaded http://localhost:56508/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56508/tests/dromaeo/cssquery-prototype.html) 13:31:44 INFO - PID 19575 | RSS: Main: 173060096 13:31:44 INFO - PID 19575 | 13:34:48 INFO - PID 19575 | Cycle 1(1): loaded http://localhost:56508/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56508/tests/dromaeo/cssquery-yui.html) 13:34:48 INFO - PID 19575 | RSS: Main: 176209920 13:34:48 INFO - PID 19575 | 13:37:50 INFO - PID 19575 | Cycle 1(1): loaded http://localhost:56508/tests/dromaeo/cssquery-yui.html (next: http://localhost:56508/tests/dromaeo/cssquery-dojo.html) 13:37:50 INFO - PID 19575 | RSS: Main: 177311744 13:37:50 INFO - PID 19575 | 13:37:50 INFO - PID 19575 | __start_tp_report 13:37:50 INFO - PID 19575 | _x_x_mozilla_page_load 13:37:50 INFO - PID 19575 | _x_x_mozilla_page_load_details 13:37:50 INFO - PID 19575 | |i|pagename|runs| 13:37:50 INFO - PID 19575 | |0;dojo.html;3155.763317751168;3161.035884055362;3178.2054486378406;3185.4584720597495;3208.0856955767595;6235.064740288959;6242.157308763316;6252.6873656317175;6273.529485288599;6292.150559674443;6113.969430152854;6207.906881396782;6211.968940155304;6228.937710622892;6233.750649974007;5629.60592758507;5649.802256921009;5650.971745141269;5672.801451949183;5698.772049118031;5649.23735295734;5669.518090962273;5692.260006199188;5693.146028095777;5705.629134106291;5796.362400135981;5821.56338274629;5826.417358264161;5834.299884013924;5839.474447299742;5643.238162848004;5675.489205971462;5707.914381284284;5741.770329186827;5797;5275.314209152824;5507.421720719347;5582.8325150245555;5628.155776633538;5665.8583535411535;6067.362926892657;6113.877722445526;6172.9074063889075;6247.00047992319;6264.874702505925;6682.83292917681;6694.732210711566;6771.72913083476;6789.219239787389;6811.182658081012;6650.70071846768;6679.93320066798;6732;6833.282505336918;6849;6292.716827742763;6292.842678933018;6296.811095667137;6304.243490781089;6312.589681670676;6849.280825513349;6878.449724022066;6979.616121113286;6983.685734141975;6986.93013069873;6457.935420645827;6568.540202185896;6609.702563384659;6619.966900165565;6704.128463299739;6495.025746138117;6620.404163625297;6622.768203112868;6676.49926255523;6684.665766711645;6620.205575330991;6622.238442579069;6632.767853125214;6637.037629543785;6645.767398141042;6647.302033286532;6710.798676039727;6741.8651626967185;6756.087928129639;6828.760993365208;5339.893202135935;5489.39616642169;5554.416786237387;5555.111182210827;5563.916541251884;4616.561426664462;4695.812167513262;4745.217039188564;4753.144434001843;4786.473487916326;5797.536197104221;5821.941780582225;5874.676892770939;5883.14694369314;5892;6630;6674.132362792806;6763.458923286224;6833.145856767904;6840.418564421981;5626.578006649518;5662.320521537442;5749.482546570829;5800.274965629296;5869.853253668607;5961.433663801932;5970.283565972111;6002.6698531580305;6005.459508644156;6007.639541627429;6407.487401007908;6502.479801615859;6520.478361731145;6523.11937888379;6587.571807832572;6705.865882682221;6739.797806065826;6744.763933262312;6790.898136527857;6845.418139458202;6381.489480841615;6438.935610643834;6470.191226096738;6562.245341785565;6575.4410875076155;6628.90056649141;6660.300668429745;6693.2972037935315;6715.5299129060495;6732.3267673232285;6779.15260592426;6780.457563394818;6789.253182150058;6892.724291028503;6894.13823272091;6381.202349706286;6532.5753826001155;6549.574277671936;6639.136912201383;6649.368310010541;6496.870062598627;6499.285078641251;6566.474682025331;6571.244306904767;6572.211334639683;6248.187735594343;6495.772647957298;6544.116544266653;6568.047633093079;6578.407943284936;6413;6488.059231411325;6526.086347911391;6609.669516524058;6618;6328.335524769833;6345.8730825384155;6406.807795766134;6432.067350234283;6434.388733070351 13:37:50 INFO - PID 19575 | |1;ext.html;860.7460799064276;872.912708729127;1151.8848115188482;1192.3025030357242;1254.6988722706553;37617.43573846391;40625.79687101564;40665.79667101668;40688;40699.38950915735;37485.81257093711;37525.812370938176;37533.81233093837;37668;37756;7093.964530177354;7238.384737297336;7240.565566066027;7243.130824301091;7245.74639887604;7160.355567998879;7220.2779722027635;7251.56490610565;7252;7268.491205615609;7265.4187664986675;7281.235470275624;7634.694612215505;7805.492642978216;7813.570253636048;7694.7306844260465;7748;7765.1458339582605;7768.067831860157;7771.417143714244;7518.173000969889;7653.770386888346;7681.846363072707;7708.383329333696;7731.922680773233;37374;37632;37667.434988475194;37766.62233377686;37797;3287.2439338952177;3326.800391976489;3327.633960264369;3328.5340052392685;3330.5004249362546;31089.067327980196;31220.531692024637;31244.531332030037;31291;31306.686933130604;4973.179425394806;4983.426905905831;4997.275395067732;5009.123403404426;5016.623753218523;191791.04104479527;192505;192615;192750;193257;42661.146777064285;43530.56469435265;49007.50992490101;49144.26283605749;49200.50799492031;51543.74228128835;51629.22556161661;51871.48128518742;51930.22104668433;51967.480325196266;16281.91859040721;16335.754963675554;16416.671666566603;16422.917885410498;16430.507084787238;13431.3955871986;13534.59396218115;13534.796978045337;13536.864631353757;13554.457821687021;14592.103026606474;14610.707785844435;14611.85388146105;14632.561023169323;14709.926450367893;5614.354349249888;5743.282089738783;5747.195392645033;5921.200637913913;5949.613275137102;982.7887004294112;989.737719504332;994.786120983992;1010.9848352274722;1013.7972405518926;22314.55370892573;22416.103355865584;22434.990425430893;22439.32682019542;22799;1092.7869065532145;1093.9726506837233;1102.4983632447218;1105.7512059786645;1112.4882554025078;12138.08964327679;12180.695482613006;12276.079294052981;12352.505899764088;15972.440964566184;228650;231211;231608;231638;231903;56428;56609.71695141498;56716.149257761164;57004;57224.42775572274;1435.397133204057;1468.3465857693225;1468.574113507071;1485.7251408489249;1486.7026594681106;27067;27578;27737.167884963485;27794.583081253393;28328.43343133167;37137.25725485421;37208.6279137216;37252.62747372492;37332;37354.69258575937;207086;207932;211143;211611;211654;30496;31067.53398698976;33677.49483757795;33956.32087358288;34325.141871453416;42639.360409594505;43159.78420107879;43348;43488.78255608702;43548.56451435572;32328;32675.34649306953;32874.342513149124;32943;33682;32117.518237226926;32187.839060805483;32253.35493290074;32710.50934236036;32788;34687.13282167966;35061.47407788834;35062.82468587743;35129.824350879106;35332.29335413329;30822.537661935537;30866.919657811868;31208.21979450441;31584.526232106076;31758.841205793822;21953;23463.530729385657;23520.647190291817;23567.882160589088;24936.37659058538 13:37:50 INFO - PID 19575 | |2;jquery.html;567.3532173322411;570.7260514952825;573.9942600573993;577.2812848004234;594.0791772752234;22359.888200558995;22843;23452.765472345294;23510.88244558777;23520.059197632076;26862.194134175956;27249.727502724967;27298.863505682493;27313.317167070785;27330.726692733067;6422.903656445145;6529.314421985694;6537.607743535379;6602.570832895871;6606.801795946105;5822.97088514559;6312.463440607531;6387.201599800025;6392.2009748781165;6430.099786029936;6336.239651241857;6341.651209183493;6346.555741098125;6346.84132896679;6351.237851457831;6342.143810585581;6346.777862774827;6349.587276827015;6359.968200158993;6371.904421433682;5942.7920022799;5945.762169513214;5948.910766338508;5951.880962380728;5952.0476723724;30134.24664383386;30336.393272134435;30841.228969275948;31244.218894527818;31432.52851207234;1926.6146770645787;1970.1036028607093;1972.0731256309512;1972.2209727157835;1972.5167334003118;19262;19868.503287417785;19909.9004504978;19942.70085948712;19946.30187943415;2407.3861165402886;2469.271564888344;2502.699676038869;2517.295157355943;2518.8614626195554;139349;145305;145883;146232.53767462296;146308;13736.862631373759;14587.562373128823;14825.184614846072;15172.165530895907;15403;23596.174133905573;23675.7632423677;23676.64485032726;23683.526329473658;23703.525929481657;10483.633072842567;10551.788964220672;10564.102051325723;10783.352998819939;10960.725981850517;9922;9998.600055997824;10035.648752293635;10147.543360548792;10266.58933642646;7690.423218258541;8991.100889910957;9042.18620324174;9093.99966003739;9389.201917836925;2587.236765154284;2704.4050308932005;2726.591011348274;2737.534619114755;2743.862806859649;955.5508910811907;991.9206463482905;992.3103443106971;993.0813997052697;999.0858364596459;19729;20133.39599812008;20296;20326.898365508077;20358;1031.4945676618404;1038.7558923652934;1041.7239431550645;1052.9894701052892;1055.6698559814688;11066.889331106586;11266.7183320416;11307.208495405243;11377.943110284396;11403.543858245743;471662;477988;479951;482334;482990;118184;118249;118753;121947;122349.38825305816;1180.2977228549;1199.5022065842577;1210.7215340471564;1217.0385395537426;1220.780259553289;17075.829241707423;17317.56706082358;17556.912215438842;17672.20475078599;17909.731354029438;55823.44176558345;56669.71665141648;57122;58156.70921645534;59018;73675;73757;74160.40438584624;77596;80552;14324.427022918966;14524.85475145235;14585.416583336548;14724.55826325212;14743.557693269218;24154;24278.87860560686;24443.51112977766;24678;24728.876355618107;18830.905845470686;19127.71308430347;19145.234190632218;19382;19460.610787784448;16887.91556042261;17271.740923886402;17282.481525554253;17379;17450.563735906195;19403.708944366128;19473.610527789082;19643.116059777058;19799;19883.502912427306;14272;14582.4896128635;14583.343749531508;14594.708105838035;14599.270036497921;17789.733154002442;17791.288348465918;17813.643727125644;17901.641967160846;17922.19350129221 13:37:50 INFO - PID 19575 | |3;mootools.html;1347.7708789505784;1398.23796031163;1406.4444544404967;1423.1390009044521;1428.2644438114369;13986.930065349672;14538.34577444015;14565.781513277308;14589.416423343053;14592.270386480674;14676.853231467681;15059.774103388458;15076.924615376907;15077.095374277551;15093.773593396108;2648.576227803547;2657.4685062987382;2690.6367640368594;2695.272276485348;2704.864756762164;2540.1236573382153;2661.6406785084055;2698.649175607178;2706.851123188224;2709.227870057024;2569.5888657814753;2610.229982155259;2611.438540713746;2660.2285337252174;2677.2369874585747;2624.2520881548667;2633.881475333605;2635.4465562231953;2643.550596398617;2646.1400044985267;2545.7072436669837;2609.582466805302;2613.3858543242322;2613.803964702655;2619.0309585453315;14014.22921739304;14038.859611403857;14230.217338046403;14251.643708907257;14258.2157981311;7498.550086994744;7836.451448398614;7849.4897831640765;7867.0952840423515;7898.565578893159;5174.197999310113;5268.586998832238;5293.814716484943;5379.8386048418215;5383.650062745948;1825.8174182581636;1830.3685228596114;1864.683003889342;1876.502726777405;1900.8764430311985;20196.798032019786;20257.189712411488;20263.08816103279;20316;20359.08384122718;6316.747330106836;6467;6673.866522669519;6755.729770809211;6759.864802704017;16012.679746405007;16105.436309729337;16218.108004059653;16232.75650865238;16284;7395;7559.433042521833;7576.356009739124;7592.430567707444;7593.354564861938;5871.383504732027;6036.078744479618;6401.07184458251;7001.614911179934;7063.89404158938;5082.923756143661;5250.107481728115;5284.075286824867;5334.866628334323;5337.306150200449;1485.3390241342715;1522.0563250784585;1528.2587944846741;1534.4015833824594;1546.7834503169565;831.7172161465131;843.0557775291712;856.5032281276845;861.1259571534897;870.9172628600273;6855.07456493377;6867.141607299088;6939.097917270722;6977.79066628002;7051.964740176369;697.2574208467965;698.7379732600275;701.2216439751871;708.4014008163094;710.4671496377716;4311.6335111515245;4664.370310008106;4926.753662316942;5007.37407824022;5212.244224587489;19494.61010779805;19750.11124499401;20081.899590501955;20288.492787680425;20676.793232067786;19921.501962450766;19932.103055362542;20228.494287642927;20411.387658370273;20441.38675839727;10790.73023174427;10947.83578246327;11066.889331106748;11221;11223.494942727597;7947.284744373034;8008.679652813822;8032.357411406956;8036.638351274323;8093;17351.479455615845;17373.65252694964;17404;17490.562735931704;17841.73237401466;17913.462596122135;17967;18254.0872956349;18256.90871545634;18570.44288671342;11487.54049837997;11977.700557485785;11987.880121198676;12012.819807702717;12029.578964736193;13348.332583370986;13484.010147214385;13690.589282321545;13694.52069177574;13694.726105478034;10907.836382454429;10931.180161488079;10960.616378426717;11037.613683521038;11173.106151508022;10844.240903136704;10883.836742449028;10916.454177290945;11026.393548354918;11174.385408802595;9728.319017668695;9892;9895;9902.207823374258;9941.30410871232;6808.7276508939085;6904.4131248844415;6927.58434493932;6942.51402401827;6962.268961759145;6254.405831446005;6263.530235232467;6297.52768542352;6365.872682546231;6383.265924418752 13:37:50 INFO - PID 19575 | |4;prototype.html;491.22006162578947;496.7313907992902;519.6933809052658;520.0924386944777;528.9576833853291;6154.938450615498;6161.876762464759;6187.783427580036;6288.779892703757;6301.834160680277;5891.175235467038;6023.096535519674;6143.508519318455;6162.691865406723;6170.228721409824;4911.287863259825;4928.78204807837;4966.354373931384;4971.651984361096;4996.825111121111;4866.951330486703;4911.361523002005;4979.502049795028;4995.125852975715;4998.925016124761;4898.191798353268;4934.212170123868;4939.60483161348;4964.304997300381;4999.350084489011;4990.65065445419;5011.198208286657;5028.7737051832755;5028.7988480460735;5046.873828154289;4395.824167033346;4448.888777780549;4473.530279320657;4498.257787465064;4509.436320459942;5988.8502787430225;6042.274927008743;6049.122877182828;6056.606320589148;6063.909041364339;5634.971825140889;5709.486146246858;5715.428457154293;5733.512651424634;5748.942510574882;6365.554411191219;6371.649559274284;6383.96808015957;6428.357164283581;6505.512086593524;4839.685420447659;4898.436679781835;4909.1409003424615;4909.852704418873;4943.777530011158;6792.864142717118;6862.828429289258;6877.312268773183;6904.792856214322;6975.755848545327;4963.6277279204205;4966.9387204309805;4967.254911763264;4994.750262486861;5045.84862454127;5068.113080210948;5077.365329333833;5110.207917772751;5112.4120726116225;5135.717535535581;5668.659880407189;5689.402612725687;5711.742971566289;5765.884682306414;5770.336411312753;6482.57863238888;6530.47756179514;6575.758635679959;6592.340765923465;6634.203895532471;1622.691688579166;1670.1732642341876;1700.523853321072;1718.3813827022261;1729.3860679458862;1166.9649910502699;1173.5716463490764;1192.5229908036854;1192.7674103549898;1210.8194510352337;5679.602427830095;5689.317281926195;5721.914171287434;5729.5702822288495;5770.769169233267;6574.665993572308;6675.632840193836;6706.5640733352175;6800.387965083166;6803.65981700913;5757.539396848242;5781.624194427349;5838.241028666331;5843.707814609253;5846.853828654318;3553.857845686195;3569.196930690574;3570.393033184365;3588.5334906461994;3590.2101537661674;6471.158749362553;6561.868762624817;6572.967135164294;6584.473242140617;6624.105745724266;6858;6900.965495172492;6928.549644273106;6949.722011119599;6985.89521157183;6504;6520.869582608321;6529.3470652935275;6532.5753826001155;6595.8021259362295;6685.866282674417;6694.230163531061;6804.625745583842;6808.523403361717;6814.955513345877;6712.986104168632;6724.630145341857;6737.730490780314;6763.898541521783;6775.864482710417;6403.7118329676205;6406.359364063556;6436.774712885027;6484.967575162094;6517.706703198272;4830.043832247945;4870.878228044186;4900.583450406612;4907;4923.926141107814;6435.485161187;6766.391024807791;6774.187097548326;6805.215566768207;6822.522423430511;6316.242050953731;6452.612843229421;6458.189668095846;6481.611103333815;6615;6409.807705768834;6441.5490915635455;6468;6480.837979050561;6568.211814582281;6314.210723659543;6392.456641185551;6450.677900881144;6461.60765598046;6520.739170433129;6537.836554086186;6578.5395022347975;6590.209174899042;6619.3380661933425;6655.700493477708;6352.237731472253;6358.332375100548;6396.825079472816;6424.229092508929;6473.676316184266 13:37:50 INFO - PID 19575 | |5;yui.html;871.8953725552931;907.6414816147621;911.5260064766321;913.0595486648746;928.5032507608427;18721.15754791034;19824.801751982475;19830.20679172833;19841.305554305603;22238.444038898997;20653.690194647093;22184;22235.666465002996;22236.776977266243;22295.665565016498;1042.838360054193;1067.01301296301;1067.4662668665667;1069.2996087562658;1069.7111779819445;1014.4775440648073;1058.30680904008;1059.6132411669723;1060.6605886116408;1061.2147011211725;1103.6413165721174;1109.1681239070697;1111.7554138089608;1113.209621168971;1114.4817659788198;1098.1215027977626;1099.62612711678;1103.1836441033659;1103.1891559703652;1104.4201794058104;806.2824086562964;809.2797410304834;809.324214281076;809.4617079642031;810.295043312322;16719.66560668792;17373.565660858454;17947.282108715637;17989;17993.370232041947;21665;21686.566268674538;22085.889570552044;22147.667784983078;22165.556688866294;16371.918140409342;16415.507534773977;16627.66744665112;16788.412405565745;16791.412300569544;3643.5809881863397;3669.6697297243377;3670.596234414212;3675.3568125577917;3684.5578530576504;118373;119636;121121.39439302923;123532;126049;17445.127743612768;17866.464006079837;18198.5450363742;18249.452516424528;18653.626927461373;31333.529997050064;31805.363892722016;31875.52186717201;31934.201644959063;32053.839730801195;7554.66004029809;7896.09194942589;8385.790355241046;8726.912730872737;8774.95612521946;3534;3605.0446631642635;3613.6928361089604;3619.511365965561;3625.4018087015347;5307.814226502054;5415.214793854869;5439.673619582838;5447.673139611636;5459.071957767189;959.947202903833;980.597954838527;987.086944576264;990.8067926754213;992.8064027514565;674.7773234832493;681.6625770243761;700.2437367643035;711.7437722419925;711.9074520312327;11805.40972951349;12068.879311206776;12418.130730848934;12772.169808962388;12922.41849116792;689.2590465249876;698.8986596943516;702.6873041496588;713.8964850096706;722.512304194677;5596.384397716248;5674.943250567524;5687.260656114761;5698.0598201296325;5817.272840894888;107195.9280407186;109067.45466272617;110492;111274;113565;95280;100154;100592;100841;104796.47601761942;86181.56909215414;88789;88933.55533222292;89918.99999999869;90077;12944.741105177656;12983.480660773841;13068.215907045227;13150.408231629792;13303.80044299317;38790.80604596959;40280;40666.59333406747;40767.388489172095;40915;70253.29746702468;72458.63770681113;74522.2547774537;75745.24254757381;78707;17190.65618687594;17516.299348025936;17552;19559.119839606967;20187.19251229993;19199.424017279503;19214.231430742613;19308.4207473776;19334.419967401;19406.611867762284;18263.634727305645;19186.712199316742;20132.59734805325;20533.38399848007;20929.581408371443;16832.915835420743;16877.66244675124;16972.66054678924;19150.904245478683;19334.903325483847;19010.904945475184;19244.903775481594;19713.507162321057;19878.304259350854;20156.798432015494;14212.147271163763;14227.359768810222;14396.20820854863;14655.193964331638;14891.85108148948;15000.774988375402;15337.463188788339;15436.614084647974;15443.691126177639;15574.766378504559 13:37:50 INFO - PID 19575 | __end_tp_report 13:37:50 INFO - PID 19575 | __start_cc_report 13:37:50 INFO - PID 19575 | _x_x_mozilla_cycle_collect,376 13:37:50 INFO - PID 19575 | __end_cc_report 13:37:50 INFO - PID 19575 | __startTimestamp1502483870910__endTimestamp 13:37:50 INFO - PID 19575 | 13:37:50 INFO - PID 19575 | ------- Summary: start ------- 13:37:50 INFO - PID 19575 | Number of tests: 6 13:37:50 INFO - PID 19575 | 13:37:50 INFO - PID 19575 | [#0] dojo.html Cycles:165 Average:6177.57 Median:6413.00 stddev:731.48 (11.4%) stddev-sans-first:694.25 13:37:50 INFO - PID 19575 | Values: 3155.8 3161.0 3178.2 3185.5 3208.1 6235.1 6242.2 6252.7 6273.5 6292.2 6114.0 6207.9 6212.0 6228.9 6233.8 5629.6 5649.8 5651.0 5672.8 5698.8 5649.2 5669.5 5692.3 5693.1 5705.6 5796.4 5821.6 5826.4 5834.3 5839.5 5643.2 5675.5 5707.9 5741.8 5797.0 5275.3 5507.4 5582.8 5628.2 5665.9 6067.4 6113.9 6172.9 6247.0 6264.9 6682.8 6694.7 6771.7 6789.2 6811.2 6650.7 6679.9 6732.0 6833.3 6849.0 6292.7 6292.8 6296.8 6304.2 6312.6 6849.3 6878.4 6979.6 6983.7 6986.9 6457.9 6568.5 6609.7 6620.0 6704.1 6495.0 6620.4 6622.8 6676.5 6684.7 6620.2 6622.2 6632.8 6637.0 6645.8 6647.3 6710.8 6741.9 6756.1 6828.8 5339.9 5489.4 5554.4 5555.1 5563.9 4616.6 4695.8 4745.2 4753.1 4786.5 5797.5 5821.9 5874.7 5883.1 5892.0 6630.0 6674.1 6763.5 6833.1 6840.4 5626.6 5662.3 5749.5 5800.3 5869.9 5961.4 5970.3 6002.7 6005.5 6007.6 6407.5 6502.5 6520.5 6523.1 6587.6 6705.9 6739.8 6744.8 6790.9 6845.4 6381.5 6438.9 6470.2 6562.2 6575.4 6628.9 6660.3 6693.3 6715.5 6732.3 6779.2 6780.5 6789.3 6892.7 6894.1 6381.2 6532.6 6549.6 6639.1 6649.4 6496.9 6499.3 6566.5 6571.2 6572.2 6248.2 6495.8 6544.1 6568.0 6578.4 6413.0 6488.1 6526.1 6609.7 6618.0 6328.3 6345.9 6406.8 6432.1 6434.4 13:37:50 INFO - PID 19575 | 13:37:50 INFO - PID 19575 | [#1] ext.html Cycles:180 Average:37984.67 Median:18504.00 stddev:54864.37 (296.5%) stddev-sans-first:54947.46 13:37:50 INFO - PID 19575 | Values: 860.7 872.9 1151.9 1192.3 1254.7 37617.4 40625.8 40665.8 40688.0 40699.4 37485.8 37525.8 37533.8 37668.0 37756.0 7094.0 7238.4 7240.6 7243.1 7245.7 7160.4 7220.3 7251.6 7252.0 7268.5 7265.4 7281.2 7634.7 7805.5 7813.6 7694.7 7748.0 7765.1 7768.1 7771.4 7518.2 7653.8 7681.8 7708.4 7731.9 37374.0 37632.0 37667.4 37766.6 37797.0 3287.2 3326.8 3327.6 3328.5 3330.5 31089.1 31220.5 31244.5 31291.0 31306.7 4973.2 4983.4 4997.3 5009.1 5016.6 191791.0 192505.0 192615.0 192750.0 193257.0 42661.1 43530.6 49007.5 49144.3 49200.5 51543.7 51629.2 51871.5 51930.2 51967.5 16281.9 16335.8 16416.7 16422.9 16430.5 13431.4 13534.6 13534.8 13536.9 13554.5 14592.1 14610.7 14611.9 14632.6 14709.9 5614.4 5743.3 5747.2 5921.2 5949.6 982.8 989.7 994.8 1011.0 1013.8 22314.6 22416.1 22435.0 22439.3 22799.0 1092.8 1094.0 1102.5 1105.8 1112.5 12138.1 12180.7 12276.1 12352.5 15972.4 228650.0 231211.0 231608.0 231638.0 231903.0 56428.0 56609.7 56716.1 57004.0 57224.4 1435.4 1468.3 1468.6 1485.7 1486.7 27067.0 27578.0 27737.2 27794.6 28328.4 37137.3 37208.6 37252.6 37332.0 37354.7 207086.0 207932.0 211143.0 211611.0 211654.0 30496.0 31067.5 33677.5 33956.3 34325.1 42639.4 43159.8 43348.0 43488.8 43548.6 32328.0 32675.3 32874.3 32943.0 33682.0 32117.5 32187.8 32253.4 32710.5 32788.0 34687.1 35061.5 35062.8 35129.8 35332.3 30822.5 30866.9 31208.2 31584.5 31758.8 21953.0 23463.5 23520.6 23567.9 24936.4 13:37:50 INFO - PID 19575 | 13:37:50 INFO - PID 19575 | [#2] jquery.html Cycles:180 Average:35127.68 Median:23481.82 stddev:81282.84 (346.2%) stddev-sans-first:81469.44 13:37:50 INFO - PID 19575 | Values: 567.4 570.7 574.0 577.3 594.1 22359.9 22843.0 23452.8 23510.9 23520.1 26862.2 27249.7 27298.9 27313.3 27330.7 6422.9 6529.3 6537.6 6602.6 6606.8 5823.0 6312.5 6387.2 6392.2 6430.1 6336.2 6341.7 6346.6 6346.8 6351.2 6342.1 6346.8 6349.6 6360.0 6371.9 5942.8 5945.8 5948.9 5951.9 5952.0 30134.2 30336.4 30841.2 31244.2 31432.5 1926.6 1970.1 1972.1 1972.2 1972.5 19262.0 19868.5 19909.9 19942.7 19946.3 2407.4 2469.3 2502.7 2517.3 2518.9 139349.0 145305.0 145883.0 146232.5 146308.0 13736.9 14587.6 14825.2 15172.2 15403.0 23596.2 23675.8 23676.6 23683.5 23703.5 10483.6 10551.8 10564.1 10783.4 10960.7 9922.0 9998.6 10035.6 10147.5 10266.6 7690.4 8991.1 9042.2 9094.0 9389.2 2587.2 2704.4 2726.6 2737.5 2743.9 955.6 991.9 992.3 993.1 999.1 19729.0 20133.4 20296.0 20326.9 20358.0 1031.5 1038.8 1041.7 1053.0 1055.7 11066.9 11266.7 11307.2 11377.9 11403.5 471662.0 477988.0 479951.0 482334.0 482990.0 118184.0 118249.0 118753.0 121947.0 122349.4 1180.3 1199.5 1210.7 1217.0 1220.8 17075.8 17317.6 17556.9 17672.2 17909.7 55823.4 56669.7 57122.0 58156.7 59018.0 73675.0 73757.0 74160.4 77596.0 80552.0 14324.4 14524.9 14585.4 14724.6 14743.6 24154.0 24278.9 24443.5 24678.0 24728.9 18830.9 19127.7 19145.2 19382.0 19460.6 16887.9 17271.7 17282.5 17379.0 17450.6 19403.7 19473.6 19643.1 19799.0 19883.5 14272.0 14582.5 14583.3 14594.7 14599.3 17789.7 17791.3 17813.6 17901.6 17922.2 13:37:50 INFO - PID 19575 | 13:37:50 INFO - PID 19575 | [#3] mootools.html Cycles:180 Average:8774.38 Median:2612.41 stddev:6016.94 (230.3%) stddev-sans-first:6007.94 13:37:50 INFO - PID 19575 | Values: 1347.8 1398.2 1406.4 1423.1 1428.3 13986.9 14538.3 14565.8 14589.4 14592.3 14676.9 15059.8 15076.9 15077.1 15093.8 2648.6 2657.5 2690.6 2695.3 2704.9 2540.1 2661.6 2698.6 2706.9 2709.2 2569.6 2610.2 2611.4 2660.2 2677.2 2624.3 2633.9 2635.4 2643.6 2646.1 2545.7 2609.6 2613.4 2613.8 2619.0 14014.2 14038.9 14230.2 14251.6 14258.2 7498.6 7836.5 7849.5 7867.1 7898.6 5174.2 5268.6 5293.8 5379.8 5383.7 1825.8 1830.4 1864.7 1876.5 1900.9 20196.8 20257.2 20263.1 20316.0 20359.1 6316.7 6467.0 6673.9 6755.7 6759.9 16012.7 16105.4 16218.1 16232.8 16284.0 7395.0 7559.4 7576.4 7592.4 7593.4 5871.4 6036.1 6401.1 7001.6 7063.9 5082.9 5250.1 5284.1 5334.9 5337.3 1485.3 1522.1 1528.3 1534.4 1546.8 831.7 843.1 856.5 861.1 870.9 6855.1 6867.1 6939.1 6977.8 7052.0 697.3 698.7 701.2 708.4 710.5 4311.6 4664.4 4926.8 5007.4 5212.2 19494.6 19750.1 20081.9 20288.5 20676.8 19921.5 19932.1 20228.5 20411.4 20441.4 10790.7 10947.8 11066.9 11221.0 11223.5 7947.3 8008.7 8032.4 8036.6 8093.0 17351.5 17373.7 17404.0 17490.6 17841.7 17913.5 17967.0 18254.1 18256.9 18570.4 11487.5 11977.7 11987.9 12012.8 12029.6 13348.3 13484.0 13690.6 13694.5 13694.7 10907.8 10931.2 10960.6 11037.6 11173.1 10844.2 10883.8 10916.5 11026.4 11174.4 9728.3 9892.0 9895.0 9902.2 9941.3 6808.7 6904.4 6927.6 6942.5 6962.3 6254.4 6263.5 6297.5 6365.9 6383.3 13:37:50 INFO - PID 19575 | 13:37:50 INFO - PID 19575 | [#4] prototype.html Cycles:180 Average:5502.95 Median:6060.26 stddev:1553.77 (25.6%) stddev-sans-first:1511.91 13:37:50 INFO - PID 19575 | Values: 491.2 496.7 519.7 520.1 529.0 6154.9 6161.9 6187.8 6288.8 6301.8 5891.2 6023.1 6143.5 6162.7 6170.2 4911.3 4928.8 4966.4 4971.7 4996.8 4867.0 4911.4 4979.5 4995.1 4998.9 4898.2 4934.2 4939.6 4964.3 4999.4 4990.7 5011.2 5028.8 5028.8 5046.9 4395.8 4448.9 4473.5 4498.3 4509.4 5988.9 6042.3 6049.1 6056.6 6063.9 5635.0 5709.5 5715.4 5733.5 5748.9 6365.6 6371.6 6384.0 6428.4 6505.5 4839.7 4898.4 4909.1 4909.9 4943.8 6792.9 6862.8 6877.3 6904.8 6975.8 4963.6 4966.9 4967.3 4994.8 5045.8 5068.1 5077.4 5110.2 5112.4 5135.7 5668.7 5689.4 5711.7 5765.9 5770.3 6482.6 6530.5 6575.8 6592.3 6634.2 1622.7 1670.2 1700.5 1718.4 1729.4 1167.0 1173.6 1192.5 1192.8 1210.8 5679.6 5689.3 5721.9 5729.6 5770.8 6574.7 6675.6 6706.6 6800.4 6803.7 5757.5 5781.6 5838.2 5843.7 5846.9 3553.9 3569.2 3570.4 3588.5 3590.2 6471.2 6561.9 6573.0 6584.5 6624.1 6858.0 6901.0 6928.5 6949.7 6985.9 6504.0 6520.9 6529.3 6532.6 6595.8 6685.9 6694.2 6804.6 6808.5 6815.0 6713.0 6724.6 6737.7 6763.9 6775.9 6403.7 6406.4 6436.8 6485.0 6517.7 4830.0 4870.9 4900.6 4907.0 4923.9 6435.5 6766.4 6774.2 6805.2 6822.5 6316.2 6452.6 6458.2 6481.6 6615.0 6409.8 6441.5 6468.0 6480.8 6568.2 6314.2 6392.5 6450.7 6461.6 6520.7 6537.8 6578.5 6590.2 6619.3 6655.7 6352.2 6358.3 6396.8 6424.2 6473.7 13:37:50 INFO - PID 19575 | 13:37:50 INFO - PID 19575 | [#5] yui.html Cycles:180 Average:24143.74 Median:19835.76 stddev:32223.40 (162.5%) stddev-sans-first:32266.42 13:37:50 INFO - PID 19575 | Values: 871.9 907.6 911.5 913.1 928.5 18721.2 19824.8 19830.2 19841.3 22238.4 20653.7 22184.0 22235.7 22236.8 22295.7 1042.8 1067.0 1067.5 1069.3 1069.7 1014.5 1058.3 1059.6 1060.7 1061.2 1103.6 1109.2 1111.8 1113.2 1114.5 1098.1 1099.6 1103.2 1103.2 1104.4 806.3 809.3 809.3 809.5 810.3 16719.7 17373.6 17947.3 17989.0 17993.4 21665.0 21686.6 22085.9 22147.7 22165.6 16371.9 16415.5 16627.7 16788.4 16791.4 3643.6 3669.7 3670.6 3675.4 3684.6 118373.0 119636.0 121121.4 123532.0 126049.0 17445.1 17866.5 18198.5 18249.5 18653.6 31333.5 31805.4 31875.5 31934.2 32053.8 7554.7 7896.1 8385.8 8726.9 8775.0 3534.0 3605.0 3613.7 3619.5 3625.4 5307.8 5415.2 5439.7 5447.7 5459.1 959.9 980.6 987.1 990.8 992.8 674.8 681.7 700.2 711.7 711.9 11805.4 12068.9 12418.1 12772.2 12922.4 689.3 698.9 702.7 713.9 722.5 5596.4 5674.9 5687.3 5698.1 5817.3 107195.9 109067.5 110492.0 111274.0 113565.0 95280.0 100154.0 100592.0 100841.0 104796.5 86181.6 88789.0 88933.6 89919.0 90077.0 12944.7 12983.5 13068.2 13150.4 13303.8 38790.8 40280.0 40666.6 40767.4 40915.0 70253.3 72458.6 74522.3 75745.2 78707.0 17190.7 17516.3 17552.0 19559.1 20187.2 19199.4 19214.2 19308.4 19334.4 19406.6 18263.6 19186.7 20132.6 20533.4 20929.6 16832.9 16877.7 16972.7 19150.9 19334.9 19010.9 19244.9 19713.5 19878.3 20156.8 14212.1 14227.4 14396.2 14655.2 14891.9 15000.8 15337.5 15436.6 15443.7 15574.8 13:37:50 INFO - PID 19575 | -------- Summary: end -------- 13:37:50 INFO - PID 19575 | 13:37:51 INFO - TEST-INFO | 19575: exit 0 13:37:51 INFO - TEST-OK | dromaeo_css | took 1113405ms 13:37:51 INFO - TEST-START | kraken 13:37:51 INFO - Initialising browser for kraken test... 13:37:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56508/getInfo.html -profile /tmp/tmpM4UQrQ/profile 13:37:51 INFO - TEST-INFO | started process 19952 (/builds/slave/test/build/application/firefox/firefox http://localhost:56508/getInfo.html) 13:37:57 INFO - TEST-INFO | 19952: exit 0 13:37:57 INFO - Browser initialized. 13:37:57 INFO - Running cycle 1/1 for kraken test... 13:37:57 INFO - TEST-INFO | started process 20188 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM4UQrQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:37:58 INFO - PID 20188 | 13:37:58 INFO - PID 20188 | (/builds/slave/test/build/application/firefox/firefox:20241): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:37:58 INFO - PID 20188 | 13:38:09 INFO - PID 20188 | RSS: Main: 168173568 13:38:09 INFO - PID 20188 | 13:38:11 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56508/tests/kraken/driver.html?testName=audio-beat-detection) 13:38:12 INFO - PID 20188 | RSS: Main: 174645248 13:38:12 INFO - PID 20188 | 13:38:15 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56508/tests/kraken/driver.html?testName=audio-dft) 13:38:16 INFO - PID 20188 | RSS: Main: 178409472 13:38:16 INFO - PID 20188 | 13:38:19 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56508/tests/kraken/driver.html?testName=audio-fft) 13:38:19 INFO - PID 20188 | RSS: Main: 172306432 13:38:19 INFO - PID 20188 | 13:38:21 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56508/tests/kraken/driver.html?testName=audio-oscillator) 13:38:22 INFO - PID 20188 | RSS: Main: 172707840 13:38:22 INFO - PID 20188 | 13:38:24 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56508/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:38:25 INFO - PID 20188 | RSS: Main: 172974080 13:38:25 INFO - PID 20188 | 13:38:28 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56508/tests/kraken/driver.html?testName=imaging-darkroom) 13:38:29 INFO - PID 20188 | RSS: Main: 173740032 13:38:29 INFO - PID 20188 | 13:38:32 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56508/tests/kraken/driver.html?testName=imaging-desaturate) 13:38:33 INFO - PID 20188 | RSS: Main: 173871104 13:38:33 INFO - PID 20188 | 13:38:36 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56508/tests/kraken/driver.html?testName=json-parse-financial) 13:38:36 INFO - PID 20188 | RSS: Main: 174845952 13:38:36 INFO - PID 20188 | 13:38:38 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56508/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:38:39 INFO - PID 20188 | RSS: Main: 174321664 13:38:39 INFO - PID 20188 | 13:38:41 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56508/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:38:41 INFO - PID 20188 | RSS: Main: 174489600 13:38:41 INFO - PID 20188 | 13:38:43 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56508/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:38:44 INFO - PID 20188 | RSS: Main: 174039040 13:38:44 INFO - PID 20188 | 13:38:47 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56508/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:38:48 INFO - PID 20188 | RSS: Main: 174501888 13:38:48 INFO - PID 20188 | 13:38:51 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56508/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:38:51 INFO - PID 20188 | RSS: Main: 174661632 13:38:51 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | Cycle 1(1): loaded http://localhost:56508/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56508/tests/kraken/driver.html?testName=ai-astar) 13:38:53 INFO - PID 20188 | RSS: Main: 176250880 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | __start_tp_report 13:38:53 INFO - PID 20188 | _x_x_mozilla_page_load 13:38:53 INFO - PID 20188 | _x_x_mozilla_page_load_details 13:38:53 INFO - PID 20188 | |i|pagename|runs| 13:38:53 INFO - PID 20188 | |0;ai-astar;101;108;100;107;107;108;114;98;106;107 13:38:53 INFO - PID 20188 | |1;audio-beat-detection;143;122;145;177;118;142;147;123;175;119 13:38:53 INFO - PID 20188 | |2;audio-dft;162;162;162;173;170;164;163;168;162;166 13:38:53 INFO - PID 20188 | |3;audio-fft;75;77;77;169;76;74;75;73;154;75 13:38:53 INFO - PID 20188 | |4;audio-oscillator;82;78;139;79;84;80;134;80;79;130 13:38:53 INFO - PID 20188 | |5;imaging-gaussian-blur;116;117;114;113;113;115;113;116;116;119 13:38:53 INFO - PID 20188 | |6;imaging-darkroom;149;153;153;152;150;150;150;150;149;150 13:38:53 INFO - PID 20188 | |7;imaging-desaturate;94;93;94;96;99;99;98;98;99;94 13:38:53 INFO - PID 20188 | |8;json-parse-financial;53;58;58;54;56;54;60;53;58;53 13:38:53 INFO - PID 20188 | |9;json-stringify-tinderbox;55;54;57;54;57;54;55;56;57;54 13:38:53 INFO - PID 20188 | |10;stanford-crypto-aes;79;85;79;79;79;81;78;94;79;82 13:38:53 INFO - PID 20188 | |11;stanford-crypto-ccm;128;134;139;126;135;127;145;150;127;174 13:38:53 INFO - PID 20188 | |12;stanford-crypto-pbkdf2;183;175;173;173;174;176;178;182;173;186 13:38:53 INFO - PID 20188 | |13;stanford-crypto-sha256-iterative;68;72;73;69;69;72;70;72;71;68 13:38:53 INFO - PID 20188 | __end_tp_report 13:38:53 INFO - PID 20188 | __start_cc_report 13:38:53 INFO - PID 20188 | _x_x_mozilla_cycle_collect,986 13:38:53 INFO - PID 20188 | __end_cc_report 13:38:53 INFO - PID 20188 | __startTimestamp1502483933971__endTimestamp 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | ------- Summary: start ------- 13:38:53 INFO - PID 20188 | Number of tests: 14 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#0] ai-astar Cycles:10 Average:105.60 Median:107.50 stddev:4.70 (4.4%) stddev-sans-first:4.68 13:38:53 INFO - PID 20188 | Values: 101.0 108.0 100.0 107.0 107.0 108.0 114.0 98.0 106.0 107.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:144.00 stddev:21.62 (15.0%) stddev-sans-first:22.92 13:38:53 INFO - PID 20188 | Values: 143.0 122.0 145.0 177.0 118.0 142.0 147.0 123.0 175.0 119.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#2] audio-dft Cycles:10 Average:165.20 Median:165.00 stddev:3.94 (2.4%) stddev-sans-first:4.00 13:38:53 INFO - PID 20188 | Values: 162.0 162.0 162.0 173.0 170.0 164.0 163.0 168.0 162.0 166.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#3] audio-fft Cycles:10 Average:92.50 Median:75.00 stddev:36.56 (48.7%) stddev-sans-first:38.22 13:38:53 INFO - PID 20188 | Values: 75.0 77.0 77.0 169.0 76.0 74.0 75.0 73.0 154.0 75.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:79.50 stddev:26.25 (33.0%) stddev-sans-first:27.31 13:38:53 INFO - PID 20188 | Values: 82.0 78.0 139.0 79.0 84.0 80.0 134.0 80.0 79.0 130.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:116.00 stddev:1.99 (1.7%) stddev-sans-first:2.09 13:38:53 INFO - PID 20188 | Values: 116.0 117.0 114.0 113.0 113.0 115.0 113.0 116.0 116.0 119.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:150.00 stddev:1.51 (1.0%) stddev-sans-first:1.48 13:38:53 INFO - PID 20188 | Values: 149.0 153.0 153.0 152.0 150.0 150.0 150.0 150.0 149.0 150.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:98.00 stddev:2.46 (2.5%) stddev-sans-first:2.45 13:38:53 INFO - PID 20188 | Values: 94.0 93.0 94.0 96.0 99.0 99.0 98.0 98.0 99.0 94.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:57.00 stddev:2.63 (4.6%) stddev-sans-first:2.60 13:38:53 INFO - PID 20188 | Values: 53.0 58.0 58.0 54.0 56.0 54.0 60.0 53.0 58.0 53.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#9] json-stringify-tinderbox Cycles:10 Average:55.30 Median:55.50 stddev:1.34 (2.4%) stddev-sans-first:1.41 13:38:53 INFO - PID 20188 | Values: 55.0 54.0 57.0 54.0 57.0 54.0 55.0 56.0 57.0 54.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:80.00 stddev:4.86 (6.1%) stddev-sans-first:5.07 13:38:53 INFO - PID 20188 | Values: 79.0 85.0 79.0 79.0 79.0 81.0 78.0 94.0 79.0 82.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#11] stanford-crypto-ccm Cycles:10 Average:138.50 Median:137.00 stddev:14.90 (10.9%) stddev-sans-first:15.31 13:38:53 INFO - PID 20188 | Values: 128.0 134.0 139.0 126.0 135.0 127.0 145.0 150.0 127.0 174.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:177.00 stddev:4.76 (2.7%) stddev-sans-first:4.58 13:38:53 INFO - PID 20188 | Values: 183.0 175.0 173.0 173.0 174.0 176.0 178.0 182.0 173.0 186.0 13:38:53 INFO - PID 20188 | 13:38:53 INFO - PID 20188 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:71.50 stddev:1.84 (2.6%) stddev-sans-first:1.73 13:38:53 INFO - PID 20188 | Values: 68.0 72.0 73.0 69.0 69.0 72.0 70.0 72.0 71.0 68.0 13:38:53 INFO - PID 20188 | -------- Summary: end -------- 13:38:53 INFO - PID 20188 | 13:38:54 INFO - PID 20188 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 13:38:54 INFO - TEST-INFO | 20188: exit 0 13:38:54 INFO - TEST-OK | kraken | took 63034ms 13:38:54 INFO - SUITE-END | took 1176s 13:38:55 INFO - Completed test suite (00:19:36) 13:38:55 INFO - javascript benchmark 13:38:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8515.628638659202, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3155.763317751168, 3161.035884055362, 3178.2054486378406, 3185.4584720597495, 3208.0856955767595, 6235.064740288959, 6242.157308763316, 6252.6873656317175, 6273.529485288599, 6292.150559674443, 6113.969430152854, 6207.906881396782, 6211.968940155304, 6228.937710622892, 6233.750649974007, 5629.60592758507, 5649.802256921009, 5650.971745141269, 5672.801451949183, 5698.772049118031, 5649.23735295734, 5669.518090962273, 5692.260006199188, 5693.146028095777, 5705.629134106291, 5796.362400135981, 5821.56338274629, 5826.417358264161, 5834.299884013924, 5839.474447299742, 5643.238162848004, 5675.489205971462, 5707.914381284284, 5741.770329186827, 5797.0, 5275.314209152824, 5507.421720719347, 5582.8325150245555, 5628.155776633538, 5665.8583535411535, 6067.362926892657, 6113.877722445526, 6172.9074063889075, 6247.00047992319, 6264.874702505925, 6682.83292917681, 6694.732210711566, 6771.72913083476, 6789.219239787389, 6811.182658081012, 6650.70071846768, 6679.93320066798, 6732.0, 6833.282505336918, 6849.0, 6292.716827742763, 6292.842678933018, 6296.811095667137, 6304.243490781089, 6312.589681670676, 6849.280825513349, 6878.449724022066, 6979.616121113286, 6983.685734141975, 6986.93013069873, 6457.935420645827, 6568.540202185896, 6609.702563384659, 6619.966900165565, 6704.128463299739, 6495.025746138117, 6620.404163625297, 6622.768203112868, 6676.49926255523, 6684.665766711645, 6620.205575330991, 6622.238442579069, 6632.767853125214, 6637.037629543785, 6645.767398141042, 6647.302033286532, 6710.798676039727, 6741.8651626967185, 6756.087928129639, 6828.760993365208, 5339.893202135935, 5489.39616642169, 5554.416786237387, 5555.111182210827, 5563.916541251884, 4616.561426664462, 4695.812167513262, 4745.217039188564, 4753.144434001843, 4786.473487916326, 5797.536197104221, 5821.941780582225, 5874.676892770939, 5883.14694369314, 5892.0, 6630.0, 6674.132362792806, 6763.458923286224, 6833.145856767904, 6840.418564421981, 5626.578006649518, 5662.320521537442, 5749.482546570829, 5800.274965629296, 5869.853253668607, 5961.433663801932, 5970.283565972111, 6002.6698531580305, 6005.459508644156, 6007.639541627429, 6407.487401007908, 6502.479801615859, 6520.478361731145, 6523.11937888379, 6587.571807832572, 6705.865882682221, 6739.797806065826, 6744.763933262312, 6790.898136527857, 6845.418139458202, 6381.489480841615, 6438.935610643834, 6470.191226096738, 6562.245341785565, 6575.4410875076155, 6628.90056649141, 6660.300668429745, 6693.2972037935315, 6715.5299129060495, 6732.3267673232285, 6779.15260592426, 6780.457563394818, 6789.253182150058, 6892.724291028503, 6894.13823272091, 6381.202349706286, 6532.5753826001155, 6549.574277671936, 6639.136912201383, 6649.368310010541, 6496.870062598627, 6499.285078641251, 6566.474682025331, 6571.244306904767, 6572.211334639683, 6248.187735594343, 6495.772647957298, 6544.116544266653, 6568.047633093079, 6578.407943284936, 6413.0, 6488.059231411325, 6526.086347911391, 6609.669516524058, 6618.0, 6328.335524769833, 6345.8730825384155, 6406.807795766134, 6432.067350234283, 6434.388733070351], "value": 6122.897372052147, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.7460799064276, 872.912708729127, 1151.8848115188482, 1192.3025030357242, 1254.6988722706553, 37617.43573846391, 40625.79687101564, 40665.79667101668, 40688.0, 40699.38950915735, 37485.81257093711, 37525.812370938176, 37533.81233093837, 37668.0, 37756.0, 7093.964530177354, 7238.384737297336, 7240.565566066027, 7243.130824301091, 7245.74639887604, 7160.355567998879, 7220.2779722027635, 7251.56490610565, 7252.0, 7268.491205615609, 7265.4187664986675, 7281.235470275624, 7634.694612215505, 7805.492642978216, 7813.570253636048, 7694.7306844260465, 7748.0, 7765.1458339582605, 7768.067831860157, 7771.417143714244, 7518.173000969889, 7653.770386888346, 7681.846363072707, 7708.383329333696, 7731.922680773233, 37374.0, 37632.0, 37667.434988475194, 37766.62233377686, 37797.0, 3287.2439338952177, 3326.800391976489, 3327.633960264369, 3328.5340052392685, 3330.5004249362546, 31089.067327980196, 31220.531692024637, 31244.531332030037, 31291.0, 31306.686933130604, 4973.179425394806, 4983.426905905831, 4997.275395067732, 5009.123403404426, 5016.623753218523, 191791.04104479527, 192505.0, 192615.0, 192750.0, 193257.0, 42661.146777064285, 43530.56469435265, 49007.50992490101, 49144.26283605749, 49200.50799492031, 51543.74228128835, 51629.22556161661, 51871.48128518742, 51930.22104668433, 51967.480325196266, 16281.91859040721, 16335.754963675554, 16416.671666566603, 16422.917885410498, 16430.507084787238, 13431.3955871986, 13534.59396218115, 13534.796978045337, 13536.864631353757, 13554.457821687021, 14592.103026606474, 14610.707785844435, 14611.85388146105, 14632.561023169323, 14709.926450367893, 5614.354349249888, 5743.282089738783, 5747.195392645033, 5921.200637913913, 5949.613275137102, 982.7887004294112, 989.737719504332, 994.786120983992, 1010.9848352274722, 1013.7972405518926, 22314.55370892573, 22416.103355865584, 22434.990425430893, 22439.32682019542, 22799.0, 1092.7869065532145, 1093.9726506837233, 1102.4983632447218, 1105.7512059786645, 1112.4882554025078, 12138.08964327679, 12180.695482613006, 12276.079294052981, 12352.505899764088, 15972.440964566184, 228650.0, 231211.0, 231608.0, 231638.0, 231903.0, 56428.0, 56609.71695141498, 56716.149257761164, 57004.0, 57224.42775572274, 1435.397133204057, 1468.3465857693225, 1468.574113507071, 1485.7251408489249, 1486.7026594681106, 27067.0, 27578.0, 27737.167884963485, 27794.583081253393, 28328.43343133167, 37137.25725485421, 37208.6279137216, 37252.62747372492, 37332.0, 37354.69258575937, 207086.0, 207932.0, 211143.0, 211611.0, 211654.0, 30496.0, 31067.53398698976, 33677.49483757795, 33956.32087358288, 34325.141871453416, 42639.360409594505, 43159.78420107879, 43348.0, 43488.78255608702, 43548.56451435572, 32328.0, 32675.34649306953, 32874.342513149124, 32943.0, 33682.0, 32117.518237226926, 32187.839060805483, 32253.35493290074, 32710.50934236036, 32788.0, 34687.13282167966, 35061.47407788834, 35062.82468587743, 35129.824350879106, 35332.29335413329, 30822.537661935537, 30866.919657811868, 31208.21979450441, 31584.526232106076, 31758.841205793822, 21953.0, 23463.530729385657, 23520.647190291817, 23567.882160589088, 24936.37659058538], "value": 17249.921902946662, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.3532173322411, 570.7260514952825, 573.9942600573993, 577.2812848004234, 594.0791772752234, 22359.888200558995, 22843.0, 23452.765472345294, 23510.88244558777, 23520.059197632076, 26862.194134175956, 27249.727502724967, 27298.863505682493, 27313.317167070785, 27330.726692733067, 6422.903656445145, 6529.314421985694, 6537.607743535379, 6602.570832895871, 6606.801795946105, 5822.97088514559, 6312.463440607531, 6387.201599800025, 6392.2009748781165, 6430.099786029936, 6336.239651241857, 6341.651209183493, 6346.555741098125, 6346.84132896679, 6351.237851457831, 6342.143810585581, 6346.777862774827, 6349.587276827015, 6359.968200158993, 6371.904421433682, 5942.7920022799, 5945.762169513214, 5948.910766338508, 5951.880962380728, 5952.0476723724, 30134.24664383386, 30336.393272134435, 30841.228969275948, 31244.218894527818, 31432.52851207234, 1926.6146770645787, 1970.1036028607093, 1972.0731256309512, 1972.2209727157835, 1972.5167334003118, 19262.0, 19868.503287417785, 19909.9004504978, 19942.70085948712, 19946.30187943415, 2407.3861165402886, 2469.271564888344, 2502.699676038869, 2517.295157355943, 2518.8614626195554, 139349.0, 145305.0, 145883.0, 146232.53767462296, 146308.0, 13736.862631373759, 14587.562373128823, 14825.184614846072, 15172.165530895907, 15403.0, 23596.174133905573, 23675.7632423677, 23676.64485032726, 23683.526329473658, 23703.525929481657, 10483.633072842567, 10551.788964220672, 10564.102051325723, 10783.352998819939, 10960.725981850517, 9922.0, 9998.600055997824, 10035.648752293635, 10147.543360548792, 10266.58933642646, 7690.423218258541, 8991.100889910957, 9042.18620324174, 9093.99966003739, 9389.201917836925, 2587.236765154284, 2704.4050308932005, 2726.591011348274, 2737.534619114755, 2743.862806859649, 955.5508910811907, 991.9206463482905, 992.3103443106971, 993.0813997052697, 999.0858364596459, 19729.0, 20133.39599812008, 20296.0, 20326.898365508077, 20358.0, 1031.4945676618404, 1038.7558923652934, 1041.7239431550645, 1052.9894701052892, 1055.6698559814688, 11066.889331106586, 11266.7183320416, 11307.208495405243, 11377.943110284396, 11403.543858245743, 471662.0, 477988.0, 479951.0, 482334.0, 482990.0, 118184.0, 118249.0, 118753.0, 121947.0, 122349.38825305816, 1180.2977228549, 1199.5022065842577, 1210.7215340471564, 1217.0385395537426, 1220.780259553289, 17075.829241707423, 17317.56706082358, 17556.912215438842, 17672.20475078599, 17909.731354029438, 55823.44176558345, 56669.71665141648, 57122.0, 58156.70921645534, 59018.0, 73675.0, 73757.0, 74160.40438584624, 77596.0, 80552.0, 14324.427022918966, 14524.85475145235, 14585.416583336548, 14724.55826325212, 14743.557693269218, 24154.0, 24278.87860560686, 24443.51112977766, 24678.0, 24728.876355618107, 18830.905845470686, 19127.71308430347, 19145.234190632218, 19382.0, 19460.610787784448, 16887.91556042261, 17271.740923886402, 17282.481525554253, 17379.0, 17450.563735906195, 19403.708944366128, 19473.610527789082, 19643.116059777058, 19799.0, 19883.502912427306, 14272.0, 14582.4896128635, 14583.343749531508, 14594.708105838035, 14599.270036497921, 17789.733154002442, 17791.288348465918, 17813.643727125644, 17901.641967160846, 17922.19350129221], "value": 12123.942848452118, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.7708789505784, 1398.23796031163, 1406.4444544404967, 1423.1390009044521, 1428.2644438114369, 13986.930065349672, 14538.34577444015, 14565.781513277308, 14589.416423343053, 14592.270386480674, 14676.853231467681, 15059.774103388458, 15076.924615376907, 15077.095374277551, 15093.773593396108, 2648.576227803547, 2657.4685062987382, 2690.6367640368594, 2695.272276485348, 2704.864756762164, 2540.1236573382153, 2661.6406785084055, 2698.649175607178, 2706.851123188224, 2709.227870057024, 2569.5888657814753, 2610.229982155259, 2611.438540713746, 2660.2285337252174, 2677.2369874585747, 2624.2520881548667, 2633.881475333605, 2635.4465562231953, 2643.550596398617, 2646.1400044985267, 2545.7072436669837, 2609.582466805302, 2613.3858543242322, 2613.803964702655, 2619.0309585453315, 14014.22921739304, 14038.859611403857, 14230.217338046403, 14251.643708907257, 14258.2157981311, 7498.550086994744, 7836.451448398614, 7849.4897831640765, 7867.0952840423515, 7898.565578893159, 5174.197999310113, 5268.586998832238, 5293.814716484943, 5379.8386048418215, 5383.650062745948, 1825.8174182581636, 1830.3685228596114, 1864.683003889342, 1876.502726777405, 1900.8764430311985, 20196.798032019786, 20257.189712411488, 20263.08816103279, 20316.0, 20359.08384122718, 6316.747330106836, 6467.0, 6673.866522669519, 6755.729770809211, 6759.864802704017, 16012.679746405007, 16105.436309729337, 16218.108004059653, 16232.75650865238, 16284.0, 7395.0, 7559.433042521833, 7576.356009739124, 7592.430567707444, 7593.354564861938, 5871.383504732027, 6036.078744479618, 6401.07184458251, 7001.614911179934, 7063.89404158938, 5082.923756143661, 5250.107481728115, 5284.075286824867, 5334.866628334323, 5337.306150200449, 1485.3390241342715, 1522.0563250784585, 1528.2587944846741, 1534.4015833824594, 1546.7834503169565, 831.7172161465131, 843.0557775291712, 856.5032281276845, 861.1259571534897, 870.9172628600273, 6855.07456493377, 6867.141607299088, 6939.097917270722, 6977.79066628002, 7051.964740176369, 697.2574208467965, 698.7379732600275, 701.2216439751871, 708.4014008163094, 710.4671496377716, 4311.6335111515245, 4664.370310008106, 4926.753662316942, 5007.37407824022, 5212.244224587489, 19494.61010779805, 19750.11124499401, 20081.899590501955, 20288.492787680425, 20676.793232067786, 19921.501962450766, 19932.103055362542, 20228.494287642927, 20411.387658370273, 20441.38675839727, 10790.73023174427, 10947.83578246327, 11066.889331106748, 11221.0, 11223.494942727597, 7947.284744373034, 8008.679652813822, 8032.357411406956, 8036.638351274323, 8093.0, 17351.479455615845, 17373.65252694964, 17404.0, 17490.562735931704, 17841.73237401466, 17913.462596122135, 17967.0, 18254.0872956349, 18256.90871545634, 18570.44288671342, 11487.54049837997, 11977.700557485785, 11987.880121198676, 12012.819807702717, 12029.578964736193, 13348.332583370986, 13484.010147214385, 13690.589282321545, 13694.52069177574, 13694.726105478034, 10907.836382454429, 10931.180161488079, 10960.616378426717, 11037.613683521038, 11173.106151508022, 10844.240903136704, 10883.836742449028, 10916.454177290945, 11026.393548354918, 11174.385408802595, 9728.319017668695, 9892.0, 9895.0, 9902.207823374258, 9941.30410871232, 6808.7276508939085, 6904.4131248844415, 6927.58434493932, 6942.51402401827, 6962.268961759145, 6254.405831446005, 6263.530235232467, 6297.52768542352, 6365.872682546231, 6383.265924418752], "value": 6326.0902363937, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.22006162578947, 496.7313907992902, 519.6933809052658, 520.0924386944777, 528.9576833853291, 6154.938450615498, 6161.876762464759, 6187.783427580036, 6288.779892703757, 6301.834160680277, 5891.175235467038, 6023.096535519674, 6143.508519318455, 6162.691865406723, 6170.228721409824, 4911.287863259825, 4928.78204807837, 4966.354373931384, 4971.651984361096, 4996.825111121111, 4866.951330486703, 4911.361523002005, 4979.502049795028, 4995.125852975715, 4998.925016124761, 4898.191798353268, 4934.212170123868, 4939.60483161348, 4964.304997300381, 4999.350084489011, 4990.65065445419, 5011.198208286657, 5028.7737051832755, 5028.7988480460735, 5046.873828154289, 4395.824167033346, 4448.888777780549, 4473.530279320657, 4498.257787465064, 4509.436320459942, 5988.8502787430225, 6042.274927008743, 6049.122877182828, 6056.606320589148, 6063.909041364339, 5634.971825140889, 5709.486146246858, 5715.428457154293, 5733.512651424634, 5748.942510574882, 6365.554411191219, 6371.649559274284, 6383.96808015957, 6428.357164283581, 6505.512086593524, 4839.685420447659, 4898.436679781835, 4909.1409003424615, 4909.852704418873, 4943.777530011158, 6792.864142717118, 6862.828429289258, 6877.312268773183, 6904.792856214322, 6975.755848545327, 4963.6277279204205, 4966.9387204309805, 4967.254911763264, 4994.750262486861, 5045.84862454127, 5068.113080210948, 5077.365329333833, 5110.207917772751, 5112.4120726116225, 5135.717535535581, 5668.659880407189, 5689.402612725687, 5711.742971566289, 5765.884682306414, 5770.336411312753, 6482.57863238888, 6530.47756179514, 6575.758635679959, 6592.340765923465, 6634.203895532471, 1622.691688579166, 1670.1732642341876, 1700.523853321072, 1718.3813827022261, 1729.3860679458862, 1166.9649910502699, 1173.5716463490764, 1192.5229908036854, 1192.7674103549898, 1210.8194510352337, 5679.602427830095, 5689.317281926195, 5721.914171287434, 5729.5702822288495, 5770.769169233267, 6574.665993572308, 6675.632840193836, 6706.5640733352175, 6800.387965083166, 6803.65981700913, 5757.539396848242, 5781.624194427349, 5838.241028666331, 5843.707814609253, 5846.853828654318, 3553.857845686195, 3569.196930690574, 3570.393033184365, 3588.5334906461994, 3590.2101537661674, 6471.158749362553, 6561.868762624817, 6572.967135164294, 6584.473242140617, 6624.105745724266, 6858.0, 6900.965495172492, 6928.549644273106, 6949.722011119599, 6985.89521157183, 6504.0, 6520.869582608321, 6529.3470652935275, 6532.5753826001155, 6595.8021259362295, 6685.866282674417, 6694.230163531061, 6804.625745583842, 6808.523403361717, 6814.955513345877, 6712.986104168632, 6724.630145341857, 6737.730490780314, 6763.898541521783, 6775.864482710417, 6403.7118329676205, 6406.359364063556, 6436.774712885027, 6484.967575162094, 6517.706703198272, 4830.043832247945, 4870.878228044186, 4900.583450406612, 4907.0, 4923.926141107814, 6435.485161187, 6766.391024807791, 6774.187097548326, 6805.215566768207, 6822.522423430511, 6316.242050953731, 6452.612843229421, 6458.189668095846, 6481.611103333815, 6615.0, 6409.807705768834, 6441.5490915635455, 6468.0, 6480.837979050561, 6568.211814582281, 6314.210723659543, 6392.456641185551, 6450.677900881144, 6461.60765598046, 6520.739170433129, 6537.836554086186, 6578.5395022347975, 6590.209174899042, 6619.3380661933425, 6655.700493477708, 6352.237731472253, 6358.332375100548, 6396.825079472816, 6424.229092508929, 6473.676316184266], "value": 5041.181301769385, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [871.8953725552931, 907.6414816147621, 911.5260064766321, 913.0595486648746, 928.5032507608427, 18721.15754791034, 19824.801751982475, 19830.20679172833, 19841.305554305603, 22238.444038898997, 20653.690194647093, 22184.0, 22235.666465002996, 22236.776977266243, 22295.665565016498, 1042.838360054193, 1067.01301296301, 1067.4662668665667, 1069.2996087562658, 1069.7111779819445, 1014.4775440648073, 1058.30680904008, 1059.6132411669723, 1060.6605886116408, 1061.2147011211725, 1103.6413165721174, 1109.1681239070697, 1111.7554138089608, 1113.209621168971, 1114.4817659788198, 1098.1215027977626, 1099.62612711678, 1103.1836441033659, 1103.1891559703652, 1104.4201794058104, 806.2824086562964, 809.2797410304834, 809.324214281076, 809.4617079642031, 810.295043312322, 16719.66560668792, 17373.565660858454, 17947.282108715637, 17989.0, 17993.370232041947, 21665.0, 21686.566268674538, 22085.889570552044, 22147.667784983078, 22165.556688866294, 16371.918140409342, 16415.507534773977, 16627.66744665112, 16788.412405565745, 16791.412300569544, 3643.5809881863397, 3669.6697297243377, 3670.596234414212, 3675.3568125577917, 3684.5578530576504, 118373.0, 119636.0, 121121.39439302923, 123532.0, 126049.0, 17445.127743612768, 17866.464006079837, 18198.5450363742, 18249.452516424528, 18653.626927461373, 31333.529997050064, 31805.363892722016, 31875.52186717201, 31934.201644959063, 32053.839730801195, 7554.66004029809, 7896.09194942589, 8385.790355241046, 8726.912730872737, 8774.95612521946, 3534.0, 3605.0446631642635, 3613.6928361089604, 3619.511365965561, 3625.4018087015347, 5307.814226502054, 5415.214793854869, 5439.673619582838, 5447.673139611636, 5459.071957767189, 959.947202903833, 980.597954838527, 987.086944576264, 990.8067926754213, 992.8064027514565, 674.7773234832493, 681.6625770243761, 700.2437367643035, 711.7437722419925, 711.9074520312327, 11805.40972951349, 12068.879311206776, 12418.130730848934, 12772.169808962388, 12922.41849116792, 689.2590465249876, 698.8986596943516, 702.6873041496588, 713.8964850096706, 722.512304194677, 5596.384397716248, 5674.943250567524, 5687.260656114761, 5698.0598201296325, 5817.272840894888, 107195.9280407186, 109067.45466272617, 110492.0, 111274.0, 113565.0, 95280.0, 100154.0, 100592.0, 100841.0, 104796.47601761942, 86181.56909215414, 88789.0, 88933.55533222292, 89918.99999999869, 90077.0, 12944.741105177656, 12983.480660773841, 13068.215907045227, 13150.408231629792, 13303.80044299317, 38790.80604596959, 40280.0, 40666.59333406747, 40767.388489172095, 40915.0, 70253.29746702468, 72458.63770681113, 74522.2547774537, 75745.24254757381, 78707.0, 17190.65618687594, 17516.299348025936, 17552.0, 19559.119839606967, 20187.19251229993, 19199.424017279503, 19214.231430742613, 19308.4207473776, 19334.419967401, 19406.611867762284, 18263.634727305645, 19186.712199316742, 20132.59734805325, 20533.38399848007, 20929.581408371443, 16832.915835420743, 16877.66244675124, 16972.66054678924, 19150.904245478683, 19334.903325483847, 19010.904945475184, 19244.903775481594, 19713.507162321057, 19878.304259350854, 20156.798432015494, 14212.147271163763, 14227.359768810222, 14396.20820854863, 14655.193964331638, 14891.85108148948, 15000.774988375402, 15337.463188788339, 15436.614084647974, 15443.691126177639, 15574.766378504559], "value": 9337.214903429473, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 108.0, 100.0, 107.0, 107.0, 108.0, 114.0, 98.0, 106.0, 107.0], "value": 105.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 122.0, 145.0, 177.0, 118.0, 142.0, 147.0, 123.0, 175.0, 119.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 173.0, 170.0, 164.0, 163.0, 168.0, 162.0, 166.0], "value": 165.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 169.0, 76.0, 74.0, 75.0, 73.0, 154.0, 75.0], "value": 92.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 139.0, 79.0, 84.0, 80.0, 134.0, 80.0, 79.0, 130.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 114.0, 113.0, 113.0, 115.0, 113.0, 116.0, 116.0, 119.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 153.0, 153.0, 152.0, 150.0, 150.0, 150.0, 150.0, 149.0, 150.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 96.0, 99.0, 99.0, 98.0, 98.0, 99.0, 94.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 58.0, 58.0, 54.0, 56.0, 54.0, 60.0, 53.0, 58.0, 53.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 57.0, 54.0, 57.0, 54.0, 55.0, 56.0, 57.0, 54.0], "value": 55.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 85.0, 79.0, 79.0, 79.0, 81.0, 78.0, 94.0, 79.0, 82.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 134.0, 139.0, 126.0, 135.0, 127.0, 145.0, 150.0, 127.0, 174.0], "value": 138.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 175.0, 173.0, 173.0, 174.0, 176.0, 178.0, 182.0, 173.0, 186.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 73.0, 69.0, 69.0, 72.0, 70.0, 72.0, 71.0, 68.0], "value": 70.4, "unit": "score"}]}]} 13:38:55 INFO - Return code: 0 13:38:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:38:55 INFO - # TBPL SUCCESS # 13:38:55 INFO - Running post-action listener: _package_coverage_data 13:38:55 INFO - Running post-action listener: _resource_record_post_action 13:38:55 INFO - [mozharness: 2017-08-11 20:38:55.247608Z] Finished run-tests step (success) 13:38:55 INFO - Running post-run listener: _resource_record_post_run 13:38:55 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 8192; Write bytes: 439046144; Read time: 76; Write time: 388160 13:38:55 INFO - TinderboxPrint: CPU usage
13.2% 13:38:55 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 76 13:38:55 INFO - TinderboxPrint: I/O write bytes / time
439,046,144 / 388,160 13:38:55 INFO - TinderboxPrint: CPU idle
8,271.8 (86.8%) 13:38:55 INFO - TinderboxPrint: CPU user
1,204.8 (12.6%) 13:38:55 INFO - TinderboxPrint: Swap in / out
0 / 0 13:38:55 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 115548160; Read time: 0; Write time: 117280 13:38:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:38:55 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 322375680; Read time: 60; Write time: 270816 13:38:55 INFO - Running post-run listener: _upload_blobber_files 13:38:55 INFO - Blob upload gear active. 13:38:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:38:55 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 13:38:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:38:56 INFO - (blobuploader) - INFO - Open directory for files ... 13:38:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:38:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:38:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:39:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:39:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:39:03 INFO - (blobuploader) - INFO - Done attempting. 13:39:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:39:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:39:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:39:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:39:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:39:04 INFO - (blobuploader) - INFO - Done attempting. 13:39:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:39:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:39:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:39:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:39:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:39:04 INFO - (blobuploader) - INFO - Done attempting. 13:39:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:39:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:39:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:39:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:39:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:39:05 INFO - (blobuploader) - INFO - Done attempting. 13:39:05 INFO - (blobuploader) - INFO - Iteration through files over. 13:39:05 INFO - Return code: 0 13:39:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:39:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:39:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1df00d2b07890da7becbb4cd0c66e7e35acd2592520a99da156ab3327956938f0301a129eb81d024d7560cafc627145a828e5a1edd2ff7c3c4da36ec3cf392d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bbea15c607992371ac859eb55274ee6ae9a3cc5a5e9358dca78b845f45da36630bfc3e2d82ff6c5820e7e462a87329e7ce24bcb45458cbf0603c2e24ff4b36e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fdbeafb52af0eca1d2b0f49bc3969909f651e3e65431c0512dd7204bec26a47f465a37990185a0e4ef955778620de3c24478c4bcdc28b1e0bcf3f5e4f93ded24", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/982916d3db52f5946ab65ba2dadfc8f5908318ccf4483c17ccfa9087ba60198c4b06ff5ab7c4d4b6e3747b475fe93d372acfc954fd3987bc85785d0a0c13cc86"} 13:39:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:39:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:39:05 INFO - Contents: 13:39:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1df00d2b07890da7becbb4cd0c66e7e35acd2592520a99da156ab3327956938f0301a129eb81d024d7560cafc627145a828e5a1edd2ff7c3c4da36ec3cf392d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bbea15c607992371ac859eb55274ee6ae9a3cc5a5e9358dca78b845f45da36630bfc3e2d82ff6c5820e7e462a87329e7ce24bcb45458cbf0603c2e24ff4b36e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fdbeafb52af0eca1d2b0f49bc3969909f651e3e65431c0512dd7204bec26a47f465a37990185a0e4ef955778620de3c24478c4bcdc28b1e0bcf3f5e4f93ded24", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/982916d3db52f5946ab65ba2dadfc8f5908318ccf4483c17ccfa9087ba60198c4b06ff5ab7c4d4b6e3747b475fe93d372acfc954fd3987bc85785d0a0c13cc86"} 13:39:05 INFO - Running post-run listener: copy_logs_to_upload_dir 13:39:05 INFO - Copying logs to upload dir... 13:39:05 INFO - mkdir: /builds/slave/test/build/upload/logs 13:39:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.186982 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-11 13:39:05.634101) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 13:39:05.637945) ========= 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=03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1df00d2b07890da7becbb4cd0c66e7e35acd2592520a99da156ab3327956938f0301a129eb81d024d7560cafc627145a828e5a1edd2ff7c3c4da36ec3cf392d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bbea15c607992371ac859eb55274ee6ae9a3cc5a5e9358dca78b845f45da36630bfc3e2d82ff6c5820e7e462a87329e7ce24bcb45458cbf0603c2e24ff4b36e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fdbeafb52af0eca1d2b0f49bc3969909f651e3e65431c0512dd7204bec26a47f465a37990185a0e4ef955778620de3c24478c4bcdc28b1e0bcf3f5e4f93ded24", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/982916d3db52f5946ab65ba2dadfc8f5908318ccf4483c17ccfa9087ba60198c4b06ff5ab7c4d4b6e3747b475fe93d372acfc954fd3987bc85785d0a0c13cc86"} build_url:https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011565 build_url: 'https://queue.taskcluster.net/v1/task/DuKjEa7WSn-Ah0CktatpHg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1df00d2b07890da7becbb4cd0c66e7e35acd2592520a99da156ab3327956938f0301a129eb81d024d7560cafc627145a828e5a1edd2ff7c3c4da36ec3cf392d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bbea15c607992371ac859eb55274ee6ae9a3cc5a5e9358dca78b845f45da36630bfc3e2d82ff6c5820e7e462a87329e7ce24bcb45458cbf0603c2e24ff4b36e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fdbeafb52af0eca1d2b0f49bc3969909f651e3e65431c0512dd7204bec26a47f465a37990185a0e4ef955778620de3c24478c4bcdc28b1e0bcf3f5e4f93ded24", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/982916d3db52f5946ab65ba2dadfc8f5908318ccf4483c17ccfa9087ba60198c4b06ff5ab7c4d4b6e3747b475fe93d372acfc954fd3987bc85785d0a0c13cc86"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 13:39:05.662827) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 13:39:05.663173) ========= 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=03627c79f6bb3d1578c4a402000001ba-1502482687.230781-887539083 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 13:39:05.747892) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 13:39:05.748225) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 13:39:05.748571) ========= ========= Total master_lag: 0.17 =========