builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-071 starttime: 1501290123.21 results: success (0) revision: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:03.211753) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:03.212142) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:03.212457) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:03.403003) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:03.403257) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-28 18:02:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.5M=0s 2017-07-28 18:02:03 (45.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.075511 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:03.558401) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:03.558741) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023030 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:03.661513) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-07-28 18:02:03.667690) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ec329722b2f8bad3b1b9d0829e8d89764a879fd1 --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 ec329722b2f8bad3b1b9d0829e8d89764a879fd1 --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=dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-28 18:02:04,180 truncating revision to first 12 chars 2017-07-28 18:02:04,180 Setting DEBUG logging. 2017-07-28 18:02:04,180 attempt 1/10 2017-07-28 18:02:04,180 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ec329722b2f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-28 18:02:04,663 attempt 1/10 2017-07-28 18:02:05,409 current task status: no status available at this point. state: STARTED 2017-07-28 18:02:05,409 sleeping for 11.00s (attempt 1/10) 2017-07-28 18:02:16,421 attempt 2/10 2017-07-28 18:02:16,788 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-07-28 18:02:16,971 unpacking tar archive at: mozilla-central-ec329722b2f8/testing/mozharness/ program finished with exit code 0 elapsedTime=13.069777 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-07-28 18:02:16.766801) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:16.767099) ========= script_repo_revision: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:16.770524) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:16.770797) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-28 18:02:16.780564) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-07-28 18:02:16.780855) ========= /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=dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:02:17 INFO - MultiFileLogger online at 20170728 18:02:17 in /builds/slave/test 18:02:17 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 18:02:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:02:17 INFO - {'append_to_log': False, 18:02:17 INFO - 'base_work_dir': '/builds/slave/test', 18:02:17 INFO - 'blob_upload_branch': 'Firefox', 18:02:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:02:17 INFO - 'branch': 'Firefox', 18:02:17 INFO - 'buildbot_json_path': 'buildprops.json', 18:02:17 INFO - 'code_coverage': False, 18:02:17 INFO - 'config_files': ('talos/linux_config.py',), 18:02:17 INFO - 'default_actions': ('clobber', 18:02:17 INFO - 'read-buildbot-config', 18:02:17 INFO - 'download-and-extract', 18:02:17 INFO - 'populate-webroot', 18:02:17 INFO - 'create-virtualenv', 18:02:17 INFO - 'install', 18:02:17 INFO - 'setup-mitmproxy', 18:02:17 INFO - 'run-tests'), 18:02:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:02:17 INFO - 'disable_ccov_upload': False, 18:02:17 INFO - 'download_minidump_stackwalk': True, 18:02:17 INFO - 'download_symbols': 'ondemand', 18:02:17 INFO - 'enable_stylo': False, 18:02:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:02:17 INFO - 'tooltool.py': '/tools/tooltool.py', 18:02:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:02:17 INFO - '/tools/misc-python/virtualenv.py')}, 18:02:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:02:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:02:17 INFO - 'gecko_profile': False, 18:02:17 INFO - 'gecko_profile_interval': 0, 18:02:17 INFO - 'installer_path': 'installer.exe', 18:02:17 INFO - 'log_level': 'info', 18:02:17 INFO - 'log_name': 'talos', 18:02:17 INFO - 'log_to_console': True, 18:02:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:02:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:02:17 INFO - 'opt_config_files': (), 18:02:17 INFO - 'pip_index': False, 18:02:17 INFO - 'suite': 'dromaeojs-e10s', 18:02:17 INFO - 'system_bits': '32', 18:02:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:02:17 INFO - 'title': 'talos-linux64-ix-071', 18:02:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:02:17 INFO - 'use_talos_json': True, 18:02:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:02:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:02:17 INFO - 'work_dir': 'build'} 18:02:17 INFO - [mozharness: 2017-07-29 01:02:17.382798Z] Running clobber step. 18:02:17 INFO - Running pre-action listener: _resource_record_pre_action 18:02:17 INFO - Running main action method: clobber 18:02:17 INFO - rmtree: /builds/slave/test/build 18:02:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:02:18 INFO - Running post-action listener: _resource_record_post_action 18:02:18 INFO - [mozharness: 2017-07-29 01:02:18.416825Z] Finished clobber step (success) 18:02:18 INFO - [mozharness: 2017-07-29 01:02:18.416929Z] Running read-buildbot-config step. 18:02:18 INFO - Running pre-action listener: _resource_record_pre_action 18:02:18 INFO - Running main action method: read_buildbot_config 18:02:18 INFO - Using buildbot properties: 18:02:18 INFO - { 18:02:18 INFO - "project": "", 18:02:18 INFO - "product": "firefox", 18:02:18 INFO - "who": "kwierso@gmail.com", 18:02:18 INFO - "installer_path": "public/build/target.tar.bz2", 18:02:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 18:02:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:02:18 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 18:02:18 INFO - "stage_platform": "linux64-stylo", 18:02:18 INFO - "basedir": "/builds/slave/test", 18:02:18 INFO - "buildnumber": 8, 18:02:18 INFO - "platform": "ubuntu64_hw_stylo", 18:02:18 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:02:18 INFO - "slavebuilddir": "test", 18:02:18 INFO - "taskId": "RWtFL5tcRq2FFeze917Dfw", 18:02:18 INFO - "branch": "mozilla-central", 18:02:18 INFO - "script_repo_revision": "production", 18:02:18 INFO - "revision": "ec329722b2f8bad3b1b9d0829e8d89764a879fd1", 18:02:18 INFO - "slavename": "talos-linux64-ix-071", 18:02:18 INFO - "repo_path": "mozilla-central" 18:02:18 INFO - } 18:02:18 INFO - Finding installer, test and symbols from parent task. 18:02:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RWtFL5tcRq2FFeze917Dfw'}, attempt #1 18:02:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RWtFL5tcRq2FFeze917Dfw'}, attempt #1 18:02:19 INFO - Task dependencies: JZh5JewrR7CLMNL_lRmk9w 18:02:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w'}, attempt #1 18:02:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:20 INFO - Set test_url: https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/test_packages.json 18:02:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip 18:02:20 INFO - Running post-action listener: _resource_record_post_action 18:02:20 INFO - [mozharness: 2017-07-29 01:02:20.188215Z] Finished read-buildbot-config step (success) 18:02:20 INFO - [mozharness: 2017-07-29 01:02:20.188436Z] Running download-and-extract step. 18:02:20 INFO - Running pre-action listener: _resource_record_pre_action 18:02:20 INFO - Running main action method: download_and_extract 18:02:20 INFO - mkdir: /builds/slave/test/build/tests 18:02:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:20 INFO - trying https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.test_packages.json 18:02:20 INFO - Downloading https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:02:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:02:20 INFO - Downloaded 989 bytes. 18:02:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:02:20 INFO - Using the following test package requirements: 18:02:20 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:02:20 INFO - u'common': [u'target.common.tests.zip'], 18:02:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:02:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:02:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:02:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:02:20 INFO - u'mozbase': [u'target.common.tests.zip'], 18:02:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:02:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:02:20 INFO - u'web-platform': [u'target.common.tests.zip', 18:02:20 INFO - u'target.web-platform.tests.tar.gz'], 18:02:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:02:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:02:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip 18:02:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:02:20 INFO - Fetch https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip into memory 18:02:22 INFO - Content-Length response header: 72564963 18:02:22 INFO - Bytes received: 72564963 18:02:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:02:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip 18:02:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:02:33 INFO - Fetch https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.common.tests.zip into memory 18:02:34 INFO - Content-Length response header: 72564963 18:02:34 INFO - Bytes received: 72564963 18:02:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.talos.tests.zip 18:02:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:02:46 INFO - Fetch https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.talos.tests.zip into memory 18:02:47 INFO - Content-Length response header: 14496686 18:02:47 INFO - Bytes received: 14496686 18:02:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:48 INFO - trying https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:48 INFO - Downloading https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:02:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:02:49 INFO - Downloaded 62227675 bytes. 18:02:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:49 INFO - mkdir: /builds/slave/test/properties 18:02:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:02:49 INFO - Writing to file /builds/slave/test/properties/build_url 18:02:49 INFO - Contents: 18:02:49 INFO - build_url:https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 18:02:49 INFO - Running post-action listener: _resource_record_post_action 18:02:49 INFO - Running post-action listener: set_extra_try_arguments 18:02:49 INFO - [mozharness: 2017-07-29 01:02:49.551957Z] Finished download-and-extract step (success) 18:02:49 INFO - [mozharness: 2017-07-29 01:02:49.552055Z] Running populate-webroot step. 18:02:49 INFO - Running pre-action listener: _resource_record_pre_action 18:02:49 INFO - Running main action method: populate_webroot 18:02:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:02:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:02:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:02:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:02:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:02:49 INFO - 'tests': ['damp', 'tps']}, 18:02:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:02:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:02:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:02:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:02:49 INFO - 'other-e10s': {'tests': ['a11yr', 18:02:49 INFO - 'ts_paint', 18:02:49 INFO - 'tpaint', 18:02:49 INFO - 'sessionrestore', 18:02:49 INFO - 'sessionrestore_many_windows', 18:02:49 INFO - 'sessionrestore_no_auto_restore', 18:02:49 INFO - 'tabpaint']}, 18:02:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:02:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:02:49 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:02:49 INFO - 'talos_options': ['--mitmproxy', 18:02:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:02:49 INFO - '--firstNonBlankPaint'], 18:02:49 INFO - 'tests': ['quantum_pageload_google', 18:02:49 INFO - 'quantum_pageload_youtube', 18:02:49 INFO - 'quantum_pageload_amazon', 18:02:49 INFO - 'quantum_pageload_facebook']}, 18:02:49 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:02:49 INFO - 'talos_options': ['--stylo', 18:02:49 INFO - '--mitmproxy', 18:02:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:02:49 INFO - '--firstNonBlankPaint'], 18:02:49 INFO - 'tests': ['quantum_pageload_google', 18:02:49 INFO - 'quantum_pageload_youtube', 18:02:49 INFO - 'quantum_pageload_amazon', 18:02:49 INFO - 'quantum_pageload_facebook']}, 18:02:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:02:49 INFO - 'tsvgr_opacity', 18:02:49 INFO - 'tart', 18:02:49 INFO - 'tscrollx', 18:02:49 INFO - 'cart', 18:02:49 INFO - 'tsvg_static']}, 18:02:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:02:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:02:49 INFO - 'talos_options': ['--stylo', 18:02:49 INFO - '--stylo-threads=1', 18:02:49 INFO - '--mitmproxy', 18:02:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:02:49 INFO - '--firstNonBlankPaint'], 18:02:49 INFO - 'tests': ['tp6_google', 18:02:49 INFO - 'tp6_youtube', 18:02:49 INFO - 'tp6_amazon', 18:02:49 INFO - 'tp6_facebook']}, 18:02:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:02:49 INFO - 'talos_options': ['--xperf_path', 18:02:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:02:49 INFO - 'tests': ['tp5n']}}} 18:02:49 INFO - Running post-action listener: _resource_record_post_action 18:02:49 INFO - [mozharness: 2017-07-29 01:02:49.560751Z] Finished populate-webroot step (success) 18:02:49 INFO - [mozharness: 2017-07-29 01:02:49.560841Z] Running create-virtualenv step. 18:02:49 INFO - Running pre-action listener: _resource_record_pre_action 18:02:49 INFO - Running main action method: create_virtualenv 18:02:49 INFO - Creating virtualenv /builds/slave/test/build/venv 18:02:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:02:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:02:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:02:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:02:49 INFO - Using real prefix '/usr' 18:02:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:02:50 INFO - Installing distribute.............................................................................................................................................................................................done. 18:02:52 INFO - Installing pip.................done. 18:02:52 INFO - Return code: 0 18:02:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:02:52 INFO - Reading from file tmpfile_stdout 18:02:52 INFO - Output received: 18:02:52 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:02:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:52 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 0x23bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2589a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x272cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2729eb0>, '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 0x272adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272b240>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:02:52 INFO - Using env: {'DISPLAY': ':0', 18:02:52 INFO - 'HOME': '/home/cltbld', 18:02:52 INFO - 'LANG': 'en_US.UTF-8', 18:02:52 INFO - 'LANGUAGE': 'en_US:en', 18:02:52 INFO - 'LOGNAME': 'cltbld', 18:02:52 INFO - 'MAIL': '/var/mail/cltbld', 18:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:52 INFO - 'MOZ_NO_REMOTE': '1', 18:02:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:52 INFO - 'NO_EM_RESTART': '1', 18:02:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:52 INFO - 'PWD': '/builds/slave/test', 18:02:52 INFO - 'SHELL': '/bin/bash', 18:02:52 INFO - 'SHLVL': '1', 18:02:52 INFO - 'TERM': 'linux', 18:02:52 INFO - 'TMOUT': '86400', 18:02:52 INFO - 'USER': 'cltbld', 18:02:52 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 18:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:52 INFO - '_': '/tools/buildbot/bin/python'} 18:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:02:52 INFO - Cleaning up... 18:02:52 INFO - Return code: 0 18:02:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:52 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 0x23bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2589a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x272cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2729eb0>, '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 0x272adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272b240>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:02:52 INFO - Using env: {'DISPLAY': ':0', 18:02:52 INFO - 'HOME': '/home/cltbld', 18:02:52 INFO - 'LANG': 'en_US.UTF-8', 18:02:52 INFO - 'LANGUAGE': 'en_US:en', 18:02:52 INFO - 'LOGNAME': 'cltbld', 18:02:52 INFO - 'MAIL': '/var/mail/cltbld', 18:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:52 INFO - 'MOZ_NO_REMOTE': '1', 18:02:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:52 INFO - 'NO_EM_RESTART': '1', 18:02:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:52 INFO - 'PWD': '/builds/slave/test', 18:02:52 INFO - 'SHELL': '/bin/bash', 18:02:52 INFO - 'SHLVL': '1', 18:02:52 INFO - 'TERM': 'linux', 18:02:52 INFO - 'TMOUT': '86400', 18:02:52 INFO - 'USER': 'cltbld', 18:02:52 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 18:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:52 INFO - '_': '/tools/buildbot/bin/python'} 18:02:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:52 INFO - Downloading/unpacking psutil>=3.1.1 18:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:02:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:53 INFO - Installing collected packages: psutil 18:02:53 INFO - Running setup.py install for psutil 18:02:54 INFO - building 'psutil._psutil_linux' extension 18:02:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:02:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:02:54 INFO - building 'psutil._psutil_posix' extension 18:02:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:02:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:02:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:02:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:02:54 INFO - Successfully installed psutil 18:02:54 INFO - Cleaning up... 18:02:54 INFO - Return code: 0 18:02:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2589a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x272cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2729eb0>, '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 0x272adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272b240>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:02:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:02:54 INFO - Using env: {'DISPLAY': ':0', 18:02:54 INFO - 'HOME': '/home/cltbld', 18:02:54 INFO - 'LANG': 'en_US.UTF-8', 18:02:54 INFO - 'LANGUAGE': 'en_US:en', 18:02:54 INFO - 'LOGNAME': 'cltbld', 18:02:54 INFO - 'MAIL': '/var/mail/cltbld', 18:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:54 INFO - 'MOZ_NO_REMOTE': '1', 18:02:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:54 INFO - 'NO_EM_RESTART': '1', 18:02:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:54 INFO - 'PWD': '/builds/slave/test', 18:02:54 INFO - 'SHELL': '/bin/bash', 18:02:54 INFO - 'SHLVL': '1', 18:02:54 INFO - 'TERM': 'linux', 18:02:54 INFO - 'TMOUT': '86400', 18:02:54 INFO - 'USER': 'cltbld', 18:02:54 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 18:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:54 INFO - '_': '/tools/buildbot/bin/python'} 18:02:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:02:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:02:55 INFO - Installing collected packages: mozsystemmonitor 18:02:55 INFO - Running setup.py install for mozsystemmonitor 18:02:55 INFO - Successfully installed mozsystemmonitor 18:02:55 INFO - Cleaning up... 18:02:55 INFO - Return code: 0 18:02:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:02: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')]} 18:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02: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')]} 18:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2589a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x272cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2729eb0>, '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 0x272adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272b240>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:02:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:02:55 INFO - Using env: {'DISPLAY': ':0', 18:02:55 INFO - 'HOME': '/home/cltbld', 18:02:55 INFO - 'LANG': 'en_US.UTF-8', 18:02:55 INFO - 'LANGUAGE': 'en_US:en', 18:02:55 INFO - 'LOGNAME': 'cltbld', 18:02:55 INFO - 'MAIL': '/var/mail/cltbld', 18:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:55 INFO - 'MOZ_NO_REMOTE': '1', 18:02:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:55 INFO - 'NO_EM_RESTART': '1', 18:02:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:55 INFO - 'PWD': '/builds/slave/test', 18:02:55 INFO - 'SHELL': '/bin/bash', 18:02:55 INFO - 'SHLVL': '1', 18:02:55 INFO - 'TERM': 'linux', 18:02:55 INFO - 'TMOUT': '86400', 18:02:55 INFO - 'USER': 'cltbld', 18:02:55 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 18:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:55 INFO - '_': '/tools/buildbot/bin/python'} 18:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:56 INFO - Downloading/unpacking jsonschema==2.5.1 18:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:02:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:57 INFO - Downloading functools32-3.2.3-2.tar.gz 18:02:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:02:57 INFO - warning: no files found matching '*.txt' 18:02:57 INFO - no previously-included directories found matching 'build' 18:02:57 INFO - no previously-included directories found matching 'dist' 18:02:57 INFO - no previously-included directories found matching '.git*' 18:02:57 INFO - Installing collected packages: jsonschema, functools32 18:02:57 INFO - Running setup.py install for functools32 18:02:57 INFO - warning: no files found matching '*.txt' 18:02:57 INFO - no previously-included directories found matching 'build' 18:02:57 INFO - no previously-included directories found matching 'dist' 18:02:57 INFO - no previously-included directories found matching '.git*' 18:02:57 INFO - Successfully installed jsonschema functools32 18:02:57 INFO - Cleaning up... 18:02:57 INFO - Return code: 0 18:02:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2589a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x272cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2729eb0>, '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 0x272adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272b240>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:02:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:02:57 INFO - Using env: {'DISPLAY': ':0', 18:02:57 INFO - 'HOME': '/home/cltbld', 18:02:57 INFO - 'LANG': 'en_US.UTF-8', 18:02:57 INFO - 'LANGUAGE': 'en_US:en', 18:02:57 INFO - 'LOGNAME': 'cltbld', 18:02:57 INFO - 'MAIL': '/var/mail/cltbld', 18:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:57 INFO - 'MOZ_NO_REMOTE': '1', 18:02:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:57 INFO - 'NO_EM_RESTART': '1', 18:02:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:57 INFO - 'PWD': '/builds/slave/test', 18:02:57 INFO - 'SHELL': '/bin/bash', 18:02:57 INFO - 'SHLVL': '1', 18:02:57 INFO - 'TERM': 'linux', 18:02:57 INFO - 'TMOUT': '86400', 18:02:57 INFO - 'USER': 'cltbld', 18:02:57 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 18:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:57 INFO - '_': '/tools/buildbot/bin/python'} 18:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:02:57 INFO - Cleaning up... 18:02:57 INFO - Return code: 0 18:02:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x23bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2589a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x272cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2729eb0>, '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 0x272adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272b240>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:02:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:02:57 INFO - Using env: {'DISPLAY': ':0', 18:02:57 INFO - 'HOME': '/home/cltbld', 18:02:57 INFO - 'LANG': 'en_US.UTF-8', 18:02:57 INFO - 'LANGUAGE': 'en_US:en', 18:02:57 INFO - 'LOGNAME': 'cltbld', 18:02:57 INFO - 'MAIL': '/var/mail/cltbld', 18:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:57 INFO - 'MOZ_NO_REMOTE': '1', 18:02:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:02:57 INFO - 'NO_EM_RESTART': '1', 18:02:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:57 INFO - 'PWD': '/builds/slave/test', 18:02:57 INFO - 'SHELL': '/bin/bash', 18:02:57 INFO - 'SHLVL': '1', 18:02:57 INFO - 'TERM': 'linux', 18:02:57 INFO - 'TMOUT': '86400', 18:02:57 INFO - 'USER': 'cltbld', 18:02:57 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 18:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:57 INFO - '_': '/tools/buildbot/bin/python'} 18:02:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:58 INFO - Downloading/unpacking blobuploader==1.2.4 18:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:59 INFO - Downloading blobuploader-1.2.4.tar.gz 18:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:02:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:02:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:59 INFO - Downloading docopt-0.6.1.tar.gz 18:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:02:59 INFO - Installing collected packages: blobuploader, requests, docopt 18:02:59 INFO - Running setup.py install for blobuploader 18:02:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:02:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:02:59 INFO - Running setup.py install for requests 18:03:00 INFO - Running setup.py install for docopt 18:03:00 INFO - Successfully installed blobuploader requests docopt 18:03:00 INFO - Cleaning up... 18:03:00 INFO - Return code: 0 18:03:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03: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')]} 18:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03: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')]} 18:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:00 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 0x23bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2589a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x272cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2729eb0>, '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 0x272adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272b240>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:03:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:03:00 INFO - Using env: {'DISPLAY': ':0', 18:03:00 INFO - 'HOME': '/home/cltbld', 18:03:00 INFO - 'LANG': 'en_US.UTF-8', 18:03:00 INFO - 'LANGUAGE': 'en_US:en', 18:03:00 INFO - 'LOGNAME': 'cltbld', 18:03:00 INFO - 'MAIL': '/var/mail/cltbld', 18:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:00 INFO - 'MOZ_NO_REMOTE': '1', 18:03:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:00 INFO - 'NO_EM_RESTART': '1', 18:03:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:00 INFO - 'PWD': '/builds/slave/test', 18:03:00 INFO - 'SHELL': '/bin/bash', 18:03:00 INFO - 'SHLVL': '1', 18:03:00 INFO - 'TERM': 'linux', 18:03:00 INFO - 'TMOUT': '86400', 18:03:00 INFO - 'USER': 'cltbld', 18:03:00 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 18:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:00 INFO - '_': '/tools/buildbot/bin/python'} 18:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:00 INFO - Running setup.py (path:/tmp/pip-fFQH8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:00 INFO - Running setup.py (path:/tmp/pip-ZYGZlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:00 INFO - Running setup.py (path:/tmp/pip-LYBUfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:00 INFO - Running setup.py (path:/tmp/pip-uc7Zws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:00 INFO - Running setup.py (path:/tmp/pip-rtuY9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:00 INFO - Running setup.py (path:/tmp/pip-YfaZzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:00 INFO - Running setup.py (path:/tmp/pip-6mVOE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:01 INFO - Running setup.py (path:/tmp/pip-Yxpu5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:01 INFO - Running setup.py (path:/tmp/pip-MFpLbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:01 INFO - Running setup.py (path:/tmp/pip-Po_tKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:01 INFO - Running setup.py (path:/tmp/pip-zcSPFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:01 INFO - Running setup.py (path:/tmp/pip-nlOujN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:01 INFO - Running setup.py (path:/tmp/pip-eqqjCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:01 INFO - Running setup.py (path:/tmp/pip-UIQ3fS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:01 INFO - Running setup.py (path:/tmp/pip-kCkvBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:01 INFO - Running setup.py (path:/tmp/pip-HfsiCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:01 INFO - Running setup.py (path:/tmp/pip-rgspwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:03:01 INFO - Running setup.py install for manifestparser 18:03:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:03:01 INFO - Running setup.py install for mozcrash 18:03:01 INFO - Running setup.py install for mozdebug 18:03:02 INFO - Running setup.py install for mozdevice 18:03:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:03:02 INFO - Running setup.py install for mozfile 18:03:02 INFO - Running setup.py install for mozhttpd 18:03:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:03:02 INFO - Running setup.py install for mozinfo 18:03:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:03:02 INFO - Running setup.py install for mozInstall 18:03:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:03:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:03:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:03:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:03:02 INFO - Running setup.py install for mozleak 18:03:02 INFO - Running setup.py install for mozlog 18:03:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:03:02 INFO - Running setup.py install for moznetwork 18:03:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:03:02 INFO - Running setup.py install for mozprocess 18:03:03 INFO - Running setup.py install for mozprofile 18:03:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:03:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:03:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:03:03 INFO - Running setup.py install for mozrunner 18:03:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:03:03 INFO - Running setup.py install for mozscreenshot 18:03:03 INFO - Running setup.py install for moztest 18:03:03 INFO - Running setup.py install for mozversion 18:03:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:03:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:03:03 INFO - Cleaning up... 18:03:03 INFO - Return code: 0 18:03:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03: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')]} 18:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03: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')]} 18:03:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:03 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 0x23bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2589a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x272cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2729eb0>, '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 0x272adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272b240>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:03:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:03:03 INFO - Using env: {'DISPLAY': ':0', 18:03:03 INFO - 'HOME': '/home/cltbld', 18:03:03 INFO - 'LANG': 'en_US.UTF-8', 18:03:03 INFO - 'LANGUAGE': 'en_US:en', 18:03:03 INFO - 'LOGNAME': 'cltbld', 18:03:03 INFO - 'MAIL': '/var/mail/cltbld', 18:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:03 INFO - 'MOZ_NO_REMOTE': '1', 18:03:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:03 INFO - 'NO_EM_RESTART': '1', 18:03:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:03 INFO - 'PWD': '/builds/slave/test', 18:03:03 INFO - 'SHELL': '/bin/bash', 18:03:03 INFO - 'SHLVL': '1', 18:03:03 INFO - 'TERM': 'linux', 18:03:03 INFO - 'TMOUT': '86400', 18:03:03 INFO - 'USER': 'cltbld', 18:03:03 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 18:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:03 INFO - '_': '/tools/buildbot/bin/python'} 18:03:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:03 INFO - Running setup.py (path:/tmp/pip-qQFpGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:03 INFO - Running setup.py (path:/tmp/pip-3uwqps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:03 INFO - Running setup.py (path:/tmp/pip-nAEFmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:04 INFO - Running setup.py (path:/tmp/pip-o2tQ1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:04 INFO - Running setup.py (path:/tmp/pip-1jVVpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:04 INFO - Running setup.py (path:/tmp/pip-1dTbOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:04 INFO - Running setup.py (path:/tmp/pip-AArHmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:04 INFO - Running setup.py (path:/tmp/pip-yMNVkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:04 INFO - Running setup.py (path:/tmp/pip-8nWDKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:04 INFO - Running setup.py (path:/tmp/pip-xYw2DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:04 INFO - Running setup.py (path:/tmp/pip-Pv1UrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:04 INFO - Running setup.py (path:/tmp/pip-MC5nFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:04 INFO - Running setup.py (path:/tmp/pip-13HIEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:04 INFO - Running setup.py (path:/tmp/pip-A88Pfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:04 INFO - Running setup.py (path:/tmp/pip-66AlEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:04 INFO - Running setup.py (path:/tmp/pip-9AZc2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:04 INFO - Running setup.py (path:/tmp/pip-RHdeJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:03:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:03: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 18:03: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 18:03: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 18:03: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 18:03:06 INFO - Downloading blessings-1.6.tar.gz 18:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:03:06 INFO - Installing collected packages: blessings 18:03:06 INFO - Running setup.py install for blessings 18:03:06 INFO - Successfully installed blessings 18:03:06 INFO - Cleaning up... 18:03:06 INFO - Return code: 0 18:03:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:03:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:03:06 INFO - Reading from file tmpfile_stdout 18:03:06 INFO - Current package versions: 18:03:06 INFO - argparse == 1.2.1 18:03:06 INFO - blessings == 1.6 18:03:06 INFO - blobuploader == 1.2.4 18:03:06 INFO - docopt == 0.6.1 18:03:06 INFO - functools32 == 3.2.3-2 18:03:06 INFO - jsonschema == 2.5.1 18:03:06 INFO - manifestparser == 1.1 18:03:06 INFO - mozInstall == 1.13 18:03:06 INFO - mozcrash == 1.0 18:03:06 INFO - mozdebug == 0.1 18:03:06 INFO - mozdevice == 0.51 18:03:06 INFO - mozfile == 1.2 18:03:06 INFO - mozhttpd == 0.7 18:03:06 INFO - mozinfo == 0.10 18:03:06 INFO - mozleak == 0.1 18:03:06 INFO - mozlog == 3.5 18:03:06 INFO - moznetwork == 0.27 18:03:06 INFO - mozprocess == 0.25 18:03:06 INFO - mozprofile == 0.28 18:03:06 INFO - mozrunner == 6.13 18:03:06 INFO - mozscreenshot == 0.1 18:03:06 INFO - mozsystemmonitor == 0.3 18:03:06 INFO - moztest == 0.8 18:03:06 INFO - mozversion == 1.4 18:03:06 INFO - psutil == 3.1.1 18:03:06 INFO - requests == 1.2.3 18:03:06 INFO - wsgiref == 0.1.2 18:03:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:06 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 0x23bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2589a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x272cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2729eb0>, '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 0x272adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272b240>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:03:06 INFO - Using env: {'DISPLAY': ':0', 18:03:06 INFO - 'HOME': '/home/cltbld', 18:03:06 INFO - 'LANG': 'en_US.UTF-8', 18:03:06 INFO - 'LANGUAGE': 'en_US:en', 18:03:06 INFO - 'LOGNAME': 'cltbld', 18:03:06 INFO - 'MAIL': '/var/mail/cltbld', 18:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:06 INFO - 'MOZ_NO_REMOTE': '1', 18:03:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:06 INFO - 'NO_EM_RESTART': '1', 18:03:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:06 INFO - 'PWD': '/builds/slave/test', 18:03:06 INFO - 'SHELL': '/bin/bash', 18:03:06 INFO - 'SHLVL': '1', 18:03:06 INFO - 'TERM': 'linux', 18:03:06 INFO - 'TMOUT': '86400', 18:03:06 INFO - 'USER': 'cltbld', 18:03:06 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 18:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:06 INFO - '_': '/tools/buildbot/bin/python'} 18:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:03:06 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:07 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:03:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 18:03: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 18:03: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 18:03: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 18:03: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 18:03:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:03:08 INFO - Installing collected packages: marionette-driver, simplejson 18:03:08 INFO - Running setup.py install for marionette-driver 18:03:08 INFO - Running setup.py install for simplejson 18:03:08 INFO - building 'simplejson._speedups' extension 18:03:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:03:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:03:09 INFO - Successfully installed marionette-driver simplejson 18:03:09 INFO - Cleaning up... 18:03:09 INFO - Return code: 0 18:03:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:09 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 0x23bd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2589a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x272cd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2729eb0>, '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 0x272adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272b240>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:03:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:03:09 INFO - Using env: {'DISPLAY': ':0', 18:03:09 INFO - 'HOME': '/home/cltbld', 18:03:09 INFO - 'LANG': 'en_US.UTF-8', 18:03:09 INFO - 'LANGUAGE': 'en_US:en', 18:03:09 INFO - 'LOGNAME': 'cltbld', 18:03:09 INFO - 'MAIL': '/var/mail/cltbld', 18:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:09 INFO - 'MOZ_NO_REMOTE': '1', 18:03:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:09 INFO - 'NO_EM_RESTART': '1', 18:03:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:09 INFO - 'PWD': '/builds/slave/test', 18:03:09 INFO - 'SHELL': '/bin/bash', 18:03:09 INFO - 'SHLVL': '1', 18:03:09 INFO - 'TERM': 'linux', 18:03:09 INFO - 'TMOUT': '86400', 18:03:09 INFO - 'USER': 'cltbld', 18:03:09 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 18:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:09 INFO - '_': '/tools/buildbot/bin/python'} 18:03:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:03:09 INFO - Cleaning up... 18:03:09 INFO - Return code: 0 18:03:09 INFO - Running post-action listener: _resource_record_post_action 18:03:09 INFO - Running post-action listener: _start_resource_monitoring 18:03:09 INFO - Starting resource monitoring. 18:03:09 INFO - [mozharness: 2017-07-29 01:03:09.620633Z] Finished create-virtualenv step (success) 18:03:09 INFO - [mozharness: 2017-07-29 01:03:09.621160Z] Running install step. 18:03:09 INFO - Running pre-action listener: _resource_record_pre_action 18:03:09 INFO - Running main action method: install 18:03:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:03:09 INFO - Reading from file tmpfile_stdout 18:03:09 INFO - Detecting whether we're running mozinstall >=1.0... 18:03:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:03:09 INFO - Reading from file tmpfile_stdout 18:03:09 INFO - Output received: 18:03:09 INFO - Usage: mozinstall [options] installer 18:03:09 INFO - Options: 18:03:09 INFO - -h, --help show this help message and exit 18:03:09 INFO - -d DEST, --destination=DEST 18:03:09 INFO - Directory to install application into. [default: 18:03:09 INFO - "/builds/slave/test"] 18:03:09 INFO - --app=APP Application being installed. [default: firefox] 18:03:09 INFO - mkdir: /builds/slave/test/build/application 18:03:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:03:28 INFO - Reading from file tmpfile_stdout 18:03:28 INFO - Output received: 18:03:28 INFO - /builds/slave/test/build/application/firefox/firefox 18:03:28 INFO - Running post-action listener: _resource_record_post_action 18:03:28 INFO - [mozharness: 2017-07-29 01:03:28.229414Z] Finished install step (success) 18:03:28 INFO - [mozharness: 2017-07-29 01:03:28.229568Z] Running setup-mitmproxy step. 18:03:28 INFO - Running pre-action listener: _resource_record_pre_action 18:03:28 INFO - Running main action method: setup_mitmproxy 18:03:28 INFO - Skipping: mitmproxy is not required 18:03:28 INFO - Running post-action listener: _resource_record_post_action 18:03:28 INFO - [mozharness: 2017-07-29 01:03:28.230724Z] Finished setup-mitmproxy step (success) 18:03:28 INFO - [mozharness: 2017-07-29 01:03:28.230945Z] Running run-tests step. 18:03:28 INFO - Running pre-action listener: _resource_record_pre_action 18:03:28 INFO - Running pre-action listener: _set_gcov_prefix 18:03:28 INFO - Running main action method: run_tests 18:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:03:28 INFO - Python 2.7.3 18:03:28 INFO - Return code: 0 18:03:28 INFO - grabbing minidump binary from tooltool 18:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:28 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 0x2729eb0>, '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 0x272adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272b240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:03:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:03:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:03:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:03:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:03:28 INFO - Return code: 0 18:03:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:03:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:03:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:03:28 INFO - ENV: RUST_BACKTRACE is now 1 18:03:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:03:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:03:28 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/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:03:28 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/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-071 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:03:28 INFO - Using env: {'DISPLAY': ':0', 18:03:28 INFO - 'HOME': '/home/cltbld', 18:03:28 INFO - 'LANG': 'en_US.UTF-8', 18:03:28 INFO - 'LANGUAGE': 'en_US:en', 18:03:28 INFO - 'LOGNAME': 'cltbld', 18:03:28 INFO - 'MAIL': '/var/mail/cltbld', 18:03:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:03:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:28 INFO - 'MOZ_NO_REMOTE': '1', 18:03:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:03:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:03:28 INFO - 'NO_EM_RESTART': '1', 18:03:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:28 INFO - 'PWD': '/builds/slave/test', 18:03:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:03:28 INFO - 'RUST_BACKTRACE': '1', 18:03:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:03:28 INFO - 'SHELL': '/bin/bash', 18:03:28 INFO - 'SHLVL': '1', 18:03:28 INFO - 'STYLO_FORCE_ENABLED': '1', 18:03:28 INFO - 'TERM': 'linux', 18:03:28 INFO - 'TMOUT': '86400', 18:03:28 INFO - 'USER': 'cltbld', 18:03:28 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079', 18:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:28 INFO - '_': '/tools/buildbot/bin/python'} 18:03:28 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/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:03:28 INFO - mozversion application_buildid: 20170729001707 18:03:28 INFO - mozversion application_changeset: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 18:03:28 INFO - mozversion application_display_name: Nightly 18:03:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:03:28 INFO - mozversion application_name: Firefox 18:03:28 INFO - mozversion application_remotingname: firefox 18:03:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:03:28 INFO - mozversion application_vendor: Mozilla 18:03:28 INFO - mozversion application_version: 56.0a1 18:03:28 INFO - mozversion platform_buildid: 20170729001707 18:03:28 INFO - mozversion platform_changeset: ec329722b2f8bad3b1b9d0829e8d89764a879fd1 18:03:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:03:28 INFO - mozversion platform_version: 56.0a1 18:03:28 INFO - using testdate: 1501290208 18:03:28 INFO - actual date: 1501290208 18:03:28 INFO - starting webserver on 'localhost:33083' 18:03:28 INFO - SUITE-START | Running 2 tests 18:03:28 INFO - TEST-START | dromaeo_css 18:03:28 INFO - Initialising browser for dromaeo_css test... 18:03:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33083/getInfo.html -profile /tmp/tmpjXiN1M/profile 18:03:28 INFO - TEST-INFO | started process 12838 (/builds/slave/test/build/application/firefox/firefox http://localhost:33083/getInfo.html) 18:03:34 INFO - TEST-INFO | 12838: exit 0 18:03:34 INFO - Browser initialized. 18:03:34 INFO - Running cycle 1/1 for dromaeo_css test... 18:03:34 INFO - TEST-INFO | started process 13063 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjXiN1M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:03:35 INFO - PID 13063 | 18:03:35 INFO - PID 13063 | (/builds/slave/test/build/application/firefox/firefox:13118): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:03:35 INFO - PID 13063 | 18:03:46 INFO - PID 13063 | RSS: Main: 173854720 18:03:46 INFO - PID 13063 | 18:06:45 INFO - PID 13063 | Cycle 1(1): loaded http://localhost:33083/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33083/tests/dromaeo/cssquery-ext.html) 18:06:46 INFO - PID 13063 | RSS: Main: 175800320 18:06:46 INFO - PID 13063 | 18:09:48 INFO - PID 13063 | Cycle 1(1): loaded http://localhost:33083/tests/dromaeo/cssquery-ext.html (next: http://localhost:33083/tests/dromaeo/cssquery-jquery.html) 18:09:49 INFO - PID 13063 | RSS: Main: 172785664 18:09:49 INFO - PID 13063 | 18:12:51 INFO - PID 13063 | Cycle 1(1): loaded http://localhost:33083/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33083/tests/dromaeo/cssquery-mootools.html) 18:12:52 INFO - PID 13063 | RSS: Main: 175521792 18:12:52 INFO - PID 13063 | 18:15:53 INFO - PID 13063 | Cycle 1(1): loaded http://localhost:33083/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33083/tests/dromaeo/cssquery-prototype.html) 18:15:54 INFO - PID 13063 | RSS: Main: 173932544 18:15:54 INFO - PID 13063 | 18:18:58 INFO - PID 13063 | Cycle 1(1): loaded http://localhost:33083/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33083/tests/dromaeo/cssquery-yui.html) 18:18:59 INFO - PID 13063 | RSS: Main: 172535808 18:18:59 INFO - PID 13063 | 18:22:01 INFO - PID 13063 | Cycle 1(1): loaded http://localhost:33083/tests/dromaeo/cssquery-yui.html (next: http://localhost:33083/tests/dromaeo/cssquery-dojo.html) 18:22:01 INFO - PID 13063 | RSS: Main: 177582080 18:22:01 INFO - PID 13063 | 18:22:01 INFO - PID 13063 | __start_tp_report 18:22:01 INFO - PID 13063 | _x_x_mozilla_page_load 18:22:01 INFO - PID 13063 | _x_x_mozilla_page_load_details 18:22:01 INFO - PID 13063 | |i|pagename|runs| 18:22:01 INFO - PID 13063 | |0;dojo.html;3112.6576076631554;3172.1752344390457;3193.552902593639;3196.792208506447;3199.2001999500126;6243.937560624392;6285.402886725765;6314.968425157874;6348.36516348365;6381.744730210792;6140;6226.782062627809;6227.346128656493;6257.217847769029;6284.685765711708;5698.487136157745;5718.857028574298;5734.827955161331;5767.653940763546;5790;5798;5801.970990145043;5805.24531810864;5806.9709651451685;5824.126381042856;5780.942190578104;5801.361850196475;5805.535557155438;5826.213461182729;5832.125181222829;5684.0905455127395;5698.715064246771;5788.218590490272;5819.330776960662;5830.4461076197695;5518.558515318765;5651.519620832234;5653.575981801381;5677.460641239076;5748.367679555246;6078.33138354785;6115.296740874812;6157.168782214389;6191.907121393183;6266.091416744592;6632.834179145511;6661.866762664719;6746.460283177383;6752.459803215731;6782.084418603524;6693.732250709966;6728.428083612899;6760.526763126583;6760.729570817161;6800.285969973131;6212;6236.376362363772;6253.030780229072;6261.9373806262265;6264.279607845065;6849.520533562602;6976.337247961435;6984.860302793915;7000.824979375455;7017.157941047107;6581.473482121419;6606.207255129415;6621.834454138589;6676.432503237279;6698.83252918681;6710.698018589175;6736.46108311334;6738.932610673928;6814.965925170442;6853.760118395832;6532.640704761188;6669.933300667029;6694.062831203538;6704.5250044990025;6747;6675.39921407066;6723.562968407038;6749.190097188369;6756;6797.320267973262;5377.838664839983;5445.59158063139;5452.563794896399;5453.700046497481;5460.918086228709;4607.838725644587;4623.052274283764;4644.094401591725;4664.86005419838;4678.742669153229;5744.770209191669;5774.624649397776;5824.592278540546;5866.911996320058;5872.412758724094;6819.420349270367;6836.897446538306;6837.384635382839;6841.794746157623;6847.281035491302;5841.912371314434;5845.240118784531;5873.471387575139;5899.351071382144;5899.380565040609;5783.537317014629;5859.355470898197;5905.911411328833;5950.583459157904;5969.552283578749;6422.967885160731;6523.315051919575;6546.116274303004;6619.735210591522;6637.86724265512;6666.300038495888;6731.932680673131;6799.3200679931615;6810.080639113854;6826.795196144124;6515.967420162869;6589.308122647053;6598.835029124119;6615.669216539058;6634;6626.668666566556;6709.664516774044;6713.227978782503;6720.361565651256;6743.224529179207;6759.5606285591275;6769.729210831511;6782.93217067823;6873.140857392826;6913.0667359905765;6496.675166241764;6551.541392102507;6553.246376666744;6558.114654521579;6559.016147577901;6511.153550038464;6514.315996820265;6532.248791389051;6544;6601.801945941629;6464.191976003;6498.057781621733;6508.251551071498;6531.412172904462;6537.215534135934;6585.473162146921;6602.8679426410245;6605.339466053356;6674.43267322283;6690.732370705117;6330.050492426173;6369.681515924278;6370.685948411725;6459.450946669585;6471.288158302489 18:22:01 INFO - PID 13063 | |1;ext.html;779.5400713578987;837.3426859914968;864.4597126795753;870.529913846523;1159.7448561316507;28845.71154288459;30455.543166852516;40594.188116237696;41215.38176927349;41290.380644290286;41381.37927931084;41838;41864.79067604665;41894.581054189526;41924.58075419245;7230.2046774854725;7572.507786993841;7713.190115037924;7732.613369331539;7733.651985660631;7581.924180758177;7736.806579835521;7745.031871016123;7745.65144568493;7749.883751743729;7711.344535714471;7781.377489800802;7819.257170568787;7825.060992680887;7826.295633392993;7704.845903081935;7705.6917723291;7723.845523089535;7730.226977302253;7753.340966017895;7541.77374678755;7661.004069470989;7663.31030207284;7663.501872378278;7673.769786906401;36216.63783362159;36386.45420318671;36591.26817463636;36606.45090323647;36664.63335366666;3481.9651803481893;3517.138301116243;3523.594786599548;3526.9647303526895;3528.8235588220487;30124.397512049854;30277.545836812464;30280;30345.544816827765;30360.54459183114;4558;4618.930716039262;4629.953700463019;4668.85993420198;4733.621310295168;185874;187318;187391;187572.0621396898;188063;46152.3077153843;46272.537274627495;46357.76821115873;46359.30461043087;46583;47363;47452;47486.5251347489;47556;47661.285080723814;15697.764533532007;15956.361745530048;15965.201739912958;15989.040657560583;15995;11650.067994560415;11965.08558630615;12942.223466592035;13100.672483187996;13162.736745265041;13452.730945381038;13505.729885402237;13574.389152488162;13705.314734263247;13845.16928984264;5583.162525621189;5589.133684278943;5622.381538030813;5644.9435505644415;5727.341355744143;976.4483067067226;981.8723565936383;982.3860087445347;1015.1878497202356;1016.517154351677;21844.78155218459;22048.457492817157;22071.227507037176;23056.308310750705;23138;1047.9895201048043;1048.638219814163;1053.5522402978704;1074.2802322443981;1075.6504136155625;11713.414329283672;11737.88262117385;11940.283582985048;12042.939785301018;12068.698282543006;225895;229010;230571;231204;231520;50616.24075638868;50847;51093.74453127711;51143;51254.487455124974;1436.1311406599066;1447.3703938786662;1450.6518435575385;1453.8328092269523;1460.7954886315924;21002.369928902157;21255.256066038233;21671.241506547194;21964.560708785415;22510.774892251524;33565.49651755275;33740.66259337375;33793;33842.8307858469;33900.83049584736;195200;195343;197465;197624;197658;29582.408351833274;29940.101796946128;29997.55003674903;33948.151296217795;34110.829445852614;36869.26261474809;41234.587654123075;41364.79317603393;41385;41719;28296.151115466568;28960.275993100342;29462.263443414085;29844.10467685973;30050.849745751133;26555.867220663775;29636.555451668675;30928;31166.22084447907;31884.6811531891;38025.429618555194;38028.239435210584;38367.61632383752;38491.807540962116;38496.80751596224;30710.385792284476;32317;32559;32752;33215.501767473994;22974.540509190057;23263.040621893102;23467.41331466727;23576.882115589997;23628.763712363343 18:22:01 INFO - PID 13063 | |2;jquery.html;561.4834352395796;562.4684672984032;563.8223959452772;567.4722508067496;586.6685322792623;21467.892660536694;22207.11171553138;22306.776932230674;22473.77526224737;22493.437664058387;24612.630810537856;26445.471090578176;26515.734842651567;26627.86686066567;26895.596566051525;6268.37316268373;6428.517861160408;6429.260635026962;6429.967850160742;6455.870882582346;5759.424057594249;6270.592411493261;6301.054841773725;6341.71462284196;6350.26971898233;6307.936920630781;6326.462250708695;6337.397947195032;6351.142595749561;6357.650329231914;6302.18071650687;6317.463015643676;6329.715162817662;6350.7459701612115;6351.777687780928;5873.412658734135;5882.0588705807295;5899.174115623773;5908.704564771744;5929.584929054968;29010.854945725347;30051.398972020655;30087.247818804488;30359.69640303591;30614.387712245854;1877.671407503695;1921.0298799106502;1924.2976313645559;1929.1897403090595;1934.7871734109237;19018.239270429167;19622.11700473482;19646.803531964782;19676.409707708648;19724.70412943807;2234.54191890662;2275.3390025281637;2284.1434462076722;2285.7828506292044;2410.4817464245098;136590;142109.99999999895;142420.28789856087;142847.28576357156;142974.28512857368;14413.639659008399;14657.267136643339;14815.555533334018;15186.924065379602;15245.847541524665;23168.768312317;23390.766092338858;23432.88283558571;23547.7645223549;23560.528789424116;10245.538950747234;10370.27408081442;10485.842712359321;10521;10528.736781580521;9555.9044409555;9583.712488625353;9671.226301895831;9817.165540928958;9874.061964113398;7535.54786712799;8757.343199260082;9191.448513089235;9274.608808678751;9461.90538094624;2642.6036094585584;2714.5792402177694;2741.533939230336;2814.859257037181;2884.812487188326;921.3596550397543;928.396542247531;952.3524003677566;959.9664011759555;965.285688590438;19341.613167736847;19719.704204436657;20162.092705828272;23947.760522394903;24794.628080578805;1008;1032.7108409645311;1038.8857225705167;1049.7008352619466;1062.670572122629;10982.670519884416;11067.72330691739;11267.380294083903;13496.257705826274;14143.080699754482;523955;531730.5627013708;531793;536099;541601;111873;121692;123607;124203;124340;1228.2078059651653;1240.6960294727849;1278.2969366848383;1279.2899940532907;1284.5118854835102;19569.217231310588;20480.795192047888;20706.515218173914;21749.782502174774;22496.437589060406;57842;57898.13152802627;60351.39648603458;60770.69614651898;61016.08475872954;64763.35236647573;65407.67296163489;65501;65818;66534.66732666305;14800.333984970486;15047.699046018799;15079.849201507845;15189.088654680754;15190.544283671508;23002.424939376648;23014;23321.766782332637;23428.18001369944;23441.179558715365;17991.5502112443;18637.44087677372;18712.90643546828;18846;18873.245070197037;15541;16242.67514649724;16468.83531164721;16799.580010499834;17368.04475753795;18780.812191877907;18950.620987580445;19262.518437039187;19304.13131409062;19413.902930485256;14792.778108328168;15005.399784008954;15005.699886002438;15032.6993460128;15040.774388383965;17476.388326408516;18701.251949922396;18772.812271877105;18801.623967520845;19036.524086897938 18:22:01 INFO - PID 13063 | |3;mootools.html;1362.6593351662084;1441.6540030392703;1445.2556933179408;1445.7469942760015;1453.178953891051;13303;13956.720865582694;13959.651008724786;13993;14006.159630422182;13667.931660341685;14159.433622655108;14618.48835290765;14638.634034149125;14643.414263429477;2666.879990400437;2732.2076597786718;2737.8767955441954;2740;2742.684591272009;2444.792192663626;2579.264909500793;2670.5593577059763;2712.186344096773;2715.375463643363;2523.4574566468204;2698.77060449862;2706.2422521693957;2708.756211940925;2711.5119278529855;2453.141400509825;2480.404702871307;2495.900163993438;2496.950060998779;2497;2524.2805800346814;2552.910648127325;2583.2637698256094;2588.184721812623;2588.585826267788;13740.793888091686;13915.930420347833;13919.30403479832;13924.721505569833;13928.93035534826;7459.738909138208;7684.692612295501;7711.113221979488;7726;7734.690612375498;4552.385427967215;4660.13787449323;4809.422869255712;4810.518948105197;4817.397825271841;1810.8189181081918;1911.7323574699415;2165.7942495462908;2281.851679640818;2302.8503147295373;23097;23476.530469390687;23478.53042939149;23509.529809403888;23521.529569408685;6312.305646378894;6366.331535188831;6518.315576864361;6520.673966301761;6601.405873471314;15582.06507609547;15591.06453612787;15593.610159745871;15612.141332226609;15617.375304987674;7415.629218539051;7522.360599349007;7525.661345239478;7526.811829704301;7532.0208372911175;6910.7581234657555;6966.442684585221;6968.965155174192;7046.859062818715;7101.041359416516;4936.358273424432;5076.314697515862;5086.21163719624;5097.64316497849;5127.64106512548;1344.6638340414897;1371.2937837013947;1374.4845682868925;1381.081580748811;1390.3743315508061;818.2635627934787;827.3960009193203;831.347392297056;860.6342304520554;883.0418995389946;7016;7189.496735228484;7292.489525733254;7293.671784769698;7375.926240737631;664.7307840324676;682.2836022176695;683.1870074611197;688.3048121397325;694.6179601219309;4116.732412393185;4129.504459464883;4189.518205406356;4191.7484950902435;4258.084511829971;17878.28486860537;18676.813231867778;18724.625507490047;18778.624427511648;19433.445324374017;19026.52433689169;19029.429117126507;19091.140898659592;19297.51756206106;19470.80529194718;8730.738077857675;9441.339106262496;9445.102715242041;10427.217958653131;10750;7280.417566594766;7506.587137707478;7645.770626881203;7800.180980996914;8017.238362355567;15244.923775381052;16806.243719032427;16894.155292235584;17073.317067317665;17260.482185534453;16331;17083.40208092711;17157;17264.13679316004;17467.912660436617;10841.457927103456;11443.25618834773;11644.417779110841;11650.592229271935;11701.122415819018;13178.472861085833;13276.668083297995;13317.800232996706;13369.53206637763;13398.0621356508;10303.544770658467;10762.73093172677;10804.135669146292;10905.454727263765;11099.667009989713;10006.799864002534;10119.696409107739;10484.685459436228;10579.10077643409;10611.151107911503;8586.055533891142;8669.566521674018;8675.392722509363;8675.696350627697;8737.519436431057;6220.818044571517;6233.345498722569;6440.0983862260255;6565.179352580927;6619.768308109193;6650;6780.93219067803;6792.184937807495;6863.828404289933;6952.30476952321 18:22:01 INFO - PID 13063 | |4;prototype.html;483.21236384692946;495.88842510435154;498.15065313640247;511.9820806271781;523.6648544931245;6129.127343761709;6151.907721384172;6154.415330543597;6171.16689246951;6193.907091393633;5720.599558030929;6034.909476357858;6038.365971572976;6051.27384713835;6052.152698622196;4871.999999999991;4877.439094504142;4883.780229889646;4926.901461970759;4932.457429682732;4923.261510773377;4938.259261110844;4938.604911607063;4939.382577177853;4956.851294461172;4623.144718227122;4738.928916066262;4807.302941073543;4816.397950256218;4838.951610483885;4816.470188279286;4825.613950883956;4863.610911127137;4879.82920597781;4932.21084626458;4343;4350.108227813291;4359.324304732771;4364.607185353333;4396.1427521633295;5932.84309559636;5985.730642121115;5989.490893274077;6015.218021657201;6047.516198704093;5600.63961623027;5605.710686542077;5632;5641.633293835887;5689.9146512802345;6390.744370225186;6402.71945610875;6408.61548307103;6417.647029413381;6444.935550644481;4783.641226907961;4789.257665061921;4818.204996175627;4831.22700367943;4835.323054772335;6718.328167183291;6789.6605169741315;6794.150731158606;6794.422474089659;6830.726770929157;4788.784504697297;4845.951540484621;4886;4898.681585696918;4920.581750551171;4950.603951683856;4960.70235785854;5001.599872010231;5019.67372120811;5031.685424704604;5585.3297604287745;5589.5807814413265;5640.576956728261;5650.152477128464;5657.858553536194;6346.428821406095;6477.352264773485;6484;6501.902471462932;6533.281339052701;1654.59462431705;1661.5846038490376;1669.0736641164272;1678.9832101679071;1724.8706347024024;1173.4015652017533;1196.8324434579165;1202.0443747220806;1202.308672513291;1208.4441157067847;5671.00757367459;5713.143628051049;5717.599188198918;5719.942800572025;5730.942690573124;6532.08550802888;6533.379328963741;6552.606843589305;6558.376954189344;6618.30507796684;5704.458076482728;5744.971275143597;5756.309242890796;5775.075987841925;5798.33319168301;3510.3330367230146;3530.3645343837843;3532.381833179235;3563.572371315459;3575.1419659281587;6458;6507.934920650828;6523.673816309166;6525.641089740015;6540.542162048706;6732.192136943598;6837.794866154023;6840.7425549568525;6866.553674011173;6915.827104322432;6492.318306577741;6512.218533775977;6517.674116294166;6544;6573.934260657524;6536.215654121535;6553.901691474727;6642.933570664231;6683.231428385798;6712.563683360566;6480.675966201577;6484.5460817742305;6503.772367967098;6563.540552161303;6597.769078082244;6431.614103153825;6473.741050358121;6499.610023398611;6505.479561635152;6545.705443254969;4945.381827271591;4955.851324460272;4982.402111746614;5016.698998060128;5026.698398096126;6718.630475323708;6757.222919364336;6775;6797.966010169917;6811.89782153278;6473.935260647521;6488.545801793829;6498.220213574401;6540.50946179029;6564.573302735307;6213.099100630474;6412.166418365583;6490.188726409199;6492.707828147651;6500.252470965899;6232.501399887908;6310.242770867525;6321.115043893767;6326.588771729823;6362.586431881913;6384.648844313608;6463.967680161569;6501.902471463027;6531.314212007861;6557.704903279459;6217.285012223471;6260.436560709558;6270.498360131087;6280.591761535486;6369.808905732835 18:22:01 INFO - PID 13063 | |5;yui.html;891.7324802559231;918.0773322810575;921.1571412157875;921.3044151665491;930.511481472227;18120.4563863084;18790.718139227905;18796.906015469922;18879.62240755184;18931.716024259647;19711.901440492813;20481.385558433234;21251.14995400186;21403.6789448158;21608.675869861927;1017.7099526634911;1047.3925123428394;1049.9475026248695;1051.6424415698662;1053.1995683280722;960.851068084448;1044.107288268527;1058.5765693722497;1058.6929790360787;1059.7562560611063;1097.0948967102133;1099.1756182862853;1108.6286094158427;1110.3948348150277;1110.9166812489054;1102.2614848051785;1107.2083460325825;1107.7673688525379;1108.2242430298784;1108.706192858885;800.7837883771407;800.8558459477292;804.1114568401948;804.1877703519428;806.0327606871778;18965.24139034437;19772.11025503856;19813.306534271233;20013;20094.799052009585;19839.702404463802;20454.977251137385;20501.692474612744;20927;21035.8948205258;14646.853531464656;14769.70460590793;14788.40846366144;14929.253537323091;15024;3306.768526203167;3361.8991430257133;3365.1418888183603;3367.562216911786;3368.2589830237307;117873.41063294628;120745.39627301895;123783.38108309491;124303.37848310701;127110;16265.59336016609;16308.103054332125;16439.26023328953;16533.83466165347;16585.336586536403;29743.40513189724;29879;29912.10263692093;29985;30072.548911766342;7627.122880868771;8097.433179677366;8254.54599996994;8273.420860539825;8295.792605184919;3693.8153092345274;3768.7361884668358;3788.1476667749534;3791.2038471921205;3795.6014618465215;5039.193729003342;5041.82353617629;5165.819196328111;5188.533032027136;5191.844244672666;937.6530683647094;964.0552258786429;968.3028219681818;968.8401413766791;969.0793745941384;635.0696230023038;635.2250254689271;648.5913874259187;657.7270432770442;666.7199776094052;10819.13446924244;11085.168612353944;11324.660260192208;13160.670983225496;14121.646958825906;667.1027468055457;674.5682763031664;677.5291172634971;683.8290427393151;710.8578284343153;5171.560417364491;5566.35986861508;5600.85997850057;5872.7357268923;6326.114343991845;96259.51870240604;99580;102402.48798755958;104606;105265.47367263115;80324.59837700774;80744.19255807484;82227.5888620553;83533.58233208796;87292;73537.26462735304;77389;77749;84948;85903;11691.941540292584;11921.225120367148;12094.395280235776;12331;13467.3939672713;33687;35407.29185416226;36269.63730362662;37428;37772.81113594415;47341.289880652505;59604;60728.963551823115;65967.0314835824;66052.33947660463;17292.740608890625;19615;19648;19873;19943.900280498503;18311.725324120416;21320.7867921325;21746.565068698856;21978.230761923256;22648.886755566116;18628.81371186271;19301.4209573713;19769.901150494155;19781;20212.19151233934;17696.55758606045;17956.55108622295;18044.819551804838;18764.343247986257;18995.050247487296;16631.168441578116;16714.832851671326;16731.079790611635;16913.408030719358;17006.404775832787;12945.482180712666;13750.587482375544;13964.441422342992;13997.300134993413;14080.78878816798;13232.08894043309;13385;13766.449342026206;13996.930015349859;14609.269536523343 18:22:01 INFO - PID 13063 | __end_tp_report 18:22:01 INFO - PID 13063 | __start_cc_report 18:22:01 INFO - PID 13063 | _x_x_mozilla_cycle_collect,362 18:22:01 INFO - PID 13063 | __end_cc_report 18:22:01 INFO - PID 13063 | __startTimestamp1501291321524__endTimestamp 18:22:01 INFO - PID 13063 | 18:22:01 INFO - PID 13063 | ------- Summary: start ------- 18:22:01 INFO - PID 13063 | Number of tests: 6 18:22:01 INFO - PID 13063 | 18:22:01 INFO - PID 13063 | [#0] dojo.html Cycles:165 Average:6203.00 Median:6496.68 stddev:740.36 (11.4%) stddev-sans-first:701.82 18:22:01 INFO - PID 13063 | Values: 3112.7 3172.2 3193.6 3196.8 3199.2 6243.9 6285.4 6315.0 6348.4 6381.7 6140.0 6226.8 6227.3 6257.2 6284.7 5698.5 5718.9 5734.8 5767.7 5790.0 5798.0 5802.0 5805.2 5807.0 5824.1 5780.9 5801.4 5805.5 5826.2 5832.1 5684.1 5698.7 5788.2 5819.3 5830.4 5518.6 5651.5 5653.6 5677.5 5748.4 6078.3 6115.3 6157.2 6191.9 6266.1 6632.8 6661.9 6746.5 6752.5 6782.1 6693.7 6728.4 6760.5 6760.7 6800.3 6212.0 6236.4 6253.0 6261.9 6264.3 6849.5 6976.3 6984.9 7000.8 7017.2 6581.5 6606.2 6621.8 6676.4 6698.8 6710.7 6736.5 6738.9 6815.0 6853.8 6532.6 6669.9 6694.1 6704.5 6747.0 6675.4 6723.6 6749.2 6756.0 6797.3 5377.8 5445.6 5452.6 5453.7 5460.9 4607.8 4623.1 4644.1 4664.9 4678.7 5744.8 5774.6 5824.6 5866.9 5872.4 6819.4 6836.9 6837.4 6841.8 6847.3 5841.9 5845.2 5873.5 5899.4 5899.4 5783.5 5859.4 5905.9 5950.6 5969.6 6423.0 6523.3 6546.1 6619.7 6637.9 6666.3 6731.9 6799.3 6810.1 6826.8 6516.0 6589.3 6598.8 6615.7 6634.0 6626.7 6709.7 6713.2 6720.4 6743.2 6759.6 6769.7 6782.9 6873.1 6913.1 6496.7 6551.5 6553.2 6558.1 6559.0 6511.2 6514.3 6532.2 6544.0 6601.8 6464.2 6498.1 6508.3 6531.4 6537.2 6585.5 6602.9 6605.3 6674.4 6690.7 6330.1 6369.7 6370.7 6459.5 6471.3 18:22:01 INFO - PID 13063 | 18:22:01 INFO - PID 13063 | [#1] ext.html Cycles:180 Average:36592.25 Median:33817.92 stddev:53198.59 (157.3%) stddev-sans-first:53279.86 18:22:01 INFO - PID 13063 | Values: 779.5 837.3 864.5 870.5 1159.7 28845.7 30455.5 40594.2 41215.4 41290.4 41381.4 41838.0 41864.8 41894.6 41924.6 7230.2 7572.5 7713.2 7732.6 7733.7 7581.9 7736.8 7745.0 7745.7 7749.9 7711.3 7781.4 7819.3 7825.1 7826.3 7704.8 7705.7 7723.8 7730.2 7753.3 7541.8 7661.0 7663.3 7663.5 7673.8 36216.6 36386.5 36591.3 36606.5 36664.6 3482.0 3517.1 3523.6 3527.0 3528.8 30124.4 30277.5 30280.0 30345.5 30360.5 4558.0 4618.9 4630.0 4668.9 4733.6 185874.0 187318.0 187391.0 187572.1 188063.0 46152.3 46272.5 46357.8 46359.3 46583.0 47363.0 47452.0 47486.5 47556.0 47661.3 15697.8 15956.4 15965.2 15989.0 15995.0 11650.1 11965.1 12942.2 13100.7 13162.7 13452.7 13505.7 13574.4 13705.3 13845.2 5583.2 5589.1 5622.4 5644.9 5727.3 976.4 981.9 982.4 1015.2 1016.5 21844.8 22048.5 22071.2 23056.3 23138.0 1048.0 1048.6 1053.6 1074.3 1075.7 11713.4 11737.9 11940.3 12042.9 12068.7 225895.0 229010.0 230571.0 231204.0 231520.0 50616.2 50847.0 51093.7 51143.0 51254.5 1436.1 1447.4 1450.7 1453.8 1460.8 21002.4 21255.3 21671.2 21964.6 22510.8 33565.5 33740.7 33793.0 33842.8 33900.8 195200.0 195343.0 197465.0 197624.0 197658.0 29582.4 29940.1 29997.6 33948.2 34110.8 36869.3 41234.6 41364.8 41385.0 41719.0 28296.2 28960.3 29462.3 29844.1 30050.8 26555.9 29636.6 30928.0 31166.2 31884.7 38025.4 38028.2 38367.6 38491.8 38496.8 30710.4 32317.0 32559.0 32752.0 33215.5 22974.5 23263.0 23467.4 23576.9 23628.8 18:22:01 INFO - PID 13063 | 18:22:01 INFO - PID 13063 | [#2] jquery.html Cycles:180 Average:36355.55 Median:2284.96 stddev:89418.41 (3913.3%) stddev-sans-first:89628.87 18:22:01 INFO - PID 13063 | Values: 561.5 562.5 563.8 567.5 586.7 21467.9 22207.1 22306.8 22473.8 22493.4 24612.6 26445.5 26515.7 26627.9 26895.6 6268.4 6428.5 6429.3 6430.0 6455.9 5759.4 6270.6 6301.1 6341.7 6350.3 6307.9 6326.5 6337.4 6351.1 6357.7 6302.2 6317.5 6329.7 6350.7 6351.8 5873.4 5882.1 5899.2 5908.7 5929.6 29010.9 30051.4 30087.2 30359.7 30614.4 1877.7 1921.0 1924.3 1929.2 1934.8 19018.2 19622.1 19646.8 19676.4 19724.7 2234.5 2275.3 2284.1 2285.8 2410.5 136590.0 142110.0 142420.3 142847.3 142974.3 14413.6 14657.3 14815.6 15186.9 15245.8 23168.8 23390.8 23432.9 23547.8 23560.5 10245.5 10370.3 10485.8 10521.0 10528.7 9555.9 9583.7 9671.2 9817.2 9874.1 7535.5 8757.3 9191.4 9274.6 9461.9 2642.6 2714.6 2741.5 2814.9 2884.8 921.4 928.4 952.4 960.0 965.3 19341.6 19719.7 20162.1 23947.8 24794.6 1008.0 1032.7 1038.9 1049.7 1062.7 10982.7 11067.7 11267.4 13496.3 14143.1 523955.0 531730.6 531793.0 536099.0 541601.0 111873.0 121692.0 123607.0 124203.0 124340.0 1228.2 1240.7 1278.3 1279.3 1284.5 19569.2 20480.8 20706.5 21749.8 22496.4 57842.0 57898.1 60351.4 60770.7 61016.1 64763.4 65407.7 65501.0 65818.0 66534.7 14800.3 15047.7 15079.8 15189.1 15190.5 23002.4 23014.0 23321.8 23428.2 23441.2 17991.6 18637.4 18712.9 18846.0 18873.2 15541.0 16242.7 16468.8 16799.6 17368.0 18780.8 18950.6 19262.5 19304.1 19413.9 14792.8 15005.4 15005.7 15032.7 15040.8 17476.4 18701.3 18772.8 18801.6 19036.5 18:22:01 INFO - PID 13063 | 18:22:01 INFO - PID 13063 | [#3] mootools.html Cycles:180 Average:8533.88 Median:2566.09 stddev:5941.92 (231.6%) stddev-sans-first:5934.16 18:22:01 INFO - PID 13063 | Values: 1362.7 1441.7 1445.3 1445.7 1453.2 13303.0 13956.7 13959.7 13993.0 14006.2 13667.9 14159.4 14618.5 14638.6 14643.4 2666.9 2732.2 2737.9 2740.0 2742.7 2444.8 2579.3 2670.6 2712.2 2715.4 2523.5 2698.8 2706.2 2708.8 2711.5 2453.1 2480.4 2495.9 2497.0 2497.0 2524.3 2552.9 2583.3 2588.2 2588.6 13740.8 13915.9 13919.3 13924.7 13928.9 7459.7 7684.7 7711.1 7726.0 7734.7 4552.4 4660.1 4809.4 4810.5 4817.4 1810.8 1911.7 2165.8 2281.9 2302.9 23097.0 23476.5 23478.5 23509.5 23521.5 6312.3 6366.3 6518.3 6520.7 6601.4 15582.1 15591.1 15593.6 15612.1 15617.4 7415.6 7522.4 7525.7 7526.8 7532.0 6910.8 6966.4 6969.0 7046.9 7101.0 4936.4 5076.3 5086.2 5097.6 5127.6 1344.7 1371.3 1374.5 1381.1 1390.4 818.3 827.4 831.3 860.6 883.0 7016.0 7189.5 7292.5 7293.7 7375.9 664.7 682.3 683.2 688.3 694.6 4116.7 4129.5 4189.5 4191.7 4258.1 17878.3 18676.8 18724.6 18778.6 19433.4 19026.5 19029.4 19091.1 19297.5 19470.8 8730.7 9441.3 9445.1 10427.2 10750.0 7280.4 7506.6 7645.8 7800.2 8017.2 15244.9 16806.2 16894.2 17073.3 17260.5 16331.0 17083.4 17157.0 17264.1 17467.9 10841.5 11443.3 11644.4 11650.6 11701.1 13178.5 13276.7 13317.8 13369.5 13398.1 10303.5 10762.7 10804.1 10905.5 11099.7 10006.8 10119.7 10484.7 10579.1 10611.2 8586.1 8669.6 8675.4 8675.7 8737.5 6220.8 6233.3 6440.1 6565.2 6619.8 6650.0 6780.9 6792.2 6863.8 6952.3 18:22:01 INFO - PID 13063 | 18:22:01 INFO - PID 13063 | [#4] prototype.html Cycles:180 Average:5438.80 Median:6036.64 stddev:1538.14 (25.5%) stddev-sans-first:1496.80 18:22:01 INFO - PID 13063 | Values: 483.2 495.9 498.2 512.0 523.7 6129.1 6151.9 6154.4 6171.2 6193.9 5720.6 6034.9 6038.4 6051.3 6052.2 4872.0 4877.4 4883.8 4926.9 4932.5 4923.3 4938.3 4938.6 4939.4 4956.9 4623.1 4738.9 4807.3 4816.4 4839.0 4816.5 4825.6 4863.6 4879.8 4932.2 4343.0 4350.1 4359.3 4364.6 4396.1 5932.8 5985.7 5989.5 6015.2 6047.5 5600.6 5605.7 5632.0 5641.6 5689.9 6390.7 6402.7 6408.6 6417.6 6444.9 4783.6 4789.3 4818.2 4831.2 4835.3 6718.3 6789.7 6794.2 6794.4 6830.7 4788.8 4846.0 4886.0 4898.7 4920.6 4950.6 4960.7 5001.6 5019.7 5031.7 5585.3 5589.6 5640.6 5650.2 5657.9 6346.4 6477.4 6484.0 6501.9 6533.3 1654.6 1661.6 1669.1 1679.0 1724.9 1173.4 1196.8 1202.0 1202.3 1208.4 5671.0 5713.1 5717.6 5719.9 5730.9 6532.1 6533.4 6552.6 6558.4 6618.3 5704.5 5745.0 5756.3 5775.1 5798.3 3510.3 3530.4 3532.4 3563.6 3575.1 6458.0 6507.9 6523.7 6525.6 6540.5 6732.2 6837.8 6840.7 6866.6 6915.8 6492.3 6512.2 6517.7 6544.0 6573.9 6536.2 6553.9 6642.9 6683.2 6712.6 6480.7 6484.5 6503.8 6563.5 6597.8 6431.6 6473.7 6499.6 6505.5 6545.7 4945.4 4955.9 4982.4 5016.7 5026.7 6718.6 6757.2 6775.0 6798.0 6811.9 6473.9 6488.5 6498.2 6540.5 6564.6 6213.1 6412.2 6490.2 6492.7 6500.3 6232.5 6310.2 6321.1 6326.6 6362.6 6384.6 6464.0 6501.9 6531.3 6557.7 6217.3 6260.4 6270.5 6280.6 6369.8 18:22:01 INFO - PID 13063 | 18:22:01 INFO - PID 13063 | [#5] yui.html Cycles:180 Average:22379.06 Median:20468.18 stddev:29546.79 (144.4%) stddev-sans-first:29585.62 18:22:01 INFO - PID 13063 | Values: 891.7 918.1 921.2 921.3 930.5 18120.5 18790.7 18796.9 18879.6 18931.7 19711.9 20481.4 21251.1 21403.7 21608.7 1017.7 1047.4 1049.9 1051.6 1053.2 960.9 1044.1 1058.6 1058.7 1059.8 1097.1 1099.2 1108.6 1110.4 1110.9 1102.3 1107.2 1107.8 1108.2 1108.7 800.8 800.9 804.1 804.2 806.0 18965.2 19772.1 19813.3 20013.0 20094.8 19839.7 20455.0 20501.7 20927.0 21035.9 14646.9 14769.7 14788.4 14929.3 15024.0 3306.8 3361.9 3365.1 3367.6 3368.3 117873.4 120745.4 123783.4 124303.4 127110.0 16265.6 16308.1 16439.3 16533.8 16585.3 29743.4 29879.0 29912.1 29985.0 30072.5 7627.1 8097.4 8254.5 8273.4 8295.8 3693.8 3768.7 3788.1 3791.2 3795.6 5039.2 5041.8 5165.8 5188.5 5191.8 937.7 964.1 968.3 968.8 969.1 635.1 635.2 648.6 657.7 666.7 10819.1 11085.2 11324.7 13160.7 14121.6 667.1 674.6 677.5 683.8 710.9 5171.6 5566.4 5600.9 5872.7 6326.1 96259.5 99580.0 102402.5 104606.0 105265.5 80324.6 80744.2 82227.6 83533.6 87292.0 73537.3 77389.0 77749.0 84948.0 85903.0 11691.9 11921.2 12094.4 12331.0 13467.4 33687.0 35407.3 36269.6 37428.0 37772.8 47341.3 59604.0 60729.0 65967.0 66052.3 17292.7 19615.0 19648.0 19873.0 19943.9 18311.7 21320.8 21746.6 21978.2 22648.9 18628.8 19301.4 19769.9 19781.0 20212.2 17696.6 17956.6 18044.8 18764.3 18995.1 16631.2 16714.8 16731.1 16913.4 17006.4 12945.5 13750.6 13964.4 13997.3 14080.8 13232.1 13385.0 13766.4 13996.9 14609.3 18:22:01 INFO - PID 13063 | -------- Summary: end -------- 18:22:01 INFO - PID 13063 | 18:22:02 INFO - TEST-INFO | 13063: exit 0 18:22:02 INFO - TEST-OK | dromaeo_css | took 1113628ms 18:22:02 INFO - TEST-START | kraken 18:22:02 INFO - Initialising browser for kraken test... 18:22:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33083/getInfo.html -profile /tmp/tmppE2VZV/profile 18:22:02 INFO - TEST-INFO | started process 13443 (/builds/slave/test/build/application/firefox/firefox http://localhost:33083/getInfo.html) 18:22:08 INFO - TEST-INFO | 13443: exit 0 18:22:08 INFO - Browser initialized. 18:22:08 INFO - Running cycle 1/1 for kraken test... 18:22:08 INFO - TEST-INFO | started process 13669 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppE2VZV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:22:09 INFO - PID 13669 | 18:22:09 INFO - PID 13669 | (/builds/slave/test/build/application/firefox/firefox:13723): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:22:09 INFO - PID 13669 | 18:22:19 INFO - PID 13669 | RSS: Main: 173707264 18:22:19 INFO - PID 13669 | 18:22:22 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33083/tests/kraken/driver.html?testName=audio-beat-detection) 18:22:23 INFO - PID 13669 | RSS: Main: 177618944 18:22:23 INFO - PID 13669 | 18:22:26 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33083/tests/kraken/driver.html?testName=audio-dft) 18:22:26 INFO - PID 13669 | RSS: Main: 178958336 18:22:26 INFO - PID 13669 | 18:22:29 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33083/tests/kraken/driver.html?testName=audio-fft) 18:22:30 INFO - PID 13669 | RSS: Main: 174551040 18:22:30 INFO - PID 13669 | 18:22:32 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33083/tests/kraken/driver.html?testName=audio-oscillator) 18:22:33 INFO - PID 13669 | RSS: Main: 174628864 18:22:33 INFO - PID 13669 | 18:22:35 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33083/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:22:36 INFO - PID 13669 | RSS: Main: 175243264 18:22:36 INFO - PID 13669 | 18:22:39 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33083/tests/kraken/driver.html?testName=imaging-darkroom) 18:22:39 INFO - PID 13669 | RSS: Main: 177889280 18:22:39 INFO - PID 13669 | 18:22:43 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33083/tests/kraken/driver.html?testName=imaging-desaturate) 18:22:44 INFO - PID 13669 | RSS: Main: 176898048 18:22:44 INFO - PID 13669 | 18:22:47 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33083/tests/kraken/driver.html?testName=json-parse-financial) 18:22:47 INFO - PID 13669 | RSS: Main: 178110464 18:22:47 INFO - PID 13669 | 18:22:49 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33083/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:22:50 INFO - PID 13669 | RSS: Main: 178077696 18:22:50 INFO - PID 13669 | 18:22:51 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33083/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:22:52 INFO - PID 13669 | RSS: Main: 174415872 18:22:52 INFO - PID 13669 | 18:22:54 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33083/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:22:55 INFO - PID 13669 | RSS: Main: 175190016 18:22:55 INFO - PID 13669 | 18:22:58 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33083/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:22:58 INFO - PID 13669 | RSS: Main: 175710208 18:22:58 INFO - PID 13669 | 18:23:02 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33083/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:23:02 INFO - PID 13669 | RSS: Main: 175353856 18:23:02 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | Cycle 1(1): loaded http://localhost:33083/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33083/tests/kraken/driver.html?testName=ai-astar) 18:23:04 INFO - PID 13669 | RSS: Main: 177180672 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | __start_tp_report 18:23:04 INFO - PID 13669 | _x_x_mozilla_page_load 18:23:04 INFO - PID 13669 | _x_x_mozilla_page_load_details 18:23:04 INFO - PID 13669 | |i|pagename|runs| 18:23:04 INFO - PID 13669 | |0;ai-astar;100;109;103;107;110;110;110;103;113;110 18:23:04 INFO - PID 13669 | |1;audio-beat-detection;157;118;148;165;117;141;168;124;173;119 18:23:04 INFO - PID 13669 | |2;audio-dft;163;161;161;162;162;170;167;174;167;163 18:23:04 INFO - PID 13669 | |3;audio-fft;76;84;81;180;76;75;74;79;164;77 18:23:04 INFO - PID 13669 | |4;audio-oscillator;79;79;138;79;80;84;130;78;79;149 18:23:04 INFO - PID 13669 | |5;imaging-gaussian-blur;114;118;114;113;114;114;114;122;114;114 18:23:04 INFO - PID 13669 | |6;imaging-darkroom;153;150;151;151;150;154;152;157;151;151 18:23:04 INFO - PID 13669 | |7;imaging-desaturate;95;95;99;94;94;94;94;97;93;95 18:23:04 INFO - PID 13669 | |8;json-parse-financial;57;57;58;57;60;57;59;58;63;62 18:23:04 INFO - PID 13669 | |9;json-stringify-tinderbox;54;55;55;54;54;54;54;55;57;54 18:23:04 INFO - PID 13669 | |10;stanford-crypto-aes;81;79;89;89;80;83;82;83;81;82 18:23:04 INFO - PID 13669 | |11;stanford-crypto-ccm;130;132;129;143;130;132;130;144;132;170 18:23:04 INFO - PID 13669 | |12;stanford-crypto-pbkdf2;197;186;187;186;179;180;176;184;181;186 18:23:04 INFO - PID 13669 | |13;stanford-crypto-sha256-iterative;69;68;74;70;73;74;67;67;69;67 18:23:04 INFO - PID 13669 | __end_tp_report 18:23:04 INFO - PID 13669 | __start_cc_report 18:23:04 INFO - PID 13669 | _x_x_mozilla_cycle_collect,930 18:23:04 INFO - PID 13669 | __end_cc_report 18:23:04 INFO - PID 13669 | __startTimestamp1501291384825__endTimestamp 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | ------- Summary: start ------- 18:23:04 INFO - PID 13669 | Number of tests: 14 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#0] ai-astar Cycles:10 Average:107.50 Median:110.00 stddev:4.14 (3.8%) stddev-sans-first:3.39 18:23:04 INFO - PID 13669 | Values: 100.0 109.0 103.0 107.0 110.0 110.0 110.0 103.0 113.0 110.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#1] audio-beat-detection Cycles:10 Average:143.00 Median:152.50 stddev:22.29 (14.6%) stddev-sans-first:23.06 18:23:04 INFO - PID 13669 | Values: 157.0 118.0 148.0 165.0 117.0 141.0 168.0 124.0 173.0 119.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#2] audio-dft Cycles:10 Average:165.00 Median:165.00 stddev:4.37 (2.6%) stddev-sans-first:4.58 18:23:04 INFO - PID 13669 | Values: 163.0 161.0 161.0 162.0 162.0 170.0 167.0 174.0 167.0 163.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#3] audio-fft Cycles:10 Average:96.60 Median:76.50 stddev:40.03 (52.3%) stddev-sans-first:41.76 18:23:04 INFO - PID 13669 | Values: 76.0 84.0 81.0 180.0 76.0 75.0 74.0 79.0 164.0 77.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:79.00 stddev:29.03 (36.8%) stddev-sans-first:30.01 18:23:04 INFO - PID 13669 | Values: 79.0 79.0 138.0 79.0 80.0 84.0 130.0 78.0 79.0 149.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:114.00 stddev:2.77 (2.4%) stddev-sans-first:2.91 18:23:04 INFO - PID 13669 | Values: 114.0 118.0 114.0 113.0 114.0 114.0 114.0 122.0 114.0 114.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#6] imaging-darkroom Cycles:10 Average:152.00 Median:151.50 stddev:2.16 (1.4%) stddev-sans-first:2.26 18:23:04 INFO - PID 13669 | Values: 153.0 150.0 151.0 151.0 150.0 154.0 152.0 157.0 151.0 151.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:1.76 (1.9%) stddev-sans-first:1.87 18:23:04 INFO - PID 13669 | Values: 95.0 95.0 99.0 94.0 94.0 94.0 94.0 97.0 93.0 95.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:58.50 stddev:2.20 (3.8%) stddev-sans-first:2.24 18:23:04 INFO - PID 13669 | Values: 57.0 57.0 58.0 57.0 60.0 57.0 59.0 58.0 63.0 62.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#9] json-stringify-tinderbox Cycles:10 Average:54.60 Median:54.50 stddev:0.97 (1.8%) stddev-sans-first:1.00 18:23:04 INFO - PID 13669 | Values: 54.0 55.0 55.0 54.0 54.0 54.0 54.0 55.0 57.0 54.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:82.50 stddev:3.45 (4.2%) stddev-sans-first:3.59 18:23:04 INFO - PID 13669 | Values: 81.0 79.0 89.0 89.0 80.0 83.0 82.0 83.0 81.0 82.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#11] stanford-crypto-ccm Cycles:10 Average:137.20 Median:132.00 stddev:12.73 (9.6%) stddev-sans-first:13.24 18:23:04 INFO - PID 13669 | Values: 130.0 132.0 129.0 143.0 130.0 132.0 130.0 144.0 132.0 170.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.20 Median:186.00 stddev:5.81 (3.1%) stddev-sans-first:3.90 18:23:04 INFO - PID 13669 | Values: 197.0 186.0 187.0 186.0 179.0 180.0 176.0 184.0 181.0 186.0 18:23:04 INFO - PID 13669 | 18:23:04 INFO - PID 13669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:69.50 stddev:2.86 (4.1%) stddev-sans-first:3.02 18:23:04 INFO - PID 13669 | Values: 69.0 68.0 74.0 70.0 73.0 74.0 67.0 67.0 69.0 67.0 18:23:04 INFO - PID 13669 | -------- Summary: end -------- 18:23:04 INFO - PID 13669 | 18:23:05 INFO - PID 13669 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:23:05 INFO - TEST-INFO | 13669: exit 0 18:23:05 INFO - TEST-OK | kraken | took 63289ms 18:23:05 INFO - SUITE-END | took 1176s 18:23:05 INFO - Completed test suite (00:19:37) 18:23:05 INFO - javascript benchmark 18:23:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8355.852291200768, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3112.6576076631554, 3172.1752344390457, 3193.552902593639, 3196.792208506447, 3199.2001999500126, 6243.937560624392, 6285.402886725765, 6314.968425157874, 6348.36516348365, 6381.744730210792, 6140.0, 6226.782062627809, 6227.346128656493, 6257.217847769029, 6284.685765711708, 5698.487136157745, 5718.857028574298, 5734.827955161331, 5767.653940763546, 5790.0, 5798.0, 5801.970990145043, 5805.24531810864, 5806.9709651451685, 5824.126381042856, 5780.942190578104, 5801.361850196475, 5805.535557155438, 5826.213461182729, 5832.125181222829, 5684.0905455127395, 5698.715064246771, 5788.218590490272, 5819.330776960662, 5830.4461076197695, 5518.558515318765, 5651.519620832234, 5653.575981801381, 5677.460641239076, 5748.367679555246, 6078.33138354785, 6115.296740874812, 6157.168782214389, 6191.907121393183, 6266.091416744592, 6632.834179145511, 6661.866762664719, 6746.460283177383, 6752.459803215731, 6782.084418603524, 6693.732250709966, 6728.428083612899, 6760.526763126583, 6760.729570817161, 6800.285969973131, 6212.0, 6236.376362363772, 6253.030780229072, 6261.9373806262265, 6264.279607845065, 6849.520533562602, 6976.337247961435, 6984.860302793915, 7000.824979375455, 7017.157941047107, 6581.473482121419, 6606.207255129415, 6621.834454138589, 6676.432503237279, 6698.83252918681, 6710.698018589175, 6736.46108311334, 6738.932610673928, 6814.965925170442, 6853.760118395832, 6532.640704761188, 6669.933300667029, 6694.062831203538, 6704.5250044990025, 6747.0, 6675.39921407066, 6723.562968407038, 6749.190097188369, 6756.0, 6797.320267973262, 5377.838664839983, 5445.59158063139, 5452.563794896399, 5453.700046497481, 5460.918086228709, 4607.838725644587, 4623.052274283764, 4644.094401591725, 4664.86005419838, 4678.742669153229, 5744.770209191669, 5774.624649397776, 5824.592278540546, 5866.911996320058, 5872.412758724094, 6819.420349270367, 6836.897446538306, 6837.384635382839, 6841.794746157623, 6847.281035491302, 5841.912371314434, 5845.240118784531, 5873.471387575139, 5899.351071382144, 5899.380565040609, 5783.537317014629, 5859.355470898197, 5905.911411328833, 5950.583459157904, 5969.552283578749, 6422.967885160731, 6523.315051919575, 6546.116274303004, 6619.735210591522, 6637.86724265512, 6666.300038495888, 6731.932680673131, 6799.3200679931615, 6810.080639113854, 6826.795196144124, 6515.967420162869, 6589.308122647053, 6598.835029124119, 6615.669216539058, 6634.0, 6626.668666566556, 6709.664516774044, 6713.227978782503, 6720.361565651256, 6743.224529179207, 6759.5606285591275, 6769.729210831511, 6782.93217067823, 6873.140857392826, 6913.0667359905765, 6496.675166241764, 6551.541392102507, 6553.246376666744, 6558.114654521579, 6559.016147577901, 6511.153550038464, 6514.315996820265, 6532.248791389051, 6544.0, 6601.801945941629, 6464.191976003, 6498.057781621733, 6508.251551071498, 6531.412172904462, 6537.215534135934, 6585.473162146921, 6602.8679426410245, 6605.339466053356, 6674.43267322283, 6690.732370705117, 6330.050492426173, 6369.681515924278, 6370.685948411725, 6459.450946669585, 6471.288158302489], "value": 6146.8526856977105, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.5400713578987, 837.3426859914968, 864.4597126795753, 870.529913846523, 1159.7448561316507, 28845.71154288459, 30455.543166852516, 40594.188116237696, 41215.38176927349, 41290.380644290286, 41381.37927931084, 41838.0, 41864.79067604665, 41894.581054189526, 41924.58075419245, 7230.2046774854725, 7572.507786993841, 7713.190115037924, 7732.613369331539, 7733.651985660631, 7581.924180758177, 7736.806579835521, 7745.031871016123, 7745.65144568493, 7749.883751743729, 7711.344535714471, 7781.377489800802, 7819.257170568787, 7825.060992680887, 7826.295633392993, 7704.845903081935, 7705.6917723291, 7723.845523089535, 7730.226977302253, 7753.340966017895, 7541.77374678755, 7661.004069470989, 7663.31030207284, 7663.501872378278, 7673.769786906401, 36216.63783362159, 36386.45420318671, 36591.26817463636, 36606.45090323647, 36664.63335366666, 3481.9651803481893, 3517.138301116243, 3523.594786599548, 3526.9647303526895, 3528.8235588220487, 30124.397512049854, 30277.545836812464, 30280.0, 30345.544816827765, 30360.54459183114, 4558.0, 4618.930716039262, 4629.953700463019, 4668.85993420198, 4733.621310295168, 185874.0, 187318.0, 187391.0, 187572.0621396898, 188063.0, 46152.3077153843, 46272.537274627495, 46357.76821115873, 46359.30461043087, 46583.0, 47363.0, 47452.0, 47486.5251347489, 47556.0, 47661.285080723814, 15697.764533532007, 15956.361745530048, 15965.201739912958, 15989.040657560583, 15995.0, 11650.067994560415, 11965.08558630615, 12942.223466592035, 13100.672483187996, 13162.736745265041, 13452.730945381038, 13505.729885402237, 13574.389152488162, 13705.314734263247, 13845.16928984264, 5583.162525621189, 5589.133684278943, 5622.381538030813, 5644.9435505644415, 5727.341355744143, 976.4483067067226, 981.8723565936383, 982.3860087445347, 1015.1878497202356, 1016.517154351677, 21844.78155218459, 22048.457492817157, 22071.227507037176, 23056.308310750705, 23138.0, 1047.9895201048043, 1048.638219814163, 1053.5522402978704, 1074.2802322443981, 1075.6504136155625, 11713.414329283672, 11737.88262117385, 11940.283582985048, 12042.939785301018, 12068.698282543006, 225895.0, 229010.0, 230571.0, 231204.0, 231520.0, 50616.24075638868, 50847.0, 51093.74453127711, 51143.0, 51254.487455124974, 1436.1311406599066, 1447.3703938786662, 1450.6518435575385, 1453.8328092269523, 1460.7954886315924, 21002.369928902157, 21255.256066038233, 21671.241506547194, 21964.560708785415, 22510.774892251524, 33565.49651755275, 33740.66259337375, 33793.0, 33842.8307858469, 33900.83049584736, 195200.0, 195343.0, 197465.0, 197624.0, 197658.0, 29582.408351833274, 29940.101796946128, 29997.55003674903, 33948.151296217795, 34110.829445852614, 36869.26261474809, 41234.587654123075, 41364.79317603393, 41385.0, 41719.0, 28296.151115466568, 28960.275993100342, 29462.263443414085, 29844.10467685973, 30050.849745751133, 26555.867220663775, 29636.555451668675, 30928.0, 31166.22084447907, 31884.6811531891, 38025.429618555194, 38028.239435210584, 38367.61632383752, 38491.807540962116, 38496.80751596224, 30710.385792284476, 32317.0, 32559.0, 32752.0, 33215.501767473994, 22974.540509190057, 23263.040621893102, 23467.41331466727, 23576.882115589997, 23628.763712363343], "value": 16656.702401774255, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.4834352395796, 562.4684672984032, 563.8223959452772, 567.4722508067496, 586.6685322792623, 21467.892660536694, 22207.11171553138, 22306.776932230674, 22473.77526224737, 22493.437664058387, 24612.630810537856, 26445.471090578176, 26515.734842651567, 26627.86686066567, 26895.596566051525, 6268.37316268373, 6428.517861160408, 6429.260635026962, 6429.967850160742, 6455.870882582346, 5759.424057594249, 6270.592411493261, 6301.054841773725, 6341.71462284196, 6350.26971898233, 6307.936920630781, 6326.462250708695, 6337.397947195032, 6351.142595749561, 6357.650329231914, 6302.18071650687, 6317.463015643676, 6329.715162817662, 6350.7459701612115, 6351.777687780928, 5873.412658734135, 5882.0588705807295, 5899.174115623773, 5908.704564771744, 5929.584929054968, 29010.854945725347, 30051.398972020655, 30087.247818804488, 30359.69640303591, 30614.387712245854, 1877.671407503695, 1921.0298799106502, 1924.2976313645559, 1929.1897403090595, 1934.7871734109237, 19018.239270429167, 19622.11700473482, 19646.803531964782, 19676.409707708648, 19724.70412943807, 2234.54191890662, 2275.3390025281637, 2284.1434462076722, 2285.7828506292044, 2410.4817464245098, 136590.0, 142109.99999999895, 142420.28789856087, 142847.28576357156, 142974.28512857368, 14413.639659008399, 14657.267136643339, 14815.555533334018, 15186.924065379602, 15245.847541524665, 23168.768312317, 23390.766092338858, 23432.88283558571, 23547.7645223549, 23560.528789424116, 10245.538950747234, 10370.27408081442, 10485.842712359321, 10521.0, 10528.736781580521, 9555.9044409555, 9583.712488625353, 9671.226301895831, 9817.165540928958, 9874.061964113398, 7535.54786712799, 8757.343199260082, 9191.448513089235, 9274.608808678751, 9461.90538094624, 2642.6036094585584, 2714.5792402177694, 2741.533939230336, 2814.859257037181, 2884.812487188326, 921.3596550397543, 928.396542247531, 952.3524003677566, 959.9664011759555, 965.285688590438, 19341.613167736847, 19719.704204436657, 20162.092705828272, 23947.760522394903, 24794.628080578805, 1008.0, 1032.7108409645311, 1038.8857225705167, 1049.7008352619466, 1062.670572122629, 10982.670519884416, 11067.72330691739, 11267.380294083903, 13496.257705826274, 14143.080699754482, 523955.0, 531730.5627013708, 531793.0, 536099.0, 541601.0, 111873.0, 121692.0, 123607.0, 124203.0, 124340.0, 1228.2078059651653, 1240.6960294727849, 1278.2969366848383, 1279.2899940532907, 1284.5118854835102, 19569.217231310588, 20480.795192047888, 20706.515218173914, 21749.782502174774, 22496.437589060406, 57842.0, 57898.13152802627, 60351.39648603458, 60770.69614651898, 61016.08475872954, 64763.35236647573, 65407.67296163489, 65501.0, 65818.0, 66534.66732666305, 14800.333984970486, 15047.699046018799, 15079.849201507845, 15189.088654680754, 15190.544283671508, 23002.424939376648, 23014.0, 23321.766782332637, 23428.18001369944, 23441.179558715365, 17991.5502112443, 18637.44087677372, 18712.90643546828, 18846.0, 18873.245070197037, 15541.0, 16242.67514649724, 16468.83531164721, 16799.580010499834, 17368.04475753795, 18780.812191877907, 18950.620987580445, 19262.518437039187, 19304.13131409062, 19413.902930485256, 14792.778108328168, 15005.399784008954, 15005.699886002438, 15032.6993460128, 15040.774388383965, 17476.388326408516, 18701.251949922396, 18772.812271877105, 18801.623967520845, 19036.524086897938], "value": 12104.79889236116, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1362.6593351662084, 1441.6540030392703, 1445.2556933179408, 1445.7469942760015, 1453.178953891051, 13303.0, 13956.720865582694, 13959.651008724786, 13993.0, 14006.159630422182, 13667.931660341685, 14159.433622655108, 14618.48835290765, 14638.634034149125, 14643.414263429477, 2666.879990400437, 2732.2076597786718, 2737.8767955441954, 2740.0, 2742.684591272009, 2444.792192663626, 2579.264909500793, 2670.5593577059763, 2712.186344096773, 2715.375463643363, 2523.4574566468204, 2698.77060449862, 2706.2422521693957, 2708.756211940925, 2711.5119278529855, 2453.141400509825, 2480.404702871307, 2495.900163993438, 2496.950060998779, 2497.0, 2524.2805800346814, 2552.910648127325, 2583.2637698256094, 2588.184721812623, 2588.585826267788, 13740.793888091686, 13915.930420347833, 13919.30403479832, 13924.721505569833, 13928.93035534826, 7459.738909138208, 7684.692612295501, 7711.113221979488, 7726.0, 7734.690612375498, 4552.385427967215, 4660.13787449323, 4809.422869255712, 4810.518948105197, 4817.397825271841, 1810.8189181081918, 1911.7323574699415, 2165.7942495462908, 2281.851679640818, 2302.8503147295373, 23097.0, 23476.530469390687, 23478.53042939149, 23509.529809403888, 23521.529569408685, 6312.305646378894, 6366.331535188831, 6518.315576864361, 6520.673966301761, 6601.405873471314, 15582.06507609547, 15591.06453612787, 15593.610159745871, 15612.141332226609, 15617.375304987674, 7415.629218539051, 7522.360599349007, 7525.661345239478, 7526.811829704301, 7532.0208372911175, 6910.7581234657555, 6966.442684585221, 6968.965155174192, 7046.859062818715, 7101.041359416516, 4936.358273424432, 5076.314697515862, 5086.21163719624, 5097.64316497849, 5127.64106512548, 1344.6638340414897, 1371.2937837013947, 1374.4845682868925, 1381.081580748811, 1390.3743315508061, 818.2635627934787, 827.3960009193203, 831.347392297056, 860.6342304520554, 883.0418995389946, 7016.0, 7189.496735228484, 7292.489525733254, 7293.671784769698, 7375.926240737631, 664.7307840324676, 682.2836022176695, 683.1870074611197, 688.3048121397325, 694.6179601219309, 4116.732412393185, 4129.504459464883, 4189.518205406356, 4191.7484950902435, 4258.084511829971, 17878.28486860537, 18676.813231867778, 18724.625507490047, 18778.624427511648, 19433.445324374017, 19026.52433689169, 19029.429117126507, 19091.140898659592, 19297.51756206106, 19470.80529194718, 8730.738077857675, 9441.339106262496, 9445.102715242041, 10427.217958653131, 10750.0, 7280.417566594766, 7506.587137707478, 7645.770626881203, 7800.180980996914, 8017.238362355567, 15244.923775381052, 16806.243719032427, 16894.155292235584, 17073.317067317665, 17260.482185534453, 16331.0, 17083.40208092711, 17157.0, 17264.13679316004, 17467.912660436617, 10841.457927103456, 11443.25618834773, 11644.417779110841, 11650.592229271935, 11701.122415819018, 13178.472861085833, 13276.668083297995, 13317.800232996706, 13369.53206637763, 13398.0621356508, 10303.544770658467, 10762.73093172677, 10804.135669146292, 10905.454727263765, 11099.667009989713, 10006.799864002534, 10119.696409107739, 10484.685459436228, 10579.10077643409, 10611.151107911503, 8586.055533891142, 8669.566521674018, 8675.392722509363, 8675.696350627697, 8737.519436431057, 6220.818044571517, 6233.345498722569, 6440.0983862260255, 6565.179352580927, 6619.768308109193, 6650.0, 6780.93219067803, 6792.184937807495, 6863.828404289933, 6952.30476952321], "value": 6162.012452038941, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.21236384692946, 495.88842510435154, 498.15065313640247, 511.9820806271781, 523.6648544931245, 6129.127343761709, 6151.907721384172, 6154.415330543597, 6171.16689246951, 6193.907091393633, 5720.599558030929, 6034.909476357858, 6038.365971572976, 6051.27384713835, 6052.152698622196, 4871.999999999991, 4877.439094504142, 4883.780229889646, 4926.901461970759, 4932.457429682732, 4923.261510773377, 4938.259261110844, 4938.604911607063, 4939.382577177853, 4956.851294461172, 4623.144718227122, 4738.928916066262, 4807.302941073543, 4816.397950256218, 4838.951610483885, 4816.470188279286, 4825.613950883956, 4863.610911127137, 4879.82920597781, 4932.21084626458, 4343.0, 4350.108227813291, 4359.324304732771, 4364.607185353333, 4396.1427521633295, 5932.84309559636, 5985.730642121115, 5989.490893274077, 6015.218021657201, 6047.516198704093, 5600.63961623027, 5605.710686542077, 5632.0, 5641.633293835887, 5689.9146512802345, 6390.744370225186, 6402.71945610875, 6408.61548307103, 6417.647029413381, 6444.935550644481, 4783.641226907961, 4789.257665061921, 4818.204996175627, 4831.22700367943, 4835.323054772335, 6718.328167183291, 6789.6605169741315, 6794.150731158606, 6794.422474089659, 6830.726770929157, 4788.784504697297, 4845.951540484621, 4886.0, 4898.681585696918, 4920.581750551171, 4950.603951683856, 4960.70235785854, 5001.599872010231, 5019.67372120811, 5031.685424704604, 5585.3297604287745, 5589.5807814413265, 5640.576956728261, 5650.152477128464, 5657.858553536194, 6346.428821406095, 6477.352264773485, 6484.0, 6501.902471462932, 6533.281339052701, 1654.59462431705, 1661.5846038490376, 1669.0736641164272, 1678.9832101679071, 1724.8706347024024, 1173.4015652017533, 1196.8324434579165, 1202.0443747220806, 1202.308672513291, 1208.4441157067847, 5671.00757367459, 5713.143628051049, 5717.599188198918, 5719.942800572025, 5730.942690573124, 6532.08550802888, 6533.379328963741, 6552.606843589305, 6558.376954189344, 6618.30507796684, 5704.458076482728, 5744.971275143597, 5756.309242890796, 5775.075987841925, 5798.33319168301, 3510.3330367230146, 3530.3645343837843, 3532.381833179235, 3563.572371315459, 3575.1419659281587, 6458.0, 6507.934920650828, 6523.673816309166, 6525.641089740015, 6540.542162048706, 6732.192136943598, 6837.794866154023, 6840.7425549568525, 6866.553674011173, 6915.827104322432, 6492.318306577741, 6512.218533775977, 6517.674116294166, 6544.0, 6573.934260657524, 6536.215654121535, 6553.901691474727, 6642.933570664231, 6683.231428385798, 6712.563683360566, 6480.675966201577, 6484.5460817742305, 6503.772367967098, 6563.540552161303, 6597.769078082244, 6431.614103153825, 6473.741050358121, 6499.610023398611, 6505.479561635152, 6545.705443254969, 4945.381827271591, 4955.851324460272, 4982.402111746614, 5016.698998060128, 5026.698398096126, 6718.630475323708, 6757.222919364336, 6775.0, 6797.966010169917, 6811.89782153278, 6473.935260647521, 6488.545801793829, 6498.220213574401, 6540.50946179029, 6564.573302735307, 6213.099100630474, 6412.166418365583, 6490.188726409199, 6492.707828147651, 6500.252470965899, 6232.501399887908, 6310.242770867525, 6321.115043893767, 6326.588771729823, 6362.586431881913, 6384.648844313608, 6463.967680161569, 6501.902471463027, 6531.314212007861, 6557.704903279459, 6217.285012223471, 6260.436560709558, 6270.498360131087, 6280.591761535486, 6369.808905732835], "value": 4983.134596479224, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [891.7324802559231, 918.0773322810575, 921.1571412157875, 921.3044151665491, 930.511481472227, 18120.4563863084, 18790.718139227905, 18796.906015469922, 18879.62240755184, 18931.716024259647, 19711.901440492813, 20481.385558433234, 21251.14995400186, 21403.6789448158, 21608.675869861927, 1017.7099526634911, 1047.3925123428394, 1049.9475026248695, 1051.6424415698662, 1053.1995683280722, 960.851068084448, 1044.107288268527, 1058.5765693722497, 1058.6929790360787, 1059.7562560611063, 1097.0948967102133, 1099.1756182862853, 1108.6286094158427, 1110.3948348150277, 1110.9166812489054, 1102.2614848051785, 1107.2083460325825, 1107.7673688525379, 1108.2242430298784, 1108.706192858885, 800.7837883771407, 800.8558459477292, 804.1114568401948, 804.1877703519428, 806.0327606871778, 18965.24139034437, 19772.11025503856, 19813.306534271233, 20013.0, 20094.799052009585, 19839.702404463802, 20454.977251137385, 20501.692474612744, 20927.0, 21035.8948205258, 14646.853531464656, 14769.70460590793, 14788.40846366144, 14929.253537323091, 15024.0, 3306.768526203167, 3361.8991430257133, 3365.1418888183603, 3367.562216911786, 3368.2589830237307, 117873.41063294628, 120745.39627301895, 123783.38108309491, 124303.37848310701, 127110.0, 16265.59336016609, 16308.103054332125, 16439.26023328953, 16533.83466165347, 16585.336586536403, 29743.40513189724, 29879.0, 29912.10263692093, 29985.0, 30072.548911766342, 7627.122880868771, 8097.433179677366, 8254.54599996994, 8273.420860539825, 8295.792605184919, 3693.8153092345274, 3768.7361884668358, 3788.1476667749534, 3791.2038471921205, 3795.6014618465215, 5039.193729003342, 5041.82353617629, 5165.819196328111, 5188.533032027136, 5191.844244672666, 937.6530683647094, 964.0552258786429, 968.3028219681818, 968.8401413766791, 969.0793745941384, 635.0696230023038, 635.2250254689271, 648.5913874259187, 657.7270432770442, 666.7199776094052, 10819.13446924244, 11085.168612353944, 11324.660260192208, 13160.670983225496, 14121.646958825906, 667.1027468055457, 674.5682763031664, 677.5291172634971, 683.8290427393151, 710.8578284343153, 5171.560417364491, 5566.35986861508, 5600.85997850057, 5872.7357268923, 6326.114343991845, 96259.51870240604, 99580.0, 102402.48798755958, 104606.0, 105265.47367263115, 80324.59837700774, 80744.19255807484, 82227.5888620553, 83533.58233208796, 87292.0, 73537.26462735304, 77389.0, 77749.0, 84948.0, 85903.0, 11691.941540292584, 11921.225120367148, 12094.395280235776, 12331.0, 13467.3939672713, 33687.0, 35407.29185416226, 36269.63730362662, 37428.0, 37772.81113594415, 47341.289880652505, 59604.0, 60728.963551823115, 65967.0314835824, 66052.33947660463, 17292.740608890625, 19615.0, 19648.0, 19873.0, 19943.900280498503, 18311.725324120416, 21320.7867921325, 21746.565068698856, 21978.230761923256, 22648.886755566116, 18628.81371186271, 19301.4209573713, 19769.901150494155, 19781.0, 20212.19151233934, 17696.55758606045, 17956.55108622295, 18044.819551804838, 18764.343247986257, 18995.050247487296, 16631.168441578116, 16714.832851671326, 16731.079790611635, 16913.408030719358, 17006.404775832787, 12945.482180712666, 13750.587482375544, 13964.441422342992, 13997.300134993413, 14080.78878816798, 13232.08894043309, 13385.0, 13766.449342026206, 13996.930015349859, 14609.269536523343], "value": 8943.200839966588, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 103.0, 107.0, 110.0, 110.0, 110.0, 103.0, 113.0, 110.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 118.0, 148.0, 165.0, 117.0, 141.0, 168.0, 124.0, 173.0, 119.0], "value": 143.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 161.0, 162.0, 162.0, 170.0, 167.0, 174.0, 167.0, 163.0], "value": 165.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 84.0, 81.0, 180.0, 76.0, 75.0, 74.0, 79.0, 164.0, 77.0], "value": 96.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 138.0, 79.0, 80.0, 84.0, 130.0, 78.0, 79.0, 149.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 114.0, 113.0, 114.0, 114.0, 114.0, 122.0, 114.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 150.0, 151.0, 151.0, 150.0, 154.0, 152.0, 157.0, 151.0, 151.0], "value": 152.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 99.0, 94.0, 94.0, 94.0, 94.0, 97.0, 93.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 58.0, 57.0, 60.0, 57.0, 59.0, 58.0, 63.0, 62.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 54.0, 54.0, 54.0, 54.0, 55.0, 57.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 89.0, 89.0, 80.0, 83.0, 82.0, 83.0, 81.0, 82.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 132.0, 129.0, 143.0, 130.0, 132.0, 130.0, 144.0, 132.0, 170.0], "value": 137.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 186.0, 187.0, 186.0, 179.0, 180.0, 176.0, 184.0, 181.0, 186.0], "value": 184.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 74.0, 70.0, 73.0, 74.0, 67.0, 67.0, 69.0, 67.0], "value": 69.8, "unit": "score"}]}]} 18:23:06 INFO - Return code: 0 18:23:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:23:06 INFO - # TBPL SUCCESS # 18:23:06 INFO - Running post-action listener: _package_coverage_data 18:23:06 INFO - Running post-action listener: _resource_record_post_action 18:23:06 INFO - [mozharness: 2017-07-29 01:23:06.119844Z] Finished run-tests step (success) 18:23:06 INFO - Running post-run listener: _resource_record_post_run 18:23:06 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 8192; Write bytes: 438198272; Read time: 28; Write time: 297988 18:23:06 INFO - TinderboxPrint: CPU usage
13.2% 18:23:06 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 28 18:23:06 INFO - TinderboxPrint: I/O write bytes / time
438,198,272 / 297,988 18:23:06 INFO - TinderboxPrint: CPU idle
8,279.0 (86.8%) 18:23:06 INFO - TinderboxPrint: CPU user
1,204.9 (12.6%) 18:23:06 INFO - TinderboxPrint: Swap in / out
0 / 0 18:23:06 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 113176576; Read time: 0; Write time: 79980 18:23:06 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:23:06 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 324517888; Read time: 20; Write time: 217956 18:23:06 INFO - Running post-run listener: _upload_blobber_files 18:23:06 INFO - Blob upload gear active. 18:23:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:23:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:23:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:23:07 INFO - (blobuploader) - INFO - Open directory for files ... 18:23:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:23:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:23:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:08 INFO - (blobuploader) - INFO - Done attempting. 18:23:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:23:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:23:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:08 INFO - (blobuploader) - INFO - Done attempting. 18:23:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:23:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:23:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:16 INFO - (blobuploader) - INFO - Done attempting. 18:23:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:23:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:23:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:17 INFO - (blobuploader) - INFO - Done attempting. 18:23:17 INFO - (blobuploader) - INFO - Iteration through files over. 18:23:17 INFO - Return code: 0 18:23:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:23:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:23:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1db95f27adad8065dab2f76a9f8d3d9c0bc91c2709812a1ff77d65fdf39898c4f0e7de7d7a79bbd33a7faf31799179059e6cde07e1ed8fc2b1305b563f1a680b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fd6dbba456df0cec526f8f57d4829365baa028f2a301e442f0f3e9468ec65f66a95a4ba83c34832988b3a100f232ac6639e7eef2bcf2ccf2854e5bd40e2ca35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46068861e2ef7fc67e45a3cece82ebd39611211144a0cfc5210cf6a891481a89442d0bf718bf2fd4c392dd0e06966ef8a4b28bbc88048df611dc4563e8870ad1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63669eb99f7a7b6772564f739afd86bb63c084365f1753fd596d5bb1d56fb875fadee2f4b5415ec1b29f858509e9c21fabb9cc5a22818745f1e78f858e0eb2b4"} 18:23:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:23:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:23:17 INFO - Contents: 18:23:17 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1db95f27adad8065dab2f76a9f8d3d9c0bc91c2709812a1ff77d65fdf39898c4f0e7de7d7a79bbd33a7faf31799179059e6cde07e1ed8fc2b1305b563f1a680b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fd6dbba456df0cec526f8f57d4829365baa028f2a301e442f0f3e9468ec65f66a95a4ba83c34832988b3a100f232ac6639e7eef2bcf2ccf2854e5bd40e2ca35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46068861e2ef7fc67e45a3cece82ebd39611211144a0cfc5210cf6a891481a89442d0bf718bf2fd4c392dd0e06966ef8a4b28bbc88048df611dc4563e8870ad1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63669eb99f7a7b6772564f739afd86bb63c084365f1753fd596d5bb1d56fb875fadee2f4b5415ec1b29f858509e9c21fabb9cc5a22818745f1e78f858e0eb2b4"} 18:23:17 INFO - Running post-run listener: copy_logs_to_upload_dir 18:23:17 INFO - Copying logs to upload dir... 18:23:17 INFO - mkdir: /builds/slave/test/build/upload/logs 18:23:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.299421 ========= master_lag: 0.53 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-07-28 18:23:17.607142) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:17.609401) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1db95f27adad8065dab2f76a9f8d3d9c0bc91c2709812a1ff77d65fdf39898c4f0e7de7d7a79bbd33a7faf31799179059e6cde07e1ed8fc2b1305b563f1a680b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fd6dbba456df0cec526f8f57d4829365baa028f2a301e442f0f3e9468ec65f66a95a4ba83c34832988b3a100f232ac6639e7eef2bcf2ccf2854e5bd40e2ca35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46068861e2ef7fc67e45a3cece82ebd39611211144a0cfc5210cf6a891481a89442d0bf718bf2fd4c392dd0e06966ef8a4b28bbc88048df611dc4563e8870ad1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63669eb99f7a7b6772564f739afd86bb63c084365f1753fd596d5bb1d56fb875fadee2f4b5415ec1b29f858509e9c21fabb9cc5a22818745f1e78f858e0eb2b4"} build_url:https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011255 build_url: 'https://queue.taskcluster.net/v1/task/JZh5JewrR7CLMNL_lRmk9w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1db95f27adad8065dab2f76a9f8d3d9c0bc91c2709812a1ff77d65fdf39898c4f0e7de7d7a79bbd33a7faf31799179059e6cde07e1ed8fc2b1305b563f1a680b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9fd6dbba456df0cec526f8f57d4829365baa028f2a301e442f0f3e9468ec65f66a95a4ba83c34832988b3a100f232ac6639e7eef2bcf2ccf2854e5bd40e2ca35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46068861e2ef7fc67e45a3cece82ebd39611211144a0cfc5210cf6a891481a89442d0bf718bf2fd4c392dd0e06966ef8a4b28bbc88048df611dc4563e8870ad1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/63669eb99f7a7b6772564f739afd86bb63c084365f1753fd596d5bb1d56fb875fadee2f4b5415ec1b29f858509e9c21fabb9cc5a22818745f1e78f858e0eb2b4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:17.633349) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:17.633656) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1501282253.64893-245679079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004522 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-28 18:23:17.689833) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-28 18:23:17.690150) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-28 18:23:17.690429) ========= ========= Total master_lag: 0.78 =========