builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-047 starttime: 1501547840.54 results: success (0) revision: 8b19670d12fde57d3aee50a5a7d1c734d9b709d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:20.544296) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:20.545077) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:20.545358) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:20.622721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:20.623012) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-31 17:37:20-- 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% 19.5M=0.001s 2017-07-31 17:37:20 (19.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113916 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:20.757024) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:20.757331) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015040 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:20.807079) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-31 17:37:20.807368) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8b19670d12fde57d3aee50a5a7d1c734d9b709d5 --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 8b19670d12fde57d3aee50a5a7d1c734d9b709d5 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-31 17:37:20,843 truncating revision to first 12 chars 2017-07-31 17:37:20,843 Setting DEBUG logging. 2017-07-31 17:37:20,843 attempt 1/10 2017-07-31 17:37:20,843 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8b19670d12fd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-31 17:37:21,705 unpacking tar archive at: mozilla-central-8b19670d12fd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.193901 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-31 17:37:22.013416) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:22.013719) ========= script_repo_revision: 8b19670d12fde57d3aee50a5a7d1c734d9b709d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:22.014092) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:22.014357) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-31 17:37:22.023845) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-07-31 17:37:22.024139) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:37:22 INFO - MultiFileLogger online at 20170731 17:37:22 in /builds/slave/test 17:37:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:37:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:37:22 INFO - {'append_to_log': False, 17:37:22 INFO - 'base_work_dir': '/builds/slave/test', 17:37:22 INFO - 'blob_upload_branch': 'Firefox', 17:37:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:37:22 INFO - 'branch': 'Firefox', 17:37:22 INFO - 'buildbot_json_path': 'buildprops.json', 17:37:22 INFO - 'code_coverage': False, 17:37:22 INFO - 'config_files': ('talos/linux_config.py',), 17:37:22 INFO - 'default_actions': ('clobber', 17:37:22 INFO - 'read-buildbot-config', 17:37:22 INFO - 'download-and-extract', 17:37:22 INFO - 'populate-webroot', 17:37:22 INFO - 'create-virtualenv', 17:37:22 INFO - 'install', 17:37:22 INFO - 'setup-mitmproxy', 17:37:22 INFO - 'run-tests'), 17:37:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:37:22 INFO - 'disable_ccov_upload': False, 17:37:22 INFO - 'download_minidump_stackwalk': True, 17:37:22 INFO - 'download_symbols': 'ondemand', 17:37:22 INFO - 'enable_stylo': False, 17:37:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:37:22 INFO - 'tooltool.py': '/tools/tooltool.py', 17:37:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:37:22 INFO - '/tools/misc-python/virtualenv.py')}, 17:37:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:37:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:37:22 INFO - 'gecko_profile': False, 17:37:22 INFO - 'gecko_profile_interval': 0, 17:37:22 INFO - 'installer_path': 'installer.exe', 17:37:22 INFO - 'log_level': 'info', 17:37:22 INFO - 'log_name': 'talos', 17:37:22 INFO - 'log_to_console': True, 17:37:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:37:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:37:22 INFO - 'opt_config_files': (), 17:37:22 INFO - 'pip_index': False, 17:37:22 INFO - 'suite': 'dromaeojs-e10s', 17:37:22 INFO - 'system_bits': '32', 17:37:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:37:22 INFO - 'title': 'talos-linux64-ix-047', 17:37:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:37:22 INFO - 'use_talos_json': True, 17:37:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:37:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:37:22 INFO - 'work_dir': 'build'} 17:37:22 INFO - [mozharness: 2017-08-01 00:37:22.154571Z] Running clobber step. 17:37:22 INFO - Running pre-action listener: _resource_record_pre_action 17:37:22 INFO - Running main action method: clobber 17:37:22 INFO - rmtree: /builds/slave/test/build 17:37:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:37:23 INFO - Running post-action listener: _resource_record_post_action 17:37:23 INFO - [mozharness: 2017-08-01 00:37:23.699421Z] Finished clobber step (success) 17:37:23 INFO - [mozharness: 2017-08-01 00:37:23.699523Z] Running read-buildbot-config step. 17:37:23 INFO - Running pre-action listener: _resource_record_pre_action 17:37:23 INFO - Running main action method: read_buildbot_config 17:37:23 INFO - Using buildbot properties: 17:37:23 INFO - { 17:37:23 INFO - "project": "", 17:37:23 INFO - "product": "firefox", 17:37:23 INFO - "who": "kwierso@gmail.com", 17:37:23 INFO - "installer_path": "public/build/target.tar.bz2", 17:37:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 17:37:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:37:23 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 17:37:23 INFO - "stage_platform": "linux64-stylo", 17:37:23 INFO - "basedir": "/builds/slave/test", 17:37:23 INFO - "buildnumber": 249, 17:37:23 INFO - "platform": "ubuntu64_hw_stylo", 17:37:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:37:23 INFO - "slavebuilddir": "test", 17:37:23 INFO - "taskId": "RGrhG6KhRkyM0cq55IW75w", 17:37:23 INFO - "branch": "mozilla-central", 17:37:23 INFO - "script_repo_revision": "production", 17:37:23 INFO - "revision": "8b19670d12fde57d3aee50a5a7d1c734d9b709d5", 17:37:23 INFO - "slavename": "talos-linux64-ix-047", 17:37:23 INFO - "repo_path": "mozilla-central" 17:37:23 INFO - } 17:37:23 INFO - Finding installer, test and symbols from parent task. 17:37:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RGrhG6KhRkyM0cq55IW75w'}, attempt #1 17:37:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RGrhG6KhRkyM0cq55IW75w'}, attempt #1 17:37:24 INFO - Task dependencies: DsdJJjUsSgahxwFgH6hwYA 17:37:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA'}, attempt #1 17:37:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.tar.bz2 17:37:25 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.test_packages.json 17:37:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.crashreporter-symbols.zip 17:37:25 INFO - Running post-action listener: _resource_record_post_action 17:37:25 INFO - [mozharness: 2017-08-01 00:37:25.043059Z] Finished read-buildbot-config step (success) 17:37:25 INFO - [mozharness: 2017-08-01 00:37:25.043281Z] Running download-and-extract step. 17:37:25 INFO - Running pre-action listener: _resource_record_pre_action 17:37:25 INFO - Running main action method: download_and_extract 17:37:25 INFO - mkdir: /builds/slave/test/build/tests 17:37: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')]} 17:37:25 INFO - trying https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.test_packages.json 17:37:25 INFO - Downloading https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:37:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:37:25 INFO - Downloaded 989 bytes. 17:37:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:37:25 INFO - Using the following test package requirements: 17:37:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:37:25 INFO - u'common': [u'target.common.tests.zip'], 17:37:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:37:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:37:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:37:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:37:25 INFO - u'mozbase': [u'target.common.tests.zip'], 17:37:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:37:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:37:25 INFO - u'web-platform': [u'target.common.tests.zip', 17:37:25 INFO - u'target.web-platform.tests.tar.gz'], 17:37:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:37:25 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:37:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.common.tests.zip 17:37:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:37:25 INFO - Fetch https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.common.tests.zip into memory 17:37:28 INFO - Content-Length response header: 72389642 17:37:28 INFO - Bytes received: 72389642 17:37:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:37:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.common.tests.zip 17:37:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:37:39 INFO - Fetch https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.common.tests.zip into memory 17:37:41 INFO - Content-Length response header: 72389642 17:37:41 INFO - Bytes received: 72389642 17:37:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.talos.tests.zip 17:37:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:37:54 INFO - Fetch https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.talos.tests.zip into memory 17:37:54 INFO - Content-Length response header: 14496467 17:37:54 INFO - Bytes received: 14496467 17:37: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')]} 17:37:55 INFO - trying https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.tar.bz2 17:37:55 INFO - Downloading https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:37:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:37:57 INFO - Downloaded 62118329 bytes. 17:37:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.tar.bz2 17:37:57 INFO - mkdir: /builds/slave/test/properties 17:37:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:37:57 INFO - Writing to file /builds/slave/test/properties/build_url 17:37:57 INFO - Contents: 17:37:57 INFO - build_url:https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.tar.bz2 17:37:57 INFO - Running post-action listener: _resource_record_post_action 17:37:57 INFO - Running post-action listener: set_extra_try_arguments 17:37:57 INFO - [mozharness: 2017-08-01 00:37:57.282664Z] Finished download-and-extract step (success) 17:37:57 INFO - [mozharness: 2017-08-01 00:37:57.282762Z] Running populate-webroot step. 17:37:57 INFO - Running pre-action listener: _resource_record_pre_action 17:37:57 INFO - Running main action method: populate_webroot 17:37:57 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:37:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:37:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:37:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:37:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:37:57 INFO - 'tests': ['damp', 'tps']}, 17:37:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:37:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:37:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:37:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:37:57 INFO - 'other-e10s': {'tests': ['a11yr', 17:37:57 INFO - 'ts_paint', 17:37:57 INFO - 'tpaint', 17:37:57 INFO - 'sessionrestore', 17:37:57 INFO - 'sessionrestore_many_windows', 17:37:57 INFO - 'sessionrestore_no_auto_restore', 17:37:57 INFO - 'tabpaint']}, 17:37:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:37:57 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:37:57 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:37:57 INFO - 'talos_options': ['--mitmproxy', 17:37:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:37:57 INFO - '--firstNonBlankPaint'], 17:37:57 INFO - 'tests': ['quantum_pageload_google', 17:37:57 INFO - 'quantum_pageload_youtube', 17:37:57 INFO - 'quantum_pageload_amazon', 17:37:57 INFO - 'quantum_pageload_facebook']}, 17:37:57 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:37:57 INFO - 'talos_options': ['--stylo', 17:37:57 INFO - '--mitmproxy', 17:37:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:37:57 INFO - '--firstNonBlankPaint'], 17:37:57 INFO - 'tests': ['quantum_pageload_google', 17:37:57 INFO - 'quantum_pageload_youtube', 17:37:57 INFO - 'quantum_pageload_amazon', 17:37:57 INFO - 'quantum_pageload_facebook']}, 17:37:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:37:57 INFO - 'tsvgr_opacity', 17:37:57 INFO - 'tart', 17:37:57 INFO - 'tscrollx', 17:37:57 INFO - 'cart', 17:37:57 INFO - 'tsvg_static']}, 17:37:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:37:57 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:37:57 INFO - 'talos_options': ['--stylo', 17:37:57 INFO - '--stylo-threads=1', 17:37:57 INFO - '--mitmproxy', 17:37:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:37:57 INFO - '--firstNonBlankPaint'], 17:37:57 INFO - 'tests': ['tp6_google', 17:37:57 INFO - 'tp6_youtube', 17:37:57 INFO - 'tp6_amazon', 17:37:57 INFO - 'tp6_facebook']}, 17:37:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:37:57 INFO - 'talos_options': ['--xperf_path', 17:37:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:37:57 INFO - 'tests': ['tp5n']}}} 17:37:57 INFO - Running post-action listener: _resource_record_post_action 17:37:57 INFO - [mozharness: 2017-08-01 00:37:57.291436Z] Finished populate-webroot step (success) 17:37:57 INFO - [mozharness: 2017-08-01 00:37:57.291527Z] Running create-virtualenv step. 17:37:57 INFO - Running pre-action listener: _resource_record_pre_action 17:37:57 INFO - Running main action method: create_virtualenv 17:37:57 INFO - Creating virtualenv /builds/slave/test/build/venv 17:37:57 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 17:37:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:37:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:37:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:37:57 INFO - Using real prefix '/usr' 17:37:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:37:57 INFO - Installing distribute.............................................................................................................................................................................................done. 17:37:59 INFO - Installing pip.................done. 17:37:59 INFO - Return code: 0 17:37:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:38:00 INFO - Reading from file tmpfile_stdout 17:38:00 INFO - Output received: 17:38:00 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) 17:38:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:38:00 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')]} 17:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:00 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')]} 17:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:00 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1762d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175feb0>, '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 0x1760db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1761240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', '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 17:38:00 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 17:38:00 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 17:38:00 INFO - Using env: {'DISPLAY': ':0', 17:38:00 INFO - 'HOME': '/home/cltbld', 17:38:00 INFO - 'LANG': 'en_US.UTF-8', 17:38:00 INFO - 'LANGUAGE': 'en_US:en', 17:38:00 INFO - 'LOGNAME': 'cltbld', 17:38:00 INFO - 'MAIL': '/var/mail/cltbld', 17:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:00 INFO - 'MOZ_NO_REMOTE': '1', 17:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:00 INFO - 'NO_EM_RESTART': '1', 17:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:00 INFO - 'PWD': '/builds/slave/test', 17:38:00 INFO - 'SHELL': '/bin/bash', 17:38:00 INFO - 'SHLVL': '1', 17:38:00 INFO - 'TERM': 'linux', 17:38:00 INFO - 'TMOUT': '86400', 17:38:00 INFO - 'USER': 'cltbld', 17:38:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', 17:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:00 INFO - '_': '/tools/buildbot/bin/python'} 17:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:00 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 17:38:00 INFO - Cleaning up... 17:38:00 INFO - Return code: 0 17:38:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:38:00 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')]} 17:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:00 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')]} 17:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:00 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1762d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175feb0>, '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 0x1760db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1761240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', '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 17:38:00 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 17:38:00 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 17:38:00 INFO - Using env: {'DISPLAY': ':0', 17:38:00 INFO - 'HOME': '/home/cltbld', 17:38:00 INFO - 'LANG': 'en_US.UTF-8', 17:38:00 INFO - 'LANGUAGE': 'en_US:en', 17:38:00 INFO - 'LOGNAME': 'cltbld', 17:38:00 INFO - 'MAIL': '/var/mail/cltbld', 17:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:00 INFO - 'MOZ_NO_REMOTE': '1', 17:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:00 INFO - 'NO_EM_RESTART': '1', 17:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:00 INFO - 'PWD': '/builds/slave/test', 17:38:00 INFO - 'SHELL': '/bin/bash', 17:38:00 INFO - 'SHLVL': '1', 17:38:00 INFO - 'TERM': 'linux', 17:38:00 INFO - 'TMOUT': '86400', 17:38:00 INFO - 'USER': 'cltbld', 17:38:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', 17:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:00 INFO - '_': '/tools/buildbot/bin/python'} 17:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:00 INFO - Downloading/unpacking psutil>=3.1.1 17:38:00 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 17:38:00 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 17:38:00 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 17:38:00 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 17:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:38:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:38:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:38:01 INFO - Installing collected packages: psutil 17:38:01 INFO - Running setup.py install for psutil 17:38:01 INFO - building 'psutil._psutil_linux' extension 17:38:01 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 17:38:01 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 17:38:01 INFO - building 'psutil._psutil_posix' extension 17:38:01 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 17:38:02 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 17:38:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:38:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:38:02 INFO - Successfully installed psutil 17:38:02 INFO - Cleaning up... 17:38:02 INFO - Return code: 0 17:38:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:02 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1762d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175feb0>, '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 0x1760db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1761240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', '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 17:38:02 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 17:38:02 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 17:38:02 INFO - Using env: {'DISPLAY': ':0', 17:38:02 INFO - 'HOME': '/home/cltbld', 17:38:02 INFO - 'LANG': 'en_US.UTF-8', 17:38:02 INFO - 'LANGUAGE': 'en_US:en', 17:38:02 INFO - 'LOGNAME': 'cltbld', 17:38:02 INFO - 'MAIL': '/var/mail/cltbld', 17:38:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:02 INFO - 'MOZ_NO_REMOTE': '1', 17:38:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:02 INFO - 'NO_EM_RESTART': '1', 17:38:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:02 INFO - 'PWD': '/builds/slave/test', 17:38:02 INFO - 'SHELL': '/bin/bash', 17:38:02 INFO - 'SHLVL': '1', 17:38:02 INFO - 'TERM': 'linux', 17:38:02 INFO - 'TMOUT': '86400', 17:38:02 INFO - 'USER': 'cltbld', 17:38:02 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', 17:38:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:02 INFO - '_': '/tools/buildbot/bin/python'} 17:38:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:38: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 17:38: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 17:38: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 17:38: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 17:38:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:38:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:38:03 INFO - Installing collected packages: mozsystemmonitor 17:38:03 INFO - Running setup.py install for mozsystemmonitor 17:38:03 INFO - Successfully installed mozsystemmonitor 17:38:03 INFO - Cleaning up... 17:38:03 INFO - Return code: 0 17:38:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:38:03 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')]} 17:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:03 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')]} 17:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:03 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1762d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175feb0>, '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 0x1760db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1761240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', '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 17:38:03 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 17:38:03 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 17:38:03 INFO - Using env: {'DISPLAY': ':0', 17:38:03 INFO - 'HOME': '/home/cltbld', 17:38:03 INFO - 'LANG': 'en_US.UTF-8', 17:38:03 INFO - 'LANGUAGE': 'en_US:en', 17:38:03 INFO - 'LOGNAME': 'cltbld', 17:38:03 INFO - 'MAIL': '/var/mail/cltbld', 17:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:03 INFO - 'MOZ_NO_REMOTE': '1', 17:38:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:03 INFO - 'NO_EM_RESTART': '1', 17:38:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:03 INFO - 'PWD': '/builds/slave/test', 17:38:03 INFO - 'SHELL': '/bin/bash', 17:38:03 INFO - 'SHLVL': '1', 17:38:03 INFO - 'TERM': 'linux', 17:38:03 INFO - 'TMOUT': '86400', 17:38:03 INFO - 'USER': 'cltbld', 17:38:03 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', 17:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:03 INFO - '_': '/tools/buildbot/bin/python'} 17:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:03 INFO - Downloading/unpacking jsonschema==2.5.1 17:38:03 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 17:38:03 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 17:38:03 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 17:38:03 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 17:38:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:38:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:38:05 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 17:38:05 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 17:38:05 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 17:38:05 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 17:38:05 INFO - Downloading functools32-3.2.3-2.tar.gz 17:38:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:38:05 INFO - warning: no files found matching '*.txt' 17:38:05 INFO - no previously-included directories found matching 'build' 17:38:05 INFO - no previously-included directories found matching 'dist' 17:38:05 INFO - no previously-included directories found matching '.git*' 17:38:05 INFO - Installing collected packages: jsonschema, functools32 17:38:05 INFO - Running setup.py install for functools32 17:38:05 INFO - warning: no files found matching '*.txt' 17:38:05 INFO - no previously-included directories found matching 'build' 17:38:05 INFO - no previously-included directories found matching 'dist' 17:38:05 INFO - no previously-included directories found matching '.git*' 17:38:05 INFO - Successfully installed jsonschema functools32 17:38:05 INFO - Cleaning up... 17:38:05 INFO - Return code: 0 17:38:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:05 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1762d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175feb0>, '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 0x1760db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1761240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', '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 17:38:05 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 17:38:05 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 17:38:05 INFO - Using env: {'DISPLAY': ':0', 17:38:05 INFO - 'HOME': '/home/cltbld', 17:38:05 INFO - 'LANG': 'en_US.UTF-8', 17:38:05 INFO - 'LANGUAGE': 'en_US:en', 17:38:05 INFO - 'LOGNAME': 'cltbld', 17:38:05 INFO - 'MAIL': '/var/mail/cltbld', 17:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:05 INFO - 'MOZ_NO_REMOTE': '1', 17:38:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:05 INFO - 'NO_EM_RESTART': '1', 17:38:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:05 INFO - 'PWD': '/builds/slave/test', 17:38:05 INFO - 'SHELL': '/bin/bash', 17:38:05 INFO - 'SHLVL': '1', 17:38:05 INFO - 'TERM': 'linux', 17:38:05 INFO - 'TMOUT': '86400', 17:38:05 INFO - 'USER': 'cltbld', 17:38:05 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', 17:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:05 INFO - '_': '/tools/buildbot/bin/python'} 17:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:38:05 INFO - Cleaning up... 17:38:05 INFO - Return code: 0 17:38:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:05 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1762d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175feb0>, '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 0x1760db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1761240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', '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 17:38:05 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 17:38:05 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 17:38:05 INFO - Using env: {'DISPLAY': ':0', 17:38:05 INFO - 'HOME': '/home/cltbld', 17:38:05 INFO - 'LANG': 'en_US.UTF-8', 17:38:05 INFO - 'LANGUAGE': 'en_US:en', 17:38:05 INFO - 'LOGNAME': 'cltbld', 17:38:05 INFO - 'MAIL': '/var/mail/cltbld', 17:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:05 INFO - 'MOZ_NO_REMOTE': '1', 17:38:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:05 INFO - 'NO_EM_RESTART': '1', 17:38:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:05 INFO - 'PWD': '/builds/slave/test', 17:38:05 INFO - 'SHELL': '/bin/bash', 17:38:05 INFO - 'SHLVL': '1', 17:38:05 INFO - 'TERM': 'linux', 17:38:05 INFO - 'TMOUT': '86400', 17:38:05 INFO - 'USER': 'cltbld', 17:38:05 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', 17:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:05 INFO - '_': '/tools/buildbot/bin/python'} 17:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:05 INFO - Downloading/unpacking blobuploader==1.2.4 17:38:05 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 17:38:05 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 17:38:05 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 17:38:05 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 17:38:06 INFO - Downloading blobuploader-1.2.4.tar.gz 17:38:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:38:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:38:07 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 17:38:07 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 17:38:07 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 17:38:07 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 17:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:38:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:38:07 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 17:38:07 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 17:38:07 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 17:38:07 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 17:38:07 INFO - Downloading docopt-0.6.1.tar.gz 17:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:38:07 INFO - Installing collected packages: blobuploader, requests, docopt 17:38:07 INFO - Running setup.py install for blobuploader 17:38:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:38:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:38:07 INFO - Running setup.py install for requests 17:38:08 INFO - Running setup.py install for docopt 17:38:08 INFO - Successfully installed blobuploader requests docopt 17:38:08 INFO - Cleaning up... 17:38:08 INFO - Return code: 0 17:38:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:08 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1762d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175feb0>, '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 0x1760db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1761240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', '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 17:38:08 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 17:38:08 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 17:38:08 INFO - Using env: {'DISPLAY': ':0', 17:38:08 INFO - 'HOME': '/home/cltbld', 17:38:08 INFO - 'LANG': 'en_US.UTF-8', 17:38:08 INFO - 'LANGUAGE': 'en_US:en', 17:38:08 INFO - 'LOGNAME': 'cltbld', 17:38:08 INFO - 'MAIL': '/var/mail/cltbld', 17:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:08 INFO - 'MOZ_NO_REMOTE': '1', 17:38:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:08 INFO - 'NO_EM_RESTART': '1', 17:38:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:08 INFO - 'PWD': '/builds/slave/test', 17:38:08 INFO - 'SHELL': '/bin/bash', 17:38:08 INFO - 'SHLVL': '1', 17:38:08 INFO - 'TERM': 'linux', 17:38:08 INFO - 'TMOUT': '86400', 17:38:08 INFO - 'USER': 'cltbld', 17:38:08 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', 17:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:08 INFO - '_': '/tools/buildbot/bin/python'} 17:38:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:38:08 INFO - Running setup.py (path:/tmp/pip-ek0lEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:38:08 INFO - Running setup.py (path:/tmp/pip-Ez6JLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:38:08 INFO - Running setup.py (path:/tmp/pip-RnRW_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:38:08 INFO - Running setup.py (path:/tmp/pip-J5geSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:38:08 INFO - Running setup.py (path:/tmp/pip-mD63Wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:38:08 INFO - Running setup.py (path:/tmp/pip-D4L2Fx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:38:08 INFO - Running setup.py (path:/tmp/pip-iHkcZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:38:08 INFO - Running setup.py (path:/tmp/pip-sFpVdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:38:08 INFO - Running setup.py (path:/tmp/pip-yFzXQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:38:09 INFO - Running setup.py (path:/tmp/pip-Cd3KlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:38:09 INFO - Running setup.py (path:/tmp/pip-3ngkWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:38:09 INFO - Running setup.py (path:/tmp/pip-Tve4NU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:38:09 INFO - Running setup.py (path:/tmp/pip-m5pJsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:38:09 INFO - Running setup.py (path:/tmp/pip-hQcid7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:09 INFO - Running setup.py (path:/tmp/pip-_IhuMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:38:09 INFO - Running setup.py (path:/tmp/pip-Kn1d1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:38:09 INFO - Running setup.py (path:/tmp/pip-ko5rnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:38:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:38:09 INFO - Running setup.py install for manifestparser 17:38:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:38:09 INFO - Running setup.py install for mozcrash 17:38:09 INFO - Running setup.py install for mozdebug 17:38:09 INFO - Running setup.py install for mozdevice 17:38:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Running setup.py install for mozfile 17:38:10 INFO - Running setup.py install for mozhttpd 17:38:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Running setup.py install for mozinfo 17:38:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Running setup.py install for mozInstall 17:38:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Running setup.py install for mozleak 17:38:10 INFO - Running setup.py install for mozlog 17:38:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Running setup.py install for moznetwork 17:38:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Running setup.py install for mozprocess 17:38:10 INFO - Running setup.py install for mozprofile 17:38:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:38:10 INFO - Running setup.py install for mozrunner 17:38:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:38:11 INFO - Running setup.py install for mozscreenshot 17:38:11 INFO - Running setup.py install for moztest 17:38:11 INFO - Running setup.py install for mozversion 17:38:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:38:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:38:11 INFO - Cleaning up... 17:38:11 INFO - Return code: 0 17:38:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:11 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1762d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175feb0>, '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 0x1760db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1761240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', '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 17:38:11 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 17:38:11 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 17:38:11 INFO - Using env: {'DISPLAY': ':0', 17:38:11 INFO - 'HOME': '/home/cltbld', 17:38:11 INFO - 'LANG': 'en_US.UTF-8', 17:38:11 INFO - 'LANGUAGE': 'en_US:en', 17:38:11 INFO - 'LOGNAME': 'cltbld', 17:38:11 INFO - 'MAIL': '/var/mail/cltbld', 17:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:11 INFO - 'MOZ_NO_REMOTE': '1', 17:38:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:11 INFO - 'NO_EM_RESTART': '1', 17:38:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:11 INFO - 'PWD': '/builds/slave/test', 17:38:11 INFO - 'SHELL': '/bin/bash', 17:38:11 INFO - 'SHLVL': '1', 17:38:11 INFO - 'TERM': 'linux', 17:38:11 INFO - 'TMOUT': '86400', 17:38:11 INFO - 'USER': 'cltbld', 17:38:11 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', 17:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:11 INFO - '_': '/tools/buildbot/bin/python'} 17:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:38:11 INFO - Running setup.py (path:/tmp/pip-T_d_4F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:38:11 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)) 17:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:38:11 INFO - Running setup.py (path:/tmp/pip-Ifngc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:38:11 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)) 17:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:38:11 INFO - Running setup.py (path:/tmp/pip-pnecPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:38:11 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)) 17:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:38:11 INFO - Running setup.py (path:/tmp/pip-Iq9AMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:38:11 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)) 17:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:38:11 INFO - Running setup.py (path:/tmp/pip-nKKapH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:38:11 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)) 17:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:38:11 INFO - Running setup.py (path:/tmp/pip-mP0Ybi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:38:12 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)) 17:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:38:12 INFO - Running setup.py (path:/tmp/pip-ccmXh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:38:12 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)) 17:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:38:12 INFO - Running setup.py (path:/tmp/pip-KBg8fO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:38:12 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)) 17:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:38:12 INFO - Running setup.py (path:/tmp/pip-J1Gwtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:38:12 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)) 17:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:38:12 INFO - Running setup.py (path:/tmp/pip-skrBmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:38:12 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)) 17:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:38:12 INFO - Running setup.py (path:/tmp/pip-MyCP4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:38:12 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)) 17:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:38:12 INFO - Running setup.py (path:/tmp/pip-pz8pKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:38:12 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)) 17:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:38:12 INFO - Running setup.py (path:/tmp/pip-cntQTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:38:12 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)) 17:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:38:12 INFO - Running setup.py (path:/tmp/pip-yjlVlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:38:12 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)) 17:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:12 INFO - Running setup.py (path:/tmp/pip-WczKPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:38:12 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)) 17:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:38:12 INFO - Running setup.py (path:/tmp/pip-gsFCd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:38:12 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)) 17:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:38:12 INFO - Running setup.py (path:/tmp/pip-ac_lWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:38:12 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)) 17:38:12 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)) 17:38:12 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)) 17:38:12 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)) 17:38:12 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)) 17:38:12 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)) 17:38:12 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)) 17:38:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:38:12 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 17:38:12 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 17:38:12 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 17:38:12 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 17:38:13 INFO - Downloading blessings-1.6.tar.gz 17:38:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:38:14 INFO - Installing collected packages: blessings 17:38:14 INFO - Running setup.py install for blessings 17:38:14 INFO - Successfully installed blessings 17:38:14 INFO - Cleaning up... 17:38:14 INFO - Return code: 0 17:38:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:38:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:38:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:38:14 INFO - Reading from file tmpfile_stdout 17:38:14 INFO - Current package versions: 17:38:14 INFO - argparse == 1.2.1 17:38:14 INFO - blessings == 1.6 17:38:14 INFO - blobuploader == 1.2.4 17:38:14 INFO - docopt == 0.6.1 17:38:14 INFO - functools32 == 3.2.3-2 17:38:14 INFO - jsonschema == 2.5.1 17:38:14 INFO - manifestparser == 1.1 17:38:14 INFO - mozInstall == 1.13 17:38:14 INFO - mozcrash == 1.0 17:38:14 INFO - mozdebug == 0.1 17:38:14 INFO - mozdevice == 0.51 17:38:14 INFO - mozfile == 1.2 17:38:14 INFO - mozhttpd == 0.7 17:38:14 INFO - mozinfo == 0.10 17:38:14 INFO - mozleak == 0.1 17:38:14 INFO - mozlog == 3.5 17:38:14 INFO - moznetwork == 0.27 17:38:14 INFO - mozprocess == 0.25 17:38:14 INFO - mozprofile == 0.28 17:38:14 INFO - mozrunner == 6.13 17:38:14 INFO - mozscreenshot == 0.1 17:38:14 INFO - mozsystemmonitor == 0.3 17:38:14 INFO - moztest == 0.8 17:38:14 INFO - mozversion == 1.4 17:38:14 INFO - psutil == 3.1.1 17:38:14 INFO - requests == 1.2.3 17:38:14 INFO - wsgiref == 0.1.2 17:38:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38:14 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')]} 17:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:14 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')]} 17:38:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:14 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1762d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175feb0>, '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 0x1760db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1761240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', '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 17:38:14 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 17:38:14 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 17:38:14 INFO - Using env: {'DISPLAY': ':0', 17:38:14 INFO - 'HOME': '/home/cltbld', 17:38:14 INFO - 'LANG': 'en_US.UTF-8', 17:38:14 INFO - 'LANGUAGE': 'en_US:en', 17:38:14 INFO - 'LOGNAME': 'cltbld', 17:38:14 INFO - 'MAIL': '/var/mail/cltbld', 17:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:14 INFO - 'MOZ_NO_REMOTE': '1', 17:38:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:14 INFO - 'NO_EM_RESTART': '1', 17:38:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:14 INFO - 'PWD': '/builds/slave/test', 17:38:14 INFO - 'SHELL': '/bin/bash', 17:38:14 INFO - 'SHLVL': '1', 17:38:14 INFO - 'TERM': 'linux', 17:38:14 INFO - 'TMOUT': '86400', 17:38:14 INFO - 'USER': 'cltbld', 17:38:14 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', 17:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:14 INFO - '_': '/tools/buildbot/bin/python'} 17:38:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:14 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)) 17:38:14 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)) 17:38:14 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)) 17:38:14 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)) 17:38:14 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)) 17:38:14 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)) 17:38:14 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)) 17:38:14 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)) 17:38:14 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)) 17:38:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:38:14 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 17:38:14 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 17:38:14 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 17:38:14 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 17:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:38:15 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)) 17:38:15 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)) 17:38:15 INFO - Installing collected packages: simplejson 17:38:15 INFO - Running setup.py install for simplejson 17:38:15 INFO - building 'simplejson._speedups' extension 17:38:15 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 17:38:16 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 17:38:16 INFO - Successfully installed simplejson 17:38:16 INFO - Cleaning up... 17:38:16 INFO - Return code: 0 17:38:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:38:16 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')]} 17:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:16 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')]} 17:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:16 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 0x13f31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15bfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1762d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x175feb0>, '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 0x1760db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1761240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', '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 17:38:16 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 17:38:16 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 17:38:16 INFO - Using env: {'DISPLAY': ':0', 17:38:16 INFO - 'HOME': '/home/cltbld', 17:38:16 INFO - 'LANG': 'en_US.UTF-8', 17:38:16 INFO - 'LANGUAGE': 'en_US:en', 17:38:16 INFO - 'LOGNAME': 'cltbld', 17:38:16 INFO - 'MAIL': '/var/mail/cltbld', 17:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:16 INFO - 'MOZ_NO_REMOTE': '1', 17:38:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:16 INFO - 'NO_EM_RESTART': '1', 17:38:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:16 INFO - 'PWD': '/builds/slave/test', 17:38:16 INFO - 'SHELL': '/bin/bash', 17:38:16 INFO - 'SHLVL': '1', 17:38:16 INFO - 'TERM': 'linux', 17:38:16 INFO - 'TMOUT': '86400', 17:38:16 INFO - 'USER': 'cltbld', 17:38:16 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', 17:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:16 INFO - '_': '/tools/buildbot/bin/python'} 17:38:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:38:17 INFO - Cleaning up... 17:38:17 INFO - Return code: 0 17:38:17 INFO - Running post-action listener: _resource_record_post_action 17:38:17 INFO - Running post-action listener: _start_resource_monitoring 17:38:17 INFO - Starting resource monitoring. 17:38:17 INFO - [mozharness: 2017-08-01 00:38:17.127021Z] Finished create-virtualenv step (success) 17:38:17 INFO - [mozharness: 2017-08-01 00:38:17.127483Z] Running install step. 17:38:17 INFO - Running pre-action listener: _resource_record_pre_action 17:38:17 INFO - Running main action method: install 17:38:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:38:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:38:17 INFO - Reading from file tmpfile_stdout 17:38:17 INFO - Detecting whether we're running mozinstall >=1.0... 17:38:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:38:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:38:17 INFO - Reading from file tmpfile_stdout 17:38:17 INFO - Output received: 17:38:17 INFO - Usage: mozinstall [options] installer 17:38:17 INFO - Options: 17:38:17 INFO - -h, --help show this help message and exit 17:38:17 INFO - -d DEST, --destination=DEST 17:38:17 INFO - Directory to install application into. [default: 17:38:17 INFO - "/builds/slave/test"] 17:38:17 INFO - --app=APP Application being installed. [default: firefox] 17:38:17 INFO - mkdir: /builds/slave/test/build/application 17:38:17 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'] 17:38:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:38:35 INFO - Reading from file tmpfile_stdout 17:38:35 INFO - Output received: 17:38:35 INFO - /builds/slave/test/build/application/firefox/firefox 17:38:35 INFO - Running post-action listener: _resource_record_post_action 17:38:35 INFO - [mozharness: 2017-08-01 00:38:35.541848Z] Finished install step (success) 17:38:35 INFO - [mozharness: 2017-08-01 00:38:35.542101Z] Running setup-mitmproxy step. 17:38:35 INFO - Running pre-action listener: _resource_record_pre_action 17:38:35 INFO - Running main action method: setup_mitmproxy 17:38:35 INFO - Skipping: mitmproxy is not required 17:38:35 INFO - Running post-action listener: _resource_record_post_action 17:38:35 INFO - [mozharness: 2017-08-01 00:38:35.543235Z] Finished setup-mitmproxy step (success) 17:38:35 INFO - [mozharness: 2017-08-01 00:38:35.543456Z] Running run-tests step. 17:38:35 INFO - Running pre-action listener: _resource_record_pre_action 17:38:35 INFO - Running pre-action listener: _set_gcov_prefix 17:38:35 INFO - Running main action method: run_tests 17:38:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:38:35 INFO - Python 2.7.3 17:38:35 INFO - Return code: 0 17:38:35 INFO - grabbing minidump binary from tooltool 17:38:35 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')]} 17:38:35 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 0x175feb0>, '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 0x1760db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1761240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:38:35 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 17:38:35 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 17:38:35 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 17:38:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:38:35 INFO - Return code: 0 17:38:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:38:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:38:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:38:35 INFO - ENV: RUST_BACKTRACE is now 1 17:38:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:38:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:38:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-047 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:38:35 INFO - Using env: {'DISPLAY': ':0', 17:38:35 INFO - 'HOME': '/home/cltbld', 17:38:35 INFO - 'LANG': 'en_US.UTF-8', 17:38:35 INFO - 'LANGUAGE': 'en_US:en', 17:38:35 INFO - 'LOGNAME': 'cltbld', 17:38:35 INFO - 'MAIL': '/var/mail/cltbld', 17:38:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:38:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:35 INFO - 'MOZ_NO_REMOTE': '1', 17:38:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:38:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:38:35 INFO - 'NO_EM_RESTART': '1', 17:38:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:35 INFO - 'PWD': '/builds/slave/test', 17:38:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:38:35 INFO - 'RUST_BACKTRACE': '1', 17:38:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:38:35 INFO - 'SHELL': '/bin/bash', 17:38:35 INFO - 'SHLVL': '1', 17:38:35 INFO - 'STYLO_FORCE_ENABLED': '1', 17:38:35 INFO - 'TERM': 'linux', 17:38:35 INFO - 'TMOUT': '86400', 17:38:35 INFO - 'USER': 'cltbld', 17:38:35 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475', 17:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:35 INFO - '_': '/tools/buildbot/bin/python'} 17:38:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:38:35 INFO - mozversion application_buildid: 20170731234331 17:38:35 INFO - mozversion application_changeset: 8b19670d12fde57d3aee50a5a7d1c734d9b709d5 17:38:35 INFO - mozversion application_display_name: Nightly 17:38:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:38:35 INFO - mozversion application_name: Firefox 17:38:35 INFO - mozversion application_remotingname: firefox 17:38:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:38:35 INFO - mozversion application_vendor: Mozilla 17:38:35 INFO - mozversion application_version: 56.0a1 17:38:35 INFO - mozversion platform_buildid: 20170731234331 17:38:35 INFO - mozversion platform_changeset: 8b19670d12fde57d3aee50a5a7d1c734d9b709d5 17:38:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:38:35 INFO - mozversion platform_version: 56.0a1 17:38:35 INFO - using testdate: 1501547915 17:38:35 INFO - actual date: 1501547915 17:38:35 INFO - starting webserver on 'localhost:48447' 17:38:35 INFO - SUITE-START | Running 2 tests 17:38:35 INFO - TEST-START | dromaeo_css 17:38:35 INFO - Initialising browser for dromaeo_css test... 17:38:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48447/getInfo.html -profile /tmp/tmpwc1bVK/profile 17:38:35 INFO - TEST-INFO | started process 22757 (/builds/slave/test/build/application/firefox/firefox http://localhost:48447/getInfo.html) 17:38:41 INFO - TEST-INFO | 22757: exit 0 17:38:41 INFO - Browser initialized. 17:38:41 INFO - Running cycle 1/1 for dromaeo_css test... 17:38:41 INFO - TEST-INFO | started process 22984 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwc1bVK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:38:42 INFO - PID 22984 | 17:38:42 INFO - PID 22984 | (/builds/slave/test/build/application/firefox/firefox:23038): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:38:42 INFO - PID 22984 | 17:38:53 INFO - PID 22984 | RSS: Main: 174182400 17:38:53 INFO - PID 22984 | 17:41:53 INFO - PID 22984 | Cycle 1(1): loaded http://localhost:48447/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48447/tests/dromaeo/cssquery-ext.html) 17:41:54 INFO - PID 22984 | RSS: Main: 176144384 17:41:54 INFO - PID 22984 | 17:44:56 INFO - PID 22984 | Cycle 1(1): loaded http://localhost:48447/tests/dromaeo/cssquery-ext.html (next: http://localhost:48447/tests/dromaeo/cssquery-jquery.html) 17:44:57 INFO - PID 22984 | RSS: Main: 173617152 17:44:57 INFO - PID 22984 | 17:47:59 INFO - PID 22984 | Cycle 1(1): loaded http://localhost:48447/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48447/tests/dromaeo/cssquery-mootools.html) 17:47:59 INFO - PID 22984 | RSS: Main: 175882240 17:47:59 INFO - PID 22984 | 17:51:01 INFO - PID 22984 | Cycle 1(1): loaded http://localhost:48447/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48447/tests/dromaeo/cssquery-prototype.html) 17:51:02 INFO - PID 22984 | RSS: Main: 174047232 17:51:02 INFO - PID 22984 | 17:54:05 INFO - PID 22984 | Cycle 1(1): loaded http://localhost:48447/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48447/tests/dromaeo/cssquery-yui.html) 17:54:06 INFO - PID 22984 | RSS: Main: 175652864 17:54:06 INFO - PID 22984 | 17:57:08 INFO - PID 22984 | Cycle 1(1): loaded http://localhost:48447/tests/dromaeo/cssquery-yui.html (next: http://localhost:48447/tests/dromaeo/cssquery-dojo.html) 17:57:08 INFO - PID 22984 | RSS: Main: 176328704 17:57:08 INFO - PID 22984 | 17:57:08 INFO - PID 22984 | __start_tp_report 17:57:08 INFO - PID 22984 | _x_x_mozilla_page_load 17:57:08 INFO - PID 22984 | _x_x_mozilla_page_load_details 17:57:08 INFO - PID 22984 | |i|pagename|runs| 17:57:08 INFO - PID 22984 | |0;dojo.html;3141.5601815745786;3145.81125132492;3160.2257446925505;3161.0674850918977;3201.2157021529715;6113.510919126459;6144.078388241766;6167.876642467154;6185.938140618592;6212.565120441571;5960.105984102376;6094.542909281799;6104.816855494331;6105.053716673911;6147.938520614804;5584.888302233953;5620.100783874581;5622.32532096149;5669.09294512878;5682.687452190128;5682.602217844753;5687.886242275152;5700.344460387047;5702.486776190142;5731.226284451608;5663.235463212475;5677.772889084432;5716.1997320375185;5769.682667453289;5782.97108514459;5577.358603760579;5644.322681278252;5673.602847800656;5728.68492232927;5735.799247026334;5227.3465816772905;5487.862803429906;5605;5626.099824028177;5653.886922261573;6047.274327080779;6059;6096.969515152396;6119.082137679339;6142.6314421134875;6573.57271777333;6644.43522300605;6665.366790154928;6676.899846502306;6683.632400217986;6625.5693379930635;6635.8009259722285;6661.866762664768;6750.392464678153;6779.050932869353;6072.51419886408;6164.044573091178;6167.814965551041;6169.475594574466;6187.535934804862;6747.224069232003;6750.189977202767;6764.594124352456;6876.346747059021;6878.38094571491;6436.903446448307;6500;6525.575837570543;6528.510361722795;6540.084388185658;6563.8359041023405;6612.86774264512;6639.170103737033;6658.533902626769;6670.633115178712;6555.639439830759;6569.9343006569325;6611.768588099393;6651.534392592569;6665.500087493458;6512.837179070561;6589.439897608661;6638.601683898982;6694.799156025327;6696.531242812956;5377.892442151213;5445.346558413015;5453.454654534515;5458.672479651154;5480;4692.249240121564;4704.15325241453;4743.241081426955;4752.643551733565;4781.713097214179;5798.623089499169;5862.325832529314;5884.882302354014;5885.146653735184;5890.852728681732;6483.3516648335735;6621.635810030494;6622.37087476689;6632.5688830226845;6747.224069232101;5728.341240757283;5741.712914354265;5752.395998420189;5766.826995190151;5771.278590176228;5799.681017544076;5802.854928626733;5803.245578074823;5807.186993820784;5838.562107841929;6344.302126766052;6415.230172379344;6416.839579010562;6435.485161187094;6518.120053792677;6220.440160385587;6598.901016484852;6610;6631.568948018364;6637.103990961158;6465.709043093166;6466.030095485715;6469.159009328757;6482.027695845661;6491.091247225298;6516.902246466401;6519;6522.608643481406;6553.246376666553;6565.409113179837;6628.138342015507;6643.435307998874;6683.866322673616;6747.460203183831;6771.830704232433;6229.314775374796;6367.777127800505;6368.140301059483;6391.360863913572;6515.478761698958;6418.358164183544;6429;6434.774782882764;6438.130852334875;6445.4199122079235;6242.3133455320785;6339.1125242467015;6359.141515895295;6398.360163983564;6409.359064093554;6325.272593651789;6413.615183089029;6439;6521.543491955517;6541.771038013837;6226.875462490815;6288.685565721604;6300.62196268207;6339.619622822645;6346.619202847659 17:57:08 INFO - PID 22984 | |1;ext.html;758.8634045871744;817.8527864984302;839.5466448118015;847.4364547575866;1165.8134698448239;29836.254093647643;30314;39601;40503.392449113286;40820;40810.38784418236;41269.7936510317;41412.79293603528;41458.37812432808;41461.58538414615;7533.849323013536;7658.61706914652;7687.884681729751;7701.922980770205;7740;7404.703811847519;7526.5107767994905;7572.962135189344;7574.242575742409;7589.620518974029;7493.925060749377;7559.773206803778;7560.4707670463085;7562.773116806477;7564.508306960058;7495.550266983944;7505.924940750577;7565.697372105109;7579.052618422697;7594.73418430355;7381.852362952764;7472.775816725507;7484.251574842527;7488.288612581796;7510.399168066541;37403.438948415795;37407;37417;37561.81219093914;37628.81185594082;3489.3544694231396;3519.208178159919;3520;3523.5067090607336;3534.1341371363924;30204.24489387761;30303.545446818316;30393;30593.388132237455;30632.8468357659;4588.839390621312;4687.695299805502;4773.3078703588135;4878.146324393252;4896.387951506062;183798;183989;184330;184659;185094;45978.31032534515;46212.5378746215;46303.30545041827;46545;47344.28983565249;49052.50947490479;49320.506794932306;49322.753386233555;49339;49490.25764613534;14081.225532595805;14349.426022959173;14393.208373539346;14450.638734031523;14505.492307769178;10034.799304014025;10188.184945204366;11266.267692599955;11482.770344593062;11617.302961822317;14203.076800007966;14309.356078976263;14313.28433578317;14324.427022918966;14486.710265794836;5617.747201375948;5618.943810561924;5665.1502274658305;5672.404397538199;5698.971505142531;974.3423189347162;986.3785814936545;991.4447909170888;1019.9439030853226;1022.1311884897926;21927.67108493374;21948.34154975353;22403.10387584511;22982.341595187383;23030.769692303198;1061.508810033959;1094.480121942071;1104.5802595013843;1110.4836251143256;1114.8327750837277;11166.665000050012;12059.819102713467;12253.326067066218;15690.607734806494;16207.351705931867;239705;243193;244940;245252;245464;54773.45226547763;54904.450955490734;55246;55250.72374638182;55426.72286638541;1439.978400323996;1462.2542503323168;1481.4814814814729;1487.3084015932643;1491.0978857791097;26041.348966275993;27517.86241068782;27584.310392240353;27752.44495110046;27854.582181267702;35721.82139089392;36270.45594316035;36474.635253648186;36507.634923650425;36536.81731591325;215517;216440;216667;216794;217381;32285.677143228266;33252.1686957828;33707.15732106619;33723.32553348968;33925.83037084799;38614;39722.2055558893;40262.39606405848;40317.1936561273;40594;23466.882665586563;24067.638985414884;24937.750622493542;25046.62430063514;25869.22392328233;22350;23602.29193124209;24207;24360;24547.877260613583;33539.83230083834;33686;33937.15157120993;34053.31893362168;34150;27942.3014424641;29244;29798.25504362408;30321.545176821925;31354.68645313518;23826.642600361356;24759.87620061888;24890.875545622155;24964.500709986063;25225.24324270275 17:57:08 INFO - PID 22984 | |2;jquery.html;566.3996164066087;569.6240481282354;575.3010092737323;578.6094386289253;582.1675004743219;21047.2633457829;21814.781852181473;22003.22988695396;22009.559808803835;22031.559368812614;27088.72911270887;27637.170884873434;27652.308692282702;27688.584671229946;27765.444691106164;6848.999999999987;7052.788416347492;7062.576245425265;7066.929330706678;7075.540089894166;6161.969190154043;6785.2196997345445;6823.147106611674;6854.862902741942;6856;6796.320367963189;6844.041834143224;6851.109355783766;6869.141357330333;6878.449724022092;6836.726530938757;6841.760538381132;6845.247022827485;6875.106236189314;6881;6711.630860302681;6725.630090345029;6727.865442691168;6735.93264067358;6737.831554211184;30695.232619184477;31352.52971205411;31573;31651.841740791147;31779.682203177905;1922.1734654098732;1960.7941166177632;1966.4395647240467;1967.7835438101797;1968.5767559974531;19630.90184549068;20385.18459261628;20398.79601203984;20409.897950510152;20418.285360012476;2412.300432874464;2415.2995631267086;2484.4534202475425;2488.5645012122986;2490.5018996200833;131614;133194.33402832923;136169;136894;137340.31329843285;20455.8977205116;21163.365099047052;21751.891240544013;21942.561148776935;22592.66111008336;43718.78140609276;44026;44072;44085;44159.77920110379;11035.227534072537;11097.44512774358;11164.441777911234;11225.775484490265;11314.943425282821;7876.527408355517;9094.954525227331;9097.81804363909;9143.497107659143;9272.860907086399;9918.454485003249;11528.76942461146;11566.363849988331;11863.940680296544;11920.701982450508;4016.357382818735;4105.014796448891;4271.834960913147;4379.255526560492;4384.276594361958;963.9708413789014;965.2229954886384;972.6352617768337;995.5022488755623;997.3816233935007;19788.40634780959;19939.401817945483;19954.20183192685;20101.698474522895;20717;1018.4449475036123;1023.8617786598713;1037.2065369991963;1039.750459889621;1044.1385856668278;10756.247062705692;13074.346282685829;13792.862071379359;14050.086744361584;14452.710945781026;523108;527886;545293;546661;547472;122631.38684306521;122797;123877.38061309815;124565;125343.37328313483;1236.2397125767588;1266.8796464335874;1279.1749321687646;1287.2405280884136;1295.1905059337914;20012.699809502577;21003.57992840165;21725.239616613344;21727.19710290788;21958.23146189876;55301.170482441994;57474.85050299054;59813.40186598252;60495;61152.694236528536;76017.61991190008;76261.61869190619;77499.61250193712;78011.60994194992;78468;12226.20529665569;12668.936655316664;13671.248081355621;14691.11853288806;15019.249037548298;21491.892540537196;21858.234961776263;21926;22130.557388851812;22437;17806.643867122842;18311.450656480327;18395.724064139315;18469.63060738751;18526;12877.162984405984;13123.556408795215;13159.934200328937;13705.725885482434;13925.860741392862;17661;18100.818991809912;18299.1765370556;18897.43307700771;19250.614987699886;14535.25576930775;14598.562043138723;14640.780388294397;14674.706505870035;14763.188024658746;18034;18098.457046288633;18253.269869205615;18282.634347313244;18362.449126526226 17:57:08 INFO - PID 22984 | |3;mootools.html;1391.9582412527625;1420.3395421129173;1432.9426822927082;1440.4814266863928;1446.2696338349133;13635.318234088292;13995.510157144501;14329.06861054032;14436.13383197009;14457.638559036017;14473.5657930262;14727.631809204775;14735.115893046424;14739.557813265592;14748.188849613294;2593.36462566671;2637.591173368131;2653.177514970356;2654.336415896026;2654.3894904172053;2526.6589010483626;2535.6196570514385;2577.6262441945905;2617.908373206938;2630.4607555451084;2632.2498088044913;2682.8866020601426;2683.9597406038924;2684.4228490874457;2687.126683827754;2565.179142674345;2577.6004719268544;2583.987080064597;2585.1598230575046;2593.572060609997;2487.8880450379775;2499.512595043967;2516.3834860459124;2517.0561039610147;2526.84838909666;13762.174269543859;13952;14002.299885005708;14061.437542498288;14071.507497237548;7389.482736208468;7682.846343073164;7687.6156192190165;7716;7731.45879788415;4888.657793954425;5040.067587496325;5072.72100034498;5175.275461435402;5185.5333020028;1701.1154199816026;1703.7444383342474;1718.2783231042993;1748.7376893465955;1766.381766381769;23137.190198342978;23426.76573234263;23462.17882374126;23465.41336466585;23509.41226469335;6419;6428.839279017969;6491.902621460682;6683.097781799492;6709.127813384229;15394.692106157814;15717.685646287238;15858.841411585967;16296.34814607426;16317;7374.963125184339;7616.35261002821;7622.96188519065;7626.084869815658;7627.618619069024;6744.865102697919;6956.826079348057;6967.616781076988;6993.930060699429;7034.577925324497;4994.850154495371;5088.592912566985;5114.64197506171;5158.535731784157;5181.222816577543;1498.1460567476433;1498.5129832804382;1501.8798496120285;1510.637447012731;1517.4082107977897;821.2321479416764;824.3158178711655;830.1159265382469;855.2132038524572;874.5408660453186;6356.269029061625;6536.084948107269;6689.996500524863;6755.864882702417;6837.9658101710165;677.8745932002595;700.3066963705894;716.6524235745659;724.9456290778107;734.944879134067;4101.487314085739;4172.415861779354;4358.193734159191;4406.074724307867;4523.231050721385;18659;19044;19631.214751409785;19684.803151968295;20109;18546.721799173025;19030.52423689391;19080.71378929317;19668.311609093613;19670.508237294183;10286;10713.83929241062;10790.352578845295;10806.35161890289;10823.783524329468;6840.905455127156;7198.460115491254;7623.161452240138;7683.500572462772;8016.478928869605;16649.583760406087;17065.91467042657;17306.307747690455;17510.474685759447;17684.204210810803;16811.41160059392;17232.655346893243;17349.479515614552;17511.82488175153;17582.296708131533;10887.346759194474;11072.833907491555;11164.497597607964;11221.214514983873;11382.54469821198;12393.62819115428;12619.558315458915;12687.682807929876;12713.55502557406;12718.364081796059;8471.32229421657;9073.863892041756;9113.225375842863;9246.306527010636;9486.098820612031;8621.827563448822;9084.500352480676;9159.267258619162;9183.265338773015;9208.493532855757;8727.825443490967;8758.737237882873;8799.47203167812;8799.692010779592;8990.775230619287;6258.780942666985;6590.308017658269;6605.471562275103;6606.174228221473;6630.668466576748;6305.873882522415;6411.166548348685;6556.770513032021;6704.597724136567;6874.828129296808 17:57:08 INFO - PID 22984 | |4;prototype.html;497.27716376507067;500.5464054328313;512.8922926185501;518.1554066871;518.8158203837637;6113.938860611393;6127.938720612792;6153.9999999999945;6160.383961603849;6176.629402235863;5883.205767221424;6035.034394496871;6049;6069.362716914727;6081.270247570286;4936;4937.160682683934;4941.530554597308;4943.530364615356;4955.851324460272;4945.958235685064;4960.900781984358;4964.255361695757;4967.62201702842;4987.975060124694;4716.90566188676;4884.535969082949;4888.41339039314;4902.779374928136;4906.730129842857;4898.330409926581;4904.117258893398;4912.459629440758;4921.876953076184;4939.234418665113;4399.406080179167;4419.513853476115;4428.3800267962515;4432.556744325574;4439.200943830109;5839.737211825478;5893.381194974551;5972.327748230502;5976.8505787355225;5993.430624090704;5621.747021384048;5634.098544232944;5641.238432811559;5648.378678345379;5657.3494048184575;6490.902636460457;6494.798462284492;6507.479401647857;6533.150690410263;6558.770443034471;4812.879678008043;4829.613630909519;4843.190949249462;4844.66087373884;4852.4662287148385;6760.966195169042;6787.16746478448;6840.008198811144;6860.451163906925;6906.689198986058;4907.895723462264;4913.434954980223;4969.155243608595;4987.361753569143;4991.650584459053;4968.403791545038;5009.649324547319;5025.346704928336;5025.864007796672;5041;5597.468240517144;5607.327120745536;5621.80323688669;5634.915476267859;5641.407652196543;6399.632365796063;6423.197100362455;6446.129772480655;6514.413702766774;6571;1606.6706325203538;1711.2299465240692;1733.4972857871328;1733.9219735111951;1747.344745720355;1126.583164229224;1131.620906996149;1167.4104577188466;1197.4312201704295;1206.5776978057609;5690;5707.3721890592005;5773.018586840247;5793.420657934173;5804.709764511842;6456.580322279036;6466.773662921869;6540.182477190351;6554.279029306773;6559.47524198063;5635.69003704792;5672.659640421588;5676.290463692038;5756.30924289088;5779.393163717833;3516.841742121611;3558.323918455485;3574.746980247962;3577.141486043383;3600.567931848195;6472.741090356333;6490.967545162244;6519.045525400635;6527.249366322933;6572.309907459744;6807.421369183675;6825.215100263533;6830.965845170842;6849.760258391032;6854.143232095988;6397.409049335027;6479.222493300834;6495.772647957298;6554.967225163844;6564.934350656432;6630.30381809903;6666.2333831609985;6668.133142691419;6676.699548520425;6687.799366019027;6458.935410646021;6463.482921366373;6479.125318082187;6508.219013718195;6556.311587283267;6476.255230648348;6491.636756281234;6544.247411547733;6547.770828020996;6553.639549824806;4944.975275123746;4945.67853089548;4952.083864485082;4954.727489987941;4956.3804524434445;6727.461803055841;6734.259231484631;6735.797926062226;6736.326367363225;6761.526693131434;6494.870102598016;6494.967525162344;6532.987386955029;6541.411272985454;6542.5747326425535;6283.460552164566;6391.392817682313;6505.5446118773125;6527.15147030883;6528.118703974903;6220.69365433246;6278.6546739929745;6323.399277068671;6361.332060133805;6382.872342553216;6215.782447614311;6219.284782250099;6340.4927605792345;6389.169407976933;6478;6190.266725316918;6199.597026193283;6212.72042758086;6252.218472690914;6317.6209427434505 17:57:08 INFO - PID 22984 | |5;yui.html;911.8814554107965;920.1948295241664;930.3255140023484;932.1563984593945;933.0529512544772;17947.10264486777;18003.729944050832;18307.267709291613;18323;19932;20401.693974590355;21032.474188145305;21218.575628487422;21293.57412851742;21305.57388852226;1023.2632504596716;1038.8805287391933;1046.100353695821;1046.1055797293318;1046.4715318764013;949.1884438804826;1037.2324479884905;1044.7074819050679;1046.2467023742893;1049.6273822792914;1092.9672109836717;1096.358630201329;1101.5758932810886;1101.5869049106584;1102.08526922654;1078.3907092492755;1087.9238453308271;1088.2491081153978;1088.3198001249218;1088.9564417423294;806.6652339279192;807.6930766308765;810.4326971120239;810.7608255564622;810.8256724804137;18470.445886623424;19216.423507294803;19409.320673776492;19490;20329;20643.380698579065;20775.376738697712;21039.158433662637;21106.472338191666;21223;15625.843741562667;16452.34190632385;16546.503604891874;16576.585585360343;16613.83386166135;3458.342914846171;3468.9653103468895;3470.1845066409373;3488.4592888102384;3491.1097670093964;112436.43781781121;112745.43627281893;114728.13179670491;126209;128904.71095289021;15402.383904643913;15509.767353489706;15800.36798528069;15959.920200398923;15960.441384551485;29038.41923161525;29375.55936660952;29415.558766618517;29472.557911631342;29504.704952950626;8521.829563408697;8788.912110878937;8842.425242359226;8943.821123577492;8976.955115224382;4223.577642235813;4308.2460569400855;4319.762413067311;4327.1345730853955;4327.697061205686;5071.54356107952;5171.534561889448;5175.585953123741;5602.215689803431;5889.234399528034;1055.6094245129352;1068.6633710381204;1089.1885545268667;1090.8854570270166;1097.2648325621853;619.2692622705251;622.1352320274822;635.8950773122475;639.536336156283;664.3555750921598;11371.488283027284;11427.828582571101;12548.24710517372;12560.30918299484;12947.741045179044;649.8797427968427;681.7988693335478;696.0916004614028;704.7498138160981;710.3322876496077;5131.640785145004;6115.541334399938;6136.263648362135;6209.223847019122;6296.652721751545;100806;101803.49098254461;103830.48084759527;104221;105417.47291263494;87050;87741.51293526978;88806;90315.54842225878;91895;72507;73344.63327683328;73347.56445053985;76641;83106.58446707728;11934.701632459259;12124.939375303067;12226;12540.372981351078;12803.679908002374;34256.31487370288;34334.48498272478;34774.65225347714;35942.281154376244;37067.07332316714;42457.787711061246;43918.12163756643;45813;62455.6877215611;68141;17499.38752143669;17519.737203942204;17598.73601895947;17820;17824.46526604204;18136.18387172607;18609;19297.614047718685;19375.8062419374;20212;17378;17561.82438175653;17569.03370314645;17713.025783582027;17833.821661783735;17072.317107315568;17173.227204776063;17196.570085747455;17383.130843457522;17524.299028038735;16632.417865374653;16967.91516042412;17359.392421265195;17405.390811321544;17538;12660.683482913;12812.61562153137;12869.806952895528;13327.400266988203;13495.122817016863;12810.295433751233;13254.481100640385;13373.465061397825;13469.191848489121;14145 17:57:08 INFO - PID 22984 | __end_tp_report 17:57:08 INFO - PID 22984 | __start_cc_report 17:57:08 INFO - PID 22984 | _x_x_mozilla_cycle_collect,369 17:57:08 INFO - PID 22984 | __end_cc_report 17:57:08 INFO - PID 22984 | __startTimestamp1501549028793__endTimestamp 17:57:08 INFO - PID 22984 | 17:57:08 INFO - PID 22984 | ------- Summary: start ------- 17:57:08 INFO - PID 22984 | Number of tests: 6 17:57:08 INFO - PID 22984 | 17:57:08 INFO - PID 22984 | [#0] dojo.html Cycles:165 Average:6097.67 Median:6339.11 stddev:701.64 (11.1%) stddev-sans-first:664.37 17:57:08 INFO - PID 22984 | Values: 3141.6 3145.8 3160.2 3161.1 3201.2 6113.5 6144.1 6167.9 6185.9 6212.6 5960.1 6094.5 6104.8 6105.1 6147.9 5584.9 5620.1 5622.3 5669.1 5682.7 5682.6 5687.9 5700.3 5702.5 5731.2 5663.2 5677.8 5716.2 5769.7 5783.0 5577.4 5644.3 5673.6 5728.7 5735.8 5227.3 5487.9 5605.0 5626.1 5653.9 6047.3 6059.0 6097.0 6119.1 6142.6 6573.6 6644.4 6665.4 6676.9 6683.6 6625.6 6635.8 6661.9 6750.4 6779.1 6072.5 6164.0 6167.8 6169.5 6187.5 6747.2 6750.2 6764.6 6876.3 6878.4 6436.9 6500.0 6525.6 6528.5 6540.1 6563.8 6612.9 6639.2 6658.5 6670.6 6555.6 6569.9 6611.8 6651.5 6665.5 6512.8 6589.4 6638.6 6694.8 6696.5 5377.9 5445.3 5453.5 5458.7 5480.0 4692.2 4704.2 4743.2 4752.6 4781.7 5798.6 5862.3 5884.9 5885.1 5890.9 6483.4 6621.6 6622.4 6632.6 6747.2 5728.3 5741.7 5752.4 5766.8 5771.3 5799.7 5802.9 5803.2 5807.2 5838.6 6344.3 6415.2 6416.8 6435.5 6518.1 6220.4 6598.9 6610.0 6631.6 6637.1 6465.7 6466.0 6469.2 6482.0 6491.1 6516.9 6519.0 6522.6 6553.2 6565.4 6628.1 6643.4 6683.9 6747.5 6771.8 6229.3 6367.8 6368.1 6391.4 6515.5 6418.4 6429.0 6434.8 6438.1 6445.4 6242.3 6339.1 6359.1 6398.4 6409.4 6325.3 6413.6 6439.0 6521.5 6541.8 6226.9 6288.7 6300.6 6339.6 6346.6 17:57:08 INFO - PID 22984 | 17:57:08 INFO - PID 22984 | [#1] ext.html Cycles:180 Average:37400.13 Median:33824.58 stddev:56210.04 (166.2%) stddev-sans-first:56300.39 17:57:08 INFO - PID 22984 | Values: 758.9 817.9 839.5 847.4 1165.8 29836.3 30314.0 39601.0 40503.4 40820.0 40810.4 41269.8 41412.8 41458.4 41461.6 7533.8 7658.6 7687.9 7701.9 7740.0 7404.7 7526.5 7573.0 7574.2 7589.6 7493.9 7559.8 7560.5 7562.8 7564.5 7495.6 7505.9 7565.7 7579.1 7594.7 7381.9 7472.8 7484.3 7488.3 7510.4 37403.4 37407.0 37417.0 37561.8 37628.8 3489.4 3519.2 3520.0 3523.5 3534.1 30204.2 30303.5 30393.0 30593.4 30632.8 4588.8 4687.7 4773.3 4878.1 4896.4 183798.0 183989.0 184330.0 184659.0 185094.0 45978.3 46212.5 46303.3 46545.0 47344.3 49052.5 49320.5 49322.8 49339.0 49490.3 14081.2 14349.4 14393.2 14450.6 14505.5 10034.8 10188.2 11266.3 11482.8 11617.3 14203.1 14309.4 14313.3 14324.4 14486.7 5617.7 5618.9 5665.2 5672.4 5699.0 974.3 986.4 991.4 1019.9 1022.1 21927.7 21948.3 22403.1 22982.3 23030.8 1061.5 1094.5 1104.6 1110.5 1114.8 11166.7 12059.8 12253.3 15690.6 16207.4 239705.0 243193.0 244940.0 245252.0 245464.0 54773.5 54904.5 55246.0 55250.7 55426.7 1440.0 1462.3 1481.5 1487.3 1491.1 26041.3 27517.9 27584.3 27752.4 27854.6 35721.8 36270.5 36474.6 36507.6 36536.8 215517.0 216440.0 216667.0 216794.0 217381.0 32285.7 33252.2 33707.2 33723.3 33925.8 38614.0 39722.2 40262.4 40317.2 40594.0 23466.9 24067.6 24937.8 25046.6 25869.2 22350.0 23602.3 24207.0 24360.0 24547.9 33539.8 33686.0 33937.2 34053.3 34150.0 27942.3 29244.0 29798.3 30321.5 31354.7 23826.6 24759.9 24890.9 24964.5 25225.2 17:57:08 INFO - PID 22984 | 17:57:08 INFO - PID 22984 | [#2] jquery.html Cycles:180 Average:37525.93 Median:21934.28 stddev:90111.99 (410.8%) stddev-sans-first:90322.04 17:57:08 INFO - PID 22984 | Values: 566.4 569.6 575.3 578.6 582.2 21047.3 21814.8 22003.2 22009.6 22031.6 27088.7 27637.2 27652.3 27688.6 27765.4 6849.0 7052.8 7062.6 7066.9 7075.5 6162.0 6785.2 6823.1 6854.9 6856.0 6796.3 6844.0 6851.1 6869.1 6878.4 6836.7 6841.8 6845.2 6875.1 6881.0 6711.6 6725.6 6727.9 6735.9 6737.8 30695.2 31352.5 31573.0 31651.8 31779.7 1922.2 1960.8 1966.4 1967.8 1968.6 19630.9 20385.2 20398.8 20409.9 20418.3 2412.3 2415.3 2484.5 2488.6 2490.5 131614.0 133194.3 136169.0 136894.0 137340.3 20455.9 21163.4 21751.9 21942.6 22592.7 43718.8 44026.0 44072.0 44085.0 44159.8 11035.2 11097.4 11164.4 11225.8 11314.9 7876.5 9095.0 9097.8 9143.5 9272.9 9918.5 11528.8 11566.4 11863.9 11920.7 4016.4 4105.0 4271.8 4379.3 4384.3 964.0 965.2 972.6 995.5 997.4 19788.4 19939.4 19954.2 20101.7 20717.0 1018.4 1023.9 1037.2 1039.8 1044.1 10756.2 13074.3 13792.9 14050.1 14452.7 523108.0 527886.0 545293.0 546661.0 547472.0 122631.4 122797.0 123877.4 124565.0 125343.4 1236.2 1266.9 1279.2 1287.2 1295.2 20012.7 21003.6 21725.2 21727.2 21958.2 55301.2 57474.9 59813.4 60495.0 61152.7 76017.6 76261.6 77499.6 78011.6 78468.0 12226.2 12668.9 13671.2 14691.1 15019.2 21491.9 21858.2 21926.0 22130.6 22437.0 17806.6 18311.5 18395.7 18469.6 18526.0 12877.2 13123.6 13159.9 13705.7 13925.9 17661.0 18100.8 18299.2 18897.4 19250.6 14535.3 14598.6 14640.8 14674.7 14763.2 18034.0 18098.5 18253.3 18282.6 18362.4 17:57:08 INFO - PID 22984 | 17:57:08 INFO - PID 22984 | [#3] mootools.html Cycles:180 Average:8508.67 Median:2631.36 stddev:6015.07 (228.6%) stddev-sans-first:6008.18 17:57:08 INFO - PID 22984 | Values: 1392.0 1420.3 1432.9 1440.5 1446.3 13635.3 13995.5 14329.1 14436.1 14457.6 14473.6 14727.6 14735.1 14739.6 14748.2 2593.4 2637.6 2653.2 2654.3 2654.4 2526.7 2535.6 2577.6 2617.9 2630.5 2632.2 2682.9 2684.0 2684.4 2687.1 2565.2 2577.6 2584.0 2585.2 2593.6 2487.9 2499.5 2516.4 2517.1 2526.8 13762.2 13952.0 14002.3 14061.4 14071.5 7389.5 7682.8 7687.6 7716.0 7731.5 4888.7 5040.1 5072.7 5175.3 5185.5 1701.1 1703.7 1718.3 1748.7 1766.4 23137.2 23426.8 23462.2 23465.4 23509.4 6419.0 6428.8 6491.9 6683.1 6709.1 15394.7 15717.7 15858.8 16296.3 16317.0 7375.0 7616.4 7623.0 7626.1 7627.6 6744.9 6956.8 6967.6 6993.9 7034.6 4994.9 5088.6 5114.6 5158.5 5181.2 1498.1 1498.5 1501.9 1510.6 1517.4 821.2 824.3 830.1 855.2 874.5 6356.3 6536.1 6690.0 6755.9 6838.0 677.9 700.3 716.7 724.9 734.9 4101.5 4172.4 4358.2 4406.1 4523.2 18659.0 19044.0 19631.2 19684.8 20109.0 18546.7 19030.5 19080.7 19668.3 19670.5 10286.0 10713.8 10790.4 10806.4 10823.8 6840.9 7198.5 7623.2 7683.5 8016.5 16649.6 17065.9 17306.3 17510.5 17684.2 16811.4 17232.7 17349.5 17511.8 17582.3 10887.3 11072.8 11164.5 11221.2 11382.5 12393.6 12619.6 12687.7 12713.6 12718.4 8471.3 9073.9 9113.2 9246.3 9486.1 8621.8 9084.5 9159.3 9183.3 9208.5 8727.8 8758.7 8799.5 8799.7 8990.8 6258.8 6590.3 6605.5 6606.2 6630.7 6305.9 6411.2 6556.8 6704.6 6874.8 17:57:08 INFO - PID 22984 | 17:57:08 INFO - PID 22984 | [#4] prototype.html Cycles:180 Average:5446.74 Median:6014.23 stddev:1531.21 (25.5%) stddev-sans-first:1489.76 17:57:08 INFO - PID 22984 | Values: 497.3 500.5 512.9 518.2 518.8 6113.9 6127.9 6154.0 6160.4 6176.6 5883.2 6035.0 6049.0 6069.4 6081.3 4936.0 4937.2 4941.5 4943.5 4955.9 4946.0 4960.9 4964.3 4967.6 4988.0 4716.9 4884.5 4888.4 4902.8 4906.7 4898.3 4904.1 4912.5 4921.9 4939.2 4399.4 4419.5 4428.4 4432.6 4439.2 5839.7 5893.4 5972.3 5976.9 5993.4 5621.7 5634.1 5641.2 5648.4 5657.3 6490.9 6494.8 6507.5 6533.2 6558.8 4812.9 4829.6 4843.2 4844.7 4852.5 6761.0 6787.2 6840.0 6860.5 6906.7 4907.9 4913.4 4969.2 4987.4 4991.7 4968.4 5009.6 5025.3 5025.9 5041.0 5597.5 5607.3 5621.8 5634.9 5641.4 6399.6 6423.2 6446.1 6514.4 6571.0 1606.7 1711.2 1733.5 1733.9 1747.3 1126.6 1131.6 1167.4 1197.4 1206.6 5690.0 5707.4 5773.0 5793.4 5804.7 6456.6 6466.8 6540.2 6554.3 6559.5 5635.7 5672.7 5676.3 5756.3 5779.4 3516.8 3558.3 3574.7 3577.1 3600.6 6472.7 6491.0 6519.0 6527.2 6572.3 6807.4 6825.2 6831.0 6849.8 6854.1 6397.4 6479.2 6495.8 6555.0 6564.9 6630.3 6666.2 6668.1 6676.7 6687.8 6458.9 6463.5 6479.1 6508.2 6556.3 6476.3 6491.6 6544.2 6547.8 6553.6 4945.0 4945.7 4952.1 4954.7 4956.4 6727.5 6734.3 6735.8 6736.3 6761.5 6494.9 6495.0 6533.0 6541.4 6542.6 6283.5 6391.4 6505.5 6527.2 6528.1 6220.7 6278.7 6323.4 6361.3 6382.9 6215.8 6219.3 6340.5 6389.2 6478.0 6190.3 6199.6 6212.7 6252.2 6317.6 17:57:08 INFO - PID 22984 | 17:57:08 INFO - PID 22984 | [#5] yui.html Cycles:180 Average:22013.08 Median:18912.71 stddev:29259.41 (154.7%) stddev-sans-first:29298.59 17:57:08 INFO - PID 22984 | Values: 911.9 920.2 930.3 932.2 933.1 17947.1 18003.7 18307.3 18323.0 19932.0 20401.7 21032.5 21218.6 21293.6 21305.6 1023.3 1038.9 1046.1 1046.1 1046.5 949.2 1037.2 1044.7 1046.2 1049.6 1093.0 1096.4 1101.6 1101.6 1102.1 1078.4 1087.9 1088.2 1088.3 1089.0 806.7 807.7 810.4 810.8 810.8 18470.4 19216.4 19409.3 19490.0 20329.0 20643.4 20775.4 21039.2 21106.5 21223.0 15625.8 16452.3 16546.5 16576.6 16613.8 3458.3 3469.0 3470.2 3488.5 3491.1 112436.4 112745.4 114728.1 126209.0 128904.7 15402.4 15509.8 15800.4 15959.9 15960.4 29038.4 29375.6 29415.6 29472.6 29504.7 8521.8 8788.9 8842.4 8943.8 8977.0 4223.6 4308.2 4319.8 4327.1 4327.7 5071.5 5171.5 5175.6 5602.2 5889.2 1055.6 1068.7 1089.2 1090.9 1097.3 619.3 622.1 635.9 639.5 664.4 11371.5 11427.8 12548.2 12560.3 12947.7 649.9 681.8 696.1 704.7 710.3 5131.6 6115.5 6136.3 6209.2 6296.7 100806.0 101803.5 103830.5 104221.0 105417.5 87050.0 87741.5 88806.0 90315.5 91895.0 72507.0 73344.6 73347.6 76641.0 83106.6 11934.7 12124.9 12226.0 12540.4 12803.7 34256.3 34334.5 34774.7 35942.3 37067.1 42457.8 43918.1 45813.0 62455.7 68141.0 17499.4 17519.7 17598.7 17820.0 17824.5 18136.2 18609.0 19297.6 19375.8 20212.0 17378.0 17561.8 17569.0 17713.0 17833.8 17072.3 17173.2 17196.6 17383.1 17524.3 16632.4 16967.9 17359.4 17405.4 17538.0 12660.7 12812.6 12869.8 13327.4 13495.1 12810.3 13254.5 13373.5 13469.2 14145.0 17:57:08 INFO - PID 22984 | -------- Summary: end -------- 17:57:08 INFO - PID 22984 | 17:57:09 INFO - TEST-INFO | 22984: exit 0 17:57:09 INFO - TEST-OK | dromaeo_css | took 1113573ms 17:57:09 INFO - TEST-START | kraken 17:57:09 INFO - Initialising browser for kraken test... 17:57:09 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48447/getInfo.html -profile /tmp/tmpfcRWoL/profile 17:57:09 INFO - TEST-INFO | started process 23349 (/builds/slave/test/build/application/firefox/firefox http://localhost:48447/getInfo.html) 17:57:15 INFO - TEST-INFO | 23349: exit 0 17:57:15 INFO - Browser initialized. 17:57:15 INFO - Running cycle 1/1 for kraken test... 17:57:15 INFO - TEST-INFO | started process 23574 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfcRWoL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:57:16 INFO - PID 23574 | 17:57:16 INFO - PID 23574 | (/builds/slave/test/build/application/firefox/firefox:23629): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:57:16 INFO - PID 23574 | 17:57:26 INFO - PID 23574 | RSS: Main: 174866432 17:57:26 INFO - PID 23574 | 17:57:29 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48447/tests/kraken/driver.html?testName=audio-beat-detection) 17:57:30 INFO - PID 23574 | RSS: Main: 179662848 17:57:30 INFO - PID 23574 | 17:57:33 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48447/tests/kraken/driver.html?testName=audio-dft) 17:57:33 INFO - PID 23574 | RSS: Main: 179699712 17:57:33 INFO - PID 23574 | 17:57:37 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48447/tests/kraken/driver.html?testName=audio-fft) 17:57:37 INFO - PID 23574 | RSS: Main: 174505984 17:57:37 INFO - PID 23574 | 17:57:39 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48447/tests/kraken/driver.html?testName=audio-oscillator) 17:57:40 INFO - PID 23574 | RSS: Main: 175357952 17:57:40 INFO - PID 23574 | 17:57:42 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48447/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:57:43 INFO - PID 23574 | RSS: Main: 175489024 17:57:43 INFO - PID 23574 | 17:57:46 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48447/tests/kraken/driver.html?testName=imaging-darkroom) 17:57:47 INFO - PID 23574 | RSS: Main: 177815552 17:57:47 INFO - PID 23574 | 17:57:50 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48447/tests/kraken/driver.html?testName=imaging-desaturate) 17:57:51 INFO - PID 23574 | RSS: Main: 176910336 17:57:51 INFO - PID 23574 | 17:57:54 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48447/tests/kraken/driver.html?testName=json-parse-financial) 17:57:54 INFO - PID 23574 | RSS: Main: 178225152 17:57:54 INFO - PID 23574 | 17:57:56 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48447/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:57:57 INFO - PID 23574 | RSS: Main: 178188288 17:57:57 INFO - PID 23574 | 17:57:59 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48447/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:57:59 INFO - PID 23574 | RSS: Main: 175239168 17:57:59 INFO - PID 23574 | 17:58:02 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48447/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:58:02 INFO - PID 23574 | RSS: Main: 175345664 17:58:02 INFO - PID 23574 | 17:58:05 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:58:06 INFO - PID 23574 | RSS: Main: 175755264 17:58:06 INFO - PID 23574 | 17:58:09 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:58:10 INFO - PID 23574 | RSS: Main: 175517696 17:58:10 INFO - PID 23574 | 17:58:11 INFO - PID 23574 | Cycle 1(1): loaded http://localhost:48447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48447/tests/kraken/driver.html?testName=ai-astar) 17:58:12 INFO - PID 23574 | RSS: Main: 177160192 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | __start_tp_report 17:58:12 INFO - PID 23574 | _x_x_mozilla_page_load 17:58:12 INFO - PID 23574 | _x_x_mozilla_page_load_details 17:58:12 INFO - PID 23574 | |i|pagename|runs| 17:58:12 INFO - PID 23574 | |0;ai-astar;101;108;104;114;108;118;109;100;110;110 17:58:12 INFO - PID 23574 | |1;audio-beat-detection;150;117;152;169;118;146;146;122;176;121 17:58:12 INFO - PID 23574 | |2;audio-dft;162;164;169;167;164;181;168;165;165;164 17:58:12 INFO - PID 23574 | |3;audio-fft;76;79;76;167;83;77;77;83;164;79 17:58:12 INFO - PID 23574 | |4;audio-oscillator;86;80;142;85;79;78;123;79;80;140 17:58:12 INFO - PID 23574 | |5;imaging-gaussian-blur;117;115;115;115;114;114;120;117;115;115 17:58:12 INFO - PID 23574 | |6;imaging-darkroom;152;152;159;151;152;152;152;155;152;150 17:58:12 INFO - PID 23574 | |7;imaging-desaturate;97;94;95;94;94;94;95;94;94;94 17:58:12 INFO - PID 23574 | |8;json-parse-financial;59;58;57;57;62;58;56;56;57;57 17:58:12 INFO - PID 23574 | |9;json-stringify-tinderbox;54;57;54;54;56;53;54;55;62;57 17:58:12 INFO - PID 23574 | |10;stanford-crypto-aes;81;94;82;99;88;81;78;88;83;79 17:58:12 INFO - PID 23574 | |11;stanford-crypto-ccm;150;130;132;139;134;145;132;136;126;168 17:58:12 INFO - PID 23574 | |12;stanford-crypto-pbkdf2;179;182;183;192;193;180;184;191;193;183 17:58:12 INFO - PID 23574 | |13;stanford-crypto-sha256-iterative;68;69;68;68;66;72;68;72;74;70 17:58:12 INFO - PID 23574 | __end_tp_report 17:58:12 INFO - PID 23574 | __start_cc_report 17:58:12 INFO - PID 23574 | _x_x_mozilla_cycle_collect,959 17:58:12 INFO - PID 23574 | __end_cc_report 17:58:12 INFO - PID 23574 | __startTimestamp1501549092221__endTimestamp 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | ------- Summary: start ------- 17:58:12 INFO - PID 23574 | Number of tests: 14 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#0] ai-astar Cycles:10 Average:108.20 Median:109.50 stddev:5.51 (5.0%) stddev-sans-first:5.20 17:58:12 INFO - PID 23574 | Values: 101.0 108.0 104.0 114.0 108.0 118.0 109.0 100.0 110.0 110.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:148.00 stddev:21.40 (14.5%) stddev-sans-first:22.49 17:58:12 INFO - PID 23574 | Values: 150.0 117.0 152.0 169.0 118.0 146.0 146.0 122.0 176.0 121.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#2] audio-dft Cycles:10 Average:166.90 Median:166.00 stddev:5.38 (3.2%) stddev-sans-first:5.41 17:58:12 INFO - PID 23574 | Values: 162.0 164.0 169.0 167.0 164.0 181.0 168.0 165.0 165.0 164.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#3] audio-fft Cycles:10 Average:96.10 Median:78.00 stddev:36.67 (47.0%) stddev-sans-first:38.17 17:58:12 INFO - PID 23574 | Values: 76.0 79.0 76.0 167.0 83.0 77.0 77.0 83.0 164.0 79.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:79.50 stddev:26.67 (33.5%) stddev-sans-first:27.98 17:58:12 INFO - PID 23574 | Values: 86.0 80.0 142.0 85.0 79.0 78.0 123.0 79.0 80.0 140.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#5] imaging-gaussian-blur Cycles:10 Average:115.70 Median:115.00 stddev:1.83 (1.6%) stddev-sans-first:1.88 17:58:12 INFO - PID 23574 | Values: 117.0 115.0 115.0 115.0 114.0 114.0 120.0 117.0 115.0 115.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#6] imaging-darkroom Cycles:10 Average:152.70 Median:152.00 stddev:2.54 (1.7%) stddev-sans-first:2.68 17:58:12 INFO - PID 23574 | Values: 152.0 152.0 159.0 151.0 152.0 152.0 152.0 155.0 152.0 150.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:0.97 (1.0%) stddev-sans-first:0.44 17:58:12 INFO - PID 23574 | Values: 97.0 94.0 95.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:57.50 stddev:1.77 (3.1%) stddev-sans-first:1.81 17:58:12 INFO - PID 23574 | Values: 59.0 58.0 57.0 57.0 62.0 58.0 56.0 56.0 57.0 57.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#9] json-stringify-tinderbox Cycles:10 Average:55.60 Median:55.50 stddev:2.63 (4.7%) stddev-sans-first:2.73 17:58:12 INFO - PID 23574 | Values: 54.0 57.0 54.0 54.0 56.0 53.0 54.0 55.0 62.0 57.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.50 stddev:6.86 (8.0%) stddev-sans-first:7.10 17:58:12 INFO - PID 23574 | Values: 81.0 94.0 82.0 99.0 88.0 81.0 78.0 88.0 83.0 79.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#11] stanford-crypto-ccm Cycles:10 Average:139.20 Median:137.50 stddev:12.38 (9.0%) stddev-sans-first:12.50 17:58:12 INFO - PID 23574 | Values: 150.0 130.0 132.0 139.0 134.0 145.0 132.0 136.0 126.0 168.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.00 Median:187.50 stddev:5.60 (3.0%) stddev-sans-first:5.33 17:58:12 INFO - PID 23574 | Values: 179.0 182.0 183.0 192.0 193.0 180.0 184.0 191.0 193.0 183.0 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.50 stddev:2.46 (3.5%) stddev-sans-first:2.55 17:58:12 INFO - PID 23574 | Values: 68.0 69.0 68.0 68.0 66.0 72.0 68.0 72.0 74.0 70.0 17:58:12 INFO - PID 23574 | -------- Summary: end -------- 17:58:12 INFO - PID 23574 | 17:58:12 INFO - PID 23574 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:58:12 INFO - TEST-INFO | 23574: exit 0 17:58:12 INFO - TEST-OK | kraken | took 63453ms 17:58:12 INFO - SUITE-END | took 1177s 17:58:13 INFO - Completed test suite (00:19:37) 17:58:13 INFO - javascript benchmark 17:58:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8402.596568491143, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3141.5601815745786, 3145.81125132492, 3160.2257446925505, 3161.0674850918977, 3201.2157021529715, 6113.510919126459, 6144.078388241766, 6167.876642467154, 6185.938140618592, 6212.565120441571, 5960.105984102376, 6094.542909281799, 6104.816855494331, 6105.053716673911, 6147.938520614804, 5584.888302233953, 5620.100783874581, 5622.32532096149, 5669.09294512878, 5682.687452190128, 5682.602217844753, 5687.886242275152, 5700.344460387047, 5702.486776190142, 5731.226284451608, 5663.235463212475, 5677.772889084432, 5716.1997320375185, 5769.682667453289, 5782.97108514459, 5577.358603760579, 5644.322681278252, 5673.602847800656, 5728.68492232927, 5735.799247026334, 5227.3465816772905, 5487.862803429906, 5605.0, 5626.099824028177, 5653.886922261573, 6047.274327080779, 6059.0, 6096.969515152396, 6119.082137679339, 6142.6314421134875, 6573.57271777333, 6644.43522300605, 6665.366790154928, 6676.899846502306, 6683.632400217986, 6625.5693379930635, 6635.8009259722285, 6661.866762664768, 6750.392464678153, 6779.050932869353, 6072.51419886408, 6164.044573091178, 6167.814965551041, 6169.475594574466, 6187.535934804862, 6747.224069232003, 6750.189977202767, 6764.594124352456, 6876.346747059021, 6878.38094571491, 6436.903446448307, 6500.0, 6525.575837570543, 6528.510361722795, 6540.084388185658, 6563.8359041023405, 6612.86774264512, 6639.170103737033, 6658.533902626769, 6670.633115178712, 6555.639439830759, 6569.9343006569325, 6611.768588099393, 6651.534392592569, 6665.500087493458, 6512.837179070561, 6589.439897608661, 6638.601683898982, 6694.799156025327, 6696.531242812956, 5377.892442151213, 5445.346558413015, 5453.454654534515, 5458.672479651154, 5480.0, 4692.249240121564, 4704.15325241453, 4743.241081426955, 4752.643551733565, 4781.713097214179, 5798.623089499169, 5862.325832529314, 5884.882302354014, 5885.146653735184, 5890.852728681732, 6483.3516648335735, 6621.635810030494, 6622.37087476689, 6632.5688830226845, 6747.224069232101, 5728.341240757283, 5741.712914354265, 5752.395998420189, 5766.826995190151, 5771.278590176228, 5799.681017544076, 5802.854928626733, 5803.245578074823, 5807.186993820784, 5838.562107841929, 6344.302126766052, 6415.230172379344, 6416.839579010562, 6435.485161187094, 6518.120053792677, 6220.440160385587, 6598.901016484852, 6610.0, 6631.568948018364, 6637.103990961158, 6465.709043093166, 6466.030095485715, 6469.159009328757, 6482.027695845661, 6491.091247225298, 6516.902246466401, 6519.0, 6522.608643481406, 6553.246376666553, 6565.409113179837, 6628.138342015507, 6643.435307998874, 6683.866322673616, 6747.460203183831, 6771.830704232433, 6229.314775374796, 6367.777127800505, 6368.140301059483, 6391.360863913572, 6515.478761698958, 6418.358164183544, 6429.0, 6434.774782882764, 6438.130852334875, 6445.4199122079235, 6242.3133455320785, 6339.1125242467015, 6359.141515895295, 6398.360163983564, 6409.359064093554, 6325.272593651789, 6413.615183089029, 6439.0, 6521.543491955517, 6541.771038013837, 6226.875462490815, 6288.685565721604, 6300.62196268207, 6339.619622822645, 6346.619202847659], "value": 6046.540135869554, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.8634045871744, 817.8527864984302, 839.5466448118015, 847.4364547575866, 1165.8134698448239, 29836.254093647643, 30314.0, 39601.0, 40503.392449113286, 40820.0, 40810.38784418236, 41269.7936510317, 41412.79293603528, 41458.37812432808, 41461.58538414615, 7533.849323013536, 7658.61706914652, 7687.884681729751, 7701.922980770205, 7740.0, 7404.703811847519, 7526.5107767994905, 7572.962135189344, 7574.242575742409, 7589.620518974029, 7493.925060749377, 7559.773206803778, 7560.4707670463085, 7562.773116806477, 7564.508306960058, 7495.550266983944, 7505.924940750577, 7565.697372105109, 7579.052618422697, 7594.73418430355, 7381.852362952764, 7472.775816725507, 7484.251574842527, 7488.288612581796, 7510.399168066541, 37403.438948415795, 37407.0, 37417.0, 37561.81219093914, 37628.81185594082, 3489.3544694231396, 3519.208178159919, 3520.0, 3523.5067090607336, 3534.1341371363924, 30204.24489387761, 30303.545446818316, 30393.0, 30593.388132237455, 30632.8468357659, 4588.839390621312, 4687.695299805502, 4773.3078703588135, 4878.146324393252, 4896.387951506062, 183798.0, 183989.0, 184330.0, 184659.0, 185094.0, 45978.31032534515, 46212.5378746215, 46303.30545041827, 46545.0, 47344.28983565249, 49052.50947490479, 49320.506794932306, 49322.753386233555, 49339.0, 49490.25764613534, 14081.225532595805, 14349.426022959173, 14393.208373539346, 14450.638734031523, 14505.492307769178, 10034.799304014025, 10188.184945204366, 11266.267692599955, 11482.770344593062, 11617.302961822317, 14203.076800007966, 14309.356078976263, 14313.28433578317, 14324.427022918966, 14486.710265794836, 5617.747201375948, 5618.943810561924, 5665.1502274658305, 5672.404397538199, 5698.971505142531, 974.3423189347162, 986.3785814936545, 991.4447909170888, 1019.9439030853226, 1022.1311884897926, 21927.67108493374, 21948.34154975353, 22403.10387584511, 22982.341595187383, 23030.769692303198, 1061.508810033959, 1094.480121942071, 1104.5802595013843, 1110.4836251143256, 1114.8327750837277, 11166.665000050012, 12059.819102713467, 12253.326067066218, 15690.607734806494, 16207.351705931867, 239705.0, 243193.0, 244940.0, 245252.0, 245464.0, 54773.45226547763, 54904.450955490734, 55246.0, 55250.72374638182, 55426.72286638541, 1439.978400323996, 1462.2542503323168, 1481.4814814814729, 1487.3084015932643, 1491.0978857791097, 26041.348966275993, 27517.86241068782, 27584.310392240353, 27752.44495110046, 27854.582181267702, 35721.82139089392, 36270.45594316035, 36474.635253648186, 36507.634923650425, 36536.81731591325, 215517.0, 216440.0, 216667.0, 216794.0, 217381.0, 32285.677143228266, 33252.1686957828, 33707.15732106619, 33723.32553348968, 33925.83037084799, 38614.0, 39722.2055558893, 40262.39606405848, 40317.1936561273, 40594.0, 23466.882665586563, 24067.638985414884, 24937.750622493542, 25046.62430063514, 25869.22392328233, 22350.0, 23602.29193124209, 24207.0, 24360.0, 24547.877260613583, 33539.83230083834, 33686.0, 33937.15157120993, 34053.31893362168, 34150.0, 27942.3014424641, 29244.0, 29798.25504362408, 30321.545176821925, 31354.68645313518, 23826.642600361356, 24759.87620061888, 24890.875545622155, 24964.500709986063, 25225.24324270275], "value": 16642.919859255988, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.3996164066087, 569.6240481282354, 575.3010092737323, 578.6094386289253, 582.1675004743219, 21047.2633457829, 21814.781852181473, 22003.22988695396, 22009.559808803835, 22031.559368812614, 27088.72911270887, 27637.170884873434, 27652.308692282702, 27688.584671229946, 27765.444691106164, 6848.999999999987, 7052.788416347492, 7062.576245425265, 7066.929330706678, 7075.540089894166, 6161.969190154043, 6785.2196997345445, 6823.147106611674, 6854.862902741942, 6856.0, 6796.320367963189, 6844.041834143224, 6851.109355783766, 6869.141357330333, 6878.449724022092, 6836.726530938757, 6841.760538381132, 6845.247022827485, 6875.106236189314, 6881.0, 6711.630860302681, 6725.630090345029, 6727.865442691168, 6735.93264067358, 6737.831554211184, 30695.232619184477, 31352.52971205411, 31573.0, 31651.841740791147, 31779.682203177905, 1922.1734654098732, 1960.7941166177632, 1966.4395647240467, 1967.7835438101797, 1968.5767559974531, 19630.90184549068, 20385.18459261628, 20398.79601203984, 20409.897950510152, 20418.285360012476, 2412.300432874464, 2415.2995631267086, 2484.4534202475425, 2488.5645012122986, 2490.5018996200833, 131614.0, 133194.33402832923, 136169.0, 136894.0, 137340.31329843285, 20455.8977205116, 21163.365099047052, 21751.891240544013, 21942.561148776935, 22592.66111008336, 43718.78140609276, 44026.0, 44072.0, 44085.0, 44159.77920110379, 11035.227534072537, 11097.44512774358, 11164.441777911234, 11225.775484490265, 11314.943425282821, 7876.527408355517, 9094.954525227331, 9097.81804363909, 9143.497107659143, 9272.860907086399, 9918.454485003249, 11528.76942461146, 11566.363849988331, 11863.940680296544, 11920.701982450508, 4016.357382818735, 4105.014796448891, 4271.834960913147, 4379.255526560492, 4384.276594361958, 963.9708413789014, 965.2229954886384, 972.6352617768337, 995.5022488755623, 997.3816233935007, 19788.40634780959, 19939.401817945483, 19954.20183192685, 20101.698474522895, 20717.0, 1018.4449475036123, 1023.8617786598713, 1037.2065369991963, 1039.750459889621, 1044.1385856668278, 10756.247062705692, 13074.346282685829, 13792.862071379359, 14050.086744361584, 14452.710945781026, 523108.0, 527886.0, 545293.0, 546661.0, 547472.0, 122631.38684306521, 122797.0, 123877.38061309815, 124565.0, 125343.37328313483, 1236.2397125767588, 1266.8796464335874, 1279.1749321687646, 1287.2405280884136, 1295.1905059337914, 20012.699809502577, 21003.57992840165, 21725.239616613344, 21727.19710290788, 21958.23146189876, 55301.170482441994, 57474.85050299054, 59813.40186598252, 60495.0, 61152.694236528536, 76017.61991190008, 76261.61869190619, 77499.61250193712, 78011.60994194992, 78468.0, 12226.20529665569, 12668.936655316664, 13671.248081355621, 14691.11853288806, 15019.249037548298, 21491.892540537196, 21858.234961776263, 21926.0, 22130.557388851812, 22437.0, 17806.643867122842, 18311.450656480327, 18395.724064139315, 18469.63060738751, 18526.0, 12877.162984405984, 13123.556408795215, 13159.934200328937, 13705.725885482434, 13925.860741392862, 17661.0, 18100.818991809912, 18299.1765370556, 18897.43307700771, 19250.614987699886, 14535.25576930775, 14598.562043138723, 14640.780388294397, 14674.706505870035, 14763.188024658746, 18034.0, 18098.457046288633, 18253.269869205615, 18282.634347313244, 18362.449126526226], "value": 12820.817650879697, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.9582412527625, 1420.3395421129173, 1432.9426822927082, 1440.4814266863928, 1446.2696338349133, 13635.318234088292, 13995.510157144501, 14329.06861054032, 14436.13383197009, 14457.638559036017, 14473.5657930262, 14727.631809204775, 14735.115893046424, 14739.557813265592, 14748.188849613294, 2593.36462566671, 2637.591173368131, 2653.177514970356, 2654.336415896026, 2654.3894904172053, 2526.6589010483626, 2535.6196570514385, 2577.6262441945905, 2617.908373206938, 2630.4607555451084, 2632.2498088044913, 2682.8866020601426, 2683.9597406038924, 2684.4228490874457, 2687.126683827754, 2565.179142674345, 2577.6004719268544, 2583.987080064597, 2585.1598230575046, 2593.572060609997, 2487.8880450379775, 2499.512595043967, 2516.3834860459124, 2517.0561039610147, 2526.84838909666, 13762.174269543859, 13952.0, 14002.299885005708, 14061.437542498288, 14071.507497237548, 7389.482736208468, 7682.846343073164, 7687.6156192190165, 7716.0, 7731.45879788415, 4888.657793954425, 5040.067587496325, 5072.72100034498, 5175.275461435402, 5185.5333020028, 1701.1154199816026, 1703.7444383342474, 1718.2783231042993, 1748.7376893465955, 1766.381766381769, 23137.190198342978, 23426.76573234263, 23462.17882374126, 23465.41336466585, 23509.41226469335, 6419.0, 6428.839279017969, 6491.902621460682, 6683.097781799492, 6709.127813384229, 15394.692106157814, 15717.685646287238, 15858.841411585967, 16296.34814607426, 16317.0, 7374.963125184339, 7616.35261002821, 7622.96188519065, 7626.084869815658, 7627.618619069024, 6744.865102697919, 6956.826079348057, 6967.616781076988, 6993.930060699429, 7034.577925324497, 4994.850154495371, 5088.592912566985, 5114.64197506171, 5158.535731784157, 5181.222816577543, 1498.1460567476433, 1498.5129832804382, 1501.8798496120285, 1510.637447012731, 1517.4082107977897, 821.2321479416764, 824.3158178711655, 830.1159265382469, 855.2132038524572, 874.5408660453186, 6356.269029061625, 6536.084948107269, 6689.996500524863, 6755.864882702417, 6837.9658101710165, 677.8745932002595, 700.3066963705894, 716.6524235745659, 724.9456290778107, 734.944879134067, 4101.487314085739, 4172.415861779354, 4358.193734159191, 4406.074724307867, 4523.231050721385, 18659.0, 19044.0, 19631.214751409785, 19684.803151968295, 20109.0, 18546.721799173025, 19030.52423689391, 19080.71378929317, 19668.311609093613, 19670.508237294183, 10286.0, 10713.83929241062, 10790.352578845295, 10806.35161890289, 10823.783524329468, 6840.905455127156, 7198.460115491254, 7623.161452240138, 7683.500572462772, 8016.478928869605, 16649.583760406087, 17065.91467042657, 17306.307747690455, 17510.474685759447, 17684.204210810803, 16811.41160059392, 17232.655346893243, 17349.479515614552, 17511.82488175153, 17582.296708131533, 10887.346759194474, 11072.833907491555, 11164.497597607964, 11221.214514983873, 11382.54469821198, 12393.62819115428, 12619.558315458915, 12687.682807929876, 12713.55502557406, 12718.364081796059, 8471.32229421657, 9073.863892041756, 9113.225375842863, 9246.306527010636, 9486.098820612031, 8621.827563448822, 9084.500352480676, 9159.267258619162, 9183.265338773015, 9208.493532855757, 8727.825443490967, 8758.737237882873, 8799.47203167812, 8799.692010779592, 8990.775230619287, 6258.780942666985, 6590.308017658269, 6605.471562275103, 6606.174228221473, 6630.668466576748, 6305.873882522415, 6411.166548348685, 6556.770513032021, 6704.597724136567, 6874.828129296808], "value": 6123.863511328322, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.27716376507067, 500.5464054328313, 512.8922926185501, 518.1554066871, 518.8158203837637, 6113.938860611393, 6127.938720612792, 6153.9999999999945, 6160.383961603849, 6176.629402235863, 5883.205767221424, 6035.034394496871, 6049.0, 6069.362716914727, 6081.270247570286, 4936.0, 4937.160682683934, 4941.530554597308, 4943.530364615356, 4955.851324460272, 4945.958235685064, 4960.900781984358, 4964.255361695757, 4967.62201702842, 4987.975060124694, 4716.90566188676, 4884.535969082949, 4888.41339039314, 4902.779374928136, 4906.730129842857, 4898.330409926581, 4904.117258893398, 4912.459629440758, 4921.876953076184, 4939.234418665113, 4399.406080179167, 4419.513853476115, 4428.3800267962515, 4432.556744325574, 4439.200943830109, 5839.737211825478, 5893.381194974551, 5972.327748230502, 5976.8505787355225, 5993.430624090704, 5621.747021384048, 5634.098544232944, 5641.238432811559, 5648.378678345379, 5657.3494048184575, 6490.902636460457, 6494.798462284492, 6507.479401647857, 6533.150690410263, 6558.770443034471, 4812.879678008043, 4829.613630909519, 4843.190949249462, 4844.66087373884, 4852.4662287148385, 6760.966195169042, 6787.16746478448, 6840.008198811144, 6860.451163906925, 6906.689198986058, 4907.895723462264, 4913.434954980223, 4969.155243608595, 4987.361753569143, 4991.650584459053, 4968.403791545038, 5009.649324547319, 5025.346704928336, 5025.864007796672, 5041.0, 5597.468240517144, 5607.327120745536, 5621.80323688669, 5634.915476267859, 5641.407652196543, 6399.632365796063, 6423.197100362455, 6446.129772480655, 6514.413702766774, 6571.0, 1606.6706325203538, 1711.2299465240692, 1733.4972857871328, 1733.9219735111951, 1747.344745720355, 1126.583164229224, 1131.620906996149, 1167.4104577188466, 1197.4312201704295, 1206.5776978057609, 5690.0, 5707.3721890592005, 5773.018586840247, 5793.420657934173, 5804.709764511842, 6456.580322279036, 6466.773662921869, 6540.182477190351, 6554.279029306773, 6559.47524198063, 5635.69003704792, 5672.659640421588, 5676.290463692038, 5756.30924289088, 5779.393163717833, 3516.841742121611, 3558.323918455485, 3574.746980247962, 3577.141486043383, 3600.567931848195, 6472.741090356333, 6490.967545162244, 6519.045525400635, 6527.249366322933, 6572.309907459744, 6807.421369183675, 6825.215100263533, 6830.965845170842, 6849.760258391032, 6854.143232095988, 6397.409049335027, 6479.222493300834, 6495.772647957298, 6554.967225163844, 6564.934350656432, 6630.30381809903, 6666.2333831609985, 6668.133142691419, 6676.699548520425, 6687.799366019027, 6458.935410646021, 6463.482921366373, 6479.125318082187, 6508.219013718195, 6556.311587283267, 6476.255230648348, 6491.636756281234, 6544.247411547733, 6547.770828020996, 6553.639549824806, 4944.975275123746, 4945.67853089548, 4952.083864485082, 4954.727489987941, 4956.3804524434445, 6727.461803055841, 6734.259231484631, 6735.797926062226, 6736.326367363225, 6761.526693131434, 6494.870102598016, 6494.967525162344, 6532.987386955029, 6541.411272985454, 6542.5747326425535, 6283.460552164566, 6391.392817682313, 6505.5446118773125, 6527.15147030883, 6528.118703974903, 6220.69365433246, 6278.6546739929745, 6323.399277068671, 6361.332060133805, 6382.872342553216, 6215.782447614311, 6219.284782250099, 6340.4927605792345, 6389.169407976933, 6478.0, 6190.266725316918, 6199.597026193283, 6212.72042758086, 6252.218472690914, 6317.6209427434505], "value": 4992.884921471293, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [911.8814554107965, 920.1948295241664, 930.3255140023484, 932.1563984593945, 933.0529512544772, 17947.10264486777, 18003.729944050832, 18307.267709291613, 18323.0, 19932.0, 20401.693974590355, 21032.474188145305, 21218.575628487422, 21293.57412851742, 21305.57388852226, 1023.2632504596716, 1038.8805287391933, 1046.100353695821, 1046.1055797293318, 1046.4715318764013, 949.1884438804826, 1037.2324479884905, 1044.7074819050679, 1046.2467023742893, 1049.6273822792914, 1092.9672109836717, 1096.358630201329, 1101.5758932810886, 1101.5869049106584, 1102.08526922654, 1078.3907092492755, 1087.9238453308271, 1088.2491081153978, 1088.3198001249218, 1088.9564417423294, 806.6652339279192, 807.6930766308765, 810.4326971120239, 810.7608255564622, 810.8256724804137, 18470.445886623424, 19216.423507294803, 19409.320673776492, 19490.0, 20329.0, 20643.380698579065, 20775.376738697712, 21039.158433662637, 21106.472338191666, 21223.0, 15625.843741562667, 16452.34190632385, 16546.503604891874, 16576.585585360343, 16613.83386166135, 3458.342914846171, 3468.9653103468895, 3470.1845066409373, 3488.4592888102384, 3491.1097670093964, 112436.43781781121, 112745.43627281893, 114728.13179670491, 126209.0, 128904.71095289021, 15402.383904643913, 15509.767353489706, 15800.36798528069, 15959.920200398923, 15960.441384551485, 29038.41923161525, 29375.55936660952, 29415.558766618517, 29472.557911631342, 29504.704952950626, 8521.829563408697, 8788.912110878937, 8842.425242359226, 8943.821123577492, 8976.955115224382, 4223.577642235813, 4308.2460569400855, 4319.762413067311, 4327.1345730853955, 4327.697061205686, 5071.54356107952, 5171.534561889448, 5175.585953123741, 5602.215689803431, 5889.234399528034, 1055.6094245129352, 1068.6633710381204, 1089.1885545268667, 1090.8854570270166, 1097.2648325621853, 619.2692622705251, 622.1352320274822, 635.8950773122475, 639.536336156283, 664.3555750921598, 11371.488283027284, 11427.828582571101, 12548.24710517372, 12560.30918299484, 12947.741045179044, 649.8797427968427, 681.7988693335478, 696.0916004614028, 704.7498138160981, 710.3322876496077, 5131.640785145004, 6115.541334399938, 6136.263648362135, 6209.223847019122, 6296.652721751545, 100806.0, 101803.49098254461, 103830.48084759527, 104221.0, 105417.47291263494, 87050.0, 87741.51293526978, 88806.0, 90315.54842225878, 91895.0, 72507.0, 73344.63327683328, 73347.56445053985, 76641.0, 83106.58446707728, 11934.701632459259, 12124.939375303067, 12226.0, 12540.372981351078, 12803.679908002374, 34256.31487370288, 34334.48498272478, 34774.65225347714, 35942.281154376244, 37067.07332316714, 42457.787711061246, 43918.12163756643, 45813.0, 62455.6877215611, 68141.0, 17499.38752143669, 17519.737203942204, 17598.73601895947, 17820.0, 17824.46526604204, 18136.18387172607, 18609.0, 19297.614047718685, 19375.8062419374, 20212.0, 17378.0, 17561.82438175653, 17569.03370314645, 17713.025783582027, 17833.821661783735, 17072.317107315568, 17173.227204776063, 17196.570085747455, 17383.130843457522, 17524.299028038735, 16632.417865374653, 16967.91516042412, 17359.392421265195, 17405.390811321544, 17538.0, 12660.683482913, 12812.61562153137, 12869.806952895528, 13327.400266988203, 13495.122817016863, 12810.295433751233, 13254.481100640385, 13373.465061397825, 13469.191848489121, 14145.0], "value": 8921.206147589803, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 108.0, 104.0, 114.0, 108.0, 118.0, 109.0, 100.0, 110.0, 110.0], "value": 108.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 117.0, 152.0, 169.0, 118.0, 146.0, 146.0, 122.0, 176.0, 121.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 169.0, 167.0, 164.0, 181.0, 168.0, 165.0, 165.0, 164.0], "value": 166.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 79.0, 76.0, 167.0, 83.0, 77.0, 77.0, 83.0, 164.0, 79.0], "value": 96.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 80.0, 142.0, 85.0, 79.0, 78.0, 123.0, 79.0, 80.0, 140.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 115.0, 115.0, 114.0, 114.0, 120.0, 117.0, 115.0, 115.0], "value": 115.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 159.0, 151.0, 152.0, 152.0, 152.0, 155.0, 152.0, 150.0], "value": 152.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 57.0, 57.0, 62.0, 58.0, 56.0, 56.0, 57.0, 57.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 54.0, 54.0, 56.0, 53.0, 54.0, 55.0, 62.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 94.0, 82.0, 99.0, 88.0, 81.0, 78.0, 88.0, 83.0, 79.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 130.0, 132.0, 139.0, 134.0, 145.0, 132.0, 136.0, 126.0, 168.0], "value": 139.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 182.0, 183.0, 192.0, 193.0, 180.0, 184.0, 191.0, 193.0, 183.0], "value": 186.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 68.0, 66.0, 72.0, 68.0, 72.0, 74.0, 70.0], "value": 69.5, "unit": "score"}]}]} 17:58:13 INFO - Return code: 0 17:58:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:58:13 INFO - # TBPL SUCCESS # 17:58:13 INFO - Running post-action listener: _package_coverage_data 17:58:13 INFO - Running post-action listener: _resource_record_post_action 17:58:13 INFO - [mozharness: 2017-08-01 00:58:13.487207Z] Finished run-tests step (success) 17:58:13 INFO - Running post-run listener: _resource_record_post_run 17:58:13 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 8192; Write bytes: 432254976; Read time: 28; Write time: 356320 17:58:13 INFO - TinderboxPrint: CPU usage
13.1% 17:58:13 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 28 17:58:13 INFO - TinderboxPrint: I/O write bytes / time
432,254,976 / 356,320 17:58:13 INFO - TinderboxPrint: CPU idle
8,278.8 (86.8%) 17:58:13 INFO - TinderboxPrint: CPU user
1,204.8 (12.6%) 17:58:13 INFO - TinderboxPrint: Swap in / out
0 / 0 17:58:13 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106668032; Read time: 0; Write time: 98408 17:58:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:58:13 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 322691072; Read time: 16; Write time: 257580 17:58:14 INFO - Running post-run listener: _upload_blobber_files 17:58:14 INFO - Blob upload gear active. 17:58:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:58:14 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 17:58:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:58:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:58:14 INFO - (blobuploader) - INFO - Open directory for files ... 17:58:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:58:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:58:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:21 INFO - (blobuploader) - INFO - Done attempting. 17:58:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:58:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:58:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:21 INFO - (blobuploader) - INFO - Done attempting. 17:58:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:58:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:58:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:22 INFO - (blobuploader) - INFO - Done attempting. 17:58:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:58:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:58:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:23 INFO - (blobuploader) - INFO - Done attempting. 17:58:23 INFO - (blobuploader) - INFO - Iteration through files over. 17:58:23 INFO - Return code: 0 17:58:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:58:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:58:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433a5b6400d37e07e068658af229fe604121f0a542cfff8e4b29b246fa45df484d6a53d16232082f409dc5233a5092e82722914ccda3bf22fc2c79fd12a46e22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed1e4292513808276da522c1875be5bfb7f3743b97e4478150a7578a4e317c01bc1d67447da187cc83ece3db817e857059a5a053b1fbf4d1a8f0ea74f59572ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b43351d5d995669440d35b3294a8ccc964c55634a11174483f0884a0dea7b44c9241ae7fae9ac87f8e98037c5768ae7cef14d5652aec0b6ad28a71e55a429dd6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a27e6fa5cda909870e2d15ba758b8044b910cfe9cde4b2fc5545847715c0659b32c7957fac1de0019d891bfff483408c6829b29f5ea0d658b023cffd8992d0"} 17:58:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:58:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:58:23 INFO - Contents: 17:58:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433a5b6400d37e07e068658af229fe604121f0a542cfff8e4b29b246fa45df484d6a53d16232082f409dc5233a5092e82722914ccda3bf22fc2c79fd12a46e22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed1e4292513808276da522c1875be5bfb7f3743b97e4478150a7578a4e317c01bc1d67447da187cc83ece3db817e857059a5a053b1fbf4d1a8f0ea74f59572ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b43351d5d995669440d35b3294a8ccc964c55634a11174483f0884a0dea7b44c9241ae7fae9ac87f8e98037c5768ae7cef14d5652aec0b6ad28a71e55a429dd6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a27e6fa5cda909870e2d15ba758b8044b910cfe9cde4b2fc5545847715c0659b32c7957fac1de0019d891bfff483408c6829b29f5ea0d658b023cffd8992d0"} 17:58:23 INFO - Running post-run listener: copy_logs_to_upload_dir 17:58:23 INFO - Copying logs to upload dir... 17:58:23 INFO - mkdir: /builds/slave/test/build/upload/logs 17:58:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.749022 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-07-31 17:58:23.808769) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-31 17:58:23.816260) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433a5b6400d37e07e068658af229fe604121f0a542cfff8e4b29b246fa45df484d6a53d16232082f409dc5233a5092e82722914ccda3bf22fc2c79fd12a46e22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed1e4292513808276da522c1875be5bfb7f3743b97e4478150a7578a4e317c01bc1d67447da187cc83ece3db817e857059a5a053b1fbf4d1a8f0ea74f59572ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b43351d5d995669440d35b3294a8ccc964c55634a11174483f0884a0dea7b44c9241ae7fae9ac87f8e98037c5768ae7cef14d5652aec0b6ad28a71e55a429dd6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a27e6fa5cda909870e2d15ba758b8044b910cfe9cde4b2fc5545847715c0659b32c7957fac1de0019d891bfff483408c6829b29f5ea0d658b023cffd8992d0"} build_url:https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/DsdJJjUsSgahxwFgH6hwYA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433a5b6400d37e07e068658af229fe604121f0a542cfff8e4b29b246fa45df484d6a53d16232082f409dc5233a5092e82722914ccda3bf22fc2c79fd12a46e22", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed1e4292513808276da522c1875be5bfb7f3743b97e4478150a7578a4e317c01bc1d67447da187cc83ece3db817e857059a5a053b1fbf4d1a8f0ea74f59572ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b43351d5d995669440d35b3294a8ccc964c55634a11174483f0884a0dea7b44c9241ae7fae9ac87f8e98037c5768ae7cef14d5652aec0b6ad28a71e55a429dd6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9a27e6fa5cda909870e2d15ba758b8044b910cfe9cde4b2fc5545847715c0659b32c7957fac1de0019d891bfff483408c6829b29f5ea0d658b023cffd8992d0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-31 17:58:23.843358) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 17:58:23.843671) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=03627c79f6bb3d1578c4a402000001ba-1501545085.467892-1757458475 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004509 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 17:58:23.923708) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-31 17:58:23.927176) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-31 17:58:23.927465) ========= ========= Total master_lag: 0.19 =========