builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-043 starttime: 1502233274.62 results: success (0) revision: 1d042bcb2632ea6a38fa08dbe21a6e8a0ee46961 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:14.619728) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:14.620193) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:14.620469) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:14.636320) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:14.636663) ========= 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=d2a8c569965ab8367867b893000001a1-1502233274.640812-865943932 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-08 16:01:15-- 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% 86.4M=0s 2017-08-08 16:01:15 (86.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092853 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:14.743300) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:14.743675) ========= 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=d2a8c569965ab8367867b893000001a1-1502233274.640812-865943932 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017343 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:14.793170) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:14.793507) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1d042bcb2632ea6a38fa08dbe21a6e8a0ee46961 --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 1d042bcb2632ea6a38fa08dbe21a6e8a0ee46961 --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=d2a8c569965ab8367867b893000001a1-1502233274.640812-865943932 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-08 16:01:15,359 truncating revision to first 12 chars 2017-08-08 16:01:15,359 Setting DEBUG logging. 2017-08-08 16:01:15,359 attempt 1/10 2017-08-08 16:01:15,359 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1d042bcb2632?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-08 16:01:15,798 unpacking tar archive at: mozilla-central-1d042bcb2632/testing/mozharness/ program finished with exit code 0 elapsedTime=0.804935 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:15.611325) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:15.611625) ========= script_repo_revision: 1d042bcb2632ea6a38fa08dbe21a6e8a0ee46961 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:15.612005) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:15.612268) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 16:01:15.621197) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-08 16:01:15.621579) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1502233274.640812-865943932 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:01:16 INFO - MultiFileLogger online at 20170808 16:01:16 in /builds/slave/test 16:01:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:01:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:01:16 INFO - {'append_to_log': False, 16:01:16 INFO - 'base_work_dir': '/builds/slave/test', 16:01:16 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:01:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:01:16 INFO - 'branch': 'Firefox-Non-PGO', 16:01:16 INFO - 'buildbot_json_path': 'buildprops.json', 16:01:16 INFO - 'code_coverage': False, 16:01:16 INFO - 'config_files': ('talos/linux_config.py',), 16:01:16 INFO - 'default_actions': ('clobber', 16:01:16 INFO - 'read-buildbot-config', 16:01:16 INFO - 'download-and-extract', 16:01:16 INFO - 'populate-webroot', 16:01:16 INFO - 'create-virtualenv', 16:01:16 INFO - 'install', 16:01:16 INFO - 'setup-mitmproxy', 16:01:16 INFO - 'run-tests'), 16:01:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:01:16 INFO - 'disable_ccov_upload': False, 16:01:16 INFO - 'download_minidump_stackwalk': True, 16:01:16 INFO - 'download_symbols': 'ondemand', 16:01:16 INFO - 'enable_stylo': False, 16:01:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:01:16 INFO - 'tooltool.py': '/tools/tooltool.py', 16:01:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:01:16 INFO - '/tools/misc-python/virtualenv.py')}, 16:01:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:01:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:01:16 INFO - 'gecko_profile': False, 16:01:16 INFO - 'gecko_profile_interval': 0, 16:01:16 INFO - 'installer_path': 'installer.exe', 16:01:16 INFO - 'log_level': 'info', 16:01:16 INFO - 'log_name': 'talos', 16:01:16 INFO - 'log_to_console': True, 16:01:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:01:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:01:16 INFO - 'opt_config_files': (), 16:01:16 INFO - 'pip_index': False, 16:01:16 INFO - 'suite': 'dromaeojs-e10s', 16:01:16 INFO - 'system_bits': '32', 16:01:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:01:16 INFO - 'title': 'talos-linux64-ix-043', 16:01:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:01:16 INFO - 'use_talos_json': True, 16:01:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:01:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:01:16 INFO - 'work_dir': 'build'} 16:01:16 INFO - [mozharness: 2017-08-08 23:01:16.291610Z] Running clobber step. 16:01:16 INFO - Running pre-action listener: _resource_record_pre_action 16:01:16 INFO - Running main action method: clobber 16:01:16 INFO - rmtree: /builds/slave/test/build 16:01:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:01:17 INFO - Running post-action listener: _resource_record_post_action 16:01:17 INFO - [mozharness: 2017-08-08 23:01:17.420424Z] Finished clobber step (success) 16:01:17 INFO - [mozharness: 2017-08-08 23:01:17.420528Z] Running read-buildbot-config step. 16:01:17 INFO - Running pre-action listener: _resource_record_pre_action 16:01:17 INFO - Running main action method: read_buildbot_config 16:01:17 INFO - Using buildbot properties: 16:01:17 INFO - { 16:01:17 INFO - "project": "", 16:01:17 INFO - "product": "firefox", 16:01:17 INFO - "who": "kwierso@gmail.com", 16:01:17 INFO - "installer_path": "public/build/target.tar.bz2", 16:01:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:01:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:01:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:01:17 INFO - "stage_platform": "linux64", 16:01:17 INFO - "basedir": "/builds/slave/test", 16:01:17 INFO - "buildnumber": 1067, 16:01:17 INFO - "platform": "ubuntu64_hw", 16:01:17 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:01:17 INFO - "slavebuilddir": "test", 16:01:17 INFO - "taskId": "Yov0bpqIQhKQPS0qd4Oz6A", 16:01:17 INFO - "branch": "mozilla-central", 16:01:17 INFO - "script_repo_revision": "production", 16:01:17 INFO - "revision": "1d042bcb2632ea6a38fa08dbe21a6e8a0ee46961", 16:01:17 INFO - "slavename": "talos-linux64-ix-043", 16:01:17 INFO - "repo_path": "mozilla-central" 16:01:17 INFO - } 16:01:17 INFO - Finding installer, test and symbols from parent task. 16:01:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yov0bpqIQhKQPS0qd4Oz6A'}, attempt #1 16:01:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yov0bpqIQhKQPS0qd4Oz6A'}, attempt #1 16:01:18 INFO - Task dependencies: F7NQqaIQR2-2IEUa3S5RaA 16:01:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA'}, attempt #1 16:01:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.tar.bz2 16:01:19 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.test_packages.json 16:01:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.crashreporter-symbols.zip 16:01:19 INFO - Running post-action listener: _resource_record_post_action 16:01:19 INFO - [mozharness: 2017-08-08 23:01:19.152795Z] Finished read-buildbot-config step (success) 16:01:19 INFO - [mozharness: 2017-08-08 23:01:19.153017Z] Running download-and-extract step. 16:01:19 INFO - Running pre-action listener: _resource_record_pre_action 16:01:19 INFO - Running main action method: download_and_extract 16:01:19 INFO - mkdir: /builds/slave/test/build/tests 16:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:19 INFO - trying https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.test_packages.json 16:01:19 INFO - Downloading https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:01:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:01:19 INFO - Downloaded 989 bytes. 16:01:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:01:19 INFO - Using the following test package requirements: 16:01:19 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:01:19 INFO - u'common': [u'target.common.tests.zip'], 16:01:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:01:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:01:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:01:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:01:19 INFO - u'mozbase': [u'target.common.tests.zip'], 16:01:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:01:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:01:19 INFO - u'web-platform': [u'target.common.tests.zip', 16:01:19 INFO - u'target.web-platform.tests.tar.gz'], 16:01:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:01:19 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:01:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.common.tests.zip 16:01:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:01:19 INFO - Fetch https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.common.tests.zip into memory 16:01:20 INFO - Content-Length response header: 54285203 16:01:20 INFO - Bytes received: 54285203 16:01:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:01:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.common.tests.zip 16:01:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:01:31 INFO - Fetch https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.common.tests.zip into memory 16:01:32 INFO - Content-Length response header: 54285203 16:01:32 INFO - Bytes received: 54285203 16:01:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.talos.tests.zip 16:01:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:01:44 INFO - Fetch https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.talos.tests.zip into memory 16:01:45 INFO - Content-Length response header: 14496008 16:01:45 INFO - Bytes received: 14496008 16:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:45 INFO - trying https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.tar.bz2 16:01:45 INFO - Downloading https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:01:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:01:46 INFO - Downloaded 61959679 bytes. 16:01:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.tar.bz2 16:01:46 INFO - mkdir: /builds/slave/test/properties 16:01:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:01:46 INFO - Writing to file /builds/slave/test/properties/build_url 16:01:46 INFO - Contents: 16:01:46 INFO - build_url:https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.tar.bz2 16:01:46 INFO - Running post-action listener: _resource_record_post_action 16:01:46 INFO - Running post-action listener: set_extra_try_arguments 16:01:46 INFO - [mozharness: 2017-08-08 23:01:46.964393Z] Finished download-and-extract step (success) 16:01:46 INFO - [mozharness: 2017-08-08 23:01:46.964491Z] Running populate-webroot step. 16:01:46 INFO - Running pre-action listener: _resource_record_pre_action 16:01:46 INFO - Running main action method: populate_webroot 16:01:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:01:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:01:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:01:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:01:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:01:46 INFO - 'tests': ['damp', 'tps']}, 16:01:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:01:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:01:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:01:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:01:46 INFO - 'other-e10s': {'tests': ['a11yr', 16:01:46 INFO - 'ts_paint', 16:01:46 INFO - 'tpaint', 16:01:46 INFO - 'sessionrestore', 16:01:46 INFO - 'sessionrestore_many_windows', 16:01:46 INFO - 'sessionrestore_no_auto_restore', 16:01:46 INFO - 'tabpaint']}, 16:01:46 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:01:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:01:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:01:46 INFO - 'tsvgr_opacity', 16:01:46 INFO - 'tart', 16:01:46 INFO - 'tscrollx', 16:01:46 INFO - 'tsvg_static']}, 16:01:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:01:46 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:01:46 INFO - 'talos_options': ['--mitmproxy', 16:01:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:01:46 INFO - '--firstNonBlankPaint'], 16:01:46 INFO - 'tests': ['tp6_google', 16:01:46 INFO - 'tp6_youtube', 16:01:46 INFO - 'tp6_amazon', 16:01:46 INFO - 'tp6_facebook']}, 16:01:46 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:01:46 INFO - 'talos_options': ['--stylo', 16:01:46 INFO - '--mitmproxy', 16:01:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:01:46 INFO - '--firstNonBlankPaint'], 16:01:46 INFO - 'tests': ['tp6_google', 16:01:46 INFO - 'tp6_youtube', 16:01:46 INFO - 'tp6_amazon', 16:01:46 INFO - 'tp6_facebook']}, 16:01:46 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:01:46 INFO - 'talos_options': ['--stylo', 16:01:46 INFO - '--stylo-threads=1', 16:01:46 INFO - '--mitmproxy', 16:01:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:01:46 INFO - '--firstNonBlankPaint'], 16:01:46 INFO - 'tests': ['tp6_google', 16:01:46 INFO - 'tp6_youtube', 16:01:46 INFO - 'tp6_amazon', 16:01:46 INFO - 'tp6_facebook']}, 16:01:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:01:46 INFO - 'talos_options': ['--xperf_path', 16:01:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:01:46 INFO - 'tests': ['tp5n']}}} 16:01:46 INFO - Running post-action listener: _resource_record_post_action 16:01:46 INFO - [mozharness: 2017-08-08 23:01:46.972958Z] Finished populate-webroot step (success) 16:01:46 INFO - [mozharness: 2017-08-08 23:01:46.973048Z] Running create-virtualenv step. 16:01:46 INFO - Running pre-action listener: _resource_record_pre_action 16:01:46 INFO - Running main action method: create_virtualenv 16:01:46 INFO - Creating virtualenv /builds/slave/test/build/venv 16:01:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:01:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:01:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:01:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:01:47 INFO - Using real prefix '/usr' 16:01:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:01:47 INFO - Installing distribute.............................................................................................................................................................................................done. 16:01:49 INFO - Installing pip.................done. 16:01:49 INFO - Return code: 0 16:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:01:49 INFO - Reading from file tmpfile_stdout 16:01:49 INFO - Output received: 16:01:49 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:01:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:49 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 0x21b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2386a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2529d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526eb0>, '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 0x2527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2528240>, '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': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:01:49 INFO - Using env: {'DISPLAY': ':0', 16:01:49 INFO - 'HOME': '/home/cltbld', 16:01:49 INFO - 'LANG': 'en_US.UTF-8', 16:01:49 INFO - 'LANGUAGE': 'en_US:en', 16:01:49 INFO - 'LOGNAME': 'cltbld', 16:01:49 INFO - 'MAIL': '/var/mail/cltbld', 16:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:49 INFO - 'MOZ_NO_REMOTE': '1', 16:01:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:49 INFO - 'NO_EM_RESTART': '1', 16:01:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:49 INFO - 'PWD': '/builds/slave/test', 16:01:49 INFO - 'SHELL': '/bin/bash', 16:01:49 INFO - 'SHLVL': '1', 16:01:49 INFO - 'TERM': 'linux', 16:01:49 INFO - 'TMOUT': '86400', 16:01:49 INFO - 'USER': 'cltbld', 16:01:49 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 16:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:49 INFO - '_': '/tools/buildbot/bin/python'} 16:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:01:49 INFO - Cleaning up... 16:01:49 INFO - Return code: 0 16:01:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:49 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 0x21b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2386a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2529d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526eb0>, '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 0x2527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2528240>, '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': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:01:49 INFO - Using env: {'DISPLAY': ':0', 16:01:49 INFO - 'HOME': '/home/cltbld', 16:01:49 INFO - 'LANG': 'en_US.UTF-8', 16:01:49 INFO - 'LANGUAGE': 'en_US:en', 16:01:49 INFO - 'LOGNAME': 'cltbld', 16:01:49 INFO - 'MAIL': '/var/mail/cltbld', 16:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:49 INFO - 'MOZ_NO_REMOTE': '1', 16:01:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:49 INFO - 'NO_EM_RESTART': '1', 16:01:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:49 INFO - 'PWD': '/builds/slave/test', 16:01:49 INFO - 'SHELL': '/bin/bash', 16:01:49 INFO - 'SHLVL': '1', 16:01:50 INFO - 'TERM': 'linux', 16:01:50 INFO - 'TMOUT': '86400', 16:01:50 INFO - 'USER': 'cltbld', 16:01:50 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 16:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:50 INFO - '_': '/tools/buildbot/bin/python'} 16:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:50 INFO - Downloading/unpacking psutil>=3.1.1 16:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:01:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:51 INFO - Installing collected packages: psutil 16:01:51 INFO - Running setup.py install for psutil 16:01:51 INFO - building 'psutil._psutil_linux' extension 16:01:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:01:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:01:51 INFO - building 'psutil._psutil_posix' extension 16:01:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:01:51 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:01:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:01:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:01:51 INFO - Successfully installed psutil 16:01:51 INFO - Cleaning up... 16:01:51 INFO - Return code: 0 16:01:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2386a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2529d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526eb0>, '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 0x2527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2528240>, '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': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:01:51 INFO - Using env: {'DISPLAY': ':0', 16:01:51 INFO - 'HOME': '/home/cltbld', 16:01:51 INFO - 'LANG': 'en_US.UTF-8', 16:01:51 INFO - 'LANGUAGE': 'en_US:en', 16:01:51 INFO - 'LOGNAME': 'cltbld', 16:01:51 INFO - 'MAIL': '/var/mail/cltbld', 16:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:51 INFO - 'MOZ_NO_REMOTE': '1', 16:01:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:51 INFO - 'NO_EM_RESTART': '1', 16:01:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:51 INFO - 'PWD': '/builds/slave/test', 16:01:51 INFO - 'SHELL': '/bin/bash', 16:01:51 INFO - 'SHLVL': '1', 16:01:51 INFO - 'TERM': 'linux', 16:01:51 INFO - 'TMOUT': '86400', 16:01:51 INFO - 'USER': 'cltbld', 16:01:51 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 16:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:51 INFO - '_': '/tools/buildbot/bin/python'} 16:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:01:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:01:53 INFO - Installing collected packages: mozsystemmonitor 16:01:53 INFO - Running setup.py install for mozsystemmonitor 16:01:53 INFO - Successfully installed mozsystemmonitor 16:01:53 INFO - Cleaning up... 16:01:53 INFO - Return code: 0 16:01:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:01: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')]} 16:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01: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')]} 16:01:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2386a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2529d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526eb0>, '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 0x2527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2528240>, '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': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:01:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:01:53 INFO - Using env: {'DISPLAY': ':0', 16:01:53 INFO - 'HOME': '/home/cltbld', 16:01:53 INFO - 'LANG': 'en_US.UTF-8', 16:01:53 INFO - 'LANGUAGE': 'en_US:en', 16:01:53 INFO - 'LOGNAME': 'cltbld', 16:01:53 INFO - 'MAIL': '/var/mail/cltbld', 16:01:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:53 INFO - 'MOZ_NO_REMOTE': '1', 16:01:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:53 INFO - 'NO_EM_RESTART': '1', 16:01:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:53 INFO - 'PWD': '/builds/slave/test', 16:01:53 INFO - 'SHELL': '/bin/bash', 16:01:53 INFO - 'SHLVL': '1', 16:01:53 INFO - 'TERM': 'linux', 16:01:53 INFO - 'TMOUT': '86400', 16:01:53 INFO - 'USER': 'cltbld', 16:01:53 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 16:01:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:53 INFO - '_': '/tools/buildbot/bin/python'} 16:01:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:53 INFO - Downloading/unpacking jsonschema==2.5.1 16:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:01:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:54 INFO - Downloading functools32-3.2.3-2.tar.gz 16:01:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:01:54 INFO - warning: no files found matching '*.txt' 16:01:54 INFO - no previously-included directories found matching 'build' 16:01:54 INFO - no previously-included directories found matching 'dist' 16:01:54 INFO - no previously-included directories found matching '.git*' 16:01:54 INFO - Installing collected packages: jsonschema, functools32 16:01:55 INFO - Running setup.py install for functools32 16:01:55 INFO - warning: no files found matching '*.txt' 16:01:55 INFO - no previously-included directories found matching 'build' 16:01:55 INFO - no previously-included directories found matching 'dist' 16:01:55 INFO - no previously-included directories found matching '.git*' 16:01:55 INFO - Successfully installed jsonschema functools32 16:01:55 INFO - Cleaning up... 16:01:55 INFO - Return code: 0 16:01:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2386a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2529d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526eb0>, '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 0x2527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2528240>, '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': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:01:55 INFO - Using env: {'DISPLAY': ':0', 16:01:55 INFO - 'HOME': '/home/cltbld', 16:01:55 INFO - 'LANG': 'en_US.UTF-8', 16:01:55 INFO - 'LANGUAGE': 'en_US:en', 16:01:55 INFO - 'LOGNAME': 'cltbld', 16:01:55 INFO - 'MAIL': '/var/mail/cltbld', 16:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:55 INFO - 'MOZ_NO_REMOTE': '1', 16:01:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:55 INFO - 'NO_EM_RESTART': '1', 16:01:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:55 INFO - 'PWD': '/builds/slave/test', 16:01:55 INFO - 'SHELL': '/bin/bash', 16:01:55 INFO - 'SHLVL': '1', 16:01:55 INFO - 'TERM': 'linux', 16:01:55 INFO - 'TMOUT': '86400', 16:01:55 INFO - 'USER': 'cltbld', 16:01:55 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 16:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:55 INFO - '_': '/tools/buildbot/bin/python'} 16:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:01:55 INFO - Cleaning up... 16:01:55 INFO - Return code: 0 16:01:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x21b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2386a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2529d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526eb0>, '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 0x2527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2528240>, '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': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:01:55 INFO - Using env: {'DISPLAY': ':0', 16:01:55 INFO - 'HOME': '/home/cltbld', 16:01:55 INFO - 'LANG': 'en_US.UTF-8', 16:01:55 INFO - 'LANGUAGE': 'en_US:en', 16:01:55 INFO - 'LOGNAME': 'cltbld', 16:01:55 INFO - 'MAIL': '/var/mail/cltbld', 16:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:55 INFO - 'MOZ_NO_REMOTE': '1', 16:01:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:55 INFO - 'NO_EM_RESTART': '1', 16:01:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:55 INFO - 'PWD': '/builds/slave/test', 16:01:55 INFO - 'SHELL': '/bin/bash', 16:01:55 INFO - 'SHLVL': '1', 16:01:55 INFO - 'TERM': 'linux', 16:01:55 INFO - 'TMOUT': '86400', 16:01:55 INFO - 'USER': 'cltbld', 16:01:55 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 16:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:55 INFO - '_': '/tools/buildbot/bin/python'} 16:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:55 INFO - Downloading/unpacking blobuploader==1.2.4 16:01: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 16:01: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 16:01: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 16:01: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 16:01:56 INFO - Downloading blobuploader-1.2.4.tar.gz 16:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:01:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:01:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:01:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:01:57 INFO - Downloading docopt-0.6.1.tar.gz 16:01:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:01:57 INFO - Installing collected packages: blobuploader, requests, docopt 16:01:57 INFO - Running setup.py install for blobuploader 16:01:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:01:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:01:57 INFO - Running setup.py install for requests 16:01:57 INFO - Running setup.py install for docopt 16:01:57 INFO - Successfully installed blobuploader requests docopt 16:01:57 INFO - Cleaning up... 16:01:57 INFO - Return code: 0 16:01:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:01:57 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 0x21b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2386a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2529d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526eb0>, '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 0x2527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2528240>, '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': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:01:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:01:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:01:57 INFO - Using env: {'DISPLAY': ':0', 16:01:57 INFO - 'HOME': '/home/cltbld', 16:01:57 INFO - 'LANG': 'en_US.UTF-8', 16:01:57 INFO - 'LANGUAGE': 'en_US:en', 16:01:57 INFO - 'LOGNAME': 'cltbld', 16:01:57 INFO - 'MAIL': '/var/mail/cltbld', 16:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:57 INFO - 'MOZ_NO_REMOTE': '1', 16:01:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:57 INFO - 'NO_EM_RESTART': '1', 16:01:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:57 INFO - 'PWD': '/builds/slave/test', 16:01:57 INFO - 'SHELL': '/bin/bash', 16:01:57 INFO - 'SHLVL': '1', 16:01:57 INFO - 'TERM': 'linux', 16:01:57 INFO - 'TMOUT': '86400', 16:01:57 INFO - 'USER': 'cltbld', 16:01:57 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 16:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:57 INFO - '_': '/tools/buildbot/bin/python'} 16:01:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:01:58 INFO - Running setup.py (path:/tmp/pip-LrpAKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:01:58 INFO - Running setup.py (path:/tmp/pip-wytKli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:01:58 INFO - Running setup.py (path:/tmp/pip-AunDv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:01:58 INFO - Running setup.py (path:/tmp/pip-An0ryC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:01:58 INFO - Running setup.py (path:/tmp/pip-PQ00yY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:01:58 INFO - Running setup.py (path:/tmp/pip-aNw0PV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:01:58 INFO - Running setup.py (path:/tmp/pip-1G7opo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:01:58 INFO - Running setup.py (path:/tmp/pip-qJS41T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:01:58 INFO - Running setup.py (path:/tmp/pip-lRL_ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:01:58 INFO - Running setup.py (path:/tmp/pip-zQFU4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:01:58 INFO - Running setup.py (path:/tmp/pip-rTGI9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:01:58 INFO - Running setup.py (path:/tmp/pip-bCRo1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:01:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:01:58 INFO - Running setup.py (path:/tmp/pip-KCJNPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:01:59 INFO - Running setup.py (path:/tmp/pip-8xGxnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:59 INFO - Running setup.py (path:/tmp/pip-sEPllz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:01:59 INFO - Running setup.py (path:/tmp/pip-CbcjzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:01:59 INFO - Running setup.py (path:/tmp/pip-08l_Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:01:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:01:59 INFO - Running setup.py install for manifestparser 16:01:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for mozcrash 16:01:59 INFO - Running setup.py install for mozdebug 16:01:59 INFO - Running setup.py install for mozdevice 16:01:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for mozfile 16:01:59 INFO - Running setup.py install for mozhttpd 16:01:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:01:59 INFO - Running setup.py install for mozinfo 16:01:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Running setup.py install for mozInstall 16:02:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Running setup.py install for mozleak 16:02:00 INFO - Running setup.py install for mozlog 16:02:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Running setup.py install for moznetwork 16:02:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Running setup.py install for mozprocess 16:02:00 INFO - Running setup.py install for mozprofile 16:02:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Running setup.py install for mozrunner 16:02:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:02:00 INFO - Running setup.py install for mozscreenshot 16:02:00 INFO - Running setup.py install for moztest 16:02:00 INFO - Running setup.py install for mozversion 16:02:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:02:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:02:01 INFO - Cleaning up... 16:02:01 INFO - Return code: 0 16:02:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:01 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 0x21b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2386a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2529d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526eb0>, '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 0x2527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2528240>, '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': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:02:01 INFO - Using env: {'DISPLAY': ':0', 16:02:01 INFO - 'HOME': '/home/cltbld', 16:02:01 INFO - 'LANG': 'en_US.UTF-8', 16:02:01 INFO - 'LANGUAGE': 'en_US:en', 16:02:01 INFO - 'LOGNAME': 'cltbld', 16:02:01 INFO - 'MAIL': '/var/mail/cltbld', 16:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:01 INFO - 'MOZ_NO_REMOTE': '1', 16:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:01 INFO - 'NO_EM_RESTART': '1', 16:02:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:01 INFO - 'PWD': '/builds/slave/test', 16:02:01 INFO - 'SHELL': '/bin/bash', 16:02:01 INFO - 'SHLVL': '1', 16:02:01 INFO - 'TERM': 'linux', 16:02:01 INFO - 'TMOUT': '86400', 16:02:01 INFO - 'USER': 'cltbld', 16:02:01 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 16:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:01 INFO - '_': '/tools/buildbot/bin/python'} 16:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:01 INFO - Running setup.py (path:/tmp/pip-jeha5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:01 INFO - Running setup.py (path:/tmp/pip-8I2v7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:01 INFO - Running setup.py (path:/tmp/pip-ZOLxdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:01 INFO - Running setup.py (path:/tmp/pip-rvBAUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:01 INFO - Running setup.py (path:/tmp/pip-CgUi8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:01 INFO - Running setup.py (path:/tmp/pip-SpunvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:01 INFO - Running setup.py (path:/tmp/pip-K7NI_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:01 INFO - Running setup.py (path:/tmp/pip-W0F2Fi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:01 INFO - Running setup.py (path:/tmp/pip-fgvh03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:01 INFO - Running setup.py (path:/tmp/pip-0dGsbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:02 INFO - Running setup.py (path:/tmp/pip-3vWloi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:02 INFO - Running setup.py (path:/tmp/pip-cMHIgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:02 INFO - Running setup.py (path:/tmp/pip-69T4Gt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:02 INFO - Running setup.py (path:/tmp/pip-zJ667m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:02 INFO - Running setup.py (path:/tmp/pip-tp9woi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:02 INFO - Running setup.py (path:/tmp/pip-tnM9bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:02 INFO - Running setup.py (path:/tmp/pip-mdOkqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:02:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:03 INFO - Downloading blessings-1.6.tar.gz 16:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:02:03 INFO - Installing collected packages: blessings 16:02:03 INFO - Running setup.py install for blessings 16:02:03 INFO - Successfully installed blessings 16:02:03 INFO - Cleaning up... 16:02:03 INFO - Return code: 0 16:02:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:02:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:04 INFO - Reading from file tmpfile_stdout 16:02:04 INFO - Current package versions: 16:02:04 INFO - argparse == 1.2.1 16:02:04 INFO - blessings == 1.6 16:02:04 INFO - blobuploader == 1.2.4 16:02:04 INFO - docopt == 0.6.1 16:02:04 INFO - functools32 == 3.2.3-2 16:02:04 INFO - jsonschema == 2.5.1 16:02:04 INFO - manifestparser == 1.1 16:02:04 INFO - mozInstall == 1.13 16:02:04 INFO - mozcrash == 1.0 16:02:04 INFO - mozdebug == 0.1 16:02:04 INFO - mozdevice == 0.51 16:02:04 INFO - mozfile == 1.2 16:02:04 INFO - mozhttpd == 0.7 16:02:04 INFO - mozinfo == 0.10 16:02:04 INFO - mozleak == 0.1 16:02:04 INFO - mozlog == 3.5 16:02:04 INFO - moznetwork == 0.27 16:02:04 INFO - mozprocess == 0.25 16:02:04 INFO - mozprofile == 0.28 16:02:04 INFO - mozrunner == 6.13 16:02:04 INFO - mozscreenshot == 0.1 16:02:04 INFO - mozsystemmonitor == 0.3 16:02:04 INFO - moztest == 0.8 16:02:04 INFO - mozversion == 1.4 16:02:04 INFO - psutil == 3.1.1 16:02:04 INFO - requests == 1.2.3 16:02:04 INFO - wsgiref == 0.1.2 16:02:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:04 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 0x21b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2386a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2529d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526eb0>, '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 0x2527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2528240>, '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': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:02:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:02:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:02:04 INFO - Using env: {'DISPLAY': ':0', 16:02:04 INFO - 'HOME': '/home/cltbld', 16:02:04 INFO - 'LANG': 'en_US.UTF-8', 16:02:04 INFO - 'LANGUAGE': 'en_US:en', 16:02:04 INFO - 'LOGNAME': 'cltbld', 16:02:04 INFO - 'MAIL': '/var/mail/cltbld', 16:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:04 INFO - 'MOZ_NO_REMOTE': '1', 16:02:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:04 INFO - 'NO_EM_RESTART': '1', 16:02:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:04 INFO - 'PWD': '/builds/slave/test', 16:02:04 INFO - 'SHELL': '/bin/bash', 16:02:04 INFO - 'SHLVL': '1', 16:02:04 INFO - 'TERM': 'linux', 16:02:04 INFO - 'TMOUT': '86400', 16:02:04 INFO - 'USER': 'cltbld', 16:02:04 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 16:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:04 INFO - '_': '/tools/buildbot/bin/python'} 16:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:02:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:02:05 INFO - Installing collected packages: simplejson 16:02:05 INFO - Running setup.py install for simplejson 16:02:05 INFO - building 'simplejson._speedups' extension 16:02:05 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 16:02:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 16:02:06 INFO - Successfully installed simplejson 16:02:06 INFO - Cleaning up... 16:02:06 INFO - Return code: 0 16:02:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:06 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 0x21b91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2386a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2529d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2526eb0>, '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 0x2527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2528240>, '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': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:02:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:02:06 INFO - Using env: {'DISPLAY': ':0', 16:02:06 INFO - 'HOME': '/home/cltbld', 16:02:06 INFO - 'LANG': 'en_US.UTF-8', 16:02:06 INFO - 'LANGUAGE': 'en_US:en', 16:02:06 INFO - 'LOGNAME': 'cltbld', 16:02:06 INFO - 'MAIL': '/var/mail/cltbld', 16:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:06 INFO - 'MOZ_NO_REMOTE': '1', 16:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:06 INFO - 'NO_EM_RESTART': '1', 16:02:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:06 INFO - 'PWD': '/builds/slave/test', 16:02:06 INFO - 'SHELL': '/bin/bash', 16:02:06 INFO - 'SHLVL': '1', 16:02:06 INFO - 'TERM': 'linux', 16:02:06 INFO - 'TMOUT': '86400', 16:02:06 INFO - 'USER': 'cltbld', 16:02:06 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 16:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:06 INFO - '_': '/tools/buildbot/bin/python'} 16:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:02:06 INFO - Cleaning up... 16:02:06 INFO - Return code: 0 16:02:06 INFO - Running post-action listener: _resource_record_post_action 16:02:06 INFO - Running post-action listener: _start_resource_monitoring 16:02:06 INFO - Starting resource monitoring. 16:02:06 INFO - [mozharness: 2017-08-08 23:02:06.850165Z] Finished create-virtualenv step (success) 16:02:06 INFO - [mozharness: 2017-08-08 23:02:06.850681Z] Running install step. 16:02:06 INFO - Running pre-action listener: _resource_record_pre_action 16:02:06 INFO - Running main action method: install 16:02:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:07 INFO - Reading from file tmpfile_stdout 16:02:07 INFO - Detecting whether we're running mozinstall >=1.0... 16:02:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:02:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:02:07 INFO - Reading from file tmpfile_stdout 16:02:07 INFO - Output received: 16:02:07 INFO - Usage: mozinstall [options] installer 16:02:07 INFO - Options: 16:02:07 INFO - -h, --help show this help message and exit 16:02:07 INFO - -d DEST, --destination=DEST 16:02:07 INFO - Directory to install application into. [default: 16:02:07 INFO - "/builds/slave/test"] 16:02:07 INFO - --app=APP Application being installed. [default: firefox] 16:02:07 INFO - mkdir: /builds/slave/test/build/application 16:02:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:02:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:02:25 INFO - Reading from file tmpfile_stdout 16:02:25 INFO - Output received: 16:02:25 INFO - /builds/slave/test/build/application/firefox/firefox 16:02:25 INFO - Running post-action listener: _resource_record_post_action 16:02:25 INFO - [mozharness: 2017-08-08 23:02:25.200922Z] Finished install step (success) 16:02:25 INFO - [mozharness: 2017-08-08 23:02:25.201075Z] Running setup-mitmproxy step. 16:02:25 INFO - Running pre-action listener: _resource_record_pre_action 16:02:25 INFO - Running main action method: setup_mitmproxy 16:02:25 INFO - Skipping: mitmproxy is not required 16:02:25 INFO - Running post-action listener: _resource_record_post_action 16:02:25 INFO - [mozharness: 2017-08-08 23:02:25.202020Z] Finished setup-mitmproxy step (success) 16:02:25 INFO - [mozharness: 2017-08-08 23:02:25.202240Z] Running run-tests step. 16:02:25 INFO - Running pre-action listener: _resource_record_pre_action 16:02:25 INFO - Running pre-action listener: _set_gcov_prefix 16:02:25 INFO - Running main action method: run_tests 16:02:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:02:25 INFO - Python 2.7.3 16:02:25 INFO - Return code: 0 16:02:25 INFO - grabbing minidump binary from tooltool 16:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:25 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 0x2526eb0>, '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 0x2527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2528240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:02:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:02:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:02:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:02:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:02:25 INFO - Return code: 0 16:02:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:02:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:02:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:02:25 INFO - ENV: RUST_BACKTRACE is now 1 16:02:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:02:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:02:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-043 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:02:25 INFO - Using env: {'DISPLAY': ':0', 16:02:25 INFO - 'HOME': '/home/cltbld', 16:02:25 INFO - 'LANG': 'en_US.UTF-8', 16:02:25 INFO - 'LANGUAGE': 'en_US:en', 16:02:25 INFO - 'LOGNAME': 'cltbld', 16:02:25 INFO - 'MAIL': '/var/mail/cltbld', 16:02:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:02:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:25 INFO - 'MOZ_NO_REMOTE': '1', 16:02:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:02:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:25 INFO - 'NO_EM_RESTART': '1', 16:02:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:25 INFO - 'PWD': '/builds/slave/test', 16:02:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:02:25 INFO - 'RUST_BACKTRACE': '1', 16:02:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:02:25 INFO - 'SHELL': '/bin/bash', 16:02:25 INFO - 'SHLVL': '1', 16:02:25 INFO - 'TERM': 'linux', 16:02:25 INFO - 'TMOUT': '86400', 16:02:25 INFO - 'USER': 'cltbld', 16:02:25 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1502233274.640812-865943932', 16:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:25 INFO - '_': '/tools/buildbot/bin/python'} 16:02:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:02:25 INFO - mozversion application_buildid: 20170808221439 16:02:25 INFO - mozversion application_changeset: 1d042bcb2632ea6a38fa08dbe21a6e8a0ee46961 16:02:25 INFO - mozversion application_display_name: Nightly 16:02:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:02:25 INFO - mozversion application_name: Firefox 16:02:25 INFO - mozversion application_remotingname: firefox 16:02:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:02:25 INFO - mozversion application_vendor: Mozilla 16:02:25 INFO - mozversion application_version: 57.0a1 16:02:25 INFO - mozversion platform_buildid: 20170808221439 16:02:25 INFO - mozversion platform_changeset: 1d042bcb2632ea6a38fa08dbe21a6e8a0ee46961 16:02:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:02:25 INFO - mozversion platform_version: 57.0a1 16:02:25 INFO - using testdate: 1502233345 16:02:25 INFO - actual date: 1502233345 16:02:25 INFO - starting webserver on 'localhost:33764' 16:02:25 INFO - SUITE-START | Running 2 tests 16:02:25 INFO - TEST-START | dromaeo_css 16:02:25 INFO - Initialising browser for dromaeo_css test... 16:02:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33764/getInfo.html -profile /tmp/tmpM8BfNH/profile 16:02:25 INFO - TEST-INFO | started process 12362 (/builds/slave/test/build/application/firefox/firefox http://localhost:33764/getInfo.html) 16:02:31 INFO - TEST-INFO | 12362: exit 0 16:02:31 INFO - Browser initialized. 16:02:31 INFO - Running cycle 1/1 for dromaeo_css test... 16:02:31 INFO - TEST-INFO | started process 12575 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM8BfNH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:02:32 INFO - PID 12575 | 16:02:32 INFO - PID 12575 | (/builds/slave/test/build/application/firefox/firefox:12624): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:02:32 INFO - PID 12575 | 16:02:43 INFO - PID 12575 | RSS: Main: 168427520 16:02:43 INFO - PID 12575 | 16:05:42 INFO - PID 12575 | Cycle 1(1): loaded http://localhost:33764/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33764/tests/dromaeo/cssquery-ext.html) 16:05:43 INFO - PID 12575 | RSS: Main: 170921984 16:05:43 INFO - PID 12575 | 16:08:45 INFO - PID 12575 | Cycle 1(1): loaded http://localhost:33764/tests/dromaeo/cssquery-ext.html (next: http://localhost:33764/tests/dromaeo/cssquery-jquery.html) 16:08:46 INFO - PID 12575 | RSS: Main: 170405888 16:08:46 INFO - PID 12575 | 16:11:47 INFO - PID 12575 | Cycle 1(1): loaded http://localhost:33764/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33764/tests/dromaeo/cssquery-mootools.html) 16:11:48 INFO - PID 12575 | RSS: Main: 172584960 16:11:48 INFO - PID 12575 | 16:14:50 INFO - PID 12575 | Cycle 1(1): loaded http://localhost:33764/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33764/tests/dromaeo/cssquery-prototype.html) 16:14:50 INFO - PID 12575 | RSS: Main: 170754048 16:14:50 INFO - PID 12575 | 16:17:54 INFO - PID 12575 | Cycle 1(1): loaded http://localhost:33764/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33764/tests/dromaeo/cssquery-yui.html) 16:17:55 INFO - PID 12575 | RSS: Main: 174284800 16:17:55 INFO - PID 12575 | 16:20:57 INFO - PID 12575 | Cycle 1(1): loaded http://localhost:33764/tests/dromaeo/cssquery-yui.html (next: http://localhost:33764/tests/dromaeo/cssquery-dojo.html) 16:20:57 INFO - PID 12575 | RSS: Main: 175026176 16:20:57 INFO - PID 12575 | 16:20:57 INFO - PID 12575 | __start_tp_report 16:20:57 INFO - PID 12575 | _x_x_mozilla_page_load 16:20:57 INFO - PID 12575 | _x_x_mozilla_page_load_details 16:20:57 INFO - PID 12575 | |i|pagename|runs| 16:20:57 INFO - PID 12575 | |0;dojo.html;3151.2279491524564;3173.9365212695748;3181.2365032392227;3187.1713354527815;3234.5471633971247;6118;6226.159468471755;6289.088082228074;6330.588511746739;6355.650439225841;6133.049377346507;6224.346443623423;6233.345498722637;6249.750009999594;6261.436470717635;5690.658560486384;5700.059490184125;5711.314642242936;5713.485786279234;5787.768489260425;5732.140178973145;5738.225339579145;5803.361630220672;5806.680632565207;5826.271716035496;5586.525145362649;5687.03320435527;5688.146777983295;5808.3030036395485;5842.357340692521;5324.840254792362;5695.231143795576;5782.971085144547;5784.16129661201;5787.884242315172;5651.265335506399;5652.689102099383;5712.457316554921;5730.968425683375;5746.712664366765;6100.96949515254;6134.69326533676;6203.038529028008;6217.316095229521;6247.562670613059;6714.925611902072;6721.428678562328;6737.056812046318;6781.525293229476;6811.489138314646;6748.797536073926;6771.424428923548;6774.729010839561;6879.105716256905;6884.414824739903;6178.907316390258;6229.3459186785185;6261.78083767066;6267.091271765568;6306.621602703806;6913.5506192097355;6917.169939607279;6923.965380173167;6924.515283930177;6926.272741362185;6513.641749703717;6579.144711187515;6580.638064906476;6590.736370545124;6606.042123892009;6545.116409284781;6575.145231119924;6600.80197594073;6610.537262391586;6711.563748356341;6605.471562275006;6614.966925165343;6716.026176204521;6736.5958042517605;6756.2568117507035;6723.193216813916;6774.491913106537;6777.136287520972;6802.489813263926;6833.077534532874;5399.244105825188;5426.131818908955;5490.066688662857;5521.889562208733;5559.610827242054;4552.931706024412;4673.854905548163;4711.151992641289;4711.7879695413785;4720.221163507983;5793.478586927196;5837.97835378807;5855.736491857877;5871.324797648325;5882.441168089024;6760.898586521206;6773.187217533928;6864;6869.553479023847;6879.58722476653;5767.8558036049435;5806.245188125601;5861.7655293787775;5867.471927526543;5888.499477544457;5875.9118613220835;5932.377100404482;5952.375000624958;5962.3739507351975;5991.700414979234;6507.511936604774;6514.05546195799;6593.472522198213;6604.14146160996;6608.074869518271;6691.966540167268;6843.144606924135;6850.040994260907;6863.142107236596;6869.072675188985;6513.641749703812;6517.055027021151;6588.868222635616;6604.339566043357;6621.27166011748;6470.223573171062;6553.475721942138;6642.966785166043;6732.057511948429;6746.123004009447;6760.121184246016;6767.154105736783;6770.356816102462;6775.898361524482;6778.694958726771;6548.770793022221;6549.70526326326;6598.90101648466;6617.735290588323;6620.668966551557;6576.473882089325;6588;6608.867822643424;6626.072349871119;6651.235107962646;6477.384648458389;6485.189351331083;6587.835304117244;6591;6610.338966103352;6563.803085907431;6602.702878370389;6694.59832410057;6703.698333575098;6707.798766037026;6296.401841825019;6370.426661600478;6395.5203359747275;6400.295967443671;6486.772962946463 16:20:57 INFO - PID 12575 | |1;ext.html;851.2508992086963;870.4255191573561;893.5085702863423;895;1049.118740258183;31871.840640796792;44141.558584414146;45289.77355113224;45313.77343113288;45349.09301813962;45417;45826;45830.77084614572;45839.31241031387;45882;7805.141434442221;7999;8015.7194498192575;8032.3172530335;8042.396820238476;7282.235365286648;7902.486338388015;7979.960100199491;7981.28168464838;8024.398170137234;7906.6442010109395;7949.244821741925;7956.363490920741;7962.522248665098;7970.561619110947;7879.212078792132;7880.0937892142565;7916.287534121928;7928.563928983904;7933.841323173533;7847.489913155627;7904.288614024766;7906.486078404885;7906.841863162705;7909.327707144899;40044.79977600122;40315.395269070985;40325.99185020369;40326.798366008275;40338.798306008284;3518.2787528556582;3569.696575791035;3574.803385813779;3590.5691317042124;3600.3879340512176;32794.18014549631;32821;32978.505322420184;33010.504842427385;33042.17394565131;5137.820176293849;5143.459936706629;5160.354955630546;5170.353705786777;5172.172452407597;188124;188128;188740.056299719;188991;189202;51590.74204628953;51622.48377516177;51635.48364516382;51746;51763.48236517662;53565.732171338896;53783.46216537863;53833.46166538284;53840.46159538433;53871.19193212105;16007.359705611645;16350.182490875408;16355.264013119438;16386.770992175636;16499.17504124789;13202.405891734896;13289.800652990212;13290.800637990438;13305.800412993813;13314.667133321744;13100.934495327463;13364.465421383036;13526.256055916821;14806.703865922622;15141.924290378698;5960.940390596039;6016.548758843104;6086.6348019119;6097.207363042776;6109.877802443926;1003.7540802503287;1006.4464544500496;1010.6816352848829;1018.5162048027275;1019.5666841592293;23080.884595576914;29614;30434.84782576073;30449.391012179633;30628.69371306303;1079.9298045627008;1086.9184811139196;1094.042712626452;1098.9670309890716;1102.3606308341143;12509.311987840574;12651.0511711622;12686.682832929066;12774;12832.230066196058;240164;243254;243368;243778;243902;56626;56725.71637141788;56764;56898;57081.714591426775;1452.5860129862938;1514.6894886548232;1514.9772753408708;1515.901466404658;1520.536236447872;23167.768322316562;23179.188728394427;23251.534969300857;23409;23619.527609448058;39405.605943941344;39504;39540.40689389719;39544.2091158181;39673.40489892596;212397;212547;214729;215065;215185;36027;36092.63907360893;36136.81931590325;36136.81931590325;38900.80549597234;49255.261171081744;49550.50449495603;49608.255876162606;50234.24648630201;50474;34800.30399392048;35434;36000.27999440049;36181;36203.09492262715;35155.64844351524;35222.29555408929;35385.82307088551;35462.82268588744;35521.644783551834;38558.6144138555;38738;38789;38815;39036.219275614894;34034.8298258517;34143.4878476828;34161;34588.48117278293;35159;23795.524089518458;24927.626085608368;25206.49587008213;25445.10942117017;25642.743572564035 16:20:57 INFO - PID 12575 | |2;jquery.html;576.1990832742486;586.1998372221913;594.6818452128113;600.9008513595257;612.8008397270887;21972.230971915986;22686.54626907461;22832.65751013736;22939.42651433715;23042.884785576047;20985.475363115893;23118.884405577992;23686.170984015564;26539.601905971427;26666.733332666667;6591.93408065918;6737.3262673732725;6740.123783908085;6744.190697116353;6754.459643228554;5955.583109182359;6486.935130648704;6526.216853977529;6656.267810540837;6660.267370589256;6409.070684750708;6436.034594810769;6438;6442.16251887254;6445.8710825783455;6426.871462570746;6438.323975982501;6442.903356449657;6443.871122577546;6446.871062578723;6011.218541589609;6022.789202377896;6023.789167379165;6031.698415079229;6053.606515576474;28865.42269154626;28988.855055724587;29446.263843404085;29549.26126846846;30243;1810.7374430707473;1824.324999750101;1852.3701941339875;1852.4257480181186;1853.8146185381488;19144.712829307573;19210.423687289403;19615.313464028834;20289.39131826033;20298.086586103513;2440.4386990991993;2466;2467.7778999889915;2472.554940110779;2531.0255551612577;118915;122817;134830;139833;144853.99999999895;14967.640362109047;15092.471763488446;15410.691786164438;15438;15574.844251557566;24180.153694620603;24195.879020604785;24226.63660045101;24304.635430468556;24317.878410607835;8596.667516534948;8680.088590698013;8686.435381700168;8726;8744.038155802857;7485.363744081705;7493.100827900686;7622.2758837910305;7625.313721765067;7630.8092297693;9125;9215.53922303882;9331.626734930662;9429.481378524248;9746.658866939622;2770.556710926242;2783.4015686627067;2787.1777825541512;2817.7745780337523;2871.9425611487654;929.7257309093834;936.7423958411491;958.6836344006462;964.8311545479513;969.3892847506168;18910.62178756417;19496.805031949498;19697.212111515666;19845.404637860887;19979.700304495444;1006.3810756384771;1021.6373187518473;1033.2302434686208;1041.1202533858877;1043.1654676259113;10644.361338319726;10649.574017039231;10876.29304095247;10928.562857485771;11007.339559626447;481397;489396;496001;499312;507463;123246;124591.37704311602;125063.374683126;125978.37010814887;128273.35863320624;1082.8538147350007;1128.8250321200228;1186.4364426897155;1211.4003568233782;1248.0826592454357;21042;21391.25130620421;21442.356729298146;21530;21649.242276520246;56643.71678141583;58031.70984145052;58080.12879806891;58665;59019.40980590139;73066;73999.6300018518;76701;77238.22761772311;77877;14863.40546378176;15255.694886101994;15414.614634634223;15437.0737755735;15540.523650253208;23870.16454424087;23995.640065398682;24024;24297.878510607334;24496.265112046665;18565.535861603028;18750;19226.903865480584;19350;19357.322493713214;17425.912870435568;17468.650626987645;17540.122993850513;17833.286668533114;17911.73132403041;18316.175772090555;19456.319028833925;19486.512837179183;19601.70597441068;19663.60672786565;15075.54773356801;15224.162671052756;15254.923725381303;15263;15298.61753456128;18482.907585461988;18898.811011889706;19112.23551057942;19190.424287271406;19235.711464328328 16:20:57 INFO - PID 12575 | |3;mootools.html;1354.397293204524;1406.563965170797;1412.9364178611975;1413.3145424469137;1423.3737155651513;13580.456781728732;13964.511242106528;14047;14144.504942327008;14161.929190354034;13881.375338109785;14035.36840842162;14136.151830890129;14193.71612567748;14206.218657973808;2619.6070589411647;2662.7071022187542;2664.5203863304596;2673.9598906016427;2674.745899139579;2572.061197662849;2628.0933078088124;2646.9470610587778;2654.707982121965;2663.9200823975216;2582.819202655815;2610.5692560727457;2616.6205900144564;2642.2733748219202;2644.8942042318286;2549.4773571418;2583.689957205138;2603.4532748122915;2604.7916166706714;2604.8437093774432;2405.20628192696;2409.4578719787905;2430.4166999920067;2434.196715084018;2440.389902524369;13718.176909385369;14009.929950350182;14032.719345613134;14042;14052.508162214273;7457.291557302047;7690.346320562759;7709.730159444449;7717.421193410517;7726.420518461138;4852.344933433977;4899.534544218293;4989.426215985171;5066.873328166826;5072.061668591323;1574.645704716441;1676.228934690044;1689.8225686303008;1722.9913850430794;1727.2141175764996;21547.1381144754;21757.67363489549;22845;23867.64198537006;23900;6379.234491861006;6534.379233972765;6542.509311801634;6661.700223489955;6738.147009572309;15822.129782861823;15833.52499425019;15845.207739613204;15886.443974460839;15969.281382337591;7496.775096746997;7614.238576142341;7646.464747467624;7648.541087534766;7678.424118191158;6985.764555342344;7014.964925175444;7069.222385537587;7081.645917704094;7093.89359159613;4917.926231106536;5004.924926126111;5033.219850923113;5071.847844564669;5112.284280200774;1417;1433.806436131109;1439.3235179465635;1452.9128252304895;1465.157534417714;851.4040171879585;859.9570021499026;871.7733389318822;875.2079368171815;885.9557022148996;6768.966155169291;6779;6803.217629972518;6833.760818371333;6883.3116688330765;666.2804171494774;682.846359569093;686.3822559696313;686.50228584276;696.1089805049545;4437.489688685778;4653.464851542116;4722.881927951828;4837.201861692782;4892.363992680928;18360;19221.711674324895;19275.22899084049;19307.683078030725;19315.710264346046;18379.90810045968;18876.339328123726;19213.519662008282;19331.130099145572;19348.80651193498;8996.685116020908;9324.533773311307;10182.745431364126;10707.62523311696;10835.295705779099;7806.531608103532;7956.801079973047;7967.2431119043595;8033.397495187767;8059.717909873127;16870.15649217559;17011.234494447534;17018.404355847983;17038.48884533466;17071.743923840902;17209.827901721324;17311.48065558035;17318.307267709653;17535.298588056336;17712.291508339524;10851.945740271249;10943.507542160782;11047.171462140212;11073.280236784583;11076.169287303323;12325.938370308091;12435.253884766942;12489.750204995667;12492.25046497213;12492.625221243377;9330.720078397659;9405.670801522188;9458.810823783624;9613.615455381987;9692.70921872345;9572.282078843975;9666.275029372684;9696.272779541421;9850.704478865646;9888.258380621626;7666;7705.190954949874;7788.571628560484;7794.259545343183;7808.258215469521;6572.8356791080605;6578.76974305897;6582.1443212384;6638.767643132467;6708.798736037927;7162.740578885796;7372.410207183305;7389.371903388273;7461.515001524884;7553.395728341829 16:20:57 INFO - PID 12575 | |4;prototype.html;502.4447984976601;507.9669821461604;529.3568314497884;532.1166863007408;536.849682089015;6285.434310912017;6370.4903607711385;6373.470367111894;6384.616922984618;6395.680215989205;5940.584159108864;6098.481629061524;6123.11214873843;6150.630962142274;6152.784652537162;4910.877228069309;4922.827701030465;4931.901361972758;4933.901321973576;4987.177115775893;4845.200541910581;4905.75471226439;4916.729579873106;4945.950540494603;5001.974990125044;4905.018996200756;4928.876778080541;4958.512446266123;4992.725400102993;4999.575036121916;4899.363082799231;4937.654364194508;4944.184209605429;4965.205567109281;4980.800767969277;4494.10117976406;4495.46054473462;4503.48209955856;4520.412346394981;4540.910181556403;6137.9079313810325;6150.600210986271;6158.753649854;6165.784197553064;6182.721777520022;5775.537956963432;5790.276215473066;5802.622829516068;5805.970970145165;5825.621334613236;6621.635810030446;6644.169478815148;6655.633940133291;6676.532642715012;6686.665666716644;4866.172750632401;4869.9269510957365;4892.9510704892855;4902.632302577321;4907.533784290523;6810.965945170243;6941.201761797407;6982.650867456607;7044.788656340318;7052.153741551047;4992.276119962585;5000.524950129732;5022.096022715873;5047.974760126176;5072.5688316493515;5112.590992720573;5114.923276150861;5117.769700363418;5120.7951681933055;5122.180451127876;5727.140928860705;5744.971275143681;5770.2498675171955;5802.274715660543;5810.593258471867;6567.474602031826;6638.933610663928;6678.365555272241;6683.899741503881;6711.832204194837;1697.8047524534836;1713.994626287247;1718.1065845760384;1736.26208861236;1785.6875046866746;1152.7809716153904;1213.490334059697;1216.1304667163022;1222.620987493862;1232.7657745028416;5733.971330143323;5735.426457354231;5778.7688492460675;5809.767609295581;5819.301683797971;6507.544471887017;6566.606003639797;6587.37419945114;6600.108985287021;6660.60036397808;5772.9217481706555;5773.88452230953;5841.912371314434;5860.267466566679;5875.529957603381;3538.256966037161;3590.479380489867;3597.9100522487147;3625.075605720577;3625.220577575781;6518.445932095825;6532.771353002717;6580.901286480706;6582.506312026617;6604;6901.102856628606;6978.5812851228075;6981.825454363681;6998.93001069993;7025.789226323116;6457.580257283262;6484.319146489645;6538.738450461929;6553.246376666649;6583.670816459254;6772.627505487246;6816.965915170393;6819.624920629413;6824.225701317594;6852;6614.272430032789;6672.599644021374;6701.798946031627;6740.708159225691;6798.898016529855;6439.710213040331;6485.675716214265;6506.707198175999;6550.213974323112;6588.670566471561;4976.511744127936;4990.226514890198;5039.412585035849;5047.9747601263225;5078.162103252922;6805.264657512303;6842.418394436529;6854.931450685629;6894.13823272091;6911.956505444371;6627.436667883284;6633.436157926631;6646.335366463315;6664.633445160369;6672.299408562225;6483.4164925156965;6531.477481801539;6569.638669873012;6577.276499584946;6613.834654133685;6024.162630397771;6136.171616831671;6437.903431448625;6472.935270647234;6496.577722448026;6453.419192272718;6480.787488132752;6527.836804079936;6601.504887133388;6633.170853643294;6321.96839015802;6327.367263273636;6338.936610633835;6419.614823110628;6421.179398258029 16:20:57 INFO - PID 12575 | |5;yui.html;905.6875377994592;928.8142371525693;937.8874535055794;938.9530523473827;940.3934462271835;18017.189226484807;19037.904810475946;19131.713024304645;19202.903985480087;22045.89770511476;21117.366479005617;21752.89123554384;21926.45183870404;22017.66973495399;22113.226037088705;964.28642804325;993.3146129170849;995.0994350113123;996.6312464388186;1000.9840012387433;1018.3482571154467;1074.2426589254596;1076.962306319279;1081.5187241677456;1084.6366467233447;1116.8380584815197;1126.7239526316064;1127.284174549163;1127.8139107047327;1128.841962125303;1114.4093630375914;1116.4808364110686;1119.6137332620233;1120.506976930148;1121.075113031748;809.2191035650626;812.1147948735847;812.3825892321819;812.7317985064974;813.7070654564411;17523.82476175235;17815.643687126314;17842.821571784247;17870;19483.025848707508;21404.78595214059;21682.56634867294;21884.562308753735;21934;22073.227437039786;15323.61690957735;15340;15485.767713484307;15488.690226195526;15496.147711875843;3630.310241054218;3668.1746607013474;3668.559772827251;3675.7794532328144;3678.356287649677;124022;126007;127512;129300.7069929298;129685;16881.577960551083;17231.913840430967;17329.56676083083;17363.739543906853;17419.825801742074;31494.842525787226;31605.367892642018;31759;32371.514427283608;32398.19004524906;7749.535027898344;7853.48952318109;8129.75610731679;8685.088065753005;9064.592093355815;4045.3122969095325;4110.219058378958;4111.568285330057;4120.958790412057;4133.503979522417;5296.417394086648;5309.628326017141;5384.326959130109;5390.921815636888;5428.321459817523;1061.0291583201285;1071.4374953149659;1083.539495714334;1086.1202426034938;1092.639428988448;691.1671435919704;709.7019251914209;713.8893471511924;723.1358526560709;737.3179808676953;11878.703032424259;12278.631641050782;12616.306103164414;13648.181109133484;13818.855299318931;705.4744215559339;724.1708244060521;724.3263764698776;728.5009768308726;729.7190581626112;5649.6327738696855;5745.2818397700285;5774.797882074107;5831.79588714393;5920.644761314335;101794;102193.48903255437;103775;104122;106975;89747;89928.55035724779;90149;94237.52881235688;94686.52656736672;75159.62420187973;76142.61928690321;76616.23383766203;78249.60875195588;79740.60129699315;11288.548458061585;11346.94326528362;11397.259178153394;11474.541018359172;11663.008644265332;36024.27951441009;36945;38173.80913095528;38480.80759596184;38965.805170975094;44908;45244.77377613091;45591;46061.76969115133;46727.766361167975;15080;16220.756688649444;16579.088150151856;16736.33054677848;16871.156442178086;19411.611767764847;21018.47453813667;21034.368968930958;21278.78721212768;21421.892890535448;18829.811701882805;18916.243350265835;19108.140133694294;19178.80821191826;19242.615147696688;19069.427917162506;19316.517087072934;19358.709619356003;19360.515987099872;19360.70958935589;18373.908130459262;18447.72328415048;18542.721859172394;18619.720704189178;18669;13007.52856957175;13239.139455935334;13420.194788312732;13538.72922541524;13579.321033948065;14457.27713614294;14502.274886255434;14506.274686265855;14713.117212967256;14727.92636036813 16:20:57 INFO - PID 12575 | __end_tp_report 16:20:57 INFO - PID 12575 | __start_cc_report 16:20:57 INFO - PID 12575 | _x_x_mozilla_cycle_collect,386 16:20:57 INFO - PID 12575 | __end_cc_report 16:20:57 INFO - PID 12575 | __startTimestamp1502234457308__endTimestamp 16:20:57 INFO - PID 12575 | 16:20:57 INFO - PID 12575 | ------- Summary: start ------- 16:20:57 INFO - PID 12575 | Number of tests: 6 16:20:57 INFO - PID 12575 | 16:20:57 INFO - PID 12575 | [#0] dojo.html Cycles:165 Average:6199.88 Median:6486.77 stddev:738.16 (11.4%) stddev-sans-first:700.61 16:20:57 INFO - PID 12575 | Values: 3151.2 3173.9 3181.2 3187.2 3234.5 6118.0 6226.2 6289.1 6330.6 6355.7 6133.0 6224.3 6233.3 6249.8 6261.4 5690.7 5700.1 5711.3 5713.5 5787.8 5732.1 5738.2 5803.4 5806.7 5826.3 5586.5 5687.0 5688.1 5808.3 5842.4 5324.8 5695.2 5783.0 5784.2 5787.9 5651.3 5652.7 5712.5 5731.0 5746.7 6101.0 6134.7 6203.0 6217.3 6247.6 6714.9 6721.4 6737.1 6781.5 6811.5 6748.8 6771.4 6774.7 6879.1 6884.4 6178.9 6229.3 6261.8 6267.1 6306.6 6913.6 6917.2 6924.0 6924.5 6926.3 6513.6 6579.1 6580.6 6590.7 6606.0 6545.1 6575.1 6600.8 6610.5 6711.6 6605.5 6615.0 6716.0 6736.6 6756.3 6723.2 6774.5 6777.1 6802.5 6833.1 5399.2 5426.1 5490.1 5521.9 5559.6 4552.9 4673.9 4711.2 4711.8 4720.2 5793.5 5838.0 5855.7 5871.3 5882.4 6760.9 6773.2 6864.0 6869.6 6879.6 5767.9 5806.2 5861.8 5867.5 5888.5 5875.9 5932.4 5952.4 5962.4 5991.7 6507.5 6514.1 6593.5 6604.1 6608.1 6692.0 6843.1 6850.0 6863.1 6869.1 6513.6 6517.1 6588.9 6604.3 6621.3 6470.2 6553.5 6643.0 6732.1 6746.1 6760.1 6767.2 6770.4 6775.9 6778.7 6548.8 6549.7 6598.9 6617.7 6620.7 6576.5 6588.0 6608.9 6626.1 6651.2 6477.4 6485.2 6587.8 6591.0 6610.3 6563.8 6602.7 6694.6 6703.7 6707.8 6296.4 6370.4 6395.5 6400.3 6486.8 16:20:57 INFO - PID 12575 | 16:20:57 INFO - PID 12575 | [#1] ext.html Cycles:180 Average:39770.75 Median:36059.82 stddev:56097.15 (155.6%) stddev-sans-first:56178.40 16:20:57 INFO - PID 12575 | Values: 851.3 870.4 893.5 895.0 1049.1 31871.8 44141.6 45289.8 45313.8 45349.1 45417.0 45826.0 45830.8 45839.3 45882.0 7805.1 7999.0 8015.7 8032.3 8042.4 7282.2 7902.5 7980.0 7981.3 8024.4 7906.6 7949.2 7956.4 7962.5 7970.6 7879.2 7880.1 7916.3 7928.6 7933.8 7847.5 7904.3 7906.5 7906.8 7909.3 40044.8 40315.4 40326.0 40326.8 40338.8 3518.3 3569.7 3574.8 3590.6 3600.4 32794.2 32821.0 32978.5 33010.5 33042.2 5137.8 5143.5 5160.4 5170.4 5172.2 188124.0 188128.0 188740.1 188991.0 189202.0 51590.7 51622.5 51635.5 51746.0 51763.5 53565.7 53783.5 53833.5 53840.5 53871.2 16007.4 16350.2 16355.3 16386.8 16499.2 13202.4 13289.8 13290.8 13305.8 13314.7 13100.9 13364.5 13526.3 14806.7 15141.9 5960.9 6016.5 6086.6 6097.2 6109.9 1003.8 1006.4 1010.7 1018.5 1019.6 23080.9 29614.0 30434.8 30449.4 30628.7 1079.9 1086.9 1094.0 1099.0 1102.4 12509.3 12651.1 12686.7 12774.0 12832.2 240164.0 243254.0 243368.0 243778.0 243902.0 56626.0 56725.7 56764.0 56898.0 57081.7 1452.6 1514.7 1515.0 1515.9 1520.5 23167.8 23179.2 23251.5 23409.0 23619.5 39405.6 39504.0 39540.4 39544.2 39673.4 212397.0 212547.0 214729.0 215065.0 215185.0 36027.0 36092.6 36136.8 36136.8 38900.8 49255.3 49550.5 49608.3 50234.2 50474.0 34800.3 35434.0 36000.3 36181.0 36203.1 35155.6 35222.3 35385.8 35462.8 35521.6 38558.6 38738.0 38789.0 38815.0 39036.2 34034.8 34143.5 34161.0 34588.5 35159.0 23795.5 24927.6 25206.5 25445.1 25642.7 16:20:57 INFO - PID 12575 | 16:20:57 INFO - PID 12575 | [#2] jquery.html Cycles:180 Average:35329.11 Median:24102.08 stddev:83461.39 (346.3%) stddev-sans-first:83654.74 16:20:57 INFO - PID 12575 | Values: 576.2 586.2 594.7 600.9 612.8 21972.2 22686.5 22832.7 22939.4 23042.9 20985.5 23118.9 23686.2 26539.6 26666.7 6591.9 6737.3 6740.1 6744.2 6754.5 5955.6 6486.9 6526.2 6656.3 6660.3 6409.1 6436.0 6438.0 6442.2 6445.9 6426.9 6438.3 6442.9 6443.9 6446.9 6011.2 6022.8 6023.8 6031.7 6053.6 28865.4 28988.9 29446.3 29549.3 30243.0 1810.7 1824.3 1852.4 1852.4 1853.8 19144.7 19210.4 19615.3 20289.4 20298.1 2440.4 2466.0 2467.8 2472.6 2531.0 118915.0 122817.0 134830.0 139833.0 144854.0 14967.6 15092.5 15410.7 15438.0 15574.8 24180.2 24195.9 24226.6 24304.6 24317.9 8596.7 8680.1 8686.4 8726.0 8744.0 7485.4 7493.1 7622.3 7625.3 7630.8 9125.0 9215.5 9331.6 9429.5 9746.7 2770.6 2783.4 2787.2 2817.8 2871.9 929.7 936.7 958.7 964.8 969.4 18910.6 19496.8 19697.2 19845.4 19979.7 1006.4 1021.6 1033.2 1041.1 1043.2 10644.4 10649.6 10876.3 10928.6 11007.3 481397.0 489396.0 496001.0 499312.0 507463.0 123246.0 124591.4 125063.4 125978.4 128273.4 1082.9 1128.8 1186.4 1211.4 1248.1 21042.0 21391.3 21442.4 21530.0 21649.2 56643.7 58031.7 58080.1 58665.0 59019.4 73066.0 73999.6 76701.0 77238.2 77877.0 14863.4 15255.7 15414.6 15437.1 15540.5 23870.2 23995.6 24024.0 24297.9 24496.3 18565.5 18750.0 19226.9 19350.0 19357.3 17425.9 17468.7 17540.1 17833.3 17911.7 18316.2 19456.3 19486.5 19601.7 19663.6 15075.5 15224.2 15254.9 15263.0 15298.6 18482.9 18898.8 19112.2 19190.4 19235.7 16:20:57 INFO - PID 12575 | 16:20:57 INFO - PID 12575 | [#3] mootools.html Cycles:180 Average:8474.34 Median:2645.92 stddev:5906.35 (223.2%) stddev-sans-first:5898.69 16:20:57 INFO - PID 12575 | Values: 1354.4 1406.6 1412.9 1413.3 1423.4 13580.5 13964.5 14047.0 14144.5 14161.9 13881.4 14035.4 14136.2 14193.7 14206.2 2619.6 2662.7 2664.5 2674.0 2674.7 2572.1 2628.1 2646.9 2654.7 2663.9 2582.8 2610.6 2616.6 2642.3 2644.9 2549.5 2583.7 2603.5 2604.8 2604.8 2405.2 2409.5 2430.4 2434.2 2440.4 13718.2 14009.9 14032.7 14042.0 14052.5 7457.3 7690.3 7709.7 7717.4 7726.4 4852.3 4899.5 4989.4 5066.9 5072.1 1574.6 1676.2 1689.8 1723.0 1727.2 21547.1 21757.7 22845.0 23867.6 23900.0 6379.2 6534.4 6542.5 6661.7 6738.1 15822.1 15833.5 15845.2 15886.4 15969.3 7496.8 7614.2 7646.5 7648.5 7678.4 6985.8 7015.0 7069.2 7081.6 7093.9 4917.9 5004.9 5033.2 5071.8 5112.3 1417.0 1433.8 1439.3 1452.9 1465.2 851.4 860.0 871.8 875.2 886.0 6769.0 6779.0 6803.2 6833.8 6883.3 666.3 682.8 686.4 686.5 696.1 4437.5 4653.5 4722.9 4837.2 4892.4 18360.0 19221.7 19275.2 19307.7 19315.7 18379.9 18876.3 19213.5 19331.1 19348.8 8996.7 9324.5 10182.7 10707.6 10835.3 7806.5 7956.8 7967.2 8033.4 8059.7 16870.2 17011.2 17018.4 17038.5 17071.7 17209.8 17311.5 17318.3 17535.3 17712.3 10851.9 10943.5 11047.2 11073.3 11076.2 12325.9 12435.3 12489.8 12492.3 12492.6 9330.7 9405.7 9458.8 9613.6 9692.7 9572.3 9666.3 9696.3 9850.7 9888.3 7666.0 7705.2 7788.6 7794.3 7808.3 6572.8 6578.8 6582.1 6638.8 6708.8 7162.7 7372.4 7389.4 7461.5 7553.4 16:20:57 INFO - PID 12575 | 16:20:57 INFO - PID 12575 | [#4] prototype.html Cycles:180 Average:5539.53 Median:6144.25 stddev:1563.54 (25.4%) stddev-sans-first:1521.53 16:20:57 INFO - PID 12575 | Values: 502.4 508.0 529.4 532.1 536.8 6285.4 6370.5 6373.5 6384.6 6395.7 5940.6 6098.5 6123.1 6150.6 6152.8 4910.9 4922.8 4931.9 4933.9 4987.2 4845.2 4905.8 4916.7 4946.0 5002.0 4905.0 4928.9 4958.5 4992.7 4999.6 4899.4 4937.7 4944.2 4965.2 4980.8 4494.1 4495.5 4503.5 4520.4 4540.9 6137.9 6150.6 6158.8 6165.8 6182.7 5775.5 5790.3 5802.6 5806.0 5825.6 6621.6 6644.2 6655.6 6676.5 6686.7 4866.2 4869.9 4893.0 4902.6 4907.5 6811.0 6941.2 6982.7 7044.8 7052.2 4992.3 5000.5 5022.1 5048.0 5072.6 5112.6 5114.9 5117.8 5120.8 5122.2 5727.1 5745.0 5770.2 5802.3 5810.6 6567.5 6638.9 6678.4 6683.9 6711.8 1697.8 1714.0 1718.1 1736.3 1785.7 1152.8 1213.5 1216.1 1222.6 1232.8 5734.0 5735.4 5778.8 5809.8 5819.3 6507.5 6566.6 6587.4 6600.1 6660.6 5772.9 5773.9 5841.9 5860.3 5875.5 3538.3 3590.5 3597.9 3625.1 3625.2 6518.4 6532.8 6580.9 6582.5 6604.0 6901.1 6978.6 6981.8 6998.9 7025.8 6457.6 6484.3 6538.7 6553.2 6583.7 6772.6 6817.0 6819.6 6824.2 6852.0 6614.3 6672.6 6701.8 6740.7 6798.9 6439.7 6485.7 6506.7 6550.2 6588.7 4976.5 4990.2 5039.4 5048.0 5078.2 6805.3 6842.4 6854.9 6894.1 6912.0 6627.4 6633.4 6646.3 6664.6 6672.3 6483.4 6531.5 6569.6 6577.3 6613.8 6024.2 6136.2 6437.9 6472.9 6496.6 6453.4 6480.8 6527.8 6601.5 6633.2 6322.0 6327.4 6338.9 6419.6 6421.2 16:20:57 INFO - PID 12575 | 16:20:57 INFO - PID 12575 | [#5] yui.html Cycles:180 Average:22533.56 Median:20250.75 stddev:30107.31 (148.7%) stddev-sans-first:30147.97 16:20:57 INFO - PID 12575 | Values: 905.7 928.8 937.9 939.0 940.4 18017.2 19037.9 19131.7 19202.9 22045.9 21117.4 21752.9 21926.5 22017.7 22113.2 964.3 993.3 995.1 996.6 1001.0 1018.3 1074.2 1077.0 1081.5 1084.6 1116.8 1126.7 1127.3 1127.8 1128.8 1114.4 1116.5 1119.6 1120.5 1121.1 809.2 812.1 812.4 812.7 813.7 17523.8 17815.6 17842.8 17870.0 19483.0 21404.8 21682.6 21884.6 21934.0 22073.2 15323.6 15340.0 15485.8 15488.7 15496.1 3630.3 3668.2 3668.6 3675.8 3678.4 124022.0 126007.0 127512.0 129300.7 129685.0 16881.6 17231.9 17329.6 17363.7 17419.8 31494.8 31605.4 31759.0 32371.5 32398.2 7749.5 7853.5 8129.8 8685.1 9064.6 4045.3 4110.2 4111.6 4121.0 4133.5 5296.4 5309.6 5384.3 5390.9 5428.3 1061.0 1071.4 1083.5 1086.1 1092.6 691.2 709.7 713.9 723.1 737.3 11878.7 12278.6 12616.3 13648.2 13818.9 705.5 724.2 724.3 728.5 729.7 5649.6 5745.3 5774.8 5831.8 5920.6 101794.0 102193.5 103775.0 104122.0 106975.0 89747.0 89928.6 90149.0 94237.5 94686.5 75159.6 76142.6 76616.2 78249.6 79740.6 11288.5 11346.9 11397.3 11474.5 11663.0 36024.3 36945.0 38173.8 38480.8 38965.8 44908.0 45244.8 45591.0 46061.8 46727.8 15080.0 16220.8 16579.1 16736.3 16871.2 19411.6 21018.5 21034.4 21278.8 21421.9 18829.8 18916.2 19108.1 19178.8 19242.6 19069.4 19316.5 19358.7 19360.5 19360.7 18373.9 18447.7 18542.7 18619.7 18669.0 13007.5 13239.1 13420.2 13538.7 13579.3 14457.3 14502.3 14506.3 14713.1 14727.9 16:20:57 INFO - PID 12575 | -------- Summary: end -------- 16:20:57 INFO - PID 12575 | 16:20:57 INFO - TEST-INFO | 12575: exit 0 16:20:57 INFO - TEST-OK | dromaeo_css | took 1112417ms 16:20:57 INFO - TEST-START | kraken 16:20:57 INFO - Initialising browser for kraken test... 16:20:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33764/getInfo.html -profile /tmp/tmpEvKUMq/profile 16:20:58 INFO - TEST-INFO | started process 12955 (/builds/slave/test/build/application/firefox/firefox http://localhost:33764/getInfo.html) 16:21:04 INFO - TEST-INFO | 12955: exit 0 16:21:04 INFO - Browser initialized. 16:21:04 INFO - Running cycle 1/1 for kraken test... 16:21:04 INFO - TEST-INFO | started process 13165 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEvKUMq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:21:05 INFO - PID 13165 | 16:21:05 INFO - PID 13165 | (/builds/slave/test/build/application/firefox/firefox:13213): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:21:05 INFO - PID 13165 | 16:21:15 INFO - PID 13165 | RSS: Main: 168308736 16:21:15 INFO - PID 13165 | 16:21:18 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33764/tests/kraken/driver.html?testName=audio-beat-detection) 16:21:19 INFO - PID 13165 | RSS: Main: 174292992 16:21:19 INFO - PID 13165 | 16:21:21 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33764/tests/kraken/driver.html?testName=audio-dft) 16:21:22 INFO - PID 13165 | RSS: Main: 174669824 16:21:22 INFO - PID 13165 | 16:21:25 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33764/tests/kraken/driver.html?testName=audio-fft) 16:21:26 INFO - PID 13165 | RSS: Main: 168091648 16:21:26 INFO - PID 13165 | 16:21:28 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33764/tests/kraken/driver.html?testName=audio-oscillator) 16:21:28 INFO - PID 13165 | RSS: Main: 168861696 16:21:28 INFO - PID 13165 | 16:21:30 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33764/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:21:31 INFO - PID 13165 | RSS: Main: 168878080 16:21:31 INFO - PID 13165 | 16:21:34 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33764/tests/kraken/driver.html?testName=imaging-darkroom) 16:21:35 INFO - PID 13165 | RSS: Main: 171839488 16:21:35 INFO - PID 13165 | 16:21:38 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33764/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:39 INFO - PID 13165 | RSS: Main: 172355584 16:21:39 INFO - PID 13165 | 16:21:42 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33764/tests/kraken/driver.html?testName=json-parse-financial) 16:21:42 INFO - PID 13165 | RSS: Main: 172060672 16:21:42 INFO - PID 13165 | 16:21:44 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33764/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:45 INFO - PID 13165 | RSS: Main: 171773952 16:21:45 INFO - PID 13165 | 16:21:46 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33764/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:47 INFO - PID 13165 | RSS: Main: 172077056 16:21:47 INFO - PID 13165 | 16:21:49 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33764/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:50 INFO - PID 13165 | RSS: Main: 172036096 16:21:50 INFO - PID 13165 | 16:21:53 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33764/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:53 INFO - PID 13165 | RSS: Main: 172679168 16:21:53 INFO - PID 13165 | 16:21:56 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33764/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:21:57 INFO - PID 13165 | RSS: Main: 173043712 16:21:57 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | Cycle 1(1): loaded http://localhost:33764/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33764/tests/kraken/driver.html?testName=ai-astar) 16:21:59 INFO - PID 13165 | RSS: Main: 174522368 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | __start_tp_report 16:21:59 INFO - PID 13165 | _x_x_mozilla_page_load 16:21:59 INFO - PID 13165 | _x_x_mozilla_page_load_details 16:21:59 INFO - PID 13165 | |i|pagename|runs| 16:21:59 INFO - PID 13165 | |0;ai-astar;99;114;101;108;108;113;109;100;106;109 16:21:59 INFO - PID 13165 | |1;audio-beat-detection;142;118;146;188;120;149;147;120;173;123 16:21:59 INFO - PID 13165 | |2;audio-dft;165;164;164;163;163;169;163;167;173;165 16:21:59 INFO - PID 13165 | |3;audio-fft;74;75;76;180;76;75;75;74;169;76 16:21:59 INFO - PID 13165 | |4;audio-oscillator;79;78;132;81;82;79;130;80;78;129 16:21:59 INFO - PID 13165 | |5;imaging-gaussian-blur;114;115;114;115;115;113;115;114;114;116 16:21:59 INFO - PID 13165 | |6;imaging-darkroom;152;152;152;152;152;150;152;150;152;150 16:21:59 INFO - PID 13165 | |7;imaging-desaturate;94;94;94;94;94;94;94;94;94;95 16:21:59 INFO - PID 13165 | |8;json-parse-financial;55;55;55;55;54;55;55;54;54;54 16:21:59 INFO - PID 13165 | |9;json-stringify-tinderbox;54;53;54;55;55;54;54;54;55;54 16:21:59 INFO - PID 13165 | |10;stanford-crypto-aes;88;87;78;78;89;87;78;87;88;77 16:21:59 INFO - PID 13165 | |11;stanford-crypto-ccm;131;128;134;125;136;138;127;129;125;170 16:21:59 INFO - PID 13165 | |12;stanford-crypto-pbkdf2;171;174;175;173;173;177;175;198;172;173 16:21:59 INFO - PID 13165 | |13;stanford-crypto-sha256-iterative;68;68;67;68;68;66;66;70;65;65 16:21:59 INFO - PID 13165 | __end_tp_report 16:21:59 INFO - PID 13165 | __start_cc_report 16:21:59 INFO - PID 13165 | _x_x_mozilla_cycle_collect,963 16:21:59 INFO - PID 13165 | __end_cc_report 16:21:59 INFO - PID 13165 | __startTimestamp1502234519477__endTimestamp 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | ------- Summary: start ------- 16:21:59 INFO - PID 13165 | Number of tests: 14 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#0] ai-astar Cycles:10 Average:106.70 Median:109.00 stddev:5.21 (4.8%) stddev-sans-first:4.72 16:21:59 INFO - PID 13165 | Values: 99.0 114.0 101.0 108.0 108.0 113.0 109.0 100.0 106.0 109.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#1] audio-beat-detection Cycles:10 Average:142.60 Median:146.50 stddev:23.73 (16.2%) stddev-sans-first:25.17 16:21:59 INFO - PID 13165 | Values: 142.0 118.0 146.0 188.0 120.0 149.0 147.0 120.0 173.0 123.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#2] audio-dft Cycles:10 Average:165.60 Median:165.00 stddev:3.24 (2.0%) stddev-sans-first:3.43 16:21:59 INFO - PID 13165 | Values: 165.0 164.0 164.0 163.0 163.0 169.0 163.0 167.0 173.0 165.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#3] audio-fft Cycles:10 Average:95.00 Median:75.00 stddev:41.99 (56.0%) stddev-sans-first:43.84 16:21:59 INFO - PID 13165 | Values: 74.0 75.0 76.0 180.0 76.0 75.0 75.0 74.0 169.0 76.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.00 stddev:24.56 (31.1%) stddev-sans-first:25.38 16:21:59 INFO - PID 13165 | Values: 79.0 78.0 132.0 81.0 82.0 79.0 130.0 80.0 78.0 129.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:115.00 stddev:0.85 (0.7%) stddev-sans-first:0.88 16:21:59 INFO - PID 13165 | Values: 114.0 115.0 114.0 115.0 115.0 113.0 115.0 114.0 114.0 116.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#6] imaging-darkroom Cycles:10 Average:151.40 Median:152.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 16:21:59 INFO - PID 13165 | Values: 152.0 152.0 152.0 152.0 152.0 150.0 152.0 150.0 152.0 150.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:21:59 INFO - PID 13165 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 16:21:59 INFO - PID 13165 | Values: 55.0 55.0 55.0 55.0 54.0 55.0 55.0 54.0 54.0 54.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#9] json-stringify-tinderbox Cycles:10 Average:54.20 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 16:21:59 INFO - PID 13165 | Values: 54.0 53.0 54.0 55.0 55.0 54.0 54.0 54.0 55.0 54.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:87.00 stddev:5.17 (5.9%) stddev-sans-first:5.24 16:21:59 INFO - PID 13165 | Values: 88.0 87.0 78.0 78.0 89.0 87.0 78.0 87.0 88.0 77.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#11] stanford-crypto-ccm Cycles:10 Average:134.30 Median:132.50 stddev:13.32 (10.1%) stddev-sans-first:14.07 16:21:59 INFO - PID 13165 | Values: 131.0 128.0 134.0 125.0 136.0 138.0 127.0 129.0 125.0 170.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.10 Median:174.50 stddev:7.88 (4.5%) stddev-sans-first:8.14 16:21:59 INFO - PID 13165 | Values: 171.0 174.0 175.0 173.0 173.0 177.0 175.0 198.0 172.0 173.0 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:68.00 stddev:1.60 (2.3%) stddev-sans-first:1.66 16:21:59 INFO - PID 13165 | Values: 68.0 68.0 67.0 68.0 68.0 66.0 66.0 70.0 65.0 65.0 16:21:59 INFO - PID 13165 | -------- Summary: end -------- 16:21:59 INFO - PID 13165 | 16:21:59 INFO - PID 13165 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:22:00 INFO - TEST-INFO | 13165: exit 0 16:22:00 INFO - TEST-OK | kraken | took 62247ms 16:22:00 INFO - SUITE-END | took 1174s 16:22:00 INFO - Completed test suite (00:19:35) 16:22:00 INFO - javascript benchmark 16:22:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8498.708242389814, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3151.2279491524564, 3173.9365212695748, 3181.2365032392227, 3187.1713354527815, 3234.5471633971247, 6118.0, 6226.159468471755, 6289.088082228074, 6330.588511746739, 6355.650439225841, 6133.049377346507, 6224.346443623423, 6233.345498722637, 6249.750009999594, 6261.436470717635, 5690.658560486384, 5700.059490184125, 5711.314642242936, 5713.485786279234, 5787.768489260425, 5732.140178973145, 5738.225339579145, 5803.361630220672, 5806.680632565207, 5826.271716035496, 5586.525145362649, 5687.03320435527, 5688.146777983295, 5808.3030036395485, 5842.357340692521, 5324.840254792362, 5695.231143795576, 5782.971085144547, 5784.16129661201, 5787.884242315172, 5651.265335506399, 5652.689102099383, 5712.457316554921, 5730.968425683375, 5746.712664366765, 6100.96949515254, 6134.69326533676, 6203.038529028008, 6217.316095229521, 6247.562670613059, 6714.925611902072, 6721.428678562328, 6737.056812046318, 6781.525293229476, 6811.489138314646, 6748.797536073926, 6771.424428923548, 6774.729010839561, 6879.105716256905, 6884.414824739903, 6178.907316390258, 6229.3459186785185, 6261.78083767066, 6267.091271765568, 6306.621602703806, 6913.5506192097355, 6917.169939607279, 6923.965380173167, 6924.515283930177, 6926.272741362185, 6513.641749703717, 6579.144711187515, 6580.638064906476, 6590.736370545124, 6606.042123892009, 6545.116409284781, 6575.145231119924, 6600.80197594073, 6610.537262391586, 6711.563748356341, 6605.471562275006, 6614.966925165343, 6716.026176204521, 6736.5958042517605, 6756.2568117507035, 6723.193216813916, 6774.491913106537, 6777.136287520972, 6802.489813263926, 6833.077534532874, 5399.244105825188, 5426.131818908955, 5490.066688662857, 5521.889562208733, 5559.610827242054, 4552.931706024412, 4673.854905548163, 4711.151992641289, 4711.7879695413785, 4720.221163507983, 5793.478586927196, 5837.97835378807, 5855.736491857877, 5871.324797648325, 5882.441168089024, 6760.898586521206, 6773.187217533928, 6864.0, 6869.553479023847, 6879.58722476653, 5767.8558036049435, 5806.245188125601, 5861.7655293787775, 5867.471927526543, 5888.499477544457, 5875.9118613220835, 5932.377100404482, 5952.375000624958, 5962.3739507351975, 5991.700414979234, 6507.511936604774, 6514.05546195799, 6593.472522198213, 6604.14146160996, 6608.074869518271, 6691.966540167268, 6843.144606924135, 6850.040994260907, 6863.142107236596, 6869.072675188985, 6513.641749703812, 6517.055027021151, 6588.868222635616, 6604.339566043357, 6621.27166011748, 6470.223573171062, 6553.475721942138, 6642.966785166043, 6732.057511948429, 6746.123004009447, 6760.121184246016, 6767.154105736783, 6770.356816102462, 6775.898361524482, 6778.694958726771, 6548.770793022221, 6549.70526326326, 6598.90101648466, 6617.735290588323, 6620.668966551557, 6576.473882089325, 6588.0, 6608.867822643424, 6626.072349871119, 6651.235107962646, 6477.384648458389, 6485.189351331083, 6587.835304117244, 6591.0, 6610.338966103352, 6563.803085907431, 6602.702878370389, 6694.59832410057, 6703.698333575098, 6707.798766037026, 6296.401841825019, 6370.426661600478, 6395.5203359747275, 6400.295967443671, 6486.772962946463], "value": 6144.335774981801, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [851.2508992086963, 870.4255191573561, 893.5085702863423, 895.0, 1049.118740258183, 31871.840640796792, 44141.558584414146, 45289.77355113224, 45313.77343113288, 45349.09301813962, 45417.0, 45826.0, 45830.77084614572, 45839.31241031387, 45882.0, 7805.141434442221, 7999.0, 8015.7194498192575, 8032.3172530335, 8042.396820238476, 7282.235365286648, 7902.486338388015, 7979.960100199491, 7981.28168464838, 8024.398170137234, 7906.6442010109395, 7949.244821741925, 7956.363490920741, 7962.522248665098, 7970.561619110947, 7879.212078792132, 7880.0937892142565, 7916.287534121928, 7928.563928983904, 7933.841323173533, 7847.489913155627, 7904.288614024766, 7906.486078404885, 7906.841863162705, 7909.327707144899, 40044.79977600122, 40315.395269070985, 40325.99185020369, 40326.798366008275, 40338.798306008284, 3518.2787528556582, 3569.696575791035, 3574.803385813779, 3590.5691317042124, 3600.3879340512176, 32794.18014549631, 32821.0, 32978.505322420184, 33010.504842427385, 33042.17394565131, 5137.820176293849, 5143.459936706629, 5160.354955630546, 5170.353705786777, 5172.172452407597, 188124.0, 188128.0, 188740.056299719, 188991.0, 189202.0, 51590.74204628953, 51622.48377516177, 51635.48364516382, 51746.0, 51763.48236517662, 53565.732171338896, 53783.46216537863, 53833.46166538284, 53840.46159538433, 53871.19193212105, 16007.359705611645, 16350.182490875408, 16355.264013119438, 16386.770992175636, 16499.17504124789, 13202.405891734896, 13289.800652990212, 13290.800637990438, 13305.800412993813, 13314.667133321744, 13100.934495327463, 13364.465421383036, 13526.256055916821, 14806.703865922622, 15141.924290378698, 5960.940390596039, 6016.548758843104, 6086.6348019119, 6097.207363042776, 6109.877802443926, 1003.7540802503287, 1006.4464544500496, 1010.6816352848829, 1018.5162048027275, 1019.5666841592293, 23080.884595576914, 29614.0, 30434.84782576073, 30449.391012179633, 30628.69371306303, 1079.9298045627008, 1086.9184811139196, 1094.042712626452, 1098.9670309890716, 1102.3606308341143, 12509.311987840574, 12651.0511711622, 12686.682832929066, 12774.0, 12832.230066196058, 240164.0, 243254.0, 243368.0, 243778.0, 243902.0, 56626.0, 56725.71637141788, 56764.0, 56898.0, 57081.714591426775, 1452.5860129862938, 1514.6894886548232, 1514.9772753408708, 1515.901466404658, 1520.536236447872, 23167.768322316562, 23179.188728394427, 23251.534969300857, 23409.0, 23619.527609448058, 39405.605943941344, 39504.0, 39540.40689389719, 39544.2091158181, 39673.40489892596, 212397.0, 212547.0, 214729.0, 215065.0, 215185.0, 36027.0, 36092.63907360893, 36136.81931590325, 36136.81931590325, 38900.80549597234, 49255.261171081744, 49550.50449495603, 49608.255876162606, 50234.24648630201, 50474.0, 34800.30399392048, 35434.0, 36000.27999440049, 36181.0, 36203.09492262715, 35155.64844351524, 35222.29555408929, 35385.82307088551, 35462.82268588744, 35521.644783551834, 38558.6144138555, 38738.0, 38789.0, 38815.0, 39036.219275614894, 34034.8298258517, 34143.4878476828, 34161.0, 34588.48117278293, 35159.0, 23795.524089518458, 24927.626085608368, 25206.49587008213, 25445.10942117017, 25642.743572564035], "value": 18032.001021850756, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.1990832742486, 586.1998372221913, 594.6818452128113, 600.9008513595257, 612.8008397270887, 21972.230971915986, 22686.54626907461, 22832.65751013736, 22939.42651433715, 23042.884785576047, 20985.475363115893, 23118.884405577992, 23686.170984015564, 26539.601905971427, 26666.733332666667, 6591.93408065918, 6737.3262673732725, 6740.123783908085, 6744.190697116353, 6754.459643228554, 5955.583109182359, 6486.935130648704, 6526.216853977529, 6656.267810540837, 6660.267370589256, 6409.070684750708, 6436.034594810769, 6438.0, 6442.16251887254, 6445.8710825783455, 6426.871462570746, 6438.323975982501, 6442.903356449657, 6443.871122577546, 6446.871062578723, 6011.218541589609, 6022.789202377896, 6023.789167379165, 6031.698415079229, 6053.606515576474, 28865.42269154626, 28988.855055724587, 29446.263843404085, 29549.26126846846, 30243.0, 1810.7374430707473, 1824.324999750101, 1852.3701941339875, 1852.4257480181186, 1853.8146185381488, 19144.712829307573, 19210.423687289403, 19615.313464028834, 20289.39131826033, 20298.086586103513, 2440.4386990991993, 2466.0, 2467.7778999889915, 2472.554940110779, 2531.0255551612577, 118915.0, 122817.0, 134830.0, 139833.0, 144853.99999999895, 14967.640362109047, 15092.471763488446, 15410.691786164438, 15438.0, 15574.844251557566, 24180.153694620603, 24195.879020604785, 24226.63660045101, 24304.635430468556, 24317.878410607835, 8596.667516534948, 8680.088590698013, 8686.435381700168, 8726.0, 8744.038155802857, 7485.363744081705, 7493.100827900686, 7622.2758837910305, 7625.313721765067, 7630.8092297693, 9125.0, 9215.53922303882, 9331.626734930662, 9429.481378524248, 9746.658866939622, 2770.556710926242, 2783.4015686627067, 2787.1777825541512, 2817.7745780337523, 2871.9425611487654, 929.7257309093834, 936.7423958411491, 958.6836344006462, 964.8311545479513, 969.3892847506168, 18910.62178756417, 19496.805031949498, 19697.212111515666, 19845.404637860887, 19979.700304495444, 1006.3810756384771, 1021.6373187518473, 1033.2302434686208, 1041.1202533858877, 1043.1654676259113, 10644.361338319726, 10649.574017039231, 10876.29304095247, 10928.562857485771, 11007.339559626447, 481397.0, 489396.0, 496001.0, 499312.0, 507463.0, 123246.0, 124591.37704311602, 125063.374683126, 125978.37010814887, 128273.35863320624, 1082.8538147350007, 1128.8250321200228, 1186.4364426897155, 1211.4003568233782, 1248.0826592454357, 21042.0, 21391.25130620421, 21442.356729298146, 21530.0, 21649.242276520246, 56643.71678141583, 58031.70984145052, 58080.12879806891, 58665.0, 59019.40980590139, 73066.0, 73999.6300018518, 76701.0, 77238.22761772311, 77877.0, 14863.40546378176, 15255.694886101994, 15414.614634634223, 15437.0737755735, 15540.523650253208, 23870.16454424087, 23995.640065398682, 24024.0, 24297.878510607334, 24496.265112046665, 18565.535861603028, 18750.0, 19226.903865480584, 19350.0, 19357.322493713214, 17425.912870435568, 17468.650626987645, 17540.122993850513, 17833.286668533114, 17911.73132403041, 18316.175772090555, 19456.319028833925, 19486.512837179183, 19601.70597441068, 19663.60672786565, 15075.54773356801, 15224.162671052756, 15254.923725381303, 15263.0, 15298.61753456128, 18482.907585461988, 18898.811011889706, 19112.23551057942, 19190.424287271406, 19235.711464328328], "value": 12033.157700475815, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.397293204524, 1406.563965170797, 1412.9364178611975, 1413.3145424469137, 1423.3737155651513, 13580.456781728732, 13964.511242106528, 14047.0, 14144.504942327008, 14161.929190354034, 13881.375338109785, 14035.36840842162, 14136.151830890129, 14193.71612567748, 14206.218657973808, 2619.6070589411647, 2662.7071022187542, 2664.5203863304596, 2673.9598906016427, 2674.745899139579, 2572.061197662849, 2628.0933078088124, 2646.9470610587778, 2654.707982121965, 2663.9200823975216, 2582.819202655815, 2610.5692560727457, 2616.6205900144564, 2642.2733748219202, 2644.8942042318286, 2549.4773571418, 2583.689957205138, 2603.4532748122915, 2604.7916166706714, 2604.8437093774432, 2405.20628192696, 2409.4578719787905, 2430.4166999920067, 2434.196715084018, 2440.389902524369, 13718.176909385369, 14009.929950350182, 14032.719345613134, 14042.0, 14052.508162214273, 7457.291557302047, 7690.346320562759, 7709.730159444449, 7717.421193410517, 7726.420518461138, 4852.344933433977, 4899.534544218293, 4989.426215985171, 5066.873328166826, 5072.061668591323, 1574.645704716441, 1676.228934690044, 1689.8225686303008, 1722.9913850430794, 1727.2141175764996, 21547.1381144754, 21757.67363489549, 22845.0, 23867.64198537006, 23900.0, 6379.234491861006, 6534.379233972765, 6542.509311801634, 6661.700223489955, 6738.147009572309, 15822.129782861823, 15833.52499425019, 15845.207739613204, 15886.443974460839, 15969.281382337591, 7496.775096746997, 7614.238576142341, 7646.464747467624, 7648.541087534766, 7678.424118191158, 6985.764555342344, 7014.964925175444, 7069.222385537587, 7081.645917704094, 7093.89359159613, 4917.926231106536, 5004.924926126111, 5033.219850923113, 5071.847844564669, 5112.284280200774, 1417.0, 1433.806436131109, 1439.3235179465635, 1452.9128252304895, 1465.157534417714, 851.4040171879585, 859.9570021499026, 871.7733389318822, 875.2079368171815, 885.9557022148996, 6768.966155169291, 6779.0, 6803.217629972518, 6833.760818371333, 6883.3116688330765, 666.2804171494774, 682.846359569093, 686.3822559696313, 686.50228584276, 696.1089805049545, 4437.489688685778, 4653.464851542116, 4722.881927951828, 4837.201861692782, 4892.363992680928, 18360.0, 19221.711674324895, 19275.22899084049, 19307.683078030725, 19315.710264346046, 18379.90810045968, 18876.339328123726, 19213.519662008282, 19331.130099145572, 19348.80651193498, 8996.685116020908, 9324.533773311307, 10182.745431364126, 10707.62523311696, 10835.295705779099, 7806.531608103532, 7956.801079973047, 7967.2431119043595, 8033.397495187767, 8059.717909873127, 16870.15649217559, 17011.234494447534, 17018.404355847983, 17038.48884533466, 17071.743923840902, 17209.827901721324, 17311.48065558035, 17318.307267709653, 17535.298588056336, 17712.291508339524, 10851.945740271249, 10943.507542160782, 11047.171462140212, 11073.280236784583, 11076.169287303323, 12325.938370308091, 12435.253884766942, 12489.750204995667, 12492.25046497213, 12492.625221243377, 9330.720078397659, 9405.670801522188, 9458.810823783624, 9613.615455381987, 9692.70921872345, 9572.282078843975, 9666.275029372684, 9696.272779541421, 9850.704478865646, 9888.258380621626, 7666.0, 7705.190954949874, 7788.571628560484, 7794.259545343183, 7808.258215469521, 6572.8356791080605, 6578.76974305897, 6582.1443212384, 6638.767643132467, 6708.798736037927, 7162.740578885796, 7372.410207183305, 7389.371903388273, 7461.515001524884, 7553.395728341829], "value": 6117.094803744027, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.4447984976601, 507.9669821461604, 529.3568314497884, 532.1166863007408, 536.849682089015, 6285.434310912017, 6370.4903607711385, 6373.470367111894, 6384.616922984618, 6395.680215989205, 5940.584159108864, 6098.481629061524, 6123.11214873843, 6150.630962142274, 6152.784652537162, 4910.877228069309, 4922.827701030465, 4931.901361972758, 4933.901321973576, 4987.177115775893, 4845.200541910581, 4905.75471226439, 4916.729579873106, 4945.950540494603, 5001.974990125044, 4905.018996200756, 4928.876778080541, 4958.512446266123, 4992.725400102993, 4999.575036121916, 4899.363082799231, 4937.654364194508, 4944.184209605429, 4965.205567109281, 4980.800767969277, 4494.10117976406, 4495.46054473462, 4503.48209955856, 4520.412346394981, 4540.910181556403, 6137.9079313810325, 6150.600210986271, 6158.753649854, 6165.784197553064, 6182.721777520022, 5775.537956963432, 5790.276215473066, 5802.622829516068, 5805.970970145165, 5825.621334613236, 6621.635810030446, 6644.169478815148, 6655.633940133291, 6676.532642715012, 6686.665666716644, 4866.172750632401, 4869.9269510957365, 4892.9510704892855, 4902.632302577321, 4907.533784290523, 6810.965945170243, 6941.201761797407, 6982.650867456607, 7044.788656340318, 7052.153741551047, 4992.276119962585, 5000.524950129732, 5022.096022715873, 5047.974760126176, 5072.5688316493515, 5112.590992720573, 5114.923276150861, 5117.769700363418, 5120.7951681933055, 5122.180451127876, 5727.140928860705, 5744.971275143681, 5770.2498675171955, 5802.274715660543, 5810.593258471867, 6567.474602031826, 6638.933610663928, 6678.365555272241, 6683.899741503881, 6711.832204194837, 1697.8047524534836, 1713.994626287247, 1718.1065845760384, 1736.26208861236, 1785.6875046866746, 1152.7809716153904, 1213.490334059697, 1216.1304667163022, 1222.620987493862, 1232.7657745028416, 5733.971330143323, 5735.426457354231, 5778.7688492460675, 5809.767609295581, 5819.301683797971, 6507.544471887017, 6566.606003639797, 6587.37419945114, 6600.108985287021, 6660.60036397808, 5772.9217481706555, 5773.88452230953, 5841.912371314434, 5860.267466566679, 5875.529957603381, 3538.256966037161, 3590.479380489867, 3597.9100522487147, 3625.075605720577, 3625.220577575781, 6518.445932095825, 6532.771353002717, 6580.901286480706, 6582.506312026617, 6604.0, 6901.102856628606, 6978.5812851228075, 6981.825454363681, 6998.93001069993, 7025.789226323116, 6457.580257283262, 6484.319146489645, 6538.738450461929, 6553.246376666649, 6583.670816459254, 6772.627505487246, 6816.965915170393, 6819.624920629413, 6824.225701317594, 6852.0, 6614.272430032789, 6672.599644021374, 6701.798946031627, 6740.708159225691, 6798.898016529855, 6439.710213040331, 6485.675716214265, 6506.707198175999, 6550.213974323112, 6588.670566471561, 4976.511744127936, 4990.226514890198, 5039.412585035849, 5047.9747601263225, 5078.162103252922, 6805.264657512303, 6842.418394436529, 6854.931450685629, 6894.13823272091, 6911.956505444371, 6627.436667883284, 6633.436157926631, 6646.335366463315, 6664.633445160369, 6672.299408562225, 6483.4164925156965, 6531.477481801539, 6569.638669873012, 6577.276499584946, 6613.834654133685, 6024.162630397771, 6136.171616831671, 6437.903431448625, 6472.935270647234, 6496.577722448026, 6453.419192272718, 6480.787488132752, 6527.836804079936, 6601.504887133388, 6633.170853643294, 6321.96839015802, 6327.367263273636, 6338.936610633835, 6419.614823110628, 6421.179398258029], "value": 5078.485971423741, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [905.6875377994592, 928.8142371525693, 937.8874535055794, 938.9530523473827, 940.3934462271835, 18017.189226484807, 19037.904810475946, 19131.713024304645, 19202.903985480087, 22045.89770511476, 21117.366479005617, 21752.89123554384, 21926.45183870404, 22017.66973495399, 22113.226037088705, 964.28642804325, 993.3146129170849, 995.0994350113123, 996.6312464388186, 1000.9840012387433, 1018.3482571154467, 1074.2426589254596, 1076.962306319279, 1081.5187241677456, 1084.6366467233447, 1116.8380584815197, 1126.7239526316064, 1127.284174549163, 1127.8139107047327, 1128.841962125303, 1114.4093630375914, 1116.4808364110686, 1119.6137332620233, 1120.506976930148, 1121.075113031748, 809.2191035650626, 812.1147948735847, 812.3825892321819, 812.7317985064974, 813.7070654564411, 17523.82476175235, 17815.643687126314, 17842.821571784247, 17870.0, 19483.025848707508, 21404.78595214059, 21682.56634867294, 21884.562308753735, 21934.0, 22073.227437039786, 15323.61690957735, 15340.0, 15485.767713484307, 15488.690226195526, 15496.147711875843, 3630.310241054218, 3668.1746607013474, 3668.559772827251, 3675.7794532328144, 3678.356287649677, 124022.0, 126007.0, 127512.0, 129300.7069929298, 129685.0, 16881.577960551083, 17231.913840430967, 17329.56676083083, 17363.739543906853, 17419.825801742074, 31494.842525787226, 31605.367892642018, 31759.0, 32371.514427283608, 32398.19004524906, 7749.535027898344, 7853.48952318109, 8129.75610731679, 8685.088065753005, 9064.592093355815, 4045.3122969095325, 4110.219058378958, 4111.568285330057, 4120.958790412057, 4133.503979522417, 5296.417394086648, 5309.628326017141, 5384.326959130109, 5390.921815636888, 5428.321459817523, 1061.0291583201285, 1071.4374953149659, 1083.539495714334, 1086.1202426034938, 1092.639428988448, 691.1671435919704, 709.7019251914209, 713.8893471511924, 723.1358526560709, 737.3179808676953, 11878.703032424259, 12278.631641050782, 12616.306103164414, 13648.181109133484, 13818.855299318931, 705.4744215559339, 724.1708244060521, 724.3263764698776, 728.5009768308726, 729.7190581626112, 5649.6327738696855, 5745.2818397700285, 5774.797882074107, 5831.79588714393, 5920.644761314335, 101794.0, 102193.48903255437, 103775.0, 104122.0, 106975.0, 89747.0, 89928.55035724779, 90149.0, 94237.52881235688, 94686.52656736672, 75159.62420187973, 76142.61928690321, 76616.23383766203, 78249.60875195588, 79740.60129699315, 11288.548458061585, 11346.94326528362, 11397.259178153394, 11474.541018359172, 11663.008644265332, 36024.27951441009, 36945.0, 38173.80913095528, 38480.80759596184, 38965.805170975094, 44908.0, 45244.77377613091, 45591.0, 46061.76969115133, 46727.766361167975, 15080.0, 16220.756688649444, 16579.088150151856, 16736.33054677848, 16871.156442178086, 19411.611767764847, 21018.47453813667, 21034.368968930958, 21278.78721212768, 21421.892890535448, 18829.811701882805, 18916.243350265835, 19108.140133694294, 19178.80821191826, 19242.615147696688, 19069.427917162506, 19316.517087072934, 19358.709619356003, 19360.515987099872, 19360.70958935589, 18373.908130459262, 18447.72328415048, 18542.721859172394, 18619.720704189178, 18669.0, 13007.52856957175, 13239.139455935334, 13420.194788312732, 13538.72922541524, 13579.321033948065, 14457.27713614294, 14502.274886255434, 14506.274686265855, 14713.117212967256, 14727.92636036813], "value": 9097.269999311327, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 114.0, 101.0, 108.0, 108.0, 113.0, 109.0, 100.0, 106.0, 109.0], "value": 106.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 118.0, 146.0, 188.0, 120.0, 149.0, 147.0, 120.0, 173.0, 123.0], "value": 142.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 164.0, 163.0, 163.0, 169.0, 163.0, 167.0, 173.0, 165.0], "value": 165.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 180.0, 76.0, 75.0, 75.0, 74.0, 169.0, 76.0], "value": 95.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 132.0, 81.0, 82.0, 79.0, 130.0, 80.0, 78.0, 129.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 115.0, 115.0, 113.0, 115.0, 114.0, 114.0, 116.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 152.0, 152.0, 152.0, 150.0, 152.0, 150.0, 152.0, 150.0], "value": 151.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 54.0, 55.0, 55.0, 54.0, 54.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 55.0, 55.0, 54.0, 54.0, 54.0, 55.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 78.0, 78.0, 89.0, 87.0, 78.0, 87.0, 88.0, 77.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 128.0, 134.0, 125.0, 136.0, 138.0, 127.0, 129.0, 125.0, 170.0], "value": 134.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 174.0, 175.0, 173.0, 173.0, 177.0, 175.0, 198.0, 172.0, 173.0], "value": 176.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 68.0, 66.0, 66.0, 70.0, 65.0, 65.0], "value": 67.1, "unit": "score"}]}]} 16:22:00 INFO - Return code: 0 16:22:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:22:00 INFO - # TBPL SUCCESS # 16:22:00 INFO - Running post-action listener: _package_coverage_data 16:22:00 INFO - Running post-action listener: _resource_record_post_action 16:22:00 INFO - [mozharness: 2017-08-08 23:22:00.741721Z] Finished run-tests step (success) 16:22:00 INFO - Running post-run listener: _resource_record_post_run 16:22:00 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 448307200; Read time: 16; Write time: 255780 16:22:00 INFO - TinderboxPrint: CPU usage
13.1% 16:22:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:22:00 INFO - TinderboxPrint: I/O write bytes / time
448,307,200 / 255,780 16:22:00 INFO - TinderboxPrint: CPU idle
8,260.6 (86.8%) 16:22:00 INFO - TinderboxPrint: CPU user
1,202.3 (12.6%) 16:22:00 INFO - TinderboxPrint: Swap in / out
0 / 0 16:22:00 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107429888; Read time: 0; Write time: 86960 16:22:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:22:00 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 324272128; Read time: 0; Write time: 159524 16:22:01 INFO - Running post-run listener: _upload_blobber_files 16:22:01 INFO - Blob upload gear active. 16:22:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:22:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:22:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:22:01 INFO - (blobuploader) - INFO - Open directory for files ... 16:22:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:22:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:22:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:02 INFO - (blobuploader) - INFO - Done attempting. 16:22:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:22:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:22:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:03 INFO - (blobuploader) - INFO - Done attempting. 16:22:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:22:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:22:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:10 INFO - (blobuploader) - INFO - Done attempting. 16:22:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:22:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:22:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:11 INFO - (blobuploader) - INFO - Done attempting. 16:22:11 INFO - (blobuploader) - INFO - Iteration through files over. 16:22:11 INFO - Return code: 0 16:22:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:22:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:22:11 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04c6e33f6131f317f328709a393e8c451fda3ab390ad956cb0e9acead88a55904e0e4a5ba8dd3d659e0f2489b755aac3872907a29a6de22381bb4e1bbde180d5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4722d72777659ff7ec83be0dfa5ec2ee7e2be4b83ee411dada33137fe4591b8bd5390e19b9477710a6f9106f9e186d8a462d00a5b3a5e3a816d1dee9838bbc1c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13529b2f7c065f69657a657fe274dc19ce017e7048c56cb36a669f12f5f9162a76ea1b447f166b10b8ed41c7b0f8ee833bc657fd09406deff0513e343407c17d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1de3f038fd1bfd7c52254ba59a574768ce33eab059d3935641f555a1d8ff348a58765febca1305155a48f01e1f38ed4a0e1fb88cabaacc16d8f46556ec18881b"} 16:22:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:22:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:22:11 INFO - Contents: 16:22:11 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04c6e33f6131f317f328709a393e8c451fda3ab390ad956cb0e9acead88a55904e0e4a5ba8dd3d659e0f2489b755aac3872907a29a6de22381bb4e1bbde180d5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4722d72777659ff7ec83be0dfa5ec2ee7e2be4b83ee411dada33137fe4591b8bd5390e19b9477710a6f9106f9e186d8a462d00a5b3a5e3a816d1dee9838bbc1c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13529b2f7c065f69657a657fe274dc19ce017e7048c56cb36a669f12f5f9162a76ea1b447f166b10b8ed41c7b0f8ee833bc657fd09406deff0513e343407c17d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1de3f038fd1bfd7c52254ba59a574768ce33eab059d3935641f555a1d8ff348a58765febca1305155a48f01e1f38ed4a0e1fb88cabaacc16d8f46556ec18881b"} 16:22:11 INFO - Running post-run listener: copy_logs_to_upload_dir 16:22:11 INFO - Copying logs to upload dir... 16:22:11 INFO - mkdir: /builds/slave/test/build/upload/logs 16:22:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.277927 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-08 16:22:10.926799) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 16:22:10.930692) ========= 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=d2a8c569965ab8367867b893000001a1-1502233274.640812-865943932 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04c6e33f6131f317f328709a393e8c451fda3ab390ad956cb0e9acead88a55904e0e4a5ba8dd3d659e0f2489b755aac3872907a29a6de22381bb4e1bbde180d5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4722d72777659ff7ec83be0dfa5ec2ee7e2be4b83ee411dada33137fe4591b8bd5390e19b9477710a6f9106f9e186d8a462d00a5b3a5e3a816d1dee9838bbc1c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13529b2f7c065f69657a657fe274dc19ce017e7048c56cb36a669f12f5f9162a76ea1b447f166b10b8ed41c7b0f8ee833bc657fd09406deff0513e343407c17d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1de3f038fd1bfd7c52254ba59a574768ce33eab059d3935641f555a1d8ff348a58765febca1305155a48f01e1f38ed4a0e1fb88cabaacc16d8f46556ec18881b"} build_url:https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011248 build_url: 'https://queue.taskcluster.net/v1/task/F7NQqaIQR2-2IEUa3S5RaA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04c6e33f6131f317f328709a393e8c451fda3ab390ad956cb0e9acead88a55904e0e4a5ba8dd3d659e0f2489b755aac3872907a29a6de22381bb4e1bbde180d5", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4722d72777659ff7ec83be0dfa5ec2ee7e2be4b83ee411dada33137fe4591b8bd5390e19b9477710a6f9106f9e186d8a462d00a5b3a5e3a816d1dee9838bbc1c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/13529b2f7c065f69657a657fe274dc19ce017e7048c56cb36a669f12f5f9162a76ea1b447f166b10b8ed41c7b0f8ee833bc657fd09406deff0513e343407c17d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1de3f038fd1bfd7c52254ba59a574768ce33eab059d3935641f555a1d8ff348a58765febca1305155a48f01e1f38ed4a0e1fb88cabaacc16d8f46556ec18881b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 16:22:10.961298) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 16:22:10.961769) ========= 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=d2a8c569965ab8367867b893000001a1-1502233274.640812-865943932 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004526 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 16:22:11.043101) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 16:22:11.043430) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 16:22:11.043751) ========= ========= Total master_lag: 0.18 =========