builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-088 starttime: 1504607544.64 results: success (0) revision: 3ecda4678c49ca255c38b1697142b9118cdd27e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 03:32:24.642833) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 03:32:24.643250) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 03:32:24.643520) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 03:32:24.668522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 03:32:24.668789) ========= 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=9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-05 03:32:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2017-09-05 03:32:24 (220 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.236971 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 03:32:24.941037) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 03:32:24.941306) ========= 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=9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022951 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 03:32:25.015179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 19 secs) (at 2017-09-05 03:32:25.015464) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3ecda4678c49ca255c38b1697142b9118cdd27e7 --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 3ecda4678c49ca255c38b1697142b9118cdd27e7 --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=9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-05 03:32:25,056 truncating revision to first 12 chars 2017-09-05 03:32:25,056 Setting DEBUG logging. 2017-09-05 03:32:25,056 attempt 1/10 2017-09-05 03:32:25,057 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3ecda4678c49?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-05 03:32:25,901 attempt 1/10 2017-09-05 03:32:26,429 current task status: no status available at this point. state: PENDING 2017-09-05 03:32:26,429 sleeping for 10.00s (attempt 1/10) 2017-09-05 03:32:36,440 attempt 2/10 2017-09-05 03:32:37,195 current task status: no status available at this point. state: PENDING 2017-09-05 03:32:37,196 sleeping for 14.00s (attempt 2/10) 2017-09-05 03:32:51,210 attempt 3/10 2017-09-05 03:32:51,700 current task status: no status available at this point. state: PENDING 2017-09-05 03:32:51,701 sleeping for 21.00s (attempt 3/10) 2017-09-05 03:33:12,722 attempt 4/10 2017-09-05 03:33:13,299 current task status: no status available at this point. state: PENDING 2017-09-05 03:33:13,299 sleeping for 30.50s (attempt 4/10) 2017-09-05 03:33:43,830 attempt 5/10 2017-09-05 03:33:44,133 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-05 03:33:44,470 unpacking tar archive at: mozilla-central-3ecda4678c49/testing/mozharness/ program finished with exit code 0 elapsedTime=79.830258 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 19 secs) (at 2017-09-05 03:33:44.866596) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 03:33:44.869937) ========= script_repo_revision: 3ecda4678c49ca255c38b1697142b9118cdd27e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 03:33:44.870337) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 03:33:44.870592) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 03:33:44.884052) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-05 03:33:44.884318) ========= /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=9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:33:45 INFO - MultiFileLogger online at 20170905 03:33:45 in /builds/slave/test 03:33:45 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 03:33:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:33:45 INFO - {'append_to_log': False, 03:33:45 INFO - 'base_work_dir': '/builds/slave/test', 03:33:45 INFO - 'blob_upload_branch': 'Firefox', 03:33:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:33:45 INFO - 'branch': 'Firefox', 03:33:45 INFO - 'buildbot_json_path': 'buildprops.json', 03:33:45 INFO - 'code_coverage': False, 03:33:45 INFO - 'config_files': ('talos/linux_config.py',), 03:33:45 INFO - 'default_actions': ('clobber', 03:33:45 INFO - 'read-buildbot-config', 03:33:45 INFO - 'download-and-extract', 03:33:45 INFO - 'populate-webroot', 03:33:45 INFO - 'create-virtualenv', 03:33:45 INFO - 'install', 03:33:45 INFO - 'setup-mitmproxy', 03:33:45 INFO - 'run-tests'), 03:33:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:33:45 INFO - 'disable_ccov_upload': False, 03:33:45 INFO - 'disable_stylo': False, 03:33:45 INFO - 'download_minidump_stackwalk': True, 03:33:45 INFO - 'download_symbols': 'ondemand', 03:33:45 INFO - 'enable_stylo': False, 03:33:45 INFO - 'enable_webrender': False, 03:33:45 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:33:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:33:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:33:45 INFO - 'gecko_profile': False, 03:33:45 INFO - 'gecko_profile_interval': 0, 03:33:45 INFO - 'installer_path': 'installer.exe', 03:33:45 INFO - 'log_level': 'info', 03:33:45 INFO - 'log_name': 'talos', 03:33:45 INFO - 'log_to_console': True, 03:33:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:33:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:33:45 INFO - 'opt_config_files': (), 03:33:45 INFO - 'pip_index': False, 03:33:45 INFO - 'suite': 'dromaeojs-e10s', 03:33:45 INFO - 'system_bits': '32', 03:33:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:33:45 INFO - 'title': 'talos-linux64-ix-088', 03:33:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:33:45 INFO - 'use_talos_json': True, 03:33:45 INFO - 'verify': 'False', 03:33:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:33:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:33:45 INFO - 'work_dir': 'build'} 03:33:45 INFO - [mozharness: 2017-09-05 10:33:45.062031Z] Running clobber step. 03:33:45 INFO - Running pre-action listener: _resource_record_pre_action 03:33:45 INFO - Running main action method: clobber 03:33:45 INFO - rmtree: /builds/slave/test/build 03:33:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:33:46 INFO - Running post-action listener: _resource_record_post_action 03:33:46 INFO - [mozharness: 2017-09-05 10:33:46.600079Z] Finished clobber step (success) 03:33:46 INFO - [mozharness: 2017-09-05 10:33:46.600177Z] Running read-buildbot-config step. 03:33:46 INFO - Running pre-action listener: _resource_record_pre_action 03:33:46 INFO - Running main action method: read_buildbot_config 03:33:46 INFO - Using buildbot properties: 03:33:46 INFO - { 03:33:46 INFO - "project": "", 03:33:46 INFO - "product": "firefox", 03:33:46 INFO - "who": "archaeopteryx@coole-files.de", 03:33:46 INFO - "installer_path": "public/build/target.tar.bz2", 03:33:46 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 03:33:46 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:33:46 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 03:33:46 INFO - "stage_platform": "linux64-stylosequential", 03:33:46 INFO - "basedir": "/builds/slave/test", 03:33:46 INFO - "buildnumber": 188, 03:33:46 INFO - "platform": "ubuntu64_hw_styloseq", 03:33:46 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:33:46 INFO - "slavebuilddir": "test", 03:33:46 INFO - "taskId": "Rjo1zBqEScSRvtxBVjYupg", 03:33:46 INFO - "branch": "mozilla-central", 03:33:46 INFO - "script_repo_revision": "production", 03:33:46 INFO - "revision": "3ecda4678c49ca255c38b1697142b9118cdd27e7", 03:33:46 INFO - "slavename": "talos-linux64-ix-088", 03:33:46 INFO - "repo_path": "mozilla-central" 03:33:46 INFO - } 03:33:46 INFO - Finding installer, test and symbols from parent task. 03:33:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rjo1zBqEScSRvtxBVjYupg'}, attempt #1 03:33:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rjo1zBqEScSRvtxBVjYupg'}, attempt #1 03:33:47 INFO - Task dependencies: Oz77JHY8R46lR-xvwFHp5Q 03:33:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q'}, attempt #1 03:33:48 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 03:33:48 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.test_packages.json 03:33:48 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.crashreporter-symbols.zip 03:33:48 INFO - Running post-action listener: _resource_record_post_action 03:33:48 INFO - [mozharness: 2017-09-05 10:33:48.036014Z] Finished read-buildbot-config step (success) 03:33:48 INFO - [mozharness: 2017-09-05 10:33:48.036231Z] Running download-and-extract step. 03:33:48 INFO - Running pre-action listener: _resource_record_pre_action 03:33:48 INFO - Running main action method: download_and_extract 03:33:48 INFO - mkdir: /builds/slave/test/build/tests 03:33: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')]} 03:33:48 INFO - trying https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.test_packages.json 03:33:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:33:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:33:48 INFO - Downloaded 989 bytes. 03:33:48 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:33:48 INFO - Using the following test package requirements: 03:33:48 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:33:48 INFO - u'common': [u'target.common.tests.zip'], 03:33:48 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:33:48 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:33:48 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:33:48 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:33:48 INFO - u'mozbase': [u'target.common.tests.zip'], 03:33:48 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:33:48 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:33:48 INFO - u'web-platform': [u'target.common.tests.zip', 03:33:48 INFO - u'target.web-platform.tests.tar.gz'], 03:33:48 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:33:48 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:33:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip 03:33:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:33:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip into memory 03:33:49 INFO - Content-Length response header: 55022230 03:33:49 INFO - Bytes received: 55022230 03:34:00 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:34:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip 03:34:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:34:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.common.tests.zip into memory 03:34:01 INFO - Content-Length response header: 55022230 03:34:01 INFO - Bytes received: 55022230 03:34:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.talos.tests.zip 03:34:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:34:13 INFO - Fetch https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.talos.tests.zip into memory 03:34:14 INFO - Content-Length response header: 14496650 03:34:14 INFO - Bytes received: 14496650 03:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:14 INFO - trying https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 03:34:14 INFO - Downloading https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:34:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:34:15 INFO - Downloaded 62687364 bytes. 03:34:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 03:34:15 INFO - mkdir: /builds/slave/test/properties 03:34:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:34:15 INFO - Writing to file /builds/slave/test/properties/build_url 03:34:15 INFO - Contents: 03:34:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 03:34:15 INFO - Running post-action listener: _resource_record_post_action 03:34:15 INFO - Running post-action listener: find_tests_for_verification 03:34:15 INFO - Running post-action listener: set_extra_try_arguments 03:34:15 INFO - [mozharness: 2017-09-05 10:34:15.635746Z] Finished download-and-extract step (success) 03:34:15 INFO - [mozharness: 2017-09-05 10:34:15.635843Z] Running populate-webroot step. 03:34:15 INFO - Running pre-action listener: _resource_record_pre_action 03:34:15 INFO - Running main action method: populate_webroot 03:34:15 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:34:15 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:34:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:34:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:34:15 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:34:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:34:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:34:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:34:15 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:34:15 INFO - 'talos_options': ['--enable-stylo'], 03:34:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:34:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:34:15 INFO - 'tests': ['damp', 'tps']}, 03:34:15 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:34:15 INFO - 'talos_options': ['--enable-stylo'], 03:34:15 INFO - 'tests': ['damp', 'tps']}, 03:34:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:34:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:34:15 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:34:15 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:34:15 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:34:15 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:34:15 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:34:15 INFO - 'talos_options': ['--enable-stylo'], 03:34:15 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:34:15 INFO - 'other-e10s': {'tests': ['a11yr', 03:34:15 INFO - 'ts_paint', 03:34:15 INFO - 'tpaint', 03:34:15 INFO - 'sessionrestore', 03:34:15 INFO - 'sessionrestore_many_windows', 03:34:15 INFO - 'sessionrestore_no_auto_restore', 03:34:15 INFO - 'tabpaint']}, 03:34:15 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:34:15 INFO - 'tests': ['a11yr', 03:34:15 INFO - 'ts_paint', 03:34:15 INFO - 'tpaint', 03:34:15 INFO - 'sessionrestore', 03:34:15 INFO - 'sessionrestore_many_windows', 03:34:15 INFO - 'sessionrestore_no_auto_restore', 03:34:15 INFO - 'tabpaint']}, 03:34:15 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:34:15 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:34:15 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:34:15 INFO - 'tests': ['bloom_basic_singleton']}, 03:34:15 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:34:15 INFO - 'tests': ['bloom_basic']}, 03:34:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:34:15 INFO - 'tsvgr_opacity', 03:34:15 INFO - 'tart', 03:34:15 INFO - 'tscrollx', 03:34:15 INFO - 'tsvg_static']}, 03:34:15 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:34:15 INFO - 'tests': ['tsvgx', 03:34:15 INFO - 'tsvgr_opacity', 03:34:15 INFO - 'tart', 03:34:15 INFO - 'tscrollx', 03:34:15 INFO - 'tsvg_static']}, 03:34:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:34:15 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:34:15 INFO - 'talos_options': ['--enable-stylo'], 03:34:15 INFO - 'tests': ['tp5o']}, 03:34:15 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:34:15 INFO - 'talos_options': ['--mitmproxy', 03:34:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:34:15 INFO - '--firstNonBlankPaint'], 03:34:15 INFO - 'tests': ['tp6_google', 03:34:15 INFO - 'tp6_youtube', 03:34:15 INFO - 'tp6_amazon', 03:34:15 INFO - 'tp6_facebook']}, 03:34:15 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:34:15 INFO - 'talos_options': ['--enable-stylo', 03:34:15 INFO - '--mitmproxy', 03:34:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:34:15 INFO - '--firstNonBlankPaint'], 03:34:15 INFO - 'tests': ['tp6_google', 03:34:15 INFO - 'tp6_youtube', 03:34:15 INFO - 'tp6_amazon', 03:34:15 INFO - 'tp6_facebook']}, 03:34:15 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:34:15 INFO - 'talos_options': ['--enable-stylo', 03:34:15 INFO - '--stylo-threads=1', 03:34:15 INFO - '--mitmproxy', 03:34:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:34:15 INFO - '--firstNonBlankPaint'], 03:34:15 INFO - 'tests': ['tp6_google', 03:34:15 INFO - 'tp6_youtube', 03:34:15 INFO - 'tp6_amazon', 03:34:15 INFO - 'tp6_facebook']}, 03:34:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:34:15 INFO - 'talos_options': ['--xperf_path', 03:34:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:34:15 INFO - 'tests': ['tp5n']}, 03:34:15 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:34:15 INFO - 'talos_options': ['--enable-stylo', 03:34:15 INFO - '--xperf_path', 03:34:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:34:15 INFO - 'tests': ['tp5n']}}} 03:34:15 INFO - Running post-action listener: _resource_record_post_action 03:34:15 INFO - [mozharness: 2017-09-05 10:34:15.650053Z] Finished populate-webroot step (success) 03:34:15 INFO - [mozharness: 2017-09-05 10:34:15.650142Z] Running create-virtualenv step. 03:34:15 INFO - Running pre-action listener: _resource_record_pre_action 03:34:15 INFO - Running main action method: create_virtualenv 03:34:15 INFO - Creating virtualenv /builds/slave/test/build/venv 03:34:15 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:34:15 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:34:15 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:34:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:34:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:34:16 INFO - Installing distribute.............................................................................................................................................................................................done. 03:34:16 INFO - Installing pip...............done. 03:34:16 INFO - Return code: 0 03:34:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:34:16 INFO - Reading from file tmpfile_stdout 03:34:16 INFO - Output received: 03:34:16 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:34:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9090>, '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 0x10f9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', '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 03:34:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:34:16 INFO - Using env: {'DISPLAY': ':0', 03:34:16 INFO - 'HOME': '/home/cltbld', 03:34:16 INFO - 'LANG': 'en_US.UTF-8', 03:34:16 INFO - 'LANGUAGE': 'en_US:en', 03:34:16 INFO - 'LOGNAME': 'cltbld', 03:34:16 INFO - 'MAIL': '/var/mail/cltbld', 03:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:16 INFO - 'MOZ_NO_REMOTE': '1', 03:34:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:16 INFO - 'NO_EM_RESTART': '1', 03:34:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:16 INFO - 'PWD': '/builds/slave/test', 03:34:16 INFO - 'SHELL': '/bin/bash', 03:34:16 INFO - 'SHLVL': '1', 03:34:16 INFO - 'TERM': 'linux', 03:34:16 INFO - 'TMOUT': '86400', 03:34:16 INFO - 'USER': 'cltbld', 03:34:16 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:16 INFO - '_': '/tools/buildbot/bin/python'} 03:34:17 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:34:17 INFO - Downloading/unpacking pip>=1.5 03:34:17 INFO - Running setup.py egg_info for package pip 03:34:17 INFO - warning: no files found matching 'pip/cacert.pem' 03:34:17 INFO - warning: no files found matching '*.html' under directory 'docs' 03:34:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:34:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:34:17 INFO - Installing collected packages: pip 03:34:17 INFO - Found existing installation: pip 1.1 03:34:17 INFO - Uninstalling pip: 03:34:17 INFO - Successfully uninstalled pip 03:34:17 INFO - Running setup.py install for pip 03:34:17 INFO - warning: no files found matching 'pip/cacert.pem' 03:34:17 INFO - warning: no files found matching '*.html' under directory 'docs' 03:34:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:34:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:34:17 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:34:17 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:34:17 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:34:17 INFO - Successfully installed pip 03:34:17 INFO - Cleaning up... 03:34:17 INFO - Return code: 0 03:34:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9090>, '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 0x10f9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', '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 03:34:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:34:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:34:17 INFO - Using env: {'DISPLAY': ':0', 03:34:17 INFO - 'HOME': '/home/cltbld', 03:34:17 INFO - 'LANG': 'en_US.UTF-8', 03:34:17 INFO - 'LANGUAGE': 'en_US:en', 03:34:17 INFO - 'LOGNAME': 'cltbld', 03:34:17 INFO - 'MAIL': '/var/mail/cltbld', 03:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:17 INFO - 'MOZ_NO_REMOTE': '1', 03:34:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:17 INFO - 'NO_EM_RESTART': '1', 03:34:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:17 INFO - 'PWD': '/builds/slave/test', 03:34:17 INFO - 'SHELL': '/bin/bash', 03:34:17 INFO - 'SHLVL': '1', 03:34:17 INFO - 'TERM': 'linux', 03:34:17 INFO - 'TMOUT': '86400', 03:34:17 INFO - 'USER': 'cltbld', 03:34:17 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:17 INFO - '_': '/tools/buildbot/bin/python'} 03:34:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:18 INFO - Downloading/unpacking psutil>=3.1.1 03:34:18 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 03:34:18 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 03:34:18 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 03:34:18 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 03:34:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:34:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:34:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:34:19 INFO - Installing collected packages: psutil 03:34:19 INFO - Running setup.py install for psutil 03:34:19 INFO - building 'psutil._psutil_linux' extension 03:34:19 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 03:34:19 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 03:34:19 INFO - building 'psutil._psutil_posix' extension 03:34:19 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 03:34:19 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 03:34:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:34:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:34:19 INFO - Successfully installed psutil 03:34:19 INFO - Cleaning up... 03:34:19 INFO - Return code: 0 03:34:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9090>, '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 0x10f9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', '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 03:34:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:34:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:34:19 INFO - Using env: {'DISPLAY': ':0', 03:34:19 INFO - 'HOME': '/home/cltbld', 03:34:19 INFO - 'LANG': 'en_US.UTF-8', 03:34:19 INFO - 'LANGUAGE': 'en_US:en', 03:34:19 INFO - 'LOGNAME': 'cltbld', 03:34:19 INFO - 'MAIL': '/var/mail/cltbld', 03:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:19 INFO - 'MOZ_NO_REMOTE': '1', 03:34:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:19 INFO - 'NO_EM_RESTART': '1', 03:34:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:19 INFO - 'PWD': '/builds/slave/test', 03:34:19 INFO - 'SHELL': '/bin/bash', 03:34:19 INFO - 'SHLVL': '1', 03:34:19 INFO - 'TERM': 'linux', 03:34:19 INFO - 'TMOUT': '86400', 03:34:19 INFO - 'USER': 'cltbld', 03:34:19 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:19 INFO - '_': '/tools/buildbot/bin/python'} 03:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:34:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:34:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:34:21 INFO - Installing collected packages: mozsystemmonitor 03:34:21 INFO - Running setup.py install for mozsystemmonitor 03:34:21 INFO - Successfully installed mozsystemmonitor 03:34:21 INFO - Cleaning up... 03:34:21 INFO - Return code: 0 03:34:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:34:21 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')]} 03:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:21 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')]} 03:34:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:21 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 0xd891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9090>, '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 0x10f9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', '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 03:34:21 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 03:34:21 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 03:34:21 INFO - Using env: {'DISPLAY': ':0', 03:34:21 INFO - 'HOME': '/home/cltbld', 03:34:21 INFO - 'LANG': 'en_US.UTF-8', 03:34:21 INFO - 'LANGUAGE': 'en_US:en', 03:34:21 INFO - 'LOGNAME': 'cltbld', 03:34:21 INFO - 'MAIL': '/var/mail/cltbld', 03:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:21 INFO - 'MOZ_NO_REMOTE': '1', 03:34:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:21 INFO - 'NO_EM_RESTART': '1', 03:34:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:21 INFO - 'PWD': '/builds/slave/test', 03:34:21 INFO - 'SHELL': '/bin/bash', 03:34:21 INFO - 'SHLVL': '1', 03:34:21 INFO - 'TERM': 'linux', 03:34:21 INFO - 'TMOUT': '86400', 03:34:21 INFO - 'USER': 'cltbld', 03:34:21 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:21 INFO - '_': '/tools/buildbot/bin/python'} 03:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:21 INFO - Downloading/unpacking jsonschema==2.5.1 03:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:34:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:34:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:34:22 INFO - Downloading functools32-3.2.3-2.tar.gz 03:34:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:34:22 INFO - warning: no files found matching '*.txt' 03:34:22 INFO - no previously-included directories found matching 'build' 03:34:22 INFO - no previously-included directories found matching 'dist' 03:34:22 INFO - no previously-included directories found matching '.git*' 03:34:22 INFO - Installing collected packages: jsonschema, functools32 03:34:22 INFO - Running setup.py install for functools32 03:34:22 INFO - warning: no files found matching '*.txt' 03:34:22 INFO - no previously-included directories found matching 'build' 03:34:22 INFO - no previously-included directories found matching 'dist' 03:34:22 INFO - no previously-included directories found matching '.git*' 03:34:22 INFO - Successfully installed jsonschema functools32 03:34:22 INFO - Cleaning up... 03:34:22 INFO - Return code: 0 03:34:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:34:22 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')]} 03:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:22 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')]} 03:34:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:22 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 0xd891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9090>, '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 0x10f9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', '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 03:34:22 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 03:34:22 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 03:34:22 INFO - Using env: {'DISPLAY': ':0', 03:34:22 INFO - 'HOME': '/home/cltbld', 03:34:22 INFO - 'LANG': 'en_US.UTF-8', 03:34:22 INFO - 'LANGUAGE': 'en_US:en', 03:34:22 INFO - 'LOGNAME': 'cltbld', 03:34:22 INFO - 'MAIL': '/var/mail/cltbld', 03:34:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:22 INFO - 'MOZ_NO_REMOTE': '1', 03:34:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:22 INFO - 'NO_EM_RESTART': '1', 03:34:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:22 INFO - 'PWD': '/builds/slave/test', 03:34:22 INFO - 'SHELL': '/bin/bash', 03:34:22 INFO - 'SHLVL': '1', 03:34:22 INFO - 'TERM': 'linux', 03:34:22 INFO - 'TMOUT': '86400', 03:34:22 INFO - 'USER': 'cltbld', 03:34:22 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:22 INFO - '_': '/tools/buildbot/bin/python'} 03:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:34:23 INFO - Cleaning up... 03:34:23 INFO - Return code: 0 03:34:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:23 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 0xd891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9090>, '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 0x10f9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', '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 03:34:23 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 03:34:23 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 03:34:23 INFO - Using env: {'DISPLAY': ':0', 03:34:23 INFO - 'HOME': '/home/cltbld', 03:34:23 INFO - 'LANG': 'en_US.UTF-8', 03:34:23 INFO - 'LANGUAGE': 'en_US:en', 03:34:23 INFO - 'LOGNAME': 'cltbld', 03:34:23 INFO - 'MAIL': '/var/mail/cltbld', 03:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:23 INFO - 'MOZ_NO_REMOTE': '1', 03:34:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:23 INFO - 'NO_EM_RESTART': '1', 03:34:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:23 INFO - 'PWD': '/builds/slave/test', 03:34:23 INFO - 'SHELL': '/bin/bash', 03:34:23 INFO - 'SHLVL': '1', 03:34:23 INFO - 'TERM': 'linux', 03:34:23 INFO - 'TMOUT': '86400', 03:34:23 INFO - 'USER': 'cltbld', 03:34:23 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:23 INFO - '_': '/tools/buildbot/bin/python'} 03:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:23 INFO - Downloading/unpacking blobuploader==1.2.4 03:34:23 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 03:34:23 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 03:34:23 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 03:34:23 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 03:34:24 INFO - Downloading blobuploader-1.2.4.tar.gz 03:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:34:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:34:24 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 03:34:24 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 03:34:24 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 03:34:24 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 03:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:34:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:34:24 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 03:34:24 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 03:34:24 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 03:34:24 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 03:34:25 INFO - Downloading docopt-0.6.1.tar.gz 03:34:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:34:25 INFO - Installing collected packages: blobuploader, requests, docopt 03:34:25 INFO - Running setup.py install for blobuploader 03:34:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:34:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:34:25 INFO - Running setup.py install for requests 03:34:25 INFO - Running setup.py install for docopt 03:34:25 INFO - Successfully installed blobuploader requests docopt 03:34:25 INFO - Cleaning up... 03:34:25 INFO - Return code: 0 03:34:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:25 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 0xd891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9090>, '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 0x10f9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', '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 03:34:25 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 03:34:25 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 03:34:25 INFO - Using env: {'DISPLAY': ':0', 03:34:25 INFO - 'HOME': '/home/cltbld', 03:34:25 INFO - 'LANG': 'en_US.UTF-8', 03:34:25 INFO - 'LANGUAGE': 'en_US:en', 03:34:25 INFO - 'LOGNAME': 'cltbld', 03:34:25 INFO - 'MAIL': '/var/mail/cltbld', 03:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:25 INFO - 'MOZ_NO_REMOTE': '1', 03:34:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:25 INFO - 'NO_EM_RESTART': '1', 03:34:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:25 INFO - 'PWD': '/builds/slave/test', 03:34:25 INFO - 'SHELL': '/bin/bash', 03:34:25 INFO - 'SHLVL': '1', 03:34:25 INFO - 'TERM': 'linux', 03:34:25 INFO - 'TMOUT': '86400', 03:34:25 INFO - 'USER': 'cltbld', 03:34:25 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:25 INFO - '_': '/tools/buildbot/bin/python'} 03:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:34:25 INFO - Running setup.py (path:/tmp/pip-1KGrVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:34:25 INFO - Running setup.py (path:/tmp/pip-Ruz1KF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:34:26 INFO - Running setup.py (path:/tmp/pip-CBd4sr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:34:26 INFO - Running setup.py (path:/tmp/pip-jqcepP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:34:26 INFO - Running setup.py (path:/tmp/pip-rpojg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:34:26 INFO - Running setup.py (path:/tmp/pip-1Nj1qK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:34:26 INFO - Running setup.py (path:/tmp/pip-XOc24v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:34:26 INFO - Running setup.py (path:/tmp/pip-0YjHrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:34:26 INFO - Running setup.py (path:/tmp/pip-MMJcSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:34:26 INFO - Running setup.py (path:/tmp/pip-0szO11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:34:26 INFO - Running setup.py (path:/tmp/pip-M5Rb14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:34:26 INFO - Running setup.py (path:/tmp/pip-PiymTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:34:26 INFO - Running setup.py (path:/tmp/pip-FK35Vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:34:26 INFO - Running setup.py (path:/tmp/pip-wSAIgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:34:26 INFO - Running setup.py (path:/tmp/pip-oZlQa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:34:26 INFO - Running setup.py (path:/tmp/pip-wJ7Aez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:34:26 INFO - Running setup.py (path:/tmp/pip-KeUK5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:34:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:34:27 INFO - Running setup.py install for manifestparser 03:34:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:34:27 INFO - Running setup.py install for mozcrash 03:34:27 INFO - Running setup.py install for mozdebug 03:34:27 INFO - Running setup.py install for mozdevice 03:34:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:34:27 INFO - Running setup.py install for mozfile 03:34:27 INFO - Running setup.py install for mozhttpd 03:34:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:34:27 INFO - Running setup.py install for mozinfo 03:34:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:34:27 INFO - Running setup.py install for mozInstall 03:34:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:34:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:34:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:34:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:34:27 INFO - Running setup.py install for mozleak 03:34:27 INFO - Running setup.py install for mozlog 03:34:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:34:28 INFO - Running setup.py install for moznetwork 03:34:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:34:28 INFO - Running setup.py install for mozprocess 03:34:28 INFO - Running setup.py install for mozprofile 03:34:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:34:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:34:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:34:28 INFO - Running setup.py install for mozrunner 03:34:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:34:28 INFO - Running setup.py install for mozscreenshot 03:34:28 INFO - Running setup.py install for moztest 03:34:28 INFO - Running setup.py install for mozversion 03:34:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:34:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:34:28 INFO - Cleaning up... 03:34:28 INFO - Return code: 0 03:34:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:34: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')]} 03:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34: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')]} 03:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:28 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 0xd891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9090>, '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 0x10f9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', '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 03:34:28 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 03:34:28 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 03:34:28 INFO - Using env: {'DISPLAY': ':0', 03:34:28 INFO - 'HOME': '/home/cltbld', 03:34:28 INFO - 'LANG': 'en_US.UTF-8', 03:34:28 INFO - 'LANGUAGE': 'en_US:en', 03:34:28 INFO - 'LOGNAME': 'cltbld', 03:34:28 INFO - 'MAIL': '/var/mail/cltbld', 03:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:28 INFO - 'MOZ_NO_REMOTE': '1', 03:34:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:28 INFO - 'NO_EM_RESTART': '1', 03:34:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:28 INFO - 'PWD': '/builds/slave/test', 03:34:28 INFO - 'SHELL': '/bin/bash', 03:34:28 INFO - 'SHLVL': '1', 03:34:28 INFO - 'TERM': 'linux', 03:34:28 INFO - 'TMOUT': '86400', 03:34:28 INFO - 'USER': 'cltbld', 03:34:28 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:28 INFO - '_': '/tools/buildbot/bin/python'} 03:34:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:34:29 INFO - Running setup.py (path:/tmp/pip-nINjrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:34:29 INFO - Running setup.py (path:/tmp/pip-5hWp_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:34:29 INFO - Running setup.py (path:/tmp/pip-CZxohs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:34:29 INFO - Running setup.py (path:/tmp/pip-kEmJYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:34:29 INFO - Running setup.py (path:/tmp/pip-vPYEbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:34:29 INFO - Running setup.py (path:/tmp/pip-FRWdbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:34:29 INFO - Running setup.py (path:/tmp/pip-JZkd1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:34:29 INFO - Running setup.py (path:/tmp/pip-BaxmT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:34:29 INFO - Running setup.py (path:/tmp/pip-gE0qTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:34:29 INFO - Running setup.py (path:/tmp/pip-klUnxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:34:29 INFO - Running setup.py (path:/tmp/pip-1y70uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:34:29 INFO - Running setup.py (path:/tmp/pip-YpeKs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:34:29 INFO - Running setup.py (path:/tmp/pip-BzPr6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:34:29 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)) 03:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:34:29 INFO - Running setup.py (path:/tmp/pip-D0nYEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:34:30 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)) 03:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:34:30 INFO - Running setup.py (path:/tmp/pip-DnWXFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:34:30 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)) 03:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:34:30 INFO - Running setup.py (path:/tmp/pip-uGFrZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:34:30 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)) 03:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:34:30 INFO - Running setup.py (path:/tmp/pip-KTXAO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:34:30 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)) 03:34:30 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)) 03:34:30 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)) 03:34:30 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)) 03:34:30 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)) 03:34:30 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)) 03:34:30 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)) 03:34:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:34:30 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 03:34:30 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 03:34:30 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 03:34:30 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 03:34:31 INFO - Downloading blessings-1.6.tar.gz 03:34:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:34:31 INFO - Installing collected packages: blessings 03:34:31 INFO - Running setup.py install for blessings 03:34:31 INFO - Successfully installed blessings 03:34:31 INFO - Cleaning up... 03:34:31 INFO - Return code: 0 03:34:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:34:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:34:31 INFO - Reading from file tmpfile_stdout 03:34:31 INFO - Current package versions: 03:34:31 INFO - argparse == 1.2.1 03:34:31 INFO - blessings == 1.6 03:34:31 INFO - blobuploader == 1.2.4 03:34:31 INFO - docopt == 0.6.1 03:34:31 INFO - functools32 == 3.2.3-2 03:34:31 INFO - jsonschema == 2.5.1 03:34:31 INFO - manifestparser == 1.1 03:34:31 INFO - mozInstall == 1.13 03:34:31 INFO - mozcrash == 1.0 03:34:31 INFO - mozdebug == 0.1 03:34:31 INFO - mozdevice == 0.51 03:34:31 INFO - mozfile == 1.2 03:34:31 INFO - mozhttpd == 0.7 03:34:31 INFO - mozinfo == 0.10 03:34:31 INFO - mozleak == 0.1 03:34:31 INFO - mozlog == 3.5 03:34:31 INFO - moznetwork == 0.27 03:34:31 INFO - mozprocess == 0.25 03:34:31 INFO - mozprofile == 0.28 03:34:31 INFO - mozrunner == 6.13 03:34:31 INFO - mozscreenshot == 0.1 03:34:31 INFO - mozsystemmonitor == 0.3 03:34:31 INFO - moztest == 0.8 03:34:31 INFO - mozversion == 1.4 03:34:31 INFO - psutil == 3.1.1 03:34:31 INFO - requests == 1.2.3 03:34:31 INFO - wsgiref == 0.1.2 03:34:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:34:31 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')]} 03:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:31 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')]} 03:34:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:31 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 0xd891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9090>, '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 0x10f9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', '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 03:34:31 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 03:34:31 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 03:34:31 INFO - Using env: {'DISPLAY': ':0', 03:34:31 INFO - 'HOME': '/home/cltbld', 03:34:31 INFO - 'LANG': 'en_US.UTF-8', 03:34:31 INFO - 'LANGUAGE': 'en_US:en', 03:34:31 INFO - 'LOGNAME': 'cltbld', 03:34:31 INFO - 'MAIL': '/var/mail/cltbld', 03:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:31 INFO - 'MOZ_NO_REMOTE': '1', 03:34:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:31 INFO - 'NO_EM_RESTART': '1', 03:34:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:31 INFO - 'PWD': '/builds/slave/test', 03:34:31 INFO - 'SHELL': '/bin/bash', 03:34:31 INFO - 'SHLVL': '1', 03:34:31 INFO - 'TERM': 'linux', 03:34:31 INFO - 'TMOUT': '86400', 03:34:31 INFO - 'USER': 'cltbld', 03:34:31 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:31 INFO - '_': '/tools/buildbot/bin/python'} 03:34:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:32 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)) 03:34:32 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)) 03:34:32 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)) 03:34:32 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)) 03:34:32 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)) 03:34:32 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)) 03:34:32 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)) 03:34:32 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)) 03:34:32 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)) 03:34:32 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:34:32 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 03:34:32 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 03:34:32 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 03:34:32 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 03:34:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:34:33 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)) 03:34:33 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)) 03:34:33 INFO - Installing collected packages: simplejson 03:34:33 INFO - Running setup.py install for simplejson 03:34:33 INFO - building 'simplejson._speedups' extension 03:34:33 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 03:34:34 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 03:34:34 INFO - Successfully installed simplejson 03:34:34 INFO - Cleaning up... 03:34:34 INFO - Return code: 0 03:34:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:34:34 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')]} 03:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:34:34 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')]} 03:34:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:34:34 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 0xd891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9090>, '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 0x10f9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f90>, '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': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', '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 03:34:34 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 03:34:34 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 03:34:34 INFO - Using env: {'DISPLAY': ':0', 03:34:34 INFO - 'HOME': '/home/cltbld', 03:34:34 INFO - 'LANG': 'en_US.UTF-8', 03:34:34 INFO - 'LANGUAGE': 'en_US:en', 03:34:34 INFO - 'LOGNAME': 'cltbld', 03:34:34 INFO - 'MAIL': '/var/mail/cltbld', 03:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:34 INFO - 'MOZ_NO_REMOTE': '1', 03:34:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:34 INFO - 'NO_EM_RESTART': '1', 03:34:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:34 INFO - 'PWD': '/builds/slave/test', 03:34:34 INFO - 'SHELL': '/bin/bash', 03:34:34 INFO - 'SHLVL': '1', 03:34:34 INFO - 'TERM': 'linux', 03:34:34 INFO - 'TMOUT': '86400', 03:34:34 INFO - 'USER': 'cltbld', 03:34:34 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:34 INFO - '_': '/tools/buildbot/bin/python'} 03:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:34:34 INFO - Cleaning up... 03:34:34 INFO - Return code: 0 03:34:34 INFO - Running post-action listener: _resource_record_post_action 03:34:34 INFO - Running post-action listener: _start_resource_monitoring 03:34:34 INFO - Starting resource monitoring. 03:34:34 INFO - [mozharness: 2017-09-05 10:34:34.529617Z] Finished create-virtualenv step (success) 03:34:34 INFO - [mozharness: 2017-09-05 10:34:34.530078Z] Running install step. 03:34:34 INFO - Running pre-action listener: _resource_record_pre_action 03:34:34 INFO - Running main action method: install 03:34:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:34:34 INFO - Reading from file tmpfile_stdout 03:34:34 INFO - Detecting whether we're running mozinstall >=1.0... 03:34:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:34:34 INFO - Reading from file tmpfile_stdout 03:34:34 INFO - Output received: 03:34:34 INFO - Usage: mozinstall [options] installer 03:34:34 INFO - Options: 03:34:34 INFO - -h, --help show this help message and exit 03:34:34 INFO - -d DEST, --destination=DEST 03:34:34 INFO - Directory to install application into. [default: 03:34:34 INFO - "/builds/slave/test"] 03:34:34 INFO - --app=APP Application being installed. [default: firefox] 03:34:34 INFO - mkdir: /builds/slave/test/build/application 03:34:34 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'] 03:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:34:51 INFO - Reading from file tmpfile_stdout 03:34:51 INFO - Output received: 03:34:51 INFO - /builds/slave/test/build/application/firefox/firefox 03:34:51 INFO - Running post-action listener: _resource_record_post_action 03:34:51 INFO - [mozharness: 2017-09-05 10:34:51.419599Z] Finished install step (success) 03:34:51 INFO - [mozharness: 2017-09-05 10:34:51.419864Z] Running setup-mitmproxy step. 03:34:51 INFO - Running pre-action listener: _resource_record_pre_action 03:34:51 INFO - Running main action method: setup_mitmproxy 03:34:51 INFO - Skipping: mitmproxy is not required 03:34:51 INFO - Running post-action listener: _resource_record_post_action 03:34:51 INFO - [mozharness: 2017-09-05 10:34:51.420995Z] Finished setup-mitmproxy step (success) 03:34:51 INFO - [mozharness: 2017-09-05 10:34:51.421218Z] Running run-tests step. 03:34:51 INFO - Running pre-action listener: _resource_record_pre_action 03:34:51 INFO - Running pre-action listener: _set_gcov_prefix 03:34:51 INFO - Running main action method: run_tests 03:34:51 WARNING - Try message not found. 03:34:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:34:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:34:51 INFO - Python 2.7.3 03:34:51 INFO - Return code: 0 03:34:51 INFO - grabbing minidump binary from tooltool 03:34:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:34:51 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x10f9090>, '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 0x10f9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:34:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 03:34:51 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:34:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 03:34:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:34:51 INFO - Return code: 0 03:34:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:34:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:34:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:34:51 INFO - ENV: RUST_BACKTRACE is now full 03:34:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:34:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:34:51 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/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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 03:34:51 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/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-088 --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 03:34:51 INFO - Using env: {'DISPLAY': ':0', 03:34:51 INFO - 'HOME': '/home/cltbld', 03:34:51 INFO - 'LANG': 'en_US.UTF-8', 03:34:51 INFO - 'LANGUAGE': 'en_US:en', 03:34:51 INFO - 'LOGNAME': 'cltbld', 03:34:51 INFO - 'MAIL': '/var/mail/cltbld', 03:34:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:34:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:51 INFO - 'MOZ_NO_REMOTE': '1', 03:34:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:34:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:51 INFO - 'NO_EM_RESTART': '1', 03:34:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:51 INFO - 'PWD': '/builds/slave/test', 03:34:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:34:51 INFO - 'RUST_BACKTRACE': 'full', 03:34:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:34:51 INFO - 'SHELL': '/bin/bash', 03:34:51 INFO - 'SHLVL': '1', 03:34:51 INFO - 'STYLO_FORCE_ENABLED': '1', 03:34:51 INFO - 'STYLO_THREADS': '1', 03:34:51 INFO - 'TERM': 'linux', 03:34:51 INFO - 'TMOUT': '86400', 03:34:51 INFO - 'USER': 'cltbld', 03:34:51 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:51 INFO - '_': '/tools/buildbot/bin/python'} 03:34:51 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/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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 03:34:51 INFO - mozversion application_buildid: 20170905094120 03:34:51 INFO - mozversion application_changeset: 3ecda4678c49ca255c38b1697142b9118cdd27e7 03:34:51 INFO - mozversion application_display_name: Nightly 03:34:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:34:51 INFO - mozversion application_name: Firefox 03:34:51 INFO - mozversion application_remotingname: firefox 03:34:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:34:51 INFO - mozversion application_vendor: Mozilla 03:34:51 INFO - mozversion application_version: 57.0a1 03:34:51 INFO - mozversion platform_buildid: 20170905094120 03:34:51 INFO - mozversion platform_changeset: 3ecda4678c49ca255c38b1697142b9118cdd27e7 03:34:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:34:51 INFO - mozversion platform_version: 57.0a1 03:34:51 INFO - using testdate: 1504607691 03:34:51 INFO - actual date: 1504607691 03:34:51 INFO - starting webserver on 'localhost:47932' 03:34:51 INFO - SUITE-START | Running 2 tests 03:34:51 INFO - TEST-START | dromaeo_css 03:34:51 INFO - Initialising browser for dromaeo_css test... 03:34:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47932/getInfo.html -profile /tmp/tmp1y8XKR/profile 03:34:51 INFO - TEST-INFO | started process 21339 (/builds/slave/test/build/application/firefox/firefox http://localhost:47932/getInfo.html) 03:34:56 INFO - TEST-INFO | 21339: exit 0 03:34:57 INFO - Browser initialized. 03:34:57 INFO - Running cycle 1/1 for dromaeo_css test... 03:34:57 INFO - Using env: {'DISPLAY': ':0', 03:34:57 INFO - 'HOME': '/home/cltbld', 03:34:57 INFO - 'JSGC_DISABLE_POISONING': '1', 03:34:57 INFO - 'LANG': 'en_US.UTF-8', 03:34:57 INFO - 'LANGUAGE': 'en_US:en', 03:34:57 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:34:57 INFO - 'LOGNAME': 'cltbld', 03:34:57 INFO - 'MAIL': '/var/mail/cltbld', 03:34:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:34:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:34:57 INFO - 'MOZ_CRASHREPORTER': '1', 03:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:34:57 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:34:57 INFO - 'MOZ_NO_REMOTE': '1', 03:34:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:34:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:34:57 INFO - 'NO_EM_RESTART': '1', 03:34:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:34:57 INFO - 'PWD': '/builds/slave/test', 03:34:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:34:57 INFO - 'RUST_BACKTRACE': 'full', 03:34:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:34:57 INFO - 'SHELL': '/bin/bash', 03:34:57 INFO - 'SHLVL': '1', 03:34:57 INFO - 'STYLO_FORCE_ENABLED': '1', 03:34:57 INFO - 'STYLO_THREADS': '1', 03:34:57 INFO - 'TERM': 'linux', 03:34:57 INFO - 'TMOUT': '86400', 03:34:57 INFO - 'USER': 'cltbld', 03:34:57 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:34:57 INFO - '_': '/tools/buildbot/bin/python'} 03:34:57 INFO - TEST-INFO | started process 21522 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1y8XKR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:34:58 INFO - PID 21522 | 03:34:58 INFO - PID 21522 | (/builds/slave/test/build/application/firefox/firefox:21575): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:34:58 INFO - PID 21522 | 03:35:08 INFO - PID 21522 | RSS: Main: 172273664 03:35:08 INFO - PID 21522 | 03:38:06 INFO - PID 21522 | Cycle 1(1): loaded http://localhost:47932/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47932/tests/dromaeo/cssquery-ext.html) 03:38:08 INFO - PID 21522 | RSS: Main: 175345664 03:38:08 INFO - PID 21522 | 03:41:10 INFO - PID 21522 | Cycle 1(1): loaded http://localhost:47932/tests/dromaeo/cssquery-ext.html (next: http://localhost:47932/tests/dromaeo/cssquery-jquery.html) 03:41:11 INFO - PID 21522 | RSS: Main: 171716608 03:41:11 INFO - PID 21522 | 03:44:12 INFO - PID 21522 | Cycle 1(1): loaded http://localhost:47932/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47932/tests/dromaeo/cssquery-mootools.html) 03:44:13 INFO - PID 21522 | RSS: Main: 173907968 03:44:13 INFO - PID 21522 | 03:47:15 INFO - PID 21522 | Cycle 1(1): loaded http://localhost:47932/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47932/tests/dromaeo/cssquery-prototype.html) 03:47:15 INFO - PID 21522 | RSS: Main: 172277760 03:47:15 INFO - PID 21522 | 03:50:19 INFO - PID 21522 | Cycle 1(1): loaded http://localhost:47932/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47932/tests/dromaeo/cssquery-yui.html) 03:50:19 INFO - PID 21522 | RSS: Main: 173277184 03:50:19 INFO - PID 21522 | 03:53:21 INFO - PID 21522 | Cycle 1(1): loaded http://localhost:47932/tests/dromaeo/cssquery-yui.html (next: http://localhost:47932/tests/dromaeo/cssquery-dojo.html) 03:53:21 INFO - PID 21522 | RSS: Main: 176443392 03:53:21 INFO - PID 21522 | 03:53:21 INFO - PID 21522 | __start_tp_report 03:53:21 INFO - PID 21522 | _x_x_mozilla_page_load 03:53:21 INFO - PID 21522 | _x_x_mozilla_page_load_details 03:53:21 INFO - PID 21522 | |i|pagename|runs| 03:53:21 INFO - PID 21522 | |0;dojo.html;3321.9833900830495;3360.7479439042063;3393.389189945809;3412.5904891413034;3424.914377140573;6490.513211509132;6493.512986526011;6526.053722210277;6530.771423000196;6579.6381199034095;6412.422881940624;6459.354064593538;6463.612183268995;6477.935220647792;6492.7402903883785;5889.05775075989;5894.204282421882;5916.319623243317;5939.55453340999;5955.315138759055;5964.284285885678;5999.430054144849;6054.939450605481;6059.151718759377;6075.787347442841;5925.170476133345;5998.160257563923;6015.969920150393;6032.909506357408;6043.123747056674;5737.88524229513;5854.560907931922;5883.441073098049;5943.286805583314;5951.880962380771;5825.067989121762;5862.794802181903;5987.700614969234;5989.55078369124;6009.639421634716;6400.231972163324;6415;6418.5507014509;6537.803865883983;6556.639384833832;6787.490938179656;6867.244603093655;6965.094537710065;6998.4751143664425;7070.717171313141;6942.548734332252;6943.965280173617;6944.479164062715;6945.236024037352;6952.096227490444;6454.290028096905;6454.870902581969;6457.677116144174;6460.418562329413;6463.709133089069;7164.605946672983;7185.3533182012825;7255.8911616325795;7288.052553168054;7325.011123498366;6858.417034552019;6885.483588730865;6963.9651801740665;6976.337247961435;6976.860462790716;6862.279460656659;6946.79159625212;7020.297970203041;7055.329743674342;7159.284071592903;6809.114815074107;6826.931730682729;6839.965800171067;6850.794476165723;6867.141607299088;6877.727620390244;6880.862382752317;6888.690008949609;6890.965545172342;6892.310768923067;5102.4897510248675;5446.509814116748;5476.041692703761;5489.725513724298;5491.7254137292975;4746.240601503743;4778.952210477851;4780.784864681098;4835.588974937099;4890.926636100461;5894.2926848778425;5991.7603295867675;6000.5799594028;6001.609895356788;6110.633361998294;6871.278515755874;6881.13985751781;6901.482388820858;6904.896426553606;6915;5978.402159784074;5979.880402391927;5986.281646202484;6005.129256257818;6007.549433792483;5884.176215329857;5905.232319798399;5964.194833697482;5972.552058595623;6052.848678783065;6732.124823772976;6741.494387920926;6795.592264464049;6820.658967051628;6824.7952561423235;6799.864002720017;6925.3074692530345;6945.75689850873;7082.964585177041;7093.184283807428;6711.765088221889;6740.12378390806;6795.7961261162245;6801.251862295243;6804.659767011531;6799.11611490503;6840.418564421881;6893.655317234218;6925.896111558432;6937.722491100501;6958.921367188094;6964.756233531802;6967;7059.258777828254;7064.717411303491;6634;6716.865662686817;6718.22740384862;6725.19297684281;6725.730970761115;6595.307492713388;6659.101021362248;6665.566738162004;6679.432248258952;6686.598804071771;6328.208973878265;6604.702788374439;6623.966880165761;6627.834304142435;6751.392374686302;6750.426213771884;6834.794956151323;6836.658167091526;6876.071730316343;6891.035255064195;6554.442872355903;6562.507811914221;6626.337366263335;6643.40209381158;6664.033715111379 03:53:21 INFO - PID 21522 | |1;ext.html;710.5630037526919;783.2676447521571;792.4848848248638;820.8850760893472;829.4567058576632;39117.80441097797;39669.20661586767;39790.602093979054;39903.60096399035;39907.80046099769;35821.641783582156;35994.8200258999;36108.277834443295;36136.81931590338;36158.27683446329;6886.104806375177;6988.161420629506;6994.405475534586;7013.929860701379;7018.19290781559;6893.0694356261765;6990.091288132536;6999.755008574701;7003.159620845506;7015.649217539129;6976.930230697704;7001.264867188948;7001.614911179883;7005.4045406140285;7555.357794587467;7637.770866874002;7691.653875575556;7697.8075548111465;7703.075630924269;7724.691012359499;7463.589502577356;7545.283198096172;7633.923660763377;7637.694492220305;7642.656080476392;36040.63959360399;36510.269794604224;36743.4488482673;36799.08002299937;36803;3542.3092496963104;3588.1747198144312;3588.856445742167;3592.5329707138167;3596.37063513884;29044;29435.264118396997;29498.85250573755;29515.409691806257;29566;5175.534201921808;5186.196139598368;5186.870328241786;5192.71440070796;5193.8961220775755;190549;190902;191165;191639;192020.039899801;44685;44734.77632611816;44870.551294486635;44895.32657010082;44964.55035449669;46818.29772553415;46970;47072.5292747075;47171.764141179076;47252;14298.070625409315;14542.491012814713;14552.126872387691;14561.344739486749;14568.708625827425;11895.70260743471;11897.524099036114;11917.940410298066;11946.641600751991;11969.401529923469;13875.653108672364;14018.509352172625;14023.737863592325;14036.368363423466;14124.152550846982;5391.838244852661;5449.1826226066405;5450.672959622435;5470.835874923759;5615.101583746581;990.5691024404407;999.6451259802666;1005.8189525885265;1019.2559431614815;1023.4780262066399;21976.34070977873;22000;22129.44676383071;22191.778082219294;22209.888950555465;1091.2961140064615;1110.4281295133012;1115.9442027898735;1123.5674265407713;1124.3928278729395;11588.478518466689;11777.705557361134;15339.386424543116;15648.139352335731;15906.840931590767;240626;241262;241436;241483;242780;47847.28229076567;50923.23615145776;51047.7447612767;51945.74027129916;52512.47487525076;1422.459465403161;1449.5506393018197;1451.9709605808036;1457.4753088888203;1461.6784307452344;25804.483910322066;26257.73742262553;26324;26416.603750944134;26443.549631429025;35430;35794.463083054296;35811.462828058124;35814.28371432609;35876.461853072746;205300;207073;208201;208649;208728;32472.512912306807;32527.18682032968;32542.047477151445;32663.18342041468;32726.83636581897;39503.407448887716;39517;42826.78586607047;43843.56156438395;43865.56134438615;32441.351172975938;32442.513362300055;32496.512552312208;32638.673613263563;33579.83210083934;31376.372472549963;31919.521207182377;31974.52038219475;32119.357612847143;32417.837910810296;36314.63685363113;36365;36501.26997460089;36592.81703591465;36627.26745465129;31478.213044674067;31685.683143169197;31696.683033170295;31803;31951.840240798647;23432.531349373257;23491.41271468227;24072;24203.63694544548;24943.750562494144 03:53:21 INFO - PID 21522 | |2;jquery.html;552.4199590430047;553.4907884746033;561.9325680918286;566.371327826113;580.5529742098585;21683.566328673416;22428;22437.775622243793;22448.102075916944;22657.77342226577;23656.290311290646;25317;25390.492190156187;25398;25440.74559254407;6515.934840651592;6661.467082633378;6666.0667506549125;6670.199576050856;6673.065770792093;6598.901016484757;6651.700673469705;6674.966625166868;7055.964720176392;7058.85882282354;7046.894296585555;7056.294370562954;7065.97543356213;7069.363757261845;7077.469189810759;7036.261192574782;7064.1523017238005;7070.752523661698;7078.186008608973;7083.362497375235;6904.378605925491;6914.896276555855;6917.27368626292;6923.6884340204815;6923.723051077951;31027.22431939197;31167.844160779052;31234.37531249385;31347.37305253905;31489.21276968071;2000.689893066577;2054.7123402723632;2055.1779288284656;2057.1360028787794;2059.3101311060814;20102.899485502625;20830.27094051701;20838;20902.686459703116;20920.895395523075;2537.4671319023028;2543.796496280293;2593.3257353088334;2601.180628102142;2602.01123573041;141737.2913135438;144651.2767436156;145475;145551;145727;19531.804681953283;21857.890710546344;21869;22184.889075554518;22282.442938926655;43959.12081758347;44073;44093;44105.77947110308;44206;11631.76736465266;11724.824127638092;11909.761804763857;11936.820947685792;11955.701107472383;9871.950640246896;9926.950365248273;9935.105840474393;9985.20118390527;10046.497675116216;9289.628414863464;11316.151288653384;11330.60342887995;11345.716357091138;11419.771604568028;4114;4156.376543518496;4202.390653355246;4310.181065597589;4371.431713877239;949.6723630347519;966.7728083900278;975.531744762518;986.7335819328741;996.2229461020415;20641.89679051595;20750.273740419012;20892.37322880316;21206.363809085753;21646.025928832925;1024.3956065921143;1054.3923056244262;1056.271172890703;1061.87257529097;1069.2141276162015;11159.884011598775;11754.41665209036;11811.114166437552;11882.019103952722;11924.224925379822;464815;468260;473410;482621;484116;130817.34591327173;131843.34078329548;132241.33879330734;132975.33512332378;133537;1170;1179.3808250668467;1193.540486912545;1196.9102317326235;1198.532572296805;18603.2558697656;18633.161507732457;19126.33057842969;22360.664590031487;23101.306960791204;64193.35806641874;64848.67575662091;65251;66176.66911665411;67106.32893671001;76028;76111.23888761041;76119;76497.23502764902;77298;20508.487287817923;20607.17571297131;21073.31413486948;21235.044423001313;21326.253581124587;23530;23566.0573577055;23793.286201413986;23839.04643814228;23917.40206494782;17834.46496605104;18152.45542633723;18152.90923545374;18405.723914141032;18839.340623078126;16669.24988375502;17223.741643875102;17421.39025134114;17456.30174792994;17847.107644617456;19122;19195.712064319327;19244.71132932979;19367.612647747246;19639.214431423155;20301.49246268855;20466.488337791674;20485.2830150944;20512.282070127472;20633;18427.90786046061;18909.810901890804;19177.424677259703;19255.422337329903;19267 03:53:21 INFO - PID 21522 | |3;mootools.html;1334.1594795278975;1362.3052243355885;1374.4502199120348;1391.6799136198667;1395.462746842466;14588.635284117889;15207.315670794813;15228.92385538072;15238.085714857116;15895.046297222174;15928.84071159288;16158.434454794084;16177.595560110974;16183.83816161838;16191;2695.3935364543017;2752.779777617786;2767;2770.224337185591;2797.146870204597;2811.381496070861;2845.871935762885;2853.429314137181;2854.115224280469;2855.100643297365;2758.613794068822;2798.664160300767;2800.957985630207;2801.705820888808;2804.649418822647;2656.8804403801873;2662.6937902141212;2669.572868341056;2673.679158500973;2679.9598006029923;2588.0424243030197;2609.1129016134605;2617.829841060325;2619.266605350505;2623.2130360891847;15008.24958752069;15338.693226135527;15348.923255383652;15395.461158859498;15410.768838467431;8150.796230094235;8399.664013439455;8436.114208008132;8452.197041281068;8454;5376.11294136467;5406.567474602022;5533.363663178746;5566.165075238706;5576.414476479952;1933.5456167800555;1940.2142132436386;1945.9513512162168;1966.3314473079222;1989.6816509358432;26856.731432685814;27091.1872643821;27095.593566096526;27096.1871143866;27153.86423067892;6828.624425656637;6868.965655171693;6895.172579290517;6987.475939304572;7055.118110236221;17402.738958915626;17523;17524.123793810257;17530.211140498708;17544.385946491813;7745.728899488604;8077.474964127313;8077.515349079074;8084.474509156886;8086.919130808734;5874.530037596982;6631.801045968628;7321.524100933422;7324.853502930018;7475.962620186864;5218.7912483500995;5404.351477822686;5451.972740136353;5507.531859791962;5525.11598144303;1470.2795630141154;1493.6041948883556;1500.887433442496;1511.078242272235;1511.8110236220473;888.1163242573681;897.2911399993932;906.2659246010752;911.1344222988188;913.0139449394638;7285.744998925013;7293.416526677853;7300.6349682515665;7400.370968467633;7413.332800048021;708.8582283543312;709.3474003916409;714.3642158478958;729.9635018249066;731.3637135691982;4803.567678908915;4947.381577302837;4994.450610432922;5098;5136.1525348317855;21265.255716050175;21614.891925540585;21664.133434662752;22296.108155673915;22391.888040560018;21197.152113915272;21760;22220.444488887584;22319.330420087423;22425.66361504579;11471.197016208953;11527.654170374903;11532.423378831025;11550.768984620261;11554.711132221762;8092.554909480035;8143.18568143181;8161.714339998071;8163.469374490639;8221.095679475133;18283.451496455127;18477.72283415723;18781;19048.80951190526;19127.808721912603;19109.33117340942;19177.424677259703;19182.328618498286;19331.51671208231;19758.604827903073;11140.665780026613;11149.275297105662;11178.776424471302;11223.887761122285;11225.438728063727;13267.66830829237;13277.336133193108;13491.460341586228;13494.797578036534;13536.323183840572;11295.3787541686;11348.148888833535;11502.53989840397;11636.76726465449;11768.470418831002;11398.829017564909;11437.885621143681;11491.253068550517;11550.768984620092;11711.1802173847;9744.220462362851;9769.31614786958;9877.851832222379;9894.158996485092;9916.504174791376;7229.457790665826;7260.854782904418;7272.96363518179;7288.198298187302;7350.52221605594;7377.926220737939;7429.442791790745;7468.887966680395;7483.588402637907;7549.282818132268 03:53:21 INFO - PID 21522 | |4;prototype.html;490.54624472363076;503.3029254482543;533.3759501383381;534.978191653941;538.7037129578732;6303.527235457336;6307.905381419271;6329.3037765845775;6339.302676705559;6421.165248517698;6103.45068943795;6217.1917650705345;6297.401746834055;6358.459530939876;6359.523035772312;5061.974690126563;5064.6961182329;5115.872103197422;5120.027194832988;5151.613628977823;4910.109607800987;5015.799368025275;5101.617378696594;5102.719350435725;5112.900726343835;5032.6477146599755;5051.747412629372;5080.263361812535;5082.313887625179;5088.5929125670045;4984.700917944917;5011.198208286675;5016.673916195472;5038.697678139323;5061.975729295219;4522.7964741586375;4532.365468834366;4534;4560.315952607102;4584.701994370389;6266.686665666698;6268.498520118425;6271.811845644638;6300.495960323209;6343.714532846033;5810.065916712076;5822.854428639275;5842.341734510787;5861.384554621746;5916.7337469813965;6623.899597310093;6626.370494802986;6672.933270667279;6686.699098540529;6703.530752847302;4924.433690125646;4939.3578834751615;4956.306117143603;4976.402831660188;4987.351644286219;7067.328603782633;7079.548692518021;7104.964475177591;7113.89329160063;7149.49953503255;5030.496950305013;5036.924446133311;5048.040872234264;5059.316992206079;5085.8728531787;5116.232565115262;5157.303763991888;5158.710322419395;5179.145441002268;5193.610479214074;5831.533477321804;5836;5845.444682755132;5850.180974663551;5856.648601083949;6617.022339947043;6649.342861426197;6709.798706038827;6759.763408280686;6790;1653.5204790610587;1691.8223586523238;1716.3048965169125;1756.7452719355622;1777.129206688713;1214.3260490427897;1221.5785553983862;1232.8027515597462;1245.582729785532;1258.08160043169;5746.482816546531;5783.306003279549;5816.952948469231;5860.794872179454;5908.645481271137;6699.531032827753;6826.624535650488;6841.965790171117;6845.623490708059;6950.965245173742;5907.911381329198;5966.970165149147;6001.609895356788;6004.39956004396;6054.122152287893;3658.6158453362546;3670.3209906167444;3673.4673472346362;3693.298273328059;3719.200371919996;6705.396514313735;6717.8579641461065;6723.966380168068;6728.865422691519;6731.562448440835;7151.678174482161;7168.641567921687;7217;7230.421566274686;7236.421086313082;6756;6774.491913106537;6778.796636100925;6784.707927816213;6785.626790526469;6930.896036559355;6935.306469353226;6943.618101004493;6967.860642787217;6982.68577913985;6791.864162716619;6859.931400686129;6863.485238607224;6877.58734475933;6879.243283238739;6697;6727.629980350932;6729.461643068641;6744.966275168593;6766;5073.949260507347;5074.923876141787;5079.796808127781;5094.592432605308;5113.181890897438;6701.262861085374;6929.440875802904;6951.756688515878;6986.126734158231;7010.684519196547;6561.868762624817;6683.164604424447;6688.331166883273;6702.162229721285;6708.329167083252;6633.867322653617;6656.134702488645;6656.167979002625;6678.131842860397;6691.297413771678;6464.321246269074;6572.441342485942;6577.80266592003;6597.175353080865;6631.602103873783;6712.731490740316;6717.496187785838;6744.393004629607;6747.527673062838;6800.149981252343;6506.674666266762;6513.706883190173;6527.5757075789925;6547.639879806657;6559.672016399257 03:53:21 INFO - PID 21522 | |5;yui.html;899.8560230363144;907.759443747407;917.2111983694026;919.5172534419429;920.6409500294886;18844.623107537842;19762.308319208812;19769.406917792454;19823.900880495614;21672.783272167275;20928;21622.675659865115;21712;21780.782192178074;21883.234086806966;931.3340961212732;955.7945041816008;957.4925289596525;959.2901253072746;959.4627008875052;949.1979277510493;1002.9448380339115;1011.9291649584532;1012.7721262715903;1014.2291858187793;1051.4952822645134;1054.5518154784224;1060.1148041385452;1060.2472244706269;1060.9098226650706;1044.190752167069;1051.0277992856616;1051.8737751469796;1052.6789329254575;1052.6894566103017;764.3541207679558;766.0577489687715;766.9463137580371;767.5970115689252;767.6545554500441;17421.39025134114;18277.63444731098;19611.411657650293;19729.408117756346;19749.308774192978;21602.135914563398;21660.783392166035;21688.89155554228;22202.333929982127;22218.55562888733;17044.14779261032;17236.482905512854;17302.56743581408;17432.91283543574;17441.389551365643;3734.458503516975;3787.507624008889;3798;3799.67702745267;3802.923941521182;128422;130510;130657.34671326677;133880;134181.32909335487;16761.91619041897;17046.74429883553;17163.742543861852;17332.65334693299;17465.30138794434;30746.077617671504;31062.68937310643;31230.84384578108;31246.84376578148;31385.215369615944;7621.352185064332;7852.568108754073;8490.320774338039;8906.510141942255;9177.219936305355;4468.352088947085;4551.5676010778925;4562.270036794097;4563.977180114144;4566.406367172246;5225.16397376418;5317.2555842181355;5345.38528069277;5355.86610334737;5356.196570514454;1056.1234175634206;1078.827387617993;1086.0768346905068;1090.9890901091046;1091.3670071358592;689.5449003657562;704.3907020427293;705.6471764117941;707.9964600176966;710.1159056974101;10836.295640783324;11389.20275580718;11469;12446.377681115908;13037.674058148432;702.4977141343963;705.693023534764;708.5359089796192;708.6988030087192;713.5968177979425;5302.92045619316;5349.1976203569;5717.313922329347;5747.511461525817;5861.32594751609;113571;116017;116749;116817.41591292158;119873.40063299802;99700;99736;100908;101600.49199753955;104616.47691761493;84495;85319.57340213383;88833.55583222171;89266.55366723124;89826.55086724524;11627.941860290644;12126.514939402325;12154.331511766937;12230.266184028631;12310.199837010565;41750.79124604358;42167.36748948707;42812.78593607012;43490.34764478599;43512.34731479094;71319.28680713127;72652.63673681598;73434.26565734274;73552;76840;18148.092595369915;18415.815841841944;18421.263149474406;18723.90638046801;19716.21135154578;21466.677999829702;21942;22058.558828823654;22202.222922197645;22365.552688946456;18289.542761431072;18516.0741962904;18530.907345463187;18532.536686582942;18636.906815465834;17788.822111778718;18400.815991840445;18423.907880460512;19648.60702785965;20096.799032009494;17973.73039404384;18085.819141808413;18398.26406943707;18440.723389149443;18514.44456666302;12560.74878502443;13126.212427254395;13258;13303.334833258492;13973.301334933416;14073.296335183404;14100.647483812987;14213.573592792232;14348.928255358656;14380.42478300898 03:53:21 INFO - PID 21522 | __end_tp_report 03:53:21 INFO - PID 21522 | __start_cc_report 03:53:21 INFO - PID 21522 | _x_x_mozilla_cycle_collect,354 03:53:21 INFO - PID 21522 | __end_cc_report 03:53:21 INFO - PID 21522 | __startTimestamp1504608801828__endTimestamp 03:53:21 INFO - PID 21522 | 03:53:21 INFO - PID 21522 | ------- Summary: start ------- 03:53:21 INFO - PID 21522 | Number of tests: 6 03:53:21 INFO - PID 21522 | 03:53:21 INFO - PID 21522 | [#0] dojo.html Cycles:165 Average:6384.79 Median:6627.83 stddev:752.84 (11.4%) stddev-sans-first:715.78 03:53:21 INFO - PID 21522 | Values: 3322.0 3360.7 3393.4 3412.6 3424.9 6490.5 6493.5 6526.1 6530.8 6579.6 6412.4 6459.4 6463.6 6477.9 6492.7 5889.1 5894.2 5916.3 5939.6 5955.3 5964.3 5999.4 6054.9 6059.2 6075.8 5925.2 5998.2 6016.0 6032.9 6043.1 5737.9 5854.6 5883.4 5943.3 5951.9 5825.1 5862.8 5987.7 5989.6 6009.6 6400.2 6415.0 6418.6 6537.8 6556.6 6787.5 6867.2 6965.1 6998.5 7070.7 6942.5 6944.0 6944.5 6945.2 6952.1 6454.3 6454.9 6457.7 6460.4 6463.7 7164.6 7185.4 7255.9 7288.1 7325.0 6858.4 6885.5 6964.0 6976.3 6976.9 6862.3 6946.8 7020.3 7055.3 7159.3 6809.1 6826.9 6840.0 6850.8 6867.1 6877.7 6880.9 6888.7 6891.0 6892.3 5102.5 5446.5 5476.0 5489.7 5491.7 4746.2 4779.0 4780.8 4835.6 4890.9 5894.3 5991.8 6000.6 6001.6 6110.6 6871.3 6881.1 6901.5 6904.9 6915.0 5978.4 5979.9 5986.3 6005.1 6007.5 5884.2 5905.2 5964.2 5972.6 6052.8 6732.1 6741.5 6795.6 6820.7 6824.8 6799.9 6925.3 6945.8 7083.0 7093.2 6711.8 6740.1 6795.8 6801.3 6804.7 6799.1 6840.4 6893.7 6925.9 6937.7 6958.9 6964.8 6967.0 7059.3 7064.7 6634.0 6716.9 6718.2 6725.2 6725.7 6595.3 6659.1 6665.6 6679.4 6686.6 6328.2 6604.7 6624.0 6627.8 6751.4 6750.4 6834.8 6836.7 6876.1 6891.0 6554.4 6562.5 6626.3 6643.4 6664.0 03:53:21 INFO - PID 21522 | 03:53:21 INFO - PID 21522 | [#1] ext.html Cycles:180 Average:37493.55 Median:35802.96 stddev:55597.87 (155.3%) stddev-sans-first:55685.24 03:53:21 INFO - PID 21522 | Values: 710.6 783.3 792.5 820.9 829.5 39117.8 39669.2 39790.6 39903.6 39907.8 35821.6 35994.8 36108.3 36136.8 36158.3 6886.1 6988.2 6994.4 7013.9 7018.2 6893.1 6990.1 6999.8 7003.2 7015.6 6976.9 7001.3 7001.6 7005.4 7555.4 7637.8 7691.7 7697.8 7703.1 7724.7 7463.6 7545.3 7633.9 7637.7 7642.7 36040.6 36510.3 36743.4 36799.1 36803.0 3542.3 3588.2 3588.9 3592.5 3596.4 29044.0 29435.3 29498.9 29515.4 29566.0 5175.5 5186.2 5186.9 5192.7 5193.9 190549.0 190902.0 191165.0 191639.0 192020.0 44685.0 44734.8 44870.6 44895.3 44964.6 46818.3 46970.0 47072.5 47171.8 47252.0 14298.1 14542.5 14552.1 14561.3 14568.7 11895.7 11897.5 11917.9 11946.6 11969.4 13875.7 14018.5 14023.7 14036.4 14124.2 5391.8 5449.2 5450.7 5470.8 5615.1 990.6 999.6 1005.8 1019.3 1023.5 21976.3 22000.0 22129.4 22191.8 22209.9 1091.3 1110.4 1115.9 1123.6 1124.4 11588.5 11777.7 15339.4 15648.1 15906.8 240626.0 241262.0 241436.0 241483.0 242780.0 47847.3 50923.2 51047.7 51945.7 52512.5 1422.5 1449.6 1452.0 1457.5 1461.7 25804.5 26257.7 26324.0 26416.6 26443.5 35430.0 35794.5 35811.5 35814.3 35876.5 205300.0 207073.0 208201.0 208649.0 208728.0 32472.5 32527.2 32542.0 32663.2 32726.8 39503.4 39517.0 42826.8 43843.6 43865.6 32441.4 32442.5 32496.5 32638.7 33579.8 31376.4 31919.5 31974.5 32119.4 32417.8 36314.6 36365.0 36501.3 36592.8 36627.3 31478.2 31685.7 31696.7 31803.0 31951.8 23432.5 23491.4 24072.0 24203.6 24943.8 03:53:21 INFO - PID 21522 | 03:53:21 INFO - PID 21522 | [#2] jquery.html Cycles:180 Average:36957.92 Median:22432.89 stddev:80862.14 (360.5%) stddev-sans-first:81042.78 03:53:21 INFO - PID 21522 | Values: 552.4 553.5 561.9 566.4 580.6 21683.6 22428.0 22437.8 22448.1 22657.8 23656.3 25317.0 25390.5 25398.0 25440.7 6515.9 6661.5 6666.1 6670.2 6673.1 6598.9 6651.7 6675.0 7056.0 7058.9 7046.9 7056.3 7066.0 7069.4 7077.5 7036.3 7064.2 7070.8 7078.2 7083.4 6904.4 6914.9 6917.3 6923.7 6923.7 31027.2 31167.8 31234.4 31347.4 31489.2 2000.7 2054.7 2055.2 2057.1 2059.3 20102.9 20830.3 20838.0 20902.7 20920.9 2537.5 2543.8 2593.3 2601.2 2602.0 141737.3 144651.3 145475.0 145551.0 145727.0 19531.8 21857.9 21869.0 22184.9 22282.4 43959.1 44073.0 44093.0 44105.8 44206.0 11631.8 11724.8 11909.8 11936.8 11955.7 9872.0 9927.0 9935.1 9985.2 10046.5 9289.6 11316.2 11330.6 11345.7 11419.8 4114.0 4156.4 4202.4 4310.2 4371.4 949.7 966.8 975.5 986.7 996.2 20641.9 20750.3 20892.4 21206.4 21646.0 1024.4 1054.4 1056.3 1061.9 1069.2 11159.9 11754.4 11811.1 11882.0 11924.2 464815.0 468260.0 473410.0 482621.0 484116.0 130817.3 131843.3 132241.3 132975.3 133537.0 1170.0 1179.4 1193.5 1196.9 1198.5 18603.3 18633.2 19126.3 22360.7 23101.3 64193.4 64848.7 65251.0 66176.7 67106.3 76028.0 76111.2 76119.0 76497.2 77298.0 20508.5 20607.2 21073.3 21235.0 21326.3 23530.0 23566.1 23793.3 23839.0 23917.4 17834.5 18152.5 18152.9 18405.7 18839.3 16669.2 17223.7 17421.4 17456.3 17847.1 19122.0 19195.7 19244.7 19367.6 19639.2 20301.5 20466.5 20485.3 20512.3 20633.0 18427.9 18909.8 19177.4 19255.4 19267.0 03:53:21 INFO - PID 21522 | 03:53:21 INFO - PID 21522 | [#3] mootools.html Cycles:180 Average:9405.98 Median:14776.06 stddev:6802.21 (46.0%) stddev-sans-first:6794.25 03:53:21 INFO - PID 21522 | Values: 1334.2 1362.3 1374.5 1391.7 1395.5 14588.6 15207.3 15228.9 15238.1 15895.0 15928.8 16158.4 16177.6 16183.8 16191.0 2695.4 2752.8 2767.0 2770.2 2797.1 2811.4 2845.9 2853.4 2854.1 2855.1 2758.6 2798.7 2801.0 2801.7 2804.6 2656.9 2662.7 2669.6 2673.7 2680.0 2588.0 2609.1 2617.8 2619.3 2623.2 15008.2 15338.7 15348.9 15395.5 15410.8 8150.8 8399.7 8436.1 8452.2 8454.0 5376.1 5406.6 5533.4 5566.2 5576.4 1933.5 1940.2 1946.0 1966.3 1989.7 26856.7 27091.2 27095.6 27096.2 27153.9 6828.6 6869.0 6895.2 6987.5 7055.1 17402.7 17523.0 17524.1 17530.2 17544.4 7745.7 8077.5 8077.5 8084.5 8086.9 5874.5 6631.8 7321.5 7324.9 7476.0 5218.8 5404.4 5452.0 5507.5 5525.1 1470.3 1493.6 1500.9 1511.1 1511.8 888.1 897.3 906.3 911.1 913.0 7285.7 7293.4 7300.6 7400.4 7413.3 708.9 709.3 714.4 730.0 731.4 4803.6 4947.4 4994.5 5098.0 5136.2 21265.3 21614.9 21664.1 22296.1 22391.9 21197.2 21760.0 22220.4 22319.3 22425.7 11471.2 11527.7 11532.4 11550.8 11554.7 8092.6 8143.2 8161.7 8163.5 8221.1 18283.5 18477.7 18781.0 19048.8 19127.8 19109.3 19177.4 19182.3 19331.5 19758.6 11140.7 11149.3 11178.8 11223.9 11225.4 13267.7 13277.3 13491.5 13494.8 13536.3 11295.4 11348.1 11502.5 11636.8 11768.5 11398.8 11437.9 11491.3 11550.8 11711.2 9744.2 9769.3 9877.9 9894.2 9916.5 7229.5 7260.9 7273.0 7288.2 7350.5 7377.9 7429.4 7468.9 7483.6 7549.3 03:53:21 INFO - PID 21522 | 03:53:21 INFO - PID 21522 | [#4] prototype.html Cycles:180 Average:5645.50 Median:6298.95 stddev:1602.80 (25.4%) stddev-sans-first:1559.90 03:53:21 INFO - PID 21522 | Values: 490.5 503.3 533.4 535.0 538.7 6303.5 6307.9 6329.3 6339.3 6421.2 6103.5 6217.2 6297.4 6358.5 6359.5 5062.0 5064.7 5115.9 5120.0 5151.6 4910.1 5015.8 5101.6 5102.7 5112.9 5032.6 5051.7 5080.3 5082.3 5088.6 4984.7 5011.2 5016.7 5038.7 5062.0 4522.8 4532.4 4534.0 4560.3 4584.7 6266.7 6268.5 6271.8 6300.5 6343.7 5810.1 5822.9 5842.3 5861.4 5916.7 6623.9 6626.4 6672.9 6686.7 6703.5 4924.4 4939.4 4956.3 4976.4 4987.4 7067.3 7079.5 7105.0 7113.9 7149.5 5030.5 5036.9 5048.0 5059.3 5085.9 5116.2 5157.3 5158.7 5179.1 5193.6 5831.5 5836.0 5845.4 5850.2 5856.6 6617.0 6649.3 6709.8 6759.8 6790.0 1653.5 1691.8 1716.3 1756.7 1777.1 1214.3 1221.6 1232.8 1245.6 1258.1 5746.5 5783.3 5817.0 5860.8 5908.6 6699.5 6826.6 6842.0 6845.6 6951.0 5907.9 5967.0 6001.6 6004.4 6054.1 3658.6 3670.3 3673.5 3693.3 3719.2 6705.4 6717.9 6724.0 6728.9 6731.6 7151.7 7168.6 7217.0 7230.4 7236.4 6756.0 6774.5 6778.8 6784.7 6785.6 6930.9 6935.3 6943.6 6967.9 6982.7 6791.9 6859.9 6863.5 6877.6 6879.2 6697.0 6727.6 6729.5 6745.0 6766.0 5073.9 5074.9 5079.8 5094.6 5113.2 6701.3 6929.4 6951.8 6986.1 7010.7 6561.9 6683.2 6688.3 6702.2 6708.3 6633.9 6656.1 6656.2 6678.1 6691.3 6464.3 6572.4 6577.8 6597.2 6631.6 6712.7 6717.5 6744.4 6747.5 6800.1 6506.7 6513.7 6527.6 6547.6 6559.7 03:53:21 INFO - PID 21522 | 03:53:21 INFO - PID 21522 | [#5] yui.html Cycles:180 Average:24580.51 Median:20512.40 stddev:33554.25 (163.6%) stddev-sans-first:33601.26 03:53:21 INFO - PID 21522 | Values: 899.9 907.8 917.2 919.5 920.6 18844.6 19762.3 19769.4 19823.9 21672.8 20928.0 21622.7 21712.0 21780.8 21883.2 931.3 955.8 957.5 959.3 959.5 949.2 1002.9 1011.9 1012.8 1014.2 1051.5 1054.6 1060.1 1060.2 1060.9 1044.2 1051.0 1051.9 1052.7 1052.7 764.4 766.1 766.9 767.6 767.7 17421.4 18277.6 19611.4 19729.4 19749.3 21602.1 21660.8 21688.9 22202.3 22218.6 17044.1 17236.5 17302.6 17432.9 17441.4 3734.5 3787.5 3798.0 3799.7 3802.9 128422.0 130510.0 130657.3 133880.0 134181.3 16761.9 17046.7 17163.7 17332.7 17465.3 30746.1 31062.7 31230.8 31246.8 31385.2 7621.4 7852.6 8490.3 8906.5 9177.2 4468.4 4551.6 4562.3 4564.0 4566.4 5225.2 5317.3 5345.4 5355.9 5356.2 1056.1 1078.8 1086.1 1091.0 1091.4 689.5 704.4 705.6 708.0 710.1 10836.3 11389.2 11469.0 12446.4 13037.7 702.5 705.7 708.5 708.7 713.6 5302.9 5349.2 5717.3 5747.5 5861.3 113571.0 116017.0 116749.0 116817.4 119873.4 99700.0 99736.0 100908.0 101600.5 104616.5 84495.0 85319.6 88833.6 89266.6 89826.6 11627.9 12126.5 12154.3 12230.3 12310.2 41750.8 42167.4 42812.8 43490.3 43512.3 71319.3 72652.6 73434.3 73552.0 76840.0 18148.1 18415.8 18421.3 18723.9 19716.2 21466.7 21942.0 22058.6 22202.2 22365.6 18289.5 18516.1 18530.9 18532.5 18636.9 17788.8 18400.8 18423.9 19648.6 20096.8 17973.7 18085.8 18398.3 18440.7 18514.4 12560.7 13126.2 13258.0 13303.3 13973.3 14073.3 14100.6 14213.6 14348.9 14380.4 03:53:21 INFO - PID 21522 | -------- Summary: end -------- 03:53:21 INFO - PID 21522 | 03:53:22 INFO - TEST-INFO | 21522: exit 0 03:53:22 INFO - TEST-OK | dromaeo_css | took 1110834ms 03:53:22 INFO - TEST-START | kraken 03:53:22 INFO - Initialising browser for kraken test... 03:53:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47932/getInfo.html -profile /tmp/tmppEu__4/profile 03:53:22 INFO - TEST-INFO | started process 21898 (/builds/slave/test/build/application/firefox/firefox http://localhost:47932/getInfo.html) 03:53:27 INFO - TEST-INFO | 21898: exit 0 03:53:27 INFO - Browser initialized. 03:53:27 INFO - Running cycle 1/1 for kraken test... 03:53:27 INFO - Using env: {'DISPLAY': ':0', 03:53:27 INFO - 'HOME': '/home/cltbld', 03:53:27 INFO - 'JSGC_DISABLE_POISONING': '1', 03:53:27 INFO - 'LANG': 'en_US.UTF-8', 03:53:27 INFO - 'LANGUAGE': 'en_US:en', 03:53:27 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:53:27 INFO - 'LOGNAME': 'cltbld', 03:53:27 INFO - 'MAIL': '/var/mail/cltbld', 03:53:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:53:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:53:27 INFO - 'MOZ_CRASHREPORTER': '1', 03:53:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:53:27 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:53:27 INFO - 'MOZ_NO_REMOTE': '1', 03:53:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:53:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:53:27 INFO - 'NO_EM_RESTART': '1', 03:53:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:53:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:53:27 INFO - 'PWD': '/builds/slave/test', 03:53:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:53:27 INFO - 'RUST_BACKTRACE': 'full', 03:53:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:53:27 INFO - 'SHELL': '/bin/bash', 03:53:27 INFO - 'SHLVL': '1', 03:53:27 INFO - 'STYLO_FORCE_ENABLED': '1', 03:53:27 INFO - 'STYLO_THREADS': '1', 03:53:27 INFO - 'TERM': 'linux', 03:53:27 INFO - 'TMOUT': '86400', 03:53:27 INFO - 'USER': 'cltbld', 03:53:27 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557', 03:53:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:53:27 INFO - '_': '/tools/buildbot/bin/python'} 03:53:27 INFO - TEST-INFO | started process 22085 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppEu__4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:53:28 INFO - PID 22085 | 03:53:28 INFO - PID 22085 | (/builds/slave/test/build/application/firefox/firefox:22137): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:53:28 INFO - PID 22085 | 03:53:38 INFO - PID 22085 | RSS: Main: 172240896 03:53:38 INFO - PID 22085 | 03:53:41 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47932/tests/kraken/driver.html?testName=audio-beat-detection) 03:53:42 INFO - PID 22085 | RSS: Main: 178049024 03:53:42 INFO - PID 22085 | 03:53:45 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47932/tests/kraken/driver.html?testName=audio-dft) 03:53:45 INFO - PID 22085 | RSS: Main: 182489088 03:53:45 INFO - PID 22085 | 03:53:48 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47932/tests/kraken/driver.html?testName=audio-fft) 03:53:49 INFO - PID 22085 | RSS: Main: 175431680 03:53:49 INFO - PID 22085 | 03:53:51 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47932/tests/kraken/driver.html?testName=audio-oscillator) 03:53:52 INFO - PID 22085 | RSS: Main: 176316416 03:53:52 INFO - PID 22085 | 03:53:54 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47932/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:53:54 INFO - PID 22085 | RSS: Main: 176898048 03:53:54 INFO - PID 22085 | 03:53:57 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47932/tests/kraken/driver.html?testName=imaging-darkroom) 03:53:58 INFO - PID 22085 | RSS: Main: 177483776 03:53:58 INFO - PID 22085 | 03:54:02 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47932/tests/kraken/driver.html?testName=imaging-desaturate) 03:54:02 INFO - PID 22085 | RSS: Main: 177405952 03:54:02 INFO - PID 22085 | 03:54:05 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47932/tests/kraken/driver.html?testName=json-parse-financial) 03:54:06 INFO - PID 22085 | RSS: Main: 177741824 03:54:06 INFO - PID 22085 | 03:54:07 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47932/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:54:08 INFO - PID 22085 | RSS: Main: 177373184 03:54:08 INFO - PID 22085 | 03:54:10 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47932/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:54:10 INFO - PID 22085 | RSS: Main: 177381376 03:54:10 INFO - PID 22085 | 03:54:12 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47932/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:54:13 INFO - PID 22085 | RSS: Main: 174579712 03:54:13 INFO - PID 22085 | 03:54:16 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47932/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:54:16 INFO - PID 22085 | RSS: Main: 174485504 03:54:16 INFO - PID 22085 | 03:54:19 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47932/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:54:20 INFO - PID 22085 | RSS: Main: 174907392 03:54:20 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | Cycle 1(1): loaded http://localhost:47932/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47932/tests/kraken/driver.html?testName=ai-astar) 03:54:22 INFO - PID 22085 | RSS: Main: 176480256 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | __start_tp_report 03:54:22 INFO - PID 22085 | _x_x_mozilla_page_load 03:54:22 INFO - PID 22085 | _x_x_mozilla_page_load_details 03:54:22 INFO - PID 22085 | |i|pagename|runs| 03:54:22 INFO - PID 22085 | |0;ai-astar;98;111;99;108;108;108;111;98;110;110 03:54:22 INFO - PID 22085 | |1;audio-beat-detection;141;119;157;186;118;154;144;122;168;118 03:54:22 INFO - PID 22085 | |2;audio-dft;165;161;161;162;160;160;160;164;168;164 03:54:22 INFO - PID 22085 | |3;audio-fft;75;74;74;175;75;74;79;83;155;74 03:54:22 INFO - PID 22085 | |4;audio-oscillator;80;79;135;79;79;79;124;80;79;130 03:54:22 INFO - PID 22085 | |5;imaging-gaussian-blur;113;113;115;116;114;114;114;114;114;115 03:54:22 INFO - PID 22085 | |6;imaging-darkroom;147;148;147;148;148;147;147;149;149;149 03:54:22 INFO - PID 22085 | |7;imaging-desaturate;94;93;93;94;94;94;94;94;94;95 03:54:22 INFO - PID 22085 | |8;json-parse-financial;58;56;59;51;52;52;52;52;53;54 03:54:22 INFO - PID 22085 | |9;json-stringify-tinderbox;38;43;43;40;38;39;38;39;39;39 03:54:22 INFO - PID 22085 | |10;stanford-crypto-aes;74;76;75;73;74;75;77;77;75;75 03:54:22 INFO - PID 22085 | |11;stanford-crypto-ccm;119;131;122;121;122;125;119;135;124;157 03:54:22 INFO - PID 22085 | |12;stanford-crypto-pbkdf2;169;168;174;168;176;171;169;168;172;167 03:54:22 INFO - PID 22085 | |13;stanford-crypto-sha256-iterative;65;70;64;64;63;63;63;63;65;63 03:54:22 INFO - PID 22085 | __end_tp_report 03:54:22 INFO - PID 22085 | __start_cc_report 03:54:22 INFO - PID 22085 | _x_x_mozilla_cycle_collect,960 03:54:22 INFO - PID 22085 | __end_cc_report 03:54:22 INFO - PID 22085 | __startTimestamp1504608862508__endTimestamp 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | ------- Summary: start ------- 03:54:22 INFO - PID 22085 | Number of tests: 14 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#0] ai-astar Cycles:10 Average:106.10 Median:111.00 stddev:5.49 (4.9%) stddev-sans-first:4.97 03:54:22 INFO - PID 22085 | Values: 98.0 111.0 99.0 108.0 108.0 108.0 111.0 98.0 110.0 110.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#1] audio-beat-detection Cycles:10 Average:142.70 Median:149.00 stddev:23.72 (15.9%) stddev-sans-first:25.15 03:54:22 INFO - PID 22085 | Values: 141.0 119.0 157.0 186.0 118.0 154.0 144.0 122.0 168.0 118.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:2.68 (1.6%) stddev-sans-first:2.68 03:54:22 INFO - PID 22085 | Values: 165.0 161.0 161.0 162.0 160.0 160.0 160.0 164.0 168.0 164.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#3] audio-fft Cycles:10 Average:93.80 Median:74.50 stddev:37.93 (50.9%) stddev-sans-first:39.62 03:54:22 INFO - PID 22085 | Values: 75.0 74.0 74.0 175.0 75.0 74.0 79.0 83.0 155.0 74.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:79.00 stddev:24.48 (31.0%) stddev-sans-first:25.40 03:54:22 INFO - PID 22085 | Values: 80.0 79.0 135.0 79.0 79.0 79.0 124.0 80.0 79.0 130.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.92 (0.8%) stddev-sans-first:0.87 03:54:22 INFO - PID 22085 | Values: 113.0 113.0 115.0 116.0 114.0 114.0 114.0 114.0 114.0 115.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#6] imaging-darkroom Cycles:10 Average:147.90 Median:148.00 stddev:0.88 (0.6%) stddev-sans-first:0.87 03:54:22 INFO - PID 22085 | Values: 147.0 148.0 147.0 148.0 148.0 147.0 147.0 149.0 149.0 149.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 03:54:22 INFO - PID 22085 | Values: 94.0 93.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:53.50 stddev:2.81 (5.2%) stddev-sans-first:2.55 03:54:22 INFO - PID 22085 | Values: 58.0 56.0 59.0 51.0 52.0 52.0 52.0 52.0 53.0 54.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#9] json-stringify-tinderbox Cycles:10 Average:39.60 Median:39.00 stddev:1.90 (4.9%) stddev-sans-first:1.92 03:54:22 INFO - PID 22085 | Values: 38.0 43.0 43.0 40.0 38.0 39.0 38.0 39.0 39.0 39.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:75.00 stddev:1.29 (1.7%) stddev-sans-first:1.30 03:54:22 INFO - PID 22085 | Values: 74.0 76.0 75.0 73.0 74.0 75.0 77.0 77.0 75.0 75.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#11] stanford-crypto-ccm Cycles:10 Average:127.50 Median:124.50 stddev:11.57 (9.3%) stddev-sans-first:11.85 03:54:22 INFO - PID 22085 | Values: 119.0 131.0 122.0 121.0 122.0 125.0 119.0 135.0 124.0 157.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:170.00 stddev:2.97 (1.7%) stddev-sans-first:3.12 03:54:22 INFO - PID 22085 | Values: 169.0 168.0 174.0 168.0 176.0 171.0 169.0 168.0 172.0 167.0 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:2.16 (3.4%) stddev-sans-first:2.28 03:54:22 INFO - PID 22085 | Values: 65.0 70.0 64.0 64.0 63.0 63.0 63.0 63.0 65.0 63.0 03:54:22 INFO - PID 22085 | -------- Summary: end -------- 03:54:22 INFO - PID 22085 | 03:54:22 INFO - PID 22085 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:54:23 INFO - TEST-INFO | 22085: exit 0 03:54:23 INFO - TEST-OK | kraken | took 60609ms 03:54:23 INFO - SUITE-END | took 1171s 03:54:23 INFO - Completed test suite (00:19:31) 03:54:23 INFO - javascript benchmark 03:54:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8764.985889175681, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3321.9833900830495, 3360.7479439042063, 3393.389189945809, 3412.5904891413034, 3424.914377140573, 6490.513211509132, 6493.512986526011, 6526.053722210277, 6530.771423000196, 6579.6381199034095, 6412.422881940624, 6459.354064593538, 6463.612183268995, 6477.935220647792, 6492.7402903883785, 5889.05775075989, 5894.204282421882, 5916.319623243317, 5939.55453340999, 5955.315138759055, 5964.284285885678, 5999.430054144849, 6054.939450605481, 6059.151718759377, 6075.787347442841, 5925.170476133345, 5998.160257563923, 6015.969920150393, 6032.909506357408, 6043.123747056674, 5737.88524229513, 5854.560907931922, 5883.441073098049, 5943.286805583314, 5951.880962380771, 5825.067989121762, 5862.794802181903, 5987.700614969234, 5989.55078369124, 6009.639421634716, 6400.231972163324, 6415.0, 6418.5507014509, 6537.803865883983, 6556.639384833832, 6787.490938179656, 6867.244603093655, 6965.094537710065, 6998.4751143664425, 7070.717171313141, 6942.548734332252, 6943.965280173617, 6944.479164062715, 6945.236024037352, 6952.096227490444, 6454.290028096905, 6454.870902581969, 6457.677116144174, 6460.418562329413, 6463.709133089069, 7164.605946672983, 7185.3533182012825, 7255.8911616325795, 7288.052553168054, 7325.011123498366, 6858.417034552019, 6885.483588730865, 6963.9651801740665, 6976.337247961435, 6976.860462790716, 6862.279460656659, 6946.79159625212, 7020.297970203041, 7055.329743674342, 7159.284071592903, 6809.114815074107, 6826.931730682729, 6839.965800171067, 6850.794476165723, 6867.141607299088, 6877.727620390244, 6880.862382752317, 6888.690008949609, 6890.965545172342, 6892.310768923067, 5102.4897510248675, 5446.509814116748, 5476.041692703761, 5489.725513724298, 5491.7254137292975, 4746.240601503743, 4778.952210477851, 4780.784864681098, 4835.588974937099, 4890.926636100461, 5894.2926848778425, 5991.7603295867675, 6000.5799594028, 6001.609895356788, 6110.633361998294, 6871.278515755874, 6881.13985751781, 6901.482388820858, 6904.896426553606, 6915.0, 5978.402159784074, 5979.880402391927, 5986.281646202484, 6005.129256257818, 6007.549433792483, 5884.176215329857, 5905.232319798399, 5964.194833697482, 5972.552058595623, 6052.848678783065, 6732.124823772976, 6741.494387920926, 6795.592264464049, 6820.658967051628, 6824.7952561423235, 6799.864002720017, 6925.3074692530345, 6945.75689850873, 7082.964585177041, 7093.184283807428, 6711.765088221889, 6740.12378390806, 6795.7961261162245, 6801.251862295243, 6804.659767011531, 6799.11611490503, 6840.418564421881, 6893.655317234218, 6925.896111558432, 6937.722491100501, 6958.921367188094, 6964.756233531802, 6967.0, 7059.258777828254, 7064.717411303491, 6634.0, 6716.865662686817, 6718.22740384862, 6725.19297684281, 6725.730970761115, 6595.307492713388, 6659.101021362248, 6665.566738162004, 6679.432248258952, 6686.598804071771, 6328.208973878265, 6604.702788374439, 6623.966880165761, 6627.834304142435, 6751.392374686302, 6750.426213771884, 6834.794956151323, 6836.658167091526, 6876.071730316343, 6891.035255064195, 6554.442872355903, 6562.507811914221, 6626.337366263335, 6643.40209381158, 6664.033715111379], "value": 6329.66068361215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.5630037526919, 783.2676447521571, 792.4848848248638, 820.8850760893472, 829.4567058576632, 39117.80441097797, 39669.20661586767, 39790.602093979054, 39903.60096399035, 39907.80046099769, 35821.641783582156, 35994.8200258999, 36108.277834443295, 36136.81931590338, 36158.27683446329, 6886.104806375177, 6988.161420629506, 6994.405475534586, 7013.929860701379, 7018.19290781559, 6893.0694356261765, 6990.091288132536, 6999.755008574701, 7003.159620845506, 7015.649217539129, 6976.930230697704, 7001.264867188948, 7001.614911179883, 7005.4045406140285, 7555.357794587467, 7637.770866874002, 7691.653875575556, 7697.8075548111465, 7703.075630924269, 7724.691012359499, 7463.589502577356, 7545.283198096172, 7633.923660763377, 7637.694492220305, 7642.656080476392, 36040.63959360399, 36510.269794604224, 36743.4488482673, 36799.08002299937, 36803.0, 3542.3092496963104, 3588.1747198144312, 3588.856445742167, 3592.5329707138167, 3596.37063513884, 29044.0, 29435.264118396997, 29498.85250573755, 29515.409691806257, 29566.0, 5175.534201921808, 5186.196139598368, 5186.870328241786, 5192.71440070796, 5193.8961220775755, 190549.0, 190902.0, 191165.0, 191639.0, 192020.039899801, 44685.0, 44734.77632611816, 44870.551294486635, 44895.32657010082, 44964.55035449669, 46818.29772553415, 46970.0, 47072.5292747075, 47171.764141179076, 47252.0, 14298.070625409315, 14542.491012814713, 14552.126872387691, 14561.344739486749, 14568.708625827425, 11895.70260743471, 11897.524099036114, 11917.940410298066, 11946.641600751991, 11969.401529923469, 13875.653108672364, 14018.509352172625, 14023.737863592325, 14036.368363423466, 14124.152550846982, 5391.838244852661, 5449.1826226066405, 5450.672959622435, 5470.835874923759, 5615.101583746581, 990.5691024404407, 999.6451259802666, 1005.8189525885265, 1019.2559431614815, 1023.4780262066399, 21976.34070977873, 22000.0, 22129.44676383071, 22191.778082219294, 22209.888950555465, 1091.2961140064615, 1110.4281295133012, 1115.9442027898735, 1123.5674265407713, 1124.3928278729395, 11588.478518466689, 11777.705557361134, 15339.386424543116, 15648.139352335731, 15906.840931590767, 240626.0, 241262.0, 241436.0, 241483.0, 242780.0, 47847.28229076567, 50923.23615145776, 51047.7447612767, 51945.74027129916, 52512.47487525076, 1422.459465403161, 1449.5506393018197, 1451.9709605808036, 1457.4753088888203, 1461.6784307452344, 25804.483910322066, 26257.73742262553, 26324.0, 26416.603750944134, 26443.549631429025, 35430.0, 35794.463083054296, 35811.462828058124, 35814.28371432609, 35876.461853072746, 205300.0, 207073.0, 208201.0, 208649.0, 208728.0, 32472.512912306807, 32527.18682032968, 32542.047477151445, 32663.18342041468, 32726.83636581897, 39503.407448887716, 39517.0, 42826.78586607047, 43843.56156438395, 43865.56134438615, 32441.351172975938, 32442.513362300055, 32496.512552312208, 32638.673613263563, 33579.83210083934, 31376.372472549963, 31919.521207182377, 31974.52038219475, 32119.357612847143, 32417.837910810296, 36314.63685363113, 36365.0, 36501.26997460089, 36592.81703591465, 36627.26745465129, 31478.213044674067, 31685.683143169197, 31696.683033170295, 31803.0, 31951.840240798647, 23432.531349373257, 23491.41271468227, 24072.0, 24203.63694544548, 24943.750562494144], "value": 16754.81406562643, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.4199590430047, 553.4907884746033, 561.9325680918286, 566.371327826113, 580.5529742098585, 21683.566328673416, 22428.0, 22437.775622243793, 22448.102075916944, 22657.77342226577, 23656.290311290646, 25317.0, 25390.492190156187, 25398.0, 25440.74559254407, 6515.934840651592, 6661.467082633378, 6666.0667506549125, 6670.199576050856, 6673.065770792093, 6598.901016484757, 6651.700673469705, 6674.966625166868, 7055.964720176392, 7058.85882282354, 7046.894296585555, 7056.294370562954, 7065.97543356213, 7069.363757261845, 7077.469189810759, 7036.261192574782, 7064.1523017238005, 7070.752523661698, 7078.186008608973, 7083.362497375235, 6904.378605925491, 6914.896276555855, 6917.27368626292, 6923.6884340204815, 6923.723051077951, 31027.22431939197, 31167.844160779052, 31234.37531249385, 31347.37305253905, 31489.21276968071, 2000.689893066577, 2054.7123402723632, 2055.1779288284656, 2057.1360028787794, 2059.3101311060814, 20102.899485502625, 20830.27094051701, 20838.0, 20902.686459703116, 20920.895395523075, 2537.4671319023028, 2543.796496280293, 2593.3257353088334, 2601.180628102142, 2602.01123573041, 141737.2913135438, 144651.2767436156, 145475.0, 145551.0, 145727.0, 19531.804681953283, 21857.890710546344, 21869.0, 22184.889075554518, 22282.442938926655, 43959.12081758347, 44073.0, 44093.0, 44105.77947110308, 44206.0, 11631.76736465266, 11724.824127638092, 11909.761804763857, 11936.820947685792, 11955.701107472383, 9871.950640246896, 9926.950365248273, 9935.105840474393, 9985.20118390527, 10046.497675116216, 9289.628414863464, 11316.151288653384, 11330.60342887995, 11345.716357091138, 11419.771604568028, 4114.0, 4156.376543518496, 4202.390653355246, 4310.181065597589, 4371.431713877239, 949.6723630347519, 966.7728083900278, 975.531744762518, 986.7335819328741, 996.2229461020415, 20641.89679051595, 20750.273740419012, 20892.37322880316, 21206.363809085753, 21646.025928832925, 1024.3956065921143, 1054.3923056244262, 1056.271172890703, 1061.87257529097, 1069.2141276162015, 11159.884011598775, 11754.41665209036, 11811.114166437552, 11882.019103952722, 11924.224925379822, 464815.0, 468260.0, 473410.0, 482621.0, 484116.0, 130817.34591327173, 131843.34078329548, 132241.33879330734, 132975.33512332378, 133537.0, 1170.0, 1179.3808250668467, 1193.540486912545, 1196.9102317326235, 1198.532572296805, 18603.2558697656, 18633.161507732457, 19126.33057842969, 22360.664590031487, 23101.306960791204, 64193.35806641874, 64848.67575662091, 65251.0, 66176.66911665411, 67106.32893671001, 76028.0, 76111.23888761041, 76119.0, 76497.23502764902, 77298.0, 20508.487287817923, 20607.17571297131, 21073.31413486948, 21235.044423001313, 21326.253581124587, 23530.0, 23566.0573577055, 23793.286201413986, 23839.04643814228, 23917.40206494782, 17834.46496605104, 18152.45542633723, 18152.90923545374, 18405.723914141032, 18839.340623078126, 16669.24988375502, 17223.741643875102, 17421.39025134114, 17456.30174792994, 17847.107644617456, 19122.0, 19195.712064319327, 19244.71132932979, 19367.612647747246, 19639.214431423155, 20301.49246268855, 20466.488337791674, 20485.2830150944, 20512.282070127472, 20633.0, 18427.90786046061, 18909.810901890804, 19177.424677259703, 19255.422337329903, 19267.0], "value": 13298.890281279168, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.1594795278975, 1362.3052243355885, 1374.4502199120348, 1391.6799136198667, 1395.462746842466, 14588.635284117889, 15207.315670794813, 15228.92385538072, 15238.085714857116, 15895.046297222174, 15928.84071159288, 16158.434454794084, 16177.595560110974, 16183.83816161838, 16191.0, 2695.3935364543017, 2752.779777617786, 2767.0, 2770.224337185591, 2797.146870204597, 2811.381496070861, 2845.871935762885, 2853.429314137181, 2854.115224280469, 2855.100643297365, 2758.613794068822, 2798.664160300767, 2800.957985630207, 2801.705820888808, 2804.649418822647, 2656.8804403801873, 2662.6937902141212, 2669.572868341056, 2673.679158500973, 2679.9598006029923, 2588.0424243030197, 2609.1129016134605, 2617.829841060325, 2619.266605350505, 2623.2130360891847, 15008.24958752069, 15338.693226135527, 15348.923255383652, 15395.461158859498, 15410.768838467431, 8150.796230094235, 8399.664013439455, 8436.114208008132, 8452.197041281068, 8454.0, 5376.11294136467, 5406.567474602022, 5533.363663178746, 5566.165075238706, 5576.414476479952, 1933.5456167800555, 1940.2142132436386, 1945.9513512162168, 1966.3314473079222, 1989.6816509358432, 26856.731432685814, 27091.1872643821, 27095.593566096526, 27096.1871143866, 27153.86423067892, 6828.624425656637, 6868.965655171693, 6895.172579290517, 6987.475939304572, 7055.118110236221, 17402.738958915626, 17523.0, 17524.123793810257, 17530.211140498708, 17544.385946491813, 7745.728899488604, 8077.474964127313, 8077.515349079074, 8084.474509156886, 8086.919130808734, 5874.530037596982, 6631.801045968628, 7321.524100933422, 7324.853502930018, 7475.962620186864, 5218.7912483500995, 5404.351477822686, 5451.972740136353, 5507.531859791962, 5525.11598144303, 1470.2795630141154, 1493.6041948883556, 1500.887433442496, 1511.078242272235, 1511.8110236220473, 888.1163242573681, 897.2911399993932, 906.2659246010752, 911.1344222988188, 913.0139449394638, 7285.744998925013, 7293.416526677853, 7300.6349682515665, 7400.370968467633, 7413.332800048021, 708.8582283543312, 709.3474003916409, 714.3642158478958, 729.9635018249066, 731.3637135691982, 4803.567678908915, 4947.381577302837, 4994.450610432922, 5098.0, 5136.1525348317855, 21265.255716050175, 21614.891925540585, 21664.133434662752, 22296.108155673915, 22391.888040560018, 21197.152113915272, 21760.0, 22220.444488887584, 22319.330420087423, 22425.66361504579, 11471.197016208953, 11527.654170374903, 11532.423378831025, 11550.768984620261, 11554.711132221762, 8092.554909480035, 8143.18568143181, 8161.714339998071, 8163.469374490639, 8221.095679475133, 18283.451496455127, 18477.72283415723, 18781.0, 19048.80951190526, 19127.808721912603, 19109.33117340942, 19177.424677259703, 19182.328618498286, 19331.51671208231, 19758.604827903073, 11140.665780026613, 11149.275297105662, 11178.776424471302, 11223.887761122285, 11225.438728063727, 13267.66830829237, 13277.336133193108, 13491.460341586228, 13494.797578036534, 13536.323183840572, 11295.3787541686, 11348.148888833535, 11502.53989840397, 11636.76726465449, 11768.470418831002, 11398.829017564909, 11437.885621143681, 11491.253068550517, 11550.768984620092, 11711.1802173847, 9744.220462362851, 9769.31614786958, 9877.851832222379, 9894.158996485092, 9916.504174791376, 7229.457790665826, 7260.854782904418, 7272.96363518179, 7288.198298187302, 7350.52221605594, 7377.926220737939, 7429.442791790745, 7468.887966680395, 7483.588402637907, 7549.282818132268], "value": 6656.89741980899, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.54624472363076, 503.3029254482543, 533.3759501383381, 534.978191653941, 538.7037129578732, 6303.527235457336, 6307.905381419271, 6329.3037765845775, 6339.302676705559, 6421.165248517698, 6103.45068943795, 6217.1917650705345, 6297.401746834055, 6358.459530939876, 6359.523035772312, 5061.974690126563, 5064.6961182329, 5115.872103197422, 5120.027194832988, 5151.613628977823, 4910.109607800987, 5015.799368025275, 5101.617378696594, 5102.719350435725, 5112.900726343835, 5032.6477146599755, 5051.747412629372, 5080.263361812535, 5082.313887625179, 5088.5929125670045, 4984.700917944917, 5011.198208286675, 5016.673916195472, 5038.697678139323, 5061.975729295219, 4522.7964741586375, 4532.365468834366, 4534.0, 4560.315952607102, 4584.701994370389, 6266.686665666698, 6268.498520118425, 6271.811845644638, 6300.495960323209, 6343.714532846033, 5810.065916712076, 5822.854428639275, 5842.341734510787, 5861.384554621746, 5916.7337469813965, 6623.899597310093, 6626.370494802986, 6672.933270667279, 6686.699098540529, 6703.530752847302, 4924.433690125646, 4939.3578834751615, 4956.306117143603, 4976.402831660188, 4987.351644286219, 7067.328603782633, 7079.548692518021, 7104.964475177591, 7113.89329160063, 7149.49953503255, 5030.496950305013, 5036.924446133311, 5048.040872234264, 5059.316992206079, 5085.8728531787, 5116.232565115262, 5157.303763991888, 5158.710322419395, 5179.145441002268, 5193.610479214074, 5831.533477321804, 5836.0, 5845.444682755132, 5850.180974663551, 5856.648601083949, 6617.022339947043, 6649.342861426197, 6709.798706038827, 6759.763408280686, 6790.0, 1653.5204790610587, 1691.8223586523238, 1716.3048965169125, 1756.7452719355622, 1777.129206688713, 1214.3260490427897, 1221.5785553983862, 1232.8027515597462, 1245.582729785532, 1258.08160043169, 5746.482816546531, 5783.306003279549, 5816.952948469231, 5860.794872179454, 5908.645481271137, 6699.531032827753, 6826.624535650488, 6841.965790171117, 6845.623490708059, 6950.965245173742, 5907.911381329198, 5966.970165149147, 6001.609895356788, 6004.39956004396, 6054.122152287893, 3658.6158453362546, 3670.3209906167444, 3673.4673472346362, 3693.298273328059, 3719.200371919996, 6705.396514313735, 6717.8579641461065, 6723.966380168068, 6728.865422691519, 6731.562448440835, 7151.678174482161, 7168.641567921687, 7217.0, 7230.421566274686, 7236.421086313082, 6756.0, 6774.491913106537, 6778.796636100925, 6784.707927816213, 6785.626790526469, 6930.896036559355, 6935.306469353226, 6943.618101004493, 6967.860642787217, 6982.68577913985, 6791.864162716619, 6859.931400686129, 6863.485238607224, 6877.58734475933, 6879.243283238739, 6697.0, 6727.629980350932, 6729.461643068641, 6744.966275168593, 6766.0, 5073.949260507347, 5074.923876141787, 5079.796808127781, 5094.592432605308, 5113.181890897438, 6701.262861085374, 6929.440875802904, 6951.756688515878, 6986.126734158231, 7010.684519196547, 6561.868762624817, 6683.164604424447, 6688.331166883273, 6702.162229721285, 6708.329167083252, 6633.867322653617, 6656.134702488645, 6656.167979002625, 6678.131842860397, 6691.297413771678, 6464.321246269074, 6572.441342485942, 6577.80266592003, 6597.175353080865, 6631.602103873783, 6712.731490740316, 6717.496187785838, 6744.393004629607, 6747.527673062838, 6800.149981252343, 6506.674666266762, 6513.706883190173, 6527.5757075789925, 6547.639879806657, 6559.672016399257], "value": 5169.318459003522, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [899.8560230363144, 907.759443747407, 917.2111983694026, 919.5172534419429, 920.6409500294886, 18844.623107537842, 19762.308319208812, 19769.406917792454, 19823.900880495614, 21672.783272167275, 20928.0, 21622.675659865115, 21712.0, 21780.782192178074, 21883.234086806966, 931.3340961212732, 955.7945041816008, 957.4925289596525, 959.2901253072746, 959.4627008875052, 949.1979277510493, 1002.9448380339115, 1011.9291649584532, 1012.7721262715903, 1014.2291858187793, 1051.4952822645134, 1054.5518154784224, 1060.1148041385452, 1060.2472244706269, 1060.9098226650706, 1044.190752167069, 1051.0277992856616, 1051.8737751469796, 1052.6789329254575, 1052.6894566103017, 764.3541207679558, 766.0577489687715, 766.9463137580371, 767.5970115689252, 767.6545554500441, 17421.39025134114, 18277.63444731098, 19611.411657650293, 19729.408117756346, 19749.308774192978, 21602.135914563398, 21660.783392166035, 21688.89155554228, 22202.333929982127, 22218.55562888733, 17044.14779261032, 17236.482905512854, 17302.56743581408, 17432.91283543574, 17441.389551365643, 3734.458503516975, 3787.507624008889, 3798.0, 3799.67702745267, 3802.923941521182, 128422.0, 130510.0, 130657.34671326677, 133880.0, 134181.32909335487, 16761.91619041897, 17046.74429883553, 17163.742543861852, 17332.65334693299, 17465.30138794434, 30746.077617671504, 31062.68937310643, 31230.84384578108, 31246.84376578148, 31385.215369615944, 7621.352185064332, 7852.568108754073, 8490.320774338039, 8906.510141942255, 9177.219936305355, 4468.352088947085, 4551.5676010778925, 4562.270036794097, 4563.977180114144, 4566.406367172246, 5225.16397376418, 5317.2555842181355, 5345.38528069277, 5355.86610334737, 5356.196570514454, 1056.1234175634206, 1078.827387617993, 1086.0768346905068, 1090.9890901091046, 1091.3670071358592, 689.5449003657562, 704.3907020427293, 705.6471764117941, 707.9964600176966, 710.1159056974101, 10836.295640783324, 11389.20275580718, 11469.0, 12446.377681115908, 13037.674058148432, 702.4977141343963, 705.693023534764, 708.5359089796192, 708.6988030087192, 713.5968177979425, 5302.92045619316, 5349.1976203569, 5717.313922329347, 5747.511461525817, 5861.32594751609, 113571.0, 116017.0, 116749.0, 116817.41591292158, 119873.40063299802, 99700.0, 99736.0, 100908.0, 101600.49199753955, 104616.47691761493, 84495.0, 85319.57340213383, 88833.55583222171, 89266.55366723124, 89826.55086724524, 11627.941860290644, 12126.514939402325, 12154.331511766937, 12230.266184028631, 12310.199837010565, 41750.79124604358, 42167.36748948707, 42812.78593607012, 43490.34764478599, 43512.34731479094, 71319.28680713127, 72652.63673681598, 73434.26565734274, 73552.0, 76840.0, 18148.092595369915, 18415.815841841944, 18421.263149474406, 18723.90638046801, 19716.21135154578, 21466.677999829702, 21942.0, 22058.558828823654, 22202.222922197645, 22365.552688946456, 18289.542761431072, 18516.0741962904, 18530.907345463187, 18532.536686582942, 18636.906815465834, 17788.822111778718, 18400.815991840445, 18423.907880460512, 19648.60702785965, 20096.799032009494, 17973.73039404384, 18085.819141808413, 18398.26406943707, 18440.723389149443, 18514.44456666302, 12560.74878502443, 13126.212427254395, 13258.0, 13303.334833258492, 13973.301334933416, 14073.296335183404, 14100.647483812987, 14213.573592792232, 14348.928255358656, 14380.42478300898], "value": 9342.026548454018, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1486.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 111.0, 99.0, 108.0, 108.0, 108.0, 111.0, 98.0, 110.0, 110.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 119.0, 157.0, 186.0, 118.0, 154.0, 144.0, 122.0, 168.0, 118.0], "value": 142.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 161.0, 161.0, 162.0, 160.0, 160.0, 160.0, 164.0, 168.0, 164.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 175.0, 75.0, 74.0, 79.0, 83.0, 155.0, 74.0], "value": 93.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 135.0, 79.0, 79.0, 79.0, 124.0, 80.0, 79.0, 130.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 115.0, 116.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 148.0, 148.0, 147.0, 147.0, 149.0, 149.0, 149.0], "value": 147.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 59.0, 51.0, 52.0, 52.0, 52.0, 52.0, 53.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 43.0, 43.0, 40.0, 38.0, 39.0, 38.0, 39.0, 39.0, 39.0], "value": 39.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 73.0, 74.0, 75.0, 77.0, 77.0, 75.0, 75.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 131.0, 122.0, 121.0, 122.0, 125.0, 119.0, 135.0, 124.0, 157.0], "value": 127.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 174.0, 168.0, 176.0, 171.0, 169.0, 168.0, 172.0, 167.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 64.0, 64.0, 63.0, 63.0, 63.0, 63.0, 65.0, 63.0], "value": 64.3, "unit": "score"}]}]} 03:54:23 INFO - Return code: 0 03:54:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:54:23 INFO - # TBPL SUCCESS # 03:54:23 INFO - Running post-action listener: _package_coverage_data 03:54:23 INFO - Running post-action listener: _resource_record_post_action 03:54:23 INFO - [mozharness: 2017-09-05 10:54:23.790949Z] Finished run-tests step (success) 03:54:23 INFO - Running post-run listener: _resource_record_post_run 03:54:23 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 303104; Write bytes: 392531968; Read time: 92; Write time: 236320 03:54:23 INFO - TinderboxPrint: CPU usage
13.1% 03:54:23 INFO - TinderboxPrint: I/O read bytes / time
303,104 / 92 03:54:23 INFO - TinderboxPrint: I/O write bytes / time
392,531,968 / 236,320 03:54:23 INFO - TinderboxPrint: CPU idle
8,238.3 (86.9%) 03:54:23 INFO - TinderboxPrint: CPU user
1,193.6 (12.6%) 03:54:23 INFO - TinderboxPrint: Swap in / out
0 / 0 03:54:23 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106651648; Read time: 0; Write time: 75100 03:54:23 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:54:23 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 303104; Write bytes: 285880320; Read time: 92; Write time: 161220 03:54:24 INFO - Running post-run listener: _upload_blobber_files 03:54:24 INFO - Blob upload gear active. 03:54:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:54:24 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 03:54:24 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'] 03:54:24 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 03:54:24 INFO - (blobuploader) - INFO - Open directory for files ... 03:54:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:54:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:54:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:54:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:54:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:54:25 INFO - (blobuploader) - INFO - Done attempting. 03:54:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:54:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:54:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:54:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:54:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:54:32 INFO - (blobuploader) - INFO - Done attempting. 03:54:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:54:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:54:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:54:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:54:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:54:33 INFO - (blobuploader) - INFO - Done attempting. 03:54:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:54:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:54:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:54:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:54:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:54:34 INFO - (blobuploader) - INFO - Done attempting. 03:54:34 INFO - (blobuploader) - INFO - Iteration through files over. 03:54:34 INFO - Return code: 0 03:54:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:54:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:54:34 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ace48558529cb8f06533751f16bed6facc97f9b309484ad6b79a567dedf1c6d9656eac217c34b101d33d95f57b63e5f5191a18272be7fab949cb91a810d75689", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc7da9a05c65d9661d57b9f4b32e5569a87db171d6a5ecf6969f009cd0403a7cbef2457bfae5667f95f555f0d2d60f3a18d3d6ca8a218fe9eb0e75ebab89d82c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4503817ad3b1ca0ef42b0d0fb6d2bf5739e167a66bd23da165f5646d701073b3b1faf282b10e7c64d8fd8a2f0f747cb8891f0470c22f533d68f2f21fcf8c3f91", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8132764919d883b82cc7dcce9bad5abb96af135d3900b32c9e3a16b258323031152d36f00a88b59bb91e272c6e96e1ec5ff1d4ffab43b238cd79985269bd28e8"} 03:54:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:54:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:54:34 INFO - Contents: 03:54:34 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ace48558529cb8f06533751f16bed6facc97f9b309484ad6b79a567dedf1c6d9656eac217c34b101d33d95f57b63e5f5191a18272be7fab949cb91a810d75689", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc7da9a05c65d9661d57b9f4b32e5569a87db171d6a5ecf6969f009cd0403a7cbef2457bfae5667f95f555f0d2d60f3a18d3d6ca8a218fe9eb0e75ebab89d82c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4503817ad3b1ca0ef42b0d0fb6d2bf5739e167a66bd23da165f5646d701073b3b1faf282b10e7c64d8fd8a2f0f747cb8891f0470c22f533d68f2f21fcf8c3f91", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8132764919d883b82cc7dcce9bad5abb96af135d3900b32c9e3a16b258323031152d36f00a88b59bb91e272c6e96e1ec5ff1d4ffab43b238cd79985269bd28e8"} 03:54:34 INFO - Running post-run listener: copy_logs_to_upload_dir 03:54:34 INFO - Copying logs to upload dir... 03:54:34 INFO - mkdir: /builds/slave/test/build/upload/logs 03:54:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.888536 ========= master_lag: 0.31 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-09-05 03:54:35.080481) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 03:54:35.088066) ========= 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=9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ace48558529cb8f06533751f16bed6facc97f9b309484ad6b79a567dedf1c6d9656eac217c34b101d33d95f57b63e5f5191a18272be7fab949cb91a810d75689", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc7da9a05c65d9661d57b9f4b32e5569a87db171d6a5ecf6969f009cd0403a7cbef2457bfae5667f95f555f0d2d60f3a18d3d6ca8a218fe9eb0e75ebab89d82c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4503817ad3b1ca0ef42b0d0fb6d2bf5739e167a66bd23da165f5646d701073b3b1faf282b10e7c64d8fd8a2f0f747cb8891f0470c22f533d68f2f21fcf8c3f91", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8132764919d883b82cc7dcce9bad5abb96af135d3900b32c9e3a16b258323031152d36f00a88b59bb91e272c6e96e1ec5ff1d4ffab43b238cd79985269bd28e8"} build_url:https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011331 build_url: 'https://queue.taskcluster.net/v1/task/Oz77JHY8R46lR-xvwFHp5Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ace48558529cb8f06533751f16bed6facc97f9b309484ad6b79a567dedf1c6d9656eac217c34b101d33d95f57b63e5f5191a18272be7fab949cb91a810d75689", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc7da9a05c65d9661d57b9f4b32e5569a87db171d6a5ecf6969f009cd0403a7cbef2457bfae5667f95f555f0d2d60f3a18d3d6ca8a218fe9eb0e75ebab89d82c", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4503817ad3b1ca0ef42b0d0fb6d2bf5739e167a66bd23da165f5646d701073b3b1faf282b10e7c64d8fd8a2f0f747cb8891f0470c22f533d68f2f21fcf8c3f91", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8132764919d883b82cc7dcce9bad5abb96af135d3900b32c9e3a16b258323031152d36f00a88b59bb91e272c6e96e1ec5ff1d4ffab43b238cd79985269bd28e8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 03:54:35.117317) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 03:54:35.122485) ========= 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=9f7544b6a75ada7b6b576032000001a7-1504601165.719016-506097557 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004604 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 03:54:35.176978) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 03:54:35.177325) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 03:54:35.177670) ========= ========= Total master_lag: 0.48 =========