builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-081 starttime: 1503799144.01 results: success (0) revision: 30ee0dcda8836389c0165ae3fb66a236fdfc51ea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:04.009467) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:04.009950) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:04.010233) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:04.029506) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:04.030063) ========= 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=483e410453545d07cca6935c000001ab-1503799142.311142-1854577332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-26 18:59:04-- 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% 85.8M=0s 2017-08-26 18:59:04 (85.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083762 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:04.133357) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:04.134244) ========= 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=483e410453545d07cca6935c000001ab-1503799142.311142-1854577332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015189 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:04.186478) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 43 secs) (at 2017-08-26 18:59:04.186836) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 30ee0dcda8836389c0165ae3fb66a236fdfc51ea --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 30ee0dcda8836389c0165ae3fb66a236fdfc51ea --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=483e410453545d07cca6935c000001ab-1503799142.311142-1854577332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-26 18:59:04,265 truncating revision to first 12 chars 2017-08-26 18:59:04,265 Setting DEBUG logging. 2017-08-26 18:59:04,265 attempt 1/10 2017-08-26 18:59:04,265 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/30ee0dcda883?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-26 18:59:04,768 attempt 1/10 2017-08-26 18:59:05,362 current task status: no status available at this point. state: PENDING 2017-08-26 18:59:05,362 sleeping for 9.00s (attempt 1/10) 2017-08-26 18:59:14,371 attempt 2/10 2017-08-26 18:59:14,849 current task status: no status available at this point. state: PENDING 2017-08-26 18:59:14,849 sleeping for 12.50s (attempt 2/10) 2017-08-26 18:59:27,362 attempt 3/10 2017-08-26 18:59:27,865 current task status: no status available at this point. state: PENDING 2017-08-26 18:59:27,865 sleeping for 18.75s (attempt 3/10) 2017-08-26 18:59:46,634 attempt 4/10 2017-08-26 18:59:46,983 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-26 18:59:47,198 unpacking tar archive at: mozilla-central-30ee0dcda883/testing/mozharness/ program finished with exit code 0 elapsedTime=43.309110 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 43 secs) (at 2017-08-26 18:59:47.510369) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:47.510785) ========= script_repo_revision: 30ee0dcda8836389c0165ae3fb66a236fdfc51ea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:47.511234) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:47.511497) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-26 18:59:47.522981) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-08-26 18:59:47.523302) ========= /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=483e410453545d07cca6935c000001ab-1503799142.311142-1854577332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:59:47 INFO - MultiFileLogger online at 20170826 18:59:47 in /builds/slave/test 18:59:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:59:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:59:47 INFO - {'append_to_log': False, 18:59:47 INFO - 'base_work_dir': '/builds/slave/test', 18:59:47 INFO - 'blob_upload_branch': 'Firefox', 18:59:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:59:47 INFO - 'branch': 'Firefox', 18:59:47 INFO - 'buildbot_json_path': 'buildprops.json', 18:59:47 INFO - 'code_coverage': False, 18:59:47 INFO - 'config_files': ('talos/linux_config.py',), 18:59:47 INFO - 'default_actions': ('clobber', 18:59:47 INFO - 'read-buildbot-config', 18:59:47 INFO - 'download-and-extract', 18:59:47 INFO - 'populate-webroot', 18:59:47 INFO - 'create-virtualenv', 18:59:47 INFO - 'install', 18:59:47 INFO - 'setup-mitmproxy', 18:59:47 INFO - 'run-tests'), 18:59:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:59:47 INFO - 'disable_ccov_upload': False, 18:59:47 INFO - 'download_minidump_stackwalk': True, 18:59:47 INFO - 'download_symbols': 'ondemand', 18:59:47 INFO - 'enable_stylo': False, 18:59:47 INFO - 'enable_webrender': False, 18:59:47 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:59:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:59:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:59:47 INFO - 'gecko_profile': False, 18:59:47 INFO - 'gecko_profile_interval': 0, 18:59:47 INFO - 'installer_path': 'installer.exe', 18:59:47 INFO - 'log_level': 'info', 18:59:47 INFO - 'log_name': 'talos', 18:59:47 INFO - 'log_to_console': True, 18:59:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:59:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:59:47 INFO - 'opt_config_files': (), 18:59:47 INFO - 'pip_index': False, 18:59:47 INFO - 'suite': 'dromaeojs-e10s', 18:59:47 INFO - 'system_bits': '32', 18:59:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:59:47 INFO - 'title': 'talos-linux64-ix-081', 18:59:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:59:47 INFO - 'use_talos_json': True, 18:59:47 INFO - 'verify': 'False', 18:59:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:59:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:59:47 INFO - 'work_dir': 'build'} 18:59:47 INFO - [mozharness: 2017-08-27 01:59:47.740497Z] Running clobber step. 18:59:47 INFO - Running pre-action listener: _resource_record_pre_action 18:59:47 INFO - Running main action method: clobber 18:59:47 INFO - rmtree: /builds/slave/test/build 18:59:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:59:48 INFO - Running post-action listener: _resource_record_post_action 18:59:48 INFO - [mozharness: 2017-08-27 01:59:48.757624Z] Finished clobber step (success) 18:59:48 INFO - [mozharness: 2017-08-27 01:59:48.757722Z] Running read-buildbot-config step. 18:59:48 INFO - Running pre-action listener: _resource_record_pre_action 18:59:48 INFO - Running main action method: read_buildbot_config 18:59:48 INFO - Using buildbot properties: 18:59:48 INFO - { 18:59:48 INFO - "project": "", 18:59:48 INFO - "product": "firefox", 18:59:48 INFO - "who": "philringnalda@gmail.com", 18:59:48 INFO - "installer_path": "public/build/target.tar.bz2", 18:59:48 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:59:48 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:59:48 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:59:48 INFO - "stage_platform": "linux64-stylosequential", 18:59:48 INFO - "basedir": "/builds/slave/test", 18:59:48 INFO - "buildnumber": 211, 18:59:48 INFO - "platform": "ubuntu64_hw_styloseq", 18:59:48 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:59:48 INFO - "slavebuilddir": "test", 18:59:48 INFO - "taskId": "YWI8LE-ZQLCcKkN6YSCxMg", 18:59:48 INFO - "branch": "mozilla-central", 18:59:48 INFO - "script_repo_revision": "production", 18:59:48 INFO - "revision": "30ee0dcda8836389c0165ae3fb66a236fdfc51ea", 18:59:48 INFO - "slavename": "talos-linux64-ix-081", 18:59:48 INFO - "repo_path": "mozilla-central" 18:59:48 INFO - } 18:59:48 INFO - Finding installer, test and symbols from parent task. 18:59:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YWI8LE-ZQLCcKkN6YSCxMg'}, attempt #1 18:59:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YWI8LE-ZQLCcKkN6YSCxMg'}, attempt #1 18:59:49 INFO - Task dependencies: W6jm1G_FQuiUetQk6AdeiA 18:59:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA'}, attempt #1 18:59:50 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.tar.bz2 18:59:50 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.test_packages.json 18:59:50 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.crashreporter-symbols.zip 18:59:50 INFO - Running post-action listener: _resource_record_post_action 18:59:50 INFO - [mozharness: 2017-08-27 01:59:50.388060Z] Finished read-buildbot-config step (success) 18:59:50 INFO - [mozharness: 2017-08-27 01:59:50.388277Z] Running download-and-extract step. 18:59:50 INFO - Running pre-action listener: _resource_record_pre_action 18:59:50 INFO - Running main action method: download_and_extract 18:59:50 INFO - mkdir: /builds/slave/test/build/tests 18:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:59:50 INFO - trying https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.test_packages.json 18:59:50 INFO - Downloading https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:59:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:59:50 INFO - Downloaded 989 bytes. 18:59:50 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:59:50 INFO - Using the following test package requirements: 18:59:50 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:59:50 INFO - u'common': [u'target.common.tests.zip'], 18:59:50 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:59:50 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:59:50 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:59:50 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:59:50 INFO - u'mozbase': [u'target.common.tests.zip'], 18:59:50 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:59:50 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:59:50 INFO - u'web-platform': [u'target.common.tests.zip', 18:59:50 INFO - u'target.web-platform.tests.tar.gz'], 18:59:50 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:59:50 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:59:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.common.tests.zip 18:59:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:59:50 INFO - Fetch https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.common.tests.zip into memory 18:59:52 INFO - Content-Length response header: 54977204 18:59:52 INFO - Bytes received: 54977204 19:00:03 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:00:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.common.tests.zip 19:00:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:00:03 INFO - Fetch https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.common.tests.zip into memory 19:00:04 INFO - Content-Length response header: 54977204 19:00:04 INFO - Bytes received: 54977204 19:00:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.talos.tests.zip 19:00:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:00:16 INFO - Fetch https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.talos.tests.zip into memory 19:00:18 INFO - Content-Length response header: 14496611 19:00:18 INFO - Bytes received: 14496611 19:00:18 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')]} 19:00:18 INFO - trying https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.tar.bz2 19:00:18 INFO - Downloading https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:00:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:00:20 INFO - Downloaded 61072430 bytes. 19:00:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.tar.bz2 19:00:20 INFO - mkdir: /builds/slave/test/properties 19:00:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:00:20 INFO - Writing to file /builds/slave/test/properties/build_url 19:00:20 INFO - Contents: 19:00:20 INFO - build_url:https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.tar.bz2 19:00:20 INFO - Running post-action listener: _resource_record_post_action 19:00:20 INFO - Running post-action listener: find_tests_for_verification 19:00:20 INFO - Running post-action listener: set_extra_try_arguments 19:00:20 INFO - [mozharness: 2017-08-27 02:00:20.063140Z] Finished download-and-extract step (success) 19:00:20 INFO - [mozharness: 2017-08-27 02:00:20.063235Z] Running populate-webroot step. 19:00:20 INFO - Running pre-action listener: _resource_record_pre_action 19:00:20 INFO - Running main action method: populate_webroot 19:00:20 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:00:20 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 19:00:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:00:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:00:20 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 19:00:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:00:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:00:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:20 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:00:20 INFO - 'talos_options': ['--stylo'], 19:00:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:00:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:00:20 INFO - 'tests': ['damp', 'tps']}, 19:00:20 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:00:20 INFO - 'talos_options': ['--stylo'], 19:00:20 INFO - 'tests': ['damp', 'tps']}, 19:00:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:00:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:00:20 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 19:00:20 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:00:20 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:00:20 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:00:20 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:00:20 INFO - 'talos_options': ['--stylo'], 19:00:20 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:00:20 INFO - 'other-e10s': {'tests': ['a11yr', 19:00:20 INFO - 'ts_paint', 19:00:20 INFO - 'tpaint', 19:00:20 INFO - 'sessionrestore', 19:00:20 INFO - 'sessionrestore_many_windows', 19:00:20 INFO - 'sessionrestore_no_auto_restore', 19:00:20 INFO - 'tabpaint']}, 19:00:20 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 19:00:20 INFO - 'tests': ['a11yr', 19:00:20 INFO - 'ts_paint', 19:00:20 INFO - 'tpaint', 19:00:20 INFO - 'sessionrestore', 19:00:20 INFO - 'sessionrestore_many_windows', 19:00:20 INFO - 'sessionrestore_no_auto_restore', 19:00:20 INFO - 'tabpaint']}, 19:00:20 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 19:00:20 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 19:00:20 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 19:00:20 INFO - 'tests': ['bloom_basic_singleton']}, 19:00:20 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 19:00:20 INFO - 'tests': ['bloom_basic']}, 19:00:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:00:20 INFO - 'tsvgr_opacity', 19:00:20 INFO - 'tart', 19:00:20 INFO - 'tscrollx', 19:00:20 INFO - 'tsvg_static']}, 19:00:20 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 19:00:20 INFO - 'tests': ['tsvgx', 19:00:20 INFO - 'tsvgr_opacity', 19:00:20 INFO - 'tart', 19:00:20 INFO - 'tscrollx', 19:00:20 INFO - 'tsvg_static']}, 19:00:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:00:20 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:00:20 INFO - 'talos_options': ['--stylo'], 19:00:20 INFO - 'tests': ['tp5o']}, 19:00:20 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:00:20 INFO - 'talos_options': ['--mitmproxy', 19:00:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:00:20 INFO - '--firstNonBlankPaint'], 19:00:20 INFO - 'tests': ['tp6_google', 19:00:20 INFO - 'tp6_youtube', 19:00:20 INFO - 'tp6_amazon', 19:00:20 INFO - 'tp6_facebook']}, 19:00:20 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:00:20 INFO - 'talos_options': ['--stylo', 19:00:20 INFO - '--mitmproxy', 19:00:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:00:20 INFO - '--firstNonBlankPaint'], 19:00:20 INFO - 'tests': ['tp6_google', 19:00:20 INFO - 'tp6_youtube', 19:00:20 INFO - 'tp6_amazon', 19:00:20 INFO - 'tp6_facebook']}, 19:00:20 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:00:20 INFO - 'talos_options': ['--stylo', 19:00:20 INFO - '--stylo-threads=1', 19:00:20 INFO - '--mitmproxy', 19:00:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:00:20 INFO - '--firstNonBlankPaint'], 19:00:20 INFO - 'tests': ['tp6_google', 19:00:20 INFO - 'tp6_youtube', 19:00:20 INFO - 'tp6_amazon', 19:00:20 INFO - 'tp6_facebook']}, 19:00:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:00:20 INFO - 'talos_options': ['--xperf_path', 19:00:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:20 INFO - 'tests': ['tp5n']}, 19:00:20 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:00:20 INFO - 'talos_options': ['--stylo', 19:00:20 INFO - '--xperf_path', 19:00:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:00:20 INFO - 'tests': ['tp5n']}}} 19:00:20 INFO - Running post-action listener: _resource_record_post_action 19:00:20 INFO - [mozharness: 2017-08-27 02:00:20.077483Z] Finished populate-webroot step (success) 19:00:20 INFO - [mozharness: 2017-08-27 02:00:20.077572Z] Running create-virtualenv step. 19:00:20 INFO - Running pre-action listener: _resource_record_pre_action 19:00:20 INFO - Running main action method: create_virtualenv 19:00:20 INFO - Creating virtualenv /builds/slave/test/build/venv 19:00:20 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:00:20 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 19:00:20 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:00:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:00:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:00:20 INFO - Installing distribute.............................................................................................................................................................................................done. 19:00:20 INFO - Installing pip...............done. 19:00:20 INFO - Return code: 0 19:00:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:00:21 INFO - Reading from file tmpfile_stdout 19:00:21 INFO - Output received: 19:00:21 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 19:00:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:00: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')]} 19:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00: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')]} 19:00:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd3130>, '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 0x1bd4030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd44c0>, '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': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', '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 19:00: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', 'pip>=1.5'] in /builds/slave/test/build 19:00: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 pip>=1.5 19:00:21 INFO - Using env: {'DISPLAY': ':0', 19:00:21 INFO - 'HOME': '/home/cltbld', 19:00:21 INFO - 'LANG': 'en_US.UTF-8', 19:00:21 INFO - 'LANGUAGE': 'en_US:en', 19:00:21 INFO - 'LOGNAME': 'cltbld', 19:00:21 INFO - 'MAIL': '/var/mail/cltbld', 19:00:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:21 INFO - 'MOZ_NO_REMOTE': '1', 19:00:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:21 INFO - 'NO_EM_RESTART': '1', 19:00:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:21 INFO - 'PWD': '/builds/slave/test', 19:00:21 INFO - 'SHELL': '/bin/bash', 19:00:21 INFO - 'SHLVL': '1', 19:00:21 INFO - 'TERM': 'linux', 19:00:21 INFO - 'TMOUT': '86400', 19:00:21 INFO - 'USER': 'cltbld', 19:00:21 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:00:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:21 INFO - '_': '/tools/buildbot/bin/python'} 19:00:22 INFO - Ignoring indexes: http://pypi.python.org/simple/ 19:00:22 INFO - Downloading/unpacking pip>=1.5 19:00:22 INFO - Running setup.py egg_info for package pip 19:00:22 INFO - warning: no files found matching 'pip/cacert.pem' 19:00:22 INFO - warning: no files found matching '*.html' under directory 'docs' 19:00:22 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:00:22 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:00:22 INFO - Installing collected packages: pip 19:00:22 INFO - Found existing installation: pip 1.1 19:00:22 INFO - Uninstalling pip: 19:00:22 INFO - Successfully uninstalled pip 19:00:22 INFO - Running setup.py install for pip 19:00:22 INFO - warning: no files found matching 'pip/cacert.pem' 19:00:22 INFO - warning: no files found matching '*.html' under directory 'docs' 19:00:22 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:00:22 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:00:22 INFO - Installing pip script to /builds/slave/test/build/venv/bin 19:00:22 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 19:00:22 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 19:00:22 INFO - Successfully installed pip 19:00:22 INFO - Cleaning up... 19:00:22 INFO - Return code: 0 19:00:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:00: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')]} 19:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00: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')]} 19:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00: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', '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 0x18671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd3130>, '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 0x1bd4030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd44c0>, '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': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', '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 19:00: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', 'psutil>=3.1.1'] in /builds/slave/test/build 19:00: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 psutil>=3.1.1 19:00:22 INFO - Using env: {'DISPLAY': ':0', 19:00:22 INFO - 'HOME': '/home/cltbld', 19:00:22 INFO - 'LANG': 'en_US.UTF-8', 19:00:22 INFO - 'LANGUAGE': 'en_US:en', 19:00:22 INFO - 'LOGNAME': 'cltbld', 19:00:22 INFO - 'MAIL': '/var/mail/cltbld', 19:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:22 INFO - 'MOZ_NO_REMOTE': '1', 19:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:22 INFO - 'NO_EM_RESTART': '1', 19:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:22 INFO - 'PWD': '/builds/slave/test', 19:00:22 INFO - 'SHELL': '/bin/bash', 19:00:22 INFO - 'SHLVL': '1', 19:00:22 INFO - 'TERM': 'linux', 19:00:22 INFO - 'TMOUT': '86400', 19:00:22 INFO - 'USER': 'cltbld', 19:00:22 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:22 INFO - '_': '/tools/buildbot/bin/python'} 19:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:22 INFO - Downloading/unpacking psutil>=3.1.1 19:00: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 19:00: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 19:00: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 19:00: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 19:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:00:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:00:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:00:23 INFO - Installing collected packages: psutil 19:00:23 INFO - Running setup.py install for psutil 19:00:23 INFO - building 'psutil._psutil_linux' extension 19:00:23 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 19:00:23 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 19:00:23 INFO - building 'psutil._psutil_posix' extension 19:00:23 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 19:00:24 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 19:00:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:00:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:00:24 INFO - Successfully installed psutil 19:00:24 INFO - Cleaning up... 19:00:24 INFO - Return code: 0 19:00:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:00:24 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')]} 19:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:24 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')]} 19:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:24 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 0x18671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd3130>, '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 0x1bd4030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd44c0>, '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': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', '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 19:00:24 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 19:00:24 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 19:00:24 INFO - Using env: {'DISPLAY': ':0', 19:00:24 INFO - 'HOME': '/home/cltbld', 19:00:24 INFO - 'LANG': 'en_US.UTF-8', 19:00:24 INFO - 'LANGUAGE': 'en_US:en', 19:00:24 INFO - 'LOGNAME': 'cltbld', 19:00:24 INFO - 'MAIL': '/var/mail/cltbld', 19:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:24 INFO - 'MOZ_NO_REMOTE': '1', 19:00:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:24 INFO - 'NO_EM_RESTART': '1', 19:00:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:24 INFO - 'PWD': '/builds/slave/test', 19:00:24 INFO - 'SHELL': '/bin/bash', 19:00:24 INFO - 'SHLVL': '1', 19:00:24 INFO - 'TERM': 'linux', 19:00:24 INFO - 'TMOUT': '86400', 19:00:24 INFO - 'USER': 'cltbld', 19:00:24 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:24 INFO - '_': '/tools/buildbot/bin/python'} 19:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:00: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 19:00: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 19:00: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 19:00: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 19:00:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:00:25 INFO - Installing collected packages: mozsystemmonitor 19:00:25 INFO - Running setup.py install for mozsystemmonitor 19:00:25 INFO - Successfully installed mozsystemmonitor 19:00:25 INFO - Cleaning up... 19:00:25 INFO - Return code: 0 19:00:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:00: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')]} 19:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00: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')]} 19:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:25 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 0x18671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd3130>, '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 0x1bd4030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd44c0>, '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': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', '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 19:00:25 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 19:00:25 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 19:00:25 INFO - Using env: {'DISPLAY': ':0', 19:00:25 INFO - 'HOME': '/home/cltbld', 19:00:25 INFO - 'LANG': 'en_US.UTF-8', 19:00:25 INFO - 'LANGUAGE': 'en_US:en', 19:00:25 INFO - 'LOGNAME': 'cltbld', 19:00:25 INFO - 'MAIL': '/var/mail/cltbld', 19:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:25 INFO - 'MOZ_NO_REMOTE': '1', 19:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:25 INFO - 'NO_EM_RESTART': '1', 19:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:25 INFO - 'PWD': '/builds/slave/test', 19:00:25 INFO - 'SHELL': '/bin/bash', 19:00:25 INFO - 'SHLVL': '1', 19:00:25 INFO - 'TERM': 'linux', 19:00:25 INFO - 'TMOUT': '86400', 19:00:25 INFO - 'USER': 'cltbld', 19:00:25 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:25 INFO - '_': '/tools/buildbot/bin/python'} 19:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:25 INFO - Downloading/unpacking jsonschema==2.5.1 19:00:25 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 19:00:25 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 19:00:25 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 19:00:25 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 19:00:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:00:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:00:27 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 19:00:27 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 19:00:27 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 19:00:27 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 19:00:27 INFO - Downloading functools32-3.2.3-2.tar.gz 19:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:00:27 INFO - warning: no files found matching '*.txt' 19:00:27 INFO - no previously-included directories found matching 'build' 19:00:27 INFO - no previously-included directories found matching 'dist' 19:00:27 INFO - no previously-included directories found matching '.git*' 19:00:27 INFO - Installing collected packages: jsonschema, functools32 19:00:27 INFO - Running setup.py install for functools32 19:00:27 INFO - warning: no files found matching '*.txt' 19:00:27 INFO - no previously-included directories found matching 'build' 19:00:27 INFO - no previously-included directories found matching 'dist' 19:00:27 INFO - no previously-included directories found matching '.git*' 19:00:27 INFO - Successfully installed jsonschema functools32 19:00:27 INFO - Cleaning up... 19:00:27 INFO - Return code: 0 19:00:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:00:27 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')]} 19:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:27 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')]} 19:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:27 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 0x18671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd3130>, '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 0x1bd4030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd44c0>, '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': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', '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 19:00:27 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 19:00:27 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 19:00:27 INFO - Using env: {'DISPLAY': ':0', 19:00:27 INFO - 'HOME': '/home/cltbld', 19:00:27 INFO - 'LANG': 'en_US.UTF-8', 19:00:27 INFO - 'LANGUAGE': 'en_US:en', 19:00:27 INFO - 'LOGNAME': 'cltbld', 19:00:27 INFO - 'MAIL': '/var/mail/cltbld', 19:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:27 INFO - 'MOZ_NO_REMOTE': '1', 19:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:27 INFO - 'NO_EM_RESTART': '1', 19:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:27 INFO - 'PWD': '/builds/slave/test', 19:00:27 INFO - 'SHELL': '/bin/bash', 19:00:27 INFO - 'SHLVL': '1', 19:00:27 INFO - 'TERM': 'linux', 19:00:27 INFO - 'TMOUT': '86400', 19:00:27 INFO - 'USER': 'cltbld', 19:00:27 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:27 INFO - '_': '/tools/buildbot/bin/python'} 19:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:00:27 INFO - Cleaning up... 19:00:27 INFO - Return code: 0 19:00:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:00:27 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')]} 19:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:27 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')]} 19:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:27 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 0x18671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd3130>, '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 0x1bd4030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd44c0>, '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': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', '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 19:00:27 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 19:00:27 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 19:00:27 INFO - Using env: {'DISPLAY': ':0', 19:00:27 INFO - 'HOME': '/home/cltbld', 19:00:27 INFO - 'LANG': 'en_US.UTF-8', 19:00:27 INFO - 'LANGUAGE': 'en_US:en', 19:00:27 INFO - 'LOGNAME': 'cltbld', 19:00:27 INFO - 'MAIL': '/var/mail/cltbld', 19:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:27 INFO - 'MOZ_NO_REMOTE': '1', 19:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:27 INFO - 'NO_EM_RESTART': '1', 19:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:27 INFO - 'PWD': '/builds/slave/test', 19:00:27 INFO - 'SHELL': '/bin/bash', 19:00:27 INFO - 'SHLVL': '1', 19:00:27 INFO - 'TERM': 'linux', 19:00:27 INFO - 'TMOUT': '86400', 19:00:27 INFO - 'USER': 'cltbld', 19:00:27 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:27 INFO - '_': '/tools/buildbot/bin/python'} 19:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:27 INFO - Downloading/unpacking blobuploader==1.2.4 19:00:27 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 19:00:27 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 19:00:27 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 19:00:27 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 19:00:28 INFO - Downloading blobuploader-1.2.4.tar.gz 19:00:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:00:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:00:29 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 19:00:29 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 19:00:29 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 19:00:29 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 19:00:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:00:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:00:29 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 19:00:29 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 19:00:29 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 19:00:29 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 19:00:29 INFO - Downloading docopt-0.6.1.tar.gz 19:00:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:00:29 INFO - Installing collected packages: blobuploader, requests, docopt 19:00:29 INFO - Running setup.py install for blobuploader 19:00:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:00:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:00:29 INFO - Running setup.py install for requests 19:00:30 INFO - Running setup.py install for docopt 19:00:30 INFO - Successfully installed blobuploader requests docopt 19:00:30 INFO - Cleaning up... 19:00:30 INFO - Return code: 0 19:00:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:30 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')]} 19:00:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:30 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')]} 19:00:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:30 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 0x18671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd3130>, '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 0x1bd4030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd44c0>, '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': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', '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 19:00:30 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 19:00:30 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 19:00:30 INFO - Using env: {'DISPLAY': ':0', 19:00:30 INFO - 'HOME': '/home/cltbld', 19:00:30 INFO - 'LANG': 'en_US.UTF-8', 19:00:30 INFO - 'LANGUAGE': 'en_US:en', 19:00:30 INFO - 'LOGNAME': 'cltbld', 19:00:30 INFO - 'MAIL': '/var/mail/cltbld', 19:00:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:30 INFO - 'MOZ_NO_REMOTE': '1', 19:00:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:30 INFO - 'NO_EM_RESTART': '1', 19:00:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:30 INFO - 'PWD': '/builds/slave/test', 19:00:30 INFO - 'SHELL': '/bin/bash', 19:00:30 INFO - 'SHLVL': '1', 19:00:30 INFO - 'TERM': 'linux', 19:00:30 INFO - 'TMOUT': '86400', 19:00:30 INFO - 'USER': 'cltbld', 19:00:30 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:00:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:30 INFO - '_': '/tools/buildbot/bin/python'} 19:00:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:30 INFO - Running setup.py (path:/tmp/pip-umTlB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:30 INFO - Running setup.py (path:/tmp/pip-j1_hSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:30 INFO - Running setup.py (path:/tmp/pip-08PuaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:30 INFO - Running setup.py (path:/tmp/pip-MOuCIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:30 INFO - Running setup.py (path:/tmp/pip-U73ByN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:30 INFO - Running setup.py (path:/tmp/pip-qLucp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:30 INFO - Running setup.py (path:/tmp/pip-ocU7bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:30 INFO - Running setup.py (path:/tmp/pip-aF_K4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:30 INFO - Running setup.py (path:/tmp/pip-IF7lCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:31 INFO - Running setup.py (path:/tmp/pip-rbSv1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:31 INFO - Running setup.py (path:/tmp/pip-laWiN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:31 INFO - Running setup.py (path:/tmp/pip-SE52In-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:31 INFO - Running setup.py (path:/tmp/pip-I7gYx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:31 INFO - Running setup.py (path:/tmp/pip-BN7zEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:31 INFO - Running setup.py (path:/tmp/pip-BHeOpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:31 INFO - Running setup.py (path:/tmp/pip-p8RXWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:31 INFO - Running setup.py (path:/tmp/pip-EcHtLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:00:31 INFO - Running setup.py install for manifestparser 19:00:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:00:31 INFO - Running setup.py install for mozcrash 19:00:31 INFO - Running setup.py install for mozdebug 19:00:31 INFO - Running setup.py install for mozdevice 19:00:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Running setup.py install for mozfile 19:00:32 INFO - Running setup.py install for mozhttpd 19:00:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Running setup.py install for mozinfo 19:00:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Running setup.py install for mozInstall 19:00:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Running setup.py install for mozleak 19:00:32 INFO - Running setup.py install for mozlog 19:00:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Running setup.py install for moznetwork 19:00:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Running setup.py install for mozprocess 19:00:32 INFO - Running setup.py install for mozprofile 19:00:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:00:32 INFO - Running setup.py install for mozrunner 19:00:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:00:33 INFO - Running setup.py install for mozscreenshot 19:00:33 INFO - Running setup.py install for moztest 19:00:33 INFO - Running setup.py install for mozversion 19:00:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:00:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:00:33 INFO - Cleaning up... 19:00:33 INFO - Return code: 0 19:00:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:33 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')]} 19:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:33 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')]} 19:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:33 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 0x18671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd3130>, '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 0x1bd4030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd44c0>, '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': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', '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 19:00:33 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 19:00:33 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 19:00:33 INFO - Using env: {'DISPLAY': ':0', 19:00:33 INFO - 'HOME': '/home/cltbld', 19:00:33 INFO - 'LANG': 'en_US.UTF-8', 19:00:33 INFO - 'LANGUAGE': 'en_US:en', 19:00:33 INFO - 'LOGNAME': 'cltbld', 19:00:33 INFO - 'MAIL': '/var/mail/cltbld', 19:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:33 INFO - 'MOZ_NO_REMOTE': '1', 19:00:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:33 INFO - 'NO_EM_RESTART': '1', 19:00:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:33 INFO - 'PWD': '/builds/slave/test', 19:00:33 INFO - 'SHELL': '/bin/bash', 19:00:33 INFO - 'SHLVL': '1', 19:00:33 INFO - 'TERM': 'linux', 19:00:33 INFO - 'TMOUT': '86400', 19:00:33 INFO - 'USER': 'cltbld', 19:00:33 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:33 INFO - '_': '/tools/buildbot/bin/python'} 19:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:00:33 INFO - Running setup.py (path:/tmp/pip-hxir1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:00:33 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)) 19:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:00:33 INFO - Running setup.py (path:/tmp/pip-LtRTgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:00:33 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)) 19:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:00:33 INFO - Running setup.py (path:/tmp/pip-MstYvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:00:33 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)) 19:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:00:33 INFO - Running setup.py (path:/tmp/pip-ZBBF0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:00:33 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)) 19:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:00:33 INFO - Running setup.py (path:/tmp/pip-XWCH_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:00:33 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)) 19:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:00:34 INFO - Running setup.py (path:/tmp/pip-1Uc_U2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:00:34 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)) 19:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:00:34 INFO - Running setup.py (path:/tmp/pip-Tb_ir0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:00:34 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)) 19:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:00:34 INFO - Running setup.py (path:/tmp/pip-pZqIru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:00:34 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)) 19:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:00:34 INFO - Running setup.py (path:/tmp/pip-STsKNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:00:34 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)) 19:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:00:34 INFO - Running setup.py (path:/tmp/pip-oA1HV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:00:34 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)) 19:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:00:34 INFO - Running setup.py (path:/tmp/pip-IOynwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:00:34 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)) 19:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:00:34 INFO - Running setup.py (path:/tmp/pip-v5mEzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:00:34 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)) 19:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:00:34 INFO - Running setup.py (path:/tmp/pip-GfTTE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:00:34 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)) 19:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:00:34 INFO - Running setup.py (path:/tmp/pip-Bb_Jof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:00:34 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)) 19:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:34 INFO - Running setup.py (path:/tmp/pip-OZU4Kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:00:34 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)) 19:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:00:34 INFO - Running setup.py (path:/tmp/pip-Iy2bZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:00:34 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)) 19:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:00:34 INFO - Running setup.py (path:/tmp/pip-Wga_qx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:00:34 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)) 19:00:34 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)) 19:00:34 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)) 19:00:34 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)) 19:00:34 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)) 19:00:34 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)) 19:00:34 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)) 19:00:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:00:34 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 19:00:34 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 19:00:34 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 19:00:34 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 19:00:35 INFO - Downloading blessings-1.6.tar.gz 19:00:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:00:36 INFO - Installing collected packages: blessings 19:00:36 INFO - Running setup.py install for blessings 19:00:36 INFO - Successfully installed blessings 19:00:36 INFO - Cleaning up... 19:00:36 INFO - Return code: 0 19:00:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:00:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:36 INFO - Reading from file tmpfile_stdout 19:00:36 INFO - Current package versions: 19:00:36 INFO - argparse == 1.2.1 19:00:36 INFO - blessings == 1.6 19:00:36 INFO - blobuploader == 1.2.4 19:00:36 INFO - docopt == 0.6.1 19:00:36 INFO - functools32 == 3.2.3-2 19:00:36 INFO - jsonschema == 2.5.1 19:00:36 INFO - manifestparser == 1.1 19:00:36 INFO - mozInstall == 1.13 19:00:36 INFO - mozcrash == 1.0 19:00:36 INFO - mozdebug == 0.1 19:00:36 INFO - mozdevice == 0.51 19:00:36 INFO - mozfile == 1.2 19:00:36 INFO - mozhttpd == 0.7 19:00:36 INFO - mozinfo == 0.10 19:00:36 INFO - mozleak == 0.1 19:00:36 INFO - mozlog == 3.5 19:00:36 INFO - moznetwork == 0.27 19:00:36 INFO - mozprocess == 0.25 19:00:36 INFO - mozprofile == 0.28 19:00:36 INFO - mozrunner == 6.13 19:00:36 INFO - mozscreenshot == 0.1 19:00:36 INFO - mozsystemmonitor == 0.3 19:00:36 INFO - moztest == 0.8 19:00:36 INFO - mozversion == 1.4 19:00:36 INFO - psutil == 3.1.1 19:00:36 INFO - requests == 1.2.3 19:00:36 INFO - wsgiref == 0.1.2 19:00:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:36 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')]} 19:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:36 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')]} 19:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:36 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 0x18671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd3130>, '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 0x1bd4030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd44c0>, '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': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', '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 19:00:36 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 19:00:36 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 19:00:36 INFO - Using env: {'DISPLAY': ':0', 19:00:36 INFO - 'HOME': '/home/cltbld', 19:00:36 INFO - 'LANG': 'en_US.UTF-8', 19:00:36 INFO - 'LANGUAGE': 'en_US:en', 19:00:36 INFO - 'LOGNAME': 'cltbld', 19:00:36 INFO - 'MAIL': '/var/mail/cltbld', 19:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:36 INFO - 'MOZ_NO_REMOTE': '1', 19:00:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:36 INFO - 'NO_EM_RESTART': '1', 19:00:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:36 INFO - 'PWD': '/builds/slave/test', 19:00:36 INFO - 'SHELL': '/bin/bash', 19:00:36 INFO - 'SHLVL': '1', 19:00:36 INFO - 'TERM': 'linux', 19:00:36 INFO - 'TMOUT': '86400', 19:00:36 INFO - 'USER': 'cltbld', 19:00:36 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:36 INFO - '_': '/tools/buildbot/bin/python'} 19:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:36 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)) 19:00:36 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)) 19:00:36 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)) 19:00:36 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)) 19:00:36 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)) 19:00:36 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)) 19:00:36 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)) 19:00:36 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)) 19:00:36 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)) 19:00:36 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:00:36 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 19:00:36 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 19:00:36 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 19:00:36 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 19:00:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 19:00:37 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)) 19:00:37 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)) 19:00:37 INFO - Installing collected packages: simplejson 19:00:37 INFO - Running setup.py install for simplejson 19:00:37 INFO - building 'simplejson._speedups' extension 19:00:37 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 19:00:38 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 19:00:38 INFO - Successfully installed simplejson 19:00:38 INFO - Cleaning up... 19:00:38 INFO - Return code: 0 19:00:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:38 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 0x18671f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a32a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd3130>, '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 0x1bd4030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd44c0>, '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': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', '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 19:00:38 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 19:00:38 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 19:00:38 INFO - Using env: {'DISPLAY': ':0', 19:00:38 INFO - 'HOME': '/home/cltbld', 19:00:38 INFO - 'LANG': 'en_US.UTF-8', 19:00:38 INFO - 'LANGUAGE': 'en_US:en', 19:00:38 INFO - 'LOGNAME': 'cltbld', 19:00:38 INFO - 'MAIL': '/var/mail/cltbld', 19:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:38 INFO - 'MOZ_NO_REMOTE': '1', 19:00:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:38 INFO - 'NO_EM_RESTART': '1', 19:00:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:38 INFO - 'PWD': '/builds/slave/test', 19:00:38 INFO - 'SHELL': '/bin/bash', 19:00:38 INFO - 'SHLVL': '1', 19:00:38 INFO - 'TERM': 'linux', 19:00:38 INFO - 'TMOUT': '86400', 19:00:38 INFO - 'USER': 'cltbld', 19:00:38 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:38 INFO - '_': '/tools/buildbot/bin/python'} 19:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:00:39 INFO - Cleaning up... 19:00:39 INFO - Return code: 0 19:00:39 INFO - Running post-action listener: _resource_record_post_action 19:00:39 INFO - Running post-action listener: _start_resource_monitoring 19:00:39 INFO - Starting resource monitoring. 19:00:39 INFO - [mozharness: 2017-08-27 02:00:39.085403Z] Finished create-virtualenv step (success) 19:00:39 INFO - [mozharness: 2017-08-27 02:00:39.085929Z] Running install step. 19:00:39 INFO - Running pre-action listener: _resource_record_pre_action 19:00:39 INFO - Running main action method: install 19:00:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:39 INFO - Reading from file tmpfile_stdout 19:00:39 INFO - Detecting whether we're running mozinstall >=1.0... 19:00:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:00:39 INFO - Reading from file tmpfile_stdout 19:00:39 INFO - Output received: 19:00:39 INFO - Usage: mozinstall [options] installer 19:00:39 INFO - Options: 19:00:39 INFO - -h, --help show this help message and exit 19:00:39 INFO - -d DEST, --destination=DEST 19:00:39 INFO - Directory to install application into. [default: 19:00:39 INFO - "/builds/slave/test"] 19:00:39 INFO - --app=APP Application being installed. [default: firefox] 19:00:39 INFO - mkdir: /builds/slave/test/build/application 19:00:39 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'] 19:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:00:57 INFO - Reading from file tmpfile_stdout 19:00:57 INFO - Output received: 19:00:57 INFO - /builds/slave/test/build/application/firefox/firefox 19:00:57 INFO - Running post-action listener: _resource_record_post_action 19:00:57 INFO - [mozharness: 2017-08-27 02:00:57.150141Z] Finished install step (success) 19:00:57 INFO - [mozharness: 2017-08-27 02:00:57.150393Z] Running setup-mitmproxy step. 19:00:57 INFO - Running pre-action listener: _resource_record_pre_action 19:00:57 INFO - Running main action method: setup_mitmproxy 19:00:57 INFO - Skipping: mitmproxy is not required 19:00:57 INFO - Running post-action listener: _resource_record_post_action 19:00:57 INFO - [mozharness: 2017-08-27 02:00:57.151534Z] Finished setup-mitmproxy step (success) 19:00:57 INFO - [mozharness: 2017-08-27 02:00:57.151755Z] Running run-tests step. 19:00:57 INFO - Running pre-action listener: _resource_record_pre_action 19:00:57 INFO - Running pre-action listener: _set_gcov_prefix 19:00:57 INFO - Running main action method: run_tests 19:00:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:00:57 INFO - Python 2.7.3 19:00:57 INFO - Return code: 0 19:00:57 INFO - grabbing minidump binary from tooltool 19:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:00:57 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 0x1bd3130>, '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 0x1bd4030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd44c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:00:57 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 19:00:57 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 19:00:57 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 19:00:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:00:57 INFO - Return code: 0 19:00:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:00:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:00:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:00:57 INFO - ENV: RUST_BACKTRACE is now full 19:00:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:00:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:00:57 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/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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 19:00:57 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/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-081 --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 19:00:57 INFO - Using env: {'DISPLAY': ':0', 19:00:57 INFO - 'HOME': '/home/cltbld', 19:00:57 INFO - 'LANG': 'en_US.UTF-8', 19:00:57 INFO - 'LANGUAGE': 'en_US:en', 19:00:57 INFO - 'LOGNAME': 'cltbld', 19:00:57 INFO - 'MAIL': '/var/mail/cltbld', 19:00:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:00:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:57 INFO - 'MOZ_NO_REMOTE': '1', 19:00:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:57 INFO - 'NO_EM_RESTART': '1', 19:00:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:57 INFO - 'PWD': '/builds/slave/test', 19:00:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:00:57 INFO - 'RUST_BACKTRACE': 'full', 19:00:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:00:57 INFO - 'SHELL': '/bin/bash', 19:00:57 INFO - 'SHLVL': '1', 19:00:57 INFO - 'STYLO_FORCE_ENABLED': '1', 19:00:57 INFO - 'TERM': 'linux', 19:00:57 INFO - 'TMOUT': '86400', 19:00:57 INFO - 'USER': 'cltbld', 19:00:57 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:57 INFO - '_': '/tools/buildbot/bin/python'} 19:00:57 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/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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 19:00:57 INFO - mozversion application_buildid: 20170827011326 19:00:57 INFO - mozversion application_changeset: 30ee0dcda8836389c0165ae3fb66a236fdfc51ea 19:00:57 INFO - mozversion application_display_name: Nightly 19:00:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:00:57 INFO - mozversion application_name: Firefox 19:00:57 INFO - mozversion application_remotingname: firefox 19:00:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:00:57 INFO - mozversion application_vendor: Mozilla 19:00:57 INFO - mozversion application_version: 57.0a1 19:00:57 INFO - mozversion platform_buildid: 20170827011326 19:00:57 INFO - mozversion platform_changeset: 30ee0dcda8836389c0165ae3fb66a236fdfc51ea 19:00:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:00:57 INFO - mozversion platform_version: 57.0a1 19:00:57 INFO - using testdate: 1503799257 19:00:57 INFO - actual date: 1503799257 19:00:57 INFO - starting webserver on 'localhost:41428' 19:00:57 INFO - SUITE-START | Running 2 tests 19:00:57 INFO - TEST-START | dromaeo_css 19:00:57 INFO - Initialising browser for dromaeo_css test... 19:00:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41428/getInfo.html -profile /tmp/tmpVQfJyE/profile 19:00:57 INFO - TEST-INFO | started process 1521 (/builds/slave/test/build/application/firefox/firefox http://localhost:41428/getInfo.html) 19:01:03 INFO - TEST-INFO | 1521: exit 0 19:01:03 INFO - Browser initialized. 19:01:03 INFO - Running cycle 1/1 for dromaeo_css test... 19:01:03 INFO - Using env: {'DISPLAY': ':0', 19:01:03 INFO - 'HOME': '/home/cltbld', 19:01:03 INFO - 'JSGC_DISABLE_POISONING': '1', 19:01:03 INFO - 'LANG': 'en_US.UTF-8', 19:01:03 INFO - 'LANGUAGE': 'en_US:en', 19:01:03 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:01:03 INFO - 'LOGNAME': 'cltbld', 19:01:03 INFO - 'MAIL': '/var/mail/cltbld', 19:01:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:01:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:01:03 INFO - 'MOZ_CRASHREPORTER': '1', 19:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:01:03 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:01:03 INFO - 'MOZ_NO_REMOTE': '1', 19:01:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:01:03 INFO - 'NO_EM_RESTART': '1', 19:01:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:01:03 INFO - 'PWD': '/builds/slave/test', 19:01:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:01:03 INFO - 'RUST_BACKTRACE': 'full', 19:01:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:01:03 INFO - 'SHELL': '/bin/bash', 19:01:03 INFO - 'SHLVL': '1', 19:01:03 INFO - 'STYLO_FORCE_ENABLED': '1', 19:01:03 INFO - 'TERM': 'linux', 19:01:03 INFO - 'TMOUT': '86400', 19:01:03 INFO - 'USER': 'cltbld', 19:01:03 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:01:03 INFO - '_': '/tools/buildbot/bin/python'} 19:01:03 INFO - TEST-INFO | started process 1708 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVQfJyE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:01:04 INFO - PID 1708 | 19:01:04 INFO - PID 1708 | (/builds/slave/test/build/application/firefox/firefox:1758): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:01:04 INFO - PID 1708 | 19:01:05 INFO - PID 1708 | 19:01:05 INFO - PID 1708 | (/builds/slave/test/build/application/firefox/firefox:1820): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:01:05 INFO - PID 1708 | 19:01:05 INFO - PID 1708 | __metrics Screen width/height:1600/1200 19:01:05 INFO - PID 1708 | colorDepth:24 19:01:05 INFO - PID 1708 | Browser inner width/height: 1024/768 19:01:05 INFO - PID 1708 | __metrics 19:01:14 INFO - PID 1708 | RSS: Main: 174899200 19:01:14 INFO - PID 1708 | 19:04:13 INFO - PID 1708 | Cycle 1(1): loaded http://localhost:41428/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41428/tests/dromaeo/cssquery-ext.html) 19:04:14 INFO - PID 1708 | RSS: Main: 178798592 19:04:14 INFO - PID 1708 | 19:07:16 INFO - PID 1708 | Cycle 1(1): loaded http://localhost:41428/tests/dromaeo/cssquery-ext.html (next: http://localhost:41428/tests/dromaeo/cssquery-jquery.html) 19:07:17 INFO - PID 1708 | RSS: Main: 173211648 19:07:17 INFO - PID 1708 | 19:10:19 INFO - PID 1708 | Cycle 1(1): loaded http://localhost:41428/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41428/tests/dromaeo/cssquery-mootools.html) 19:10:19 INFO - PID 1708 | RSS: Main: 177692672 19:10:19 INFO - PID 1708 | 19:13:21 INFO - PID 1708 | Cycle 1(1): loaded http://localhost:41428/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41428/tests/dromaeo/cssquery-prototype.html) 19:13:22 INFO - PID 1708 | RSS: Main: 175550464 19:13:22 INFO - PID 1708 | 19:16:25 INFO - PID 1708 | Cycle 1(1): loaded http://localhost:41428/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41428/tests/dromaeo/cssquery-yui.html) 19:16:26 INFO - PID 1708 | RSS: Main: 179097600 19:16:26 INFO - PID 1708 | 19:19:28 INFO - PID 1708 | Cycle 1(1): loaded http://localhost:41428/tests/dromaeo/cssquery-yui.html (next: http://localhost:41428/tests/dromaeo/cssquery-dojo.html) 19:19:28 INFO - PID 1708 | RSS: Main: 177926144 19:19:28 INFO - PID 1708 | 19:19:28 INFO - PID 1708 | __start_tp_report 19:19:28 INFO - PID 1708 | _x_x_mozilla_page_load 19:19:28 INFO - PID 1708 | _x_x_mozilla_page_load_details 19:19:28 INFO - PID 1708 | |i|pagename|runs| 19:19:28 INFO - PID 1708 | |0;dojo.html;3320.7011368976796;3321.3357328534285;3325.2850637113015;3339.949900751489;3340.248444100076;6264.123022776815;6311.558190926637;6345.904811427827;6359.650219237946;6367.617942923428;6176.567640265183;6269.435750782429;6270.18487596613;6270.937290627093;6286.057091436287;5753.309602847664;5787.826365209029;5793.536517078624;5817.767289308423;5848.385919478457;5776.740046697888;5784.2769653793275;5812.564057695668;5840.0947853082625;5890.087036509348;5842.32813226478;5898.967680655889;5899.46904778572;5919.467247947684;5939.7921072762465;5667.886642267132;5733.168690539848;5743.9425605744245;5816.447437493432;5823.097419899922;5515.61390702651;5719.428057194289;5753.137029445575;5794.768209271624;5881.735321910524;6240.469560087398;6301.7164227609865;6378.011408231731;6384.776532821329;6391.520635952275;6601.075849381091;6657;6684.966575167142;6692.665366731644;6703.162104736908;6795.966020169867;6797.354251346114;6819.113515242987;6820.215675197366;6820.6930688118655;6271.027990661501;6277;6288.528360372945;6290;6353.078803573456;6917.446604271747;6934.653267336613;6964.616946068015;6974.895376569356;7050.506464547535;6606.735730570819;6617.172853393326;6663.966680166568;6699.363560461748;6719.697613607399;6659.034440006172;6730.798076057726;6736.427403670645;6745.325467453215;6871.690773915086;6628.304028077079;6652.634105124264;6657.667116644148;6670.599764014175;6679.666016699146;6810.046593476917;6833.931660683429;6837.726490940307;6867.107276054081;6868.862622747517;5333.679979201261;5399.595030372738;5408.378036525771;5409.621326507186;5416.2688037114485;4635.837745678885;4646.535346465326;4693.976530117396;4721.787519561628;4752;5913.083472061838;5925.9407405925385;5935.228420305332;5935.821925342247;5958.761649534057;6780.525363224527;6831.521793474409;6834.829129271709;6843.7604683835825;6846.965765171142;5992.610480318765;6034.336223015464;6040.395960403925;6041.154238406627;6046.244219472566;5830;5839.562032847553;5918.141869428909;5920.911186332209;5928.992071347795;6568.671566421755;6569.967150164219;6581.736730530821;6670.433013193836;6678.632675202911;6809.2509823918335;6834.897476537756;6835.760748373783;6866.210385805696;6870.759523416857;6561.934380656132;6566.9014964774615;6667.233268174222;6667.933320666732;6675.032120342426;6622.801315960529;6670.299618539983;6717.697703603448;6737.225219099866;6765.42493888025;6665.566738162004;6742.6291553965;6746.123004009643;6752.493562982698;6874.793756187322;6467.579607325509;6479.546431749733;6516.283208846929;6589.27517973013;6607.96696016536;6539.771108011197;6603.735850565923;6614.404703576702;6625.304343044104;6649.301823308677;6480.805575832733;6590.736370545316;6621.039949207434;6621.304763000008;6622.1722284714415;6596.109525214035;6622.834429139118;6712.966435167793;6718.462522998246;6733.293004234485;6336.746530138743;6381.1704478417505;6388.4569811566535;6451.516136289891;6460.25707043696 19:19:28 INFO - PID 1708 | |1;ext.html;792.184050428059;865.9393842431031;887.4941283468423;894.5214310343963;1106.789709955109;29578;39211.607883921155;39888.60111398892;39963.80018099905;40069.39895901564;39471;39865.20269594606;39981.40027899584;40045.599544004624;40057;7525.548467091965;7594.164641889388;7714.421418393615;7737.845243095135;7739.5356278623185;7617.5810330431805;7731.188225236354;7732.806679832993;7733.342665873408;7736.2650548197835;7672.194419585947;7705;7718.768436946901;7759.806004849867;7762.495437796553;7654.885176722353;7703.268189521987;7708.421868359896;7708.961455192744;7711.3059824615775;7551.433642476782;7656.770296891046;7660.425468089916;7670.3480204182715;7679.961600192019;36839.63160368389;37101.44347834784;37119;37137.814310928276;37144.44283335752;3419.3674170278578;3446.4313388290902;3447.3967055765393;3448.0862571418343;3448.81031543265;30215.848920755474;30334.48327583634;30387;30437.23906902323;30502.084937451913;4846.878828029292;5051.26756620288;5083.389993200803;5131.1277082895995;5163.122269214243;182666;182879;183762;184542;184666;47607.523924761;47634.76182619134;47656.28515572269;47793.044139117024;47820.521794782304;50168.247476287885;50556.494435055916;50562;50597.49402505928;50605;12565.371731413576;14653.853461465462;14937.850621493862;15030.774538381933;15074.924625376803;10334.844977325347;11098.223124381217;11279.774404511863;11290.604828830952;11305.26515776472;13978.44086236539;13986.440542378394;13988.650283742987;14024.579262622137;14141.787873181911;5690.174924635905;5690.231818704505;5744.712764361765;5812.157237200582;5846.7076646167525;969.9854502182473;972.3825370889537;989.2531138990161;991.7223177510309;1006.7281833904805;22080.558388832134;22184.778152218594;22286.108555657916;22365.105395783987;22370;1083.5882364701363;1087.2932593813925;1088.7822435512771;1098.3135540287321;1100.7908497385472;11763.470643821049;11846.644600661994;11878.287302761863;11885.286882786888;11900.642980710592;222645;223255;223384;224732;225910;50349.244761328606;50828;50961;51991.74004129955;53315.20027199595;1372.4167228927665;1394.853540378266;1399.7410479061202;1401.0262867307124;1408.8732014118777;26024.60963085593;26364.472710546066;26442.603360949986;26876.865615672577;27604.585931211448;48677.75661121672;48781;48850.267245990624;48880.51119488759;48964.75517622389;196441;197535;197742;197886;198228;33322.33355332831;34466;34954.30091398107;35098.473522897686;35124.473132902516;42336.576634233264;42991.785041074596;43630.12739745124;43655.78172109119;43852;32004.679953201103;32205.838970804994;32217.51673724849;32714;32927.83536082304;32198.678013220506;32247.838760806048;32496.187595310308;32653.673463265062;32937.34125317432;36002.27995440129;36440.81779591085;36508.634913651586;36630.08424789402;36941;30807.38385232238;31088.844555777076;31400.842995784875;31734.365312693157;31903;23628.763712362655;23789.286321410385;23802.880985594962;23830.761692382854;24144.275671729876 19:19:28 INFO - PID 1708 | |2;jquery.html;565.0789213581863;566.2921348314607;569.9230603868477;571.5142129190186;574.1445246582591;21150.153993840246;21851.562968740614;21871.562568748617;21909.233176838814;21943.6708449373;24327;25241.36896577582;26455.206343809667;26645.200643980712;26772.59841102385;6808.285130061359;6949.305069493061;6957.408620267258;6963.651817409134;6971.05890704756;6594.109795177662;6955.130608673916;6975.825604359881;6992.6154061526595;7000.29997000301;6916.23921368649;6931.861362772742;6940.340667636567;6947.58314501129;6949.617771022617;6946.756863509778;6956.026156338066;6959.373656370925;6963.05998690178;6967.581945083311;6793.184817821843;6801.4898882584;6811.591304521745;6824.863502729967;6829.3170682931805;29613.259668508243;30577.541336879964;31145.22136946572;31327.37345253105;31546.211344716565;2100.8949552522313;2143.185589475989;2145.9034343454427;2147.119680930811;2147.656374980011;19886.20455181791;20497;20505.8974705127;20507.077181526867;20531.48671283215;2475.5296493666146;2492.090387008747;2540.5427023135826;2662.041665000598;2671.131882138313;130261.34869325593;133716.99999999904;133994;134244;135799;19865.03374156461;21286;21329.480635694938;21481.462963425725;22088.558228835333;40548.79725601412;41158.79420602938;41205.1758964825;41221.38167927483;41232.587674123475;10352.689419317432;10475.266731328733;10609.734756631146;10666.520006599567;10916;8598.269147122564;8633.827323453495;8727.694530691522;8752.21230089295;8788.648454061766;11162.832557511643;11229.943850280697;11310.660680179608;11497.252678575866;11568.479418426192;3796.335641262768;3921.4706264556844;3985.9202815943518;4096.201240758023;4353.49934757507;961.1637875048752;976.682578162086;983.8917719050899;987.0968064221157;1007.3804610164696;19378.70931935994;19642.21431142767;19943.60112797736;20169;20255.18979240814;1024.6977141743203;1027.1474676018886;1028.6451174344838;1041.802057609067;1045.6235755128148;10840.241183117263;11033.503492342863;11063.72340691489;11083.556657733601;11094.833577496343;450122;450821;452609;456919;460047;104765;105630;106381;107534.46232768943;108665.45667271613;1148.5520646947698;1155.5782139519183;1189.6193218170276;1189.6966273600176;1197.0842305636195;17131.486055418358;17357;17779.911100444417;17925.46223613294;18002.009889456207;52679.20981185209;52732.47267527276;54100.45899540954;54969.72515137399;55479;63759.681201593696;64370.67814660897;64399.67800160969;65981.67009164924;76116.097097573;12557.874421256036;12976.416061277076;14387.42450302018;14518.20149891766;14916.477923272634;23231.53536929286;23461.76538234596;23646.17238396717;24241.39396515101;24390;17889.821101789337;18140.637187255918;18142.818571814114;18145.27418903229;19118;15265;15361.769573456184;16030.919845400698;16334.101624410772;16360.100194488941;18722.15750291213;19480.415587532396;19592.80407195967;19645.607087858447;19763;14395.496157634432;14556.92721536428;14558.85441145575;14562.854371456573;14698.338574763948;18362.357317493825;18481.630367392845;18497;18527.81472185261;18628.254869805056 19:19:28 INFO - PID 1708 | |3;mootools.html;1324.9801252981206;1348.2315080404173;1353.7427888701145;1359.4494229836919;1380.675541247807;14719.411223551071;14802.407903683854;15420.383184672613;15446.61383465414;15473.76789348158;14887;15236.390544378211;15246.237688115578;15247.847521524782;15268.312925918333;2740.6848212455625;2795.496810574106;2796.566532187514;2797.8181418207855;2798.3563780330537;2648.8543130127837;2679.8660066996667;2714.1043455659683;2799.426117645888;2804.2708895687065;2724.5776904579725;2729.290384500034;2768.5847122931623;2771.598118272849;2772.88908443662;2647.6558047453805;2657.4153686189106;2658.029819116028;2660.9068682596017;2673.25148958292;2614.8954041838306;2640.6699162604673;2654.694710108343;2656.53510635638;2675.0637276953107;14447.494337698236;14518.419263229458;14572.198529080897;14591.92704036473;14598.489052883204;7586.241375862425;7893.092294386104;7901.486403383819;7950.681972721084;7956.085050219183;5171.870703232412;5291.550218231455;5495.752691128909;5537.1140617501405;5542.057850165482;1854.6012607289497;1899.9905000475048;1923.7787654419642;1944.3097700316325;1949.541857663448;24954.750452495424;25668.614970775452;25677.48645027089;25719.22842314715;25725.485490290088;6995.475339349569;7034.222391752928;7132.215456299803;7269.418446524265;7303.123625165014;16958.152092395576;16971.91514042422;17002.574935626708;17148.485545433658;17149.657006859794;7469.215732348134;7553.924460755432;7587.772366829004;7587.962060189664;7595.734149304748;6808.2170550386345;6946.0622815920215;6963.199232088274;7055.2944705529035;7064.152301723723;4703.17694403478;4954.603631709455;5169.327987361619;5205.401378841481;5206.167013277857;1422.333125389894;1434.211183848879;1463.8462961389114;1479.2455847517842;1500.5423345879612;887.8579427291731;896.0859922878758;902.3412908576645;905.2893478619252;920.1902325953118;8087.878681819778;8350.540720260322;8368.20502052304;8844.513551754715;8959.641614335354;968.162539403411;992.3103443107116;999.3753903810118;1001.8547310639916;1002.04304888831;5194.4805519447755;5196.376434827845;5290.81482148123;5335;5370.436104209002;19925.501862453264;20494.590108197754;20664.690029649566;20750.792492075187;20768.169273229203;20127;20435.489112772004;20863.582728345653;20907.58184836295;20912.37262882116;11103.72240693989;11250.774984500264;11322.867713228612;11380.317180969003;11385.999999999834;8161.877571836546;8313;8343.290820280345;8431.28334091609;8478.364122690946;17745.29018839232;17842.28630854751;17978.910105449388;18197.45407637773;18543.165557549673;18140.72788908141;18189.727154092965;18517;18528.980906050296;18658.160382782535;11606.24559403636;12005.459754311252;12100.818487722867;12101.878981210075;12106.213096148722;12037.157398981988;12619.432125554556;12702.682432939251;12710.618681439571;12745.49018039268;10446.947765261126;10521.947390263;10539.57841686324;10761.085307748928;10790.73023174427;10392.324498907547;10408;10485.266031378036;10623.256372053882;10873.619423320146;9958.302918795904;10071.647492337734;10074.294799363974;10080.798384032425;10119.089281964658;7288.08898887639;7293.671784769805;7300.26997300287;7314.926850731425;7371.226021267689;7660.195679453577;7719.575423351769;7743.264389882952;7763.378929685725;7885.44801863864 19:19:28 INFO - PID 1708 | |4;prototype.html;500.23963575365457;503.39592489013194;517.7695925313236;521.6635270250688;529.3779808724747;6274.84312892178;6302.495800335973;6336.930455635496;6390.041493775936;6450.741970321182;6257.181969883187;6336.081268216106;6338.619682819034;6345.587536810116;6350.396712312335;4950.207966725325;5142.3829140503;5192.299039629658;5244.711540865251;5261.368635763704;5102.974485127569;5128.923066153992;5134.794608215667;5141.58345577578;5190.506901844319;5048.974755126237;5111.469822437898;5114.309568208281;5127.769250383742;5131.794728210867;5072.594192464631;5076.086304465195;5110.616703747197;5120.308758317617;5127.692338459686;4574.496805351442;4611.123886461595;4618.445786505607;4623.42207224097;4626.444826620794;6275.780347687809;6308.968455157741;6323.241211054703;6357.809265722036;6391.360863913618;5648.830535083954;5702.3157221133315;5786.884262314772;5816.302043754777;5832.358440571534;6750.054992301082;6771.661416929134;6793;6814.114165158548;6844.4182244509275;4939.851804445872;4941.209406494943;4966.528179822911;4985.326980857574;5011.498850114996;6730.427913627396;6823.420009299216;6971.58170509771;7034.753783617549;7045.260247674023;5056.696598204119;5057.6459647824295;5084.949150508522;5091.8981620367385;5106.642535022513;5009.949900500948;5134.024535338273;5152.355955505562;5169.896602067937;5172.5603323717905;5728.914066289009;5745.770169193269;5762.538996880239;5779.942200578024;5784.582777219608;6724.831879202961;6749.358810912955;6753.324667533306;6792.864142717118;6838.384545390939;1793.9461816145536;1798.651011741194;1802.5763945472804;1802.8107048759691;1810.4025671528627;1164.4759858063906;1211.4730092409654;1238.504598160725;1242.1739543203669;1256.5790460195938;5906.084556893602;5946.313285762768;6031.909521357183;6054.576179667469;6059.969700151471;6780.593164410053;6786.321367863175;6947.263184203949;6967.756128535477;6991.300869912968;5942.168096466498;5951.369664504648;5994.250718660168;5994.670293133832;6010.789622363196;3645.3438381091128;3701.777893326409;3703.5555733312176;3726.757760745543;3728.776273423549;6621.668916554153;6712.865742685119;6724.696194435565;6789.117414736152;6818.727250909908;6545.639989800607;6979.267176946449;6989.685464154125;7047.471439642048;7101.325374089453;6563.9343606563325;6649.202095748445;6677.527685603459;6683.4319082876555;6714.395704386531;6818.318168183142;6905.516613836983;6921.204061532787;6938.23679395256;6978.930210698031;6756;6810.965945170243;6903.240643529108;6904.93095069063;6908;6622.403983641495;6642.4021838034805;6667.166604174478;6684.4318232950745;6715.865682686416;5054.494550544916;5062.848114556569;5079.212722028092;5095.847124586268;5121.8975620486635;6616.603003819786;6707.8993815091835;6729.663516824237;6777.423918966746;6916.100906882073;6539.967300163468;6621.556790469025;6649.63427011519;6658.334166583303;6701.932980670326;6632.734690612515;6663.46692264608;6681.29846366144;6685.498587605852;6739.5619284746335;6583.531175809072;6609.405153536205;6630.403263706289;6652.401283884474;6694.83262918431;6722.394984451423;6758.831029224309;6800.829979250559;6805.353491418307;6811.863762724817;6554.770583029571;6568;6577.671116444254;6601.570897891622;6620.536562440583 19:19:28 INFO - PID 1708 | |5;yui.html;860.1143164682494;877.7761670773953;885.7475619448454;896.6368620708615;898.2320116300563;16475.588110297234;17242.741358879626;17317.39389121381;19565.804341956577;21429.464263393424;19676.606467870635;19723.901380493113;20325;20327.695084573745;20522.48693782656;923.4551614547399;947.313197931501;952.0146648219095;952.119289657068;953.2374100719431;922.064104933493;942.7548837302317;964.9421034737903;967.351874244258;970.0687340153461;1000.2598077422691;1000.9199264058858;1001.6744558018637;1002.8245057115012;1003.2074661017787;996.9850452243186;997.0577803975247;997.9850302245472;998.7503124218945;1000.1848493477829;736.4145504324075;738.0921466596063;738.2617382617383;739.0909181706477;739.2644318902726;19043.238270469166;19092.23631054756;19476.4157075288;19567.412977610693;19590.412287631392;21113.5777284455;21135.5772884543;21511;21606.567868642695;21612.891935540378;15866.682666346724;15969.36122555108;16022.839771602252;16271.186440678037;16296.674066518603;3731.9253614927547;3784.432335149722;3794.7153963452574;3795.7532760370486;3795.9240815183816;130846;130885;133990;134202.999999999;135627;16004.679906401807;16088.115153666427;16099.356025759073;16435.26041328143;16544.586385340222;29268.7073129266;29653.406931861242;29889.850550747105;29938.85030574877;30132.24669383283;8537.786555336043;8595;8598.355123365647;8653.480791152551;8664.046954834963;4178.749275043507;4353.434053573015;4392.450943632046;4436.933445998313;4550.385697930737;5099;5204.76578553966;5225.4774522548205;5228.346456692913;5235.607329450307;975.9024097590184;1005.7686732051669;1015.9847602285971;1019.021739130443;1021.371856308365;691.4917535611322;699.1679900917892;702.0732632924593;703.3529153178986;704.1444644756646;11052.889471105347;11952;11975;12883.294508153205;13040.673983150496;689.7344522358928;693.6531734132933;695.3811108113783;716.451914285572;718.0234880562431;5644.85887852807;5687.488126068673;5746.798862039808;5834.679092649945;5879.676617785977;111256;111757;113970.43014785039;114164.42917785358;117416;97506.5124674372;97968;98890;100474;101426;82585.58707206546;84398;85557.57221213855;86858.56570717233;87945.1205487937;11436.599719009795;11887.28676279426;11912.821307680219;12019.75960480768;12744.49022039108;38397;40224;40552.59447405488;41271.1745765077;42121.578784212994;67831.66084169745;71833.6408317955;72343.63828181036;74768;77162.61418692871;16778.1610919456;18293;18543.907280464053;18567.535811604277;18603.2558697656;17740.645187096445;17957.820421796136;18041.09794510243;18132.365367212085;18338.81661183371;17822.287108516033;18491.537711557317;18649.254029839198;18896;18963.241470341032;15427.61430964235;15542.378304867678;17295.221715023104;17846.28614855443;18063.729044064086;17553.473395798148;17623;18248.543786404916;18326.08369581542;18513;11564.884351156717;12845.42195601219;13157.671058223621;13201.867981320063;13587.388567514288;13643.658908527366;13661.86338136606;14173.574792756233;14562.41750329975;15006.549803505912 19:19:28 INFO - PID 1708 | __end_tp_report 19:19:28 INFO - PID 1708 | __start_cc_report 19:19:28 INFO - PID 1708 | _x_x_mozilla_cycle_collect,379 19:19:28 INFO - PID 1708 | __end_cc_report 19:19:28 INFO - PID 1708 | __startTimestamp1503800368296__endTimestamp 19:19:28 INFO - PID 1708 | 19:19:28 INFO - PID 1708 | ------- Summary: start ------- 19:19:28 INFO - PID 1708 | Number of tests: 6 19:19:28 INFO - PID 1708 | 19:19:28 INFO - PID 1708 | [#0] dojo.html Cycles:165 Average:6243.39 Median:6480.81 stddev:719.33 (11.1%) stddev-sans-first:684.02 19:19:28 INFO - PID 1708 | Values: 3320.7 3321.3 3325.3 3339.9 3340.2 6264.1 6311.6 6345.9 6359.7 6367.6 6176.6 6269.4 6270.2 6270.9 6286.1 5753.3 5787.8 5793.5 5817.8 5848.4 5776.7 5784.3 5812.6 5840.1 5890.1 5842.3 5899.0 5899.5 5919.5 5939.8 5667.9 5733.2 5743.9 5816.4 5823.1 5515.6 5719.4 5753.1 5794.8 5881.7 6240.5 6301.7 6378.0 6384.8 6391.5 6601.1 6657.0 6685.0 6692.7 6703.2 6796.0 6797.4 6819.1 6820.2 6820.7 6271.0 6277.0 6288.5 6290.0 6353.1 6917.4 6934.7 6964.6 6974.9 7050.5 6606.7 6617.2 6664.0 6699.4 6719.7 6659.0 6730.8 6736.4 6745.3 6871.7 6628.3 6652.6 6657.7 6670.6 6679.7 6810.0 6833.9 6837.7 6867.1 6868.9 5333.7 5399.6 5408.4 5409.6 5416.3 4635.8 4646.5 4694.0 4721.8 4752.0 5913.1 5925.9 5935.2 5935.8 5958.8 6780.5 6831.5 6834.8 6843.8 6847.0 5992.6 6034.3 6040.4 6041.2 6046.2 5830.0 5839.6 5918.1 5920.9 5929.0 6568.7 6570.0 6581.7 6670.4 6678.6 6809.3 6834.9 6835.8 6866.2 6870.8 6561.9 6566.9 6667.2 6667.9 6675.0 6622.8 6670.3 6717.7 6737.2 6765.4 6665.6 6742.6 6746.1 6752.5 6874.8 6467.6 6479.5 6516.3 6589.3 6608.0 6539.8 6603.7 6614.4 6625.3 6649.3 6480.8 6590.7 6621.0 6621.3 6622.2 6596.1 6622.8 6713.0 6718.5 6733.3 6336.7 6381.2 6388.5 6451.5 6460.3 19:19:28 INFO - PID 1708 | 19:19:28 INFO - PID 1708 | [#1] ext.html Cycles:180 Average:37243.60 Median:35026.39 stddev:52459.03 (149.8%) stddev-sans-first:52534.78 19:19:28 INFO - PID 1708 | Values: 792.2 865.9 887.5 894.5 1106.8 29578.0 39211.6 39888.6 39963.8 40069.4 39471.0 39865.2 39981.4 40045.6 40057.0 7525.5 7594.2 7714.4 7737.8 7739.5 7617.6 7731.2 7732.8 7733.3 7736.3 7672.2 7705.0 7718.8 7759.8 7762.5 7654.9 7703.3 7708.4 7709.0 7711.3 7551.4 7656.8 7660.4 7670.3 7680.0 36839.6 37101.4 37119.0 37137.8 37144.4 3419.4 3446.4 3447.4 3448.1 3448.8 30215.8 30334.5 30387.0 30437.2 30502.1 4846.9 5051.3 5083.4 5131.1 5163.1 182666.0 182879.0 183762.0 184542.0 184666.0 47607.5 47634.8 47656.3 47793.0 47820.5 50168.2 50556.5 50562.0 50597.5 50605.0 12565.4 14653.9 14937.9 15030.8 15074.9 10334.8 11098.2 11279.8 11290.6 11305.3 13978.4 13986.4 13988.7 14024.6 14141.8 5690.2 5690.2 5744.7 5812.2 5846.7 970.0 972.4 989.3 991.7 1006.7 22080.6 22184.8 22286.1 22365.1 22370.0 1083.6 1087.3 1088.8 1098.3 1100.8 11763.5 11846.6 11878.3 11885.3 11900.6 222645.0 223255.0 223384.0 224732.0 225910.0 50349.2 50828.0 50961.0 51991.7 53315.2 1372.4 1394.9 1399.7 1401.0 1408.9 26024.6 26364.5 26442.6 26876.9 27604.6 48677.8 48781.0 48850.3 48880.5 48964.8 196441.0 197535.0 197742.0 197886.0 198228.0 33322.3 34466.0 34954.3 35098.5 35124.5 42336.6 42991.8 43630.1 43655.8 43852.0 32004.7 32205.8 32217.5 32714.0 32927.8 32198.7 32247.8 32496.2 32653.7 32937.3 36002.3 36440.8 36508.6 36630.1 36941.0 30807.4 31088.8 31400.8 31734.4 31903.0 23628.8 23789.3 23802.9 23830.8 24144.3 19:19:28 INFO - PID 1708 | 19:19:28 INFO - PID 1708 | [#2] jquery.html Cycles:180 Average:34081.10 Median:21666.51 stddev:76477.40 (353.0%) stddev-sans-first:76650.53 19:19:28 INFO - PID 1708 | Values: 565.1 566.3 569.9 571.5 574.1 21150.2 21851.6 21871.6 21909.2 21943.7 24327.0 25241.4 26455.2 26645.2 26772.6 6808.3 6949.3 6957.4 6963.7 6971.1 6594.1 6955.1 6975.8 6992.6 7000.3 6916.2 6931.9 6940.3 6947.6 6949.6 6946.8 6956.0 6959.4 6963.1 6967.6 6793.2 6801.5 6811.6 6824.9 6829.3 29613.3 30577.5 31145.2 31327.4 31546.2 2100.9 2143.2 2145.9 2147.1 2147.7 19886.2 20497.0 20505.9 20507.1 20531.5 2475.5 2492.1 2540.5 2662.0 2671.1 130261.3 133717.0 133994.0 134244.0 135799.0 19865.0 21286.0 21329.5 21481.5 22088.6 40548.8 41158.8 41205.2 41221.4 41232.6 10352.7 10475.3 10609.7 10666.5 10916.0 8598.3 8633.8 8727.7 8752.2 8788.6 11162.8 11229.9 11310.7 11497.3 11568.5 3796.3 3921.5 3985.9 4096.2 4353.5 961.2 976.7 983.9 987.1 1007.4 19378.7 19642.2 19943.6 20169.0 20255.2 1024.7 1027.1 1028.6 1041.8 1045.6 10840.2 11033.5 11063.7 11083.6 11094.8 450122.0 450821.0 452609.0 456919.0 460047.0 104765.0 105630.0 106381.0 107534.5 108665.5 1148.6 1155.6 1189.6 1189.7 1197.1 17131.5 17357.0 17779.9 17925.5 18002.0 52679.2 52732.5 54100.5 54969.7 55479.0 63759.7 64370.7 64399.7 65981.7 76116.1 12557.9 12976.4 14387.4 14518.2 14916.5 23231.5 23461.8 23646.2 24241.4 24390.0 17889.8 18140.6 18142.8 18145.3 19118.0 15265.0 15361.8 16030.9 16334.1 16360.1 18722.2 19480.4 19592.8 19645.6 19763.0 14395.5 14556.9 14558.9 14562.9 14698.3 18362.4 18481.6 18497.0 18527.8 18628.3 19:19:28 INFO - PID 1708 | 19:19:28 INFO - PID 1708 | [#3] mootools.html Cycles:180 Average:9155.36 Median:14170.19 stddev:6405.41 (45.2%) stddev-sans-first:6396.35 19:19:28 INFO - PID 1708 | Values: 1325.0 1348.2 1353.7 1359.4 1380.7 14719.4 14802.4 15420.4 15446.6 15473.8 14887.0 15236.4 15246.2 15247.8 15268.3 2740.7 2795.5 2796.6 2797.8 2798.4 2648.9 2679.9 2714.1 2799.4 2804.3 2724.6 2729.3 2768.6 2771.6 2772.9 2647.7 2657.4 2658.0 2660.9 2673.3 2614.9 2640.7 2654.7 2656.5 2675.1 14447.5 14518.4 14572.2 14591.9 14598.5 7586.2 7893.1 7901.5 7950.7 7956.1 5171.9 5291.6 5495.8 5537.1 5542.1 1854.6 1900.0 1923.8 1944.3 1949.5 24954.8 25668.6 25677.5 25719.2 25725.5 6995.5 7034.2 7132.2 7269.4 7303.1 16958.2 16971.9 17002.6 17148.5 17149.7 7469.2 7553.9 7587.8 7588.0 7595.7 6808.2 6946.1 6963.2 7055.3 7064.2 4703.2 4954.6 5169.3 5205.4 5206.2 1422.3 1434.2 1463.8 1479.2 1500.5 887.9 896.1 902.3 905.3 920.2 8087.9 8350.5 8368.2 8844.5 8959.6 968.2 992.3 999.4 1001.9 1002.0 5194.5 5196.4 5290.8 5335.0 5370.4 19925.5 20494.6 20664.7 20750.8 20768.2 20127.0 20435.5 20863.6 20907.6 20912.4 11103.7 11250.8 11322.9 11380.3 11386.0 8161.9 8313.0 8343.3 8431.3 8478.4 17745.3 17842.3 17978.9 18197.5 18543.2 18140.7 18189.7 18517.0 18529.0 18658.2 11606.2 12005.5 12100.8 12101.9 12106.2 12037.2 12619.4 12702.7 12710.6 12745.5 10446.9 10521.9 10539.6 10761.1 10790.7 10392.3 10408.0 10485.3 10623.3 10873.6 9958.3 10071.6 10074.3 10080.8 10119.1 7288.1 7293.7 7300.3 7314.9 7371.2 7660.2 7719.6 7743.3 7763.4 7885.4 19:19:28 INFO - PID 1708 | 19:19:28 INFO - PID 1708 | [#4] prototype.html Cycles:180 Average:5647.90 Median:6329.66 stddev:1585.16 (25.0%) stddev-sans-first:1541.80 19:19:28 INFO - PID 1708 | Values: 500.2 503.4 517.8 521.7 529.4 6274.8 6302.5 6336.9 6390.0 6450.7 6257.2 6336.1 6338.6 6345.6 6350.4 4950.2 5142.4 5192.3 5244.7 5261.4 5103.0 5128.9 5134.8 5141.6 5190.5 5049.0 5111.5 5114.3 5127.8 5131.8 5072.6 5076.1 5110.6 5120.3 5127.7 4574.5 4611.1 4618.4 4623.4 4626.4 6275.8 6309.0 6323.2 6357.8 6391.4 5648.8 5702.3 5786.9 5816.3 5832.4 6750.1 6771.7 6793.0 6814.1 6844.4 4939.9 4941.2 4966.5 4985.3 5011.5 6730.4 6823.4 6971.6 7034.8 7045.3 5056.7 5057.6 5084.9 5091.9 5106.6 5009.9 5134.0 5152.4 5169.9 5172.6 5728.9 5745.8 5762.5 5779.9 5784.6 6724.8 6749.4 6753.3 6792.9 6838.4 1793.9 1798.7 1802.6 1802.8 1810.4 1164.5 1211.5 1238.5 1242.2 1256.6 5906.1 5946.3 6031.9 6054.6 6060.0 6780.6 6786.3 6947.3 6967.8 6991.3 5942.2 5951.4 5994.3 5994.7 6010.8 3645.3 3701.8 3703.6 3726.8 3728.8 6621.7 6712.9 6724.7 6789.1 6818.7 6545.6 6979.3 6989.7 7047.5 7101.3 6563.9 6649.2 6677.5 6683.4 6714.4 6818.3 6905.5 6921.2 6938.2 6978.9 6756.0 6811.0 6903.2 6904.9 6908.0 6622.4 6642.4 6667.2 6684.4 6715.9 5054.5 5062.8 5079.2 5095.8 5121.9 6616.6 6707.9 6729.7 6777.4 6916.1 6540.0 6621.6 6649.6 6658.3 6701.9 6632.7 6663.5 6681.3 6685.5 6739.6 6583.5 6609.4 6630.4 6652.4 6694.8 6722.4 6758.8 6800.8 6805.4 6811.9 6554.8 6568.0 6577.7 6601.6 6620.5 19:19:28 INFO - PID 1708 | 19:19:28 INFO - PID 1708 | [#5] yui.html Cycles:180 Average:23966.35 Median:20818.03 stddev:33228.55 (159.6%) stddev-sans-first:33276.47 19:19:28 INFO - PID 1708 | Values: 860.1 877.8 885.7 896.6 898.2 16475.6 17242.7 17317.4 19565.8 21429.5 19676.6 19723.9 20325.0 20327.7 20522.5 923.5 947.3 952.0 952.1 953.2 922.1 942.8 964.9 967.4 970.1 1000.3 1000.9 1001.7 1002.8 1003.2 997.0 997.1 998.0 998.8 1000.2 736.4 738.1 738.3 739.1 739.3 19043.2 19092.2 19476.4 19567.4 19590.4 21113.6 21135.6 21511.0 21606.6 21612.9 15866.7 15969.4 16022.8 16271.2 16296.7 3731.9 3784.4 3794.7 3795.8 3795.9 130846.0 130885.0 133990.0 134203.0 135627.0 16004.7 16088.1 16099.4 16435.3 16544.6 29268.7 29653.4 29889.9 29938.9 30132.2 8537.8 8595.0 8598.4 8653.5 8664.0 4178.7 4353.4 4392.5 4436.9 4550.4 5099.0 5204.8 5225.5 5228.3 5235.6 975.9 1005.8 1016.0 1019.0 1021.4 691.5 699.2 702.1 703.4 704.1 11052.9 11952.0 11975.0 12883.3 13040.7 689.7 693.7 695.4 716.5 718.0 5644.9 5687.5 5746.8 5834.7 5879.7 111256.0 111757.0 113970.4 114164.4 117416.0 97506.5 97968.0 98890.0 100474.0 101426.0 82585.6 84398.0 85557.6 86858.6 87945.1 11436.6 11887.3 11912.8 12019.8 12744.5 38397.0 40224.0 40552.6 41271.2 42121.6 67831.7 71833.6 72343.6 74768.0 77162.6 16778.2 18293.0 18543.9 18567.5 18603.3 17740.6 17957.8 18041.1 18132.4 18338.8 17822.3 18491.5 18649.3 18896.0 18963.2 15427.6 15542.4 17295.2 17846.3 18063.7 17553.5 17623.0 18248.5 18326.1 18513.0 11564.9 12845.4 13157.7 13201.9 13587.4 13643.7 13661.9 14173.6 14562.4 15006.5 19:19:28 INFO - PID 1708 | -------- Summary: end -------- 19:19:28 INFO - PID 1708 | 19:19:29 INFO - TEST-INFO | 1708: exit 0 19:19:29 INFO - TEST-OK | dromaeo_css | took 1111553ms 19:19:29 INFO - TEST-START | kraken 19:19:29 INFO - Initialising browser for kraken test... 19:19:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41428/getInfo.html -profile /tmp/tmpCl8Lq5/profile 19:19:29 INFO - TEST-INFO | started process 2132 (/builds/slave/test/build/application/firefox/firefox http://localhost:41428/getInfo.html) 19:19:34 INFO - TEST-INFO | 2132: exit 0 19:19:34 INFO - Browser initialized. 19:19:34 INFO - Running cycle 1/1 for kraken test... 19:19:34 INFO - Using env: {'DISPLAY': ':0', 19:19:34 INFO - 'HOME': '/home/cltbld', 19:19:34 INFO - 'JSGC_DISABLE_POISONING': '1', 19:19:34 INFO - 'LANG': 'en_US.UTF-8', 19:19:34 INFO - 'LANGUAGE': 'en_US:en', 19:19:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:19:34 INFO - 'LOGNAME': 'cltbld', 19:19:34 INFO - 'MAIL': '/var/mail/cltbld', 19:19:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:19:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:19:34 INFO - 'MOZ_CRASHREPORTER': '1', 19:19:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:19:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:19:34 INFO - 'MOZ_NO_REMOTE': '1', 19:19:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:19:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:19:34 INFO - 'NO_EM_RESTART': '1', 19:19:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:19:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:19:34 INFO - 'PWD': '/builds/slave/test', 19:19:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:19:34 INFO - 'RUST_BACKTRACE': 'full', 19:19:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:19:34 INFO - 'SHELL': '/bin/bash', 19:19:34 INFO - 'SHLVL': '1', 19:19:34 INFO - 'STYLO_FORCE_ENABLED': '1', 19:19:34 INFO - 'TERM': 'linux', 19:19:34 INFO - 'TMOUT': '86400', 19:19:34 INFO - 'USER': 'cltbld', 19:19:34 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1503799142.311142-1854577332', 19:19:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:19:34 INFO - '_': '/tools/buildbot/bin/python'} 19:19:34 INFO - TEST-INFO | started process 2320 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCl8Lq5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:19:35 INFO - PID 2320 | 19:19:35 INFO - PID 2320 | (/builds/slave/test/build/application/firefox/firefox:2369): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:19:35 INFO - PID 2320 | 19:19:36 INFO - PID 2320 | 19:19:36 INFO - PID 2320 | (/builds/slave/test/build/application/firefox/firefox:2432): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:19:36 INFO - PID 2320 | 19:19:36 INFO - PID 2320 | __metrics Screen width/height:1600/1200 19:19:36 INFO - PID 2320 | colorDepth:24 19:19:36 INFO - PID 2320 | Browser inner width/height: 1024/768 19:19:36 INFO - PID 2320 | __metrics 19:19:46 INFO - PID 2320 | RSS: Main: 174768128 19:19:46 INFO - PID 2320 | 19:19:48 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41428/tests/kraken/driver.html?testName=audio-beat-detection) 19:19:49 INFO - PID 2320 | RSS: Main: 178794496 19:19:49 INFO - PID 2320 | 19:19:52 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41428/tests/kraken/driver.html?testName=audio-dft) 19:19:52 INFO - PID 2320 | RSS: Main: 180813824 19:19:52 INFO - PID 2320 | 19:19:55 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41428/tests/kraken/driver.html?testName=audio-fft) 19:19:56 INFO - PID 2320 | RSS: Main: 174804992 19:19:56 INFO - PID 2320 | 19:19:58 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41428/tests/kraken/driver.html?testName=audio-oscillator) 19:19:59 INFO - PID 2320 | RSS: Main: 175226880 19:19:59 INFO - PID 2320 | 19:20:01 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41428/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:20:02 INFO - PID 2320 | RSS: Main: 175800320 19:20:02 INFO - PID 2320 | 19:20:05 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41428/tests/kraken/driver.html?testName=imaging-darkroom) 19:20:05 INFO - PID 2320 | RSS: Main: 176910336 19:20:05 INFO - PID 2320 | 19:20:09 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41428/tests/kraken/driver.html?testName=imaging-desaturate) 19:20:09 INFO - PID 2320 | RSS: Main: 176852992 19:20:09 INFO - PID 2320 | 19:20:12 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41428/tests/kraken/driver.html?testName=json-parse-financial) 19:20:13 INFO - PID 2320 | RSS: Main: 177676288 19:20:13 INFO - PID 2320 | 19:20:15 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41428/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:20:15 INFO - PID 2320 | RSS: Main: 177156096 19:20:15 INFO - PID 2320 | 19:20:17 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41428/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:20:17 INFO - PID 2320 | RSS: Main: 176889856 19:20:17 INFO - PID 2320 | 19:20:20 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41428/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:20:20 INFO - PID 2320 | RSS: Main: 177025024 19:20:20 INFO - PID 2320 | 19:20:23 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41428/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:20:24 INFO - PID 2320 | RSS: Main: 177233920 19:20:24 INFO - PID 2320 | 19:20:27 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41428/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:20:27 INFO - PID 2320 | RSS: Main: 177676288 19:20:27 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | Cycle 1(1): loaded http://localhost:41428/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41428/tests/kraken/driver.html?testName=ai-astar) 19:20:29 INFO - PID 2320 | RSS: Main: 179101696 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | __start_tp_report 19:20:29 INFO - PID 2320 | _x_x_mozilla_page_load 19:20:29 INFO - PID 2320 | _x_x_mozilla_page_load_details 19:20:29 INFO - PID 2320 | |i|pagename|runs| 19:20:29 INFO - PID 2320 | |0;ai-astar;102;107;95;107;107;110;112;107;107;107 19:20:29 INFO - PID 2320 | |1;audio-beat-detection;141;116;141;164;117;142;148;121;164;120 19:20:29 INFO - PID 2320 | |2;audio-dft;166;161;163;163;165;162;161;162;163;169 19:20:29 INFO - PID 2320 | |3;audio-fft;78;75;75;165;77;78;74;73;174;75 19:20:29 INFO - PID 2320 | |4;audio-oscillator;80;78;135;79;79;79;125;80;79;132 19:20:29 INFO - PID 2320 | |5;imaging-gaussian-blur;114;114;113;113;119;114;114;114;122;114 19:20:29 INFO - PID 2320 | |6;imaging-darkroom;147;147;148;146;147;148;148;147;150;150 19:20:29 INFO - PID 2320 | |7;imaging-desaturate;94;94;94;95;94;93;95;94;94;94 19:20:29 INFO - PID 2320 | |8;json-parse-financial;53;53;54;52;52;54;52;57;52;53 19:20:29 INFO - PID 2320 | |9;json-stringify-tinderbox;38;37;38;38;38;38;38;38;38;39 19:20:29 INFO - PID 2320 | |10;stanford-crypto-aes;77;76;74;74;74;73;74;73;74;73 19:20:29 INFO - PID 2320 | |11;stanford-crypto-ccm;120;125;118;118;115;121;125;123;128;173 19:20:29 INFO - PID 2320 | |12;stanford-crypto-pbkdf2;178;170;182;173;173;183;173;171;171;175 19:20:29 INFO - PID 2320 | |13;stanford-crypto-sha256-iterative;64;67;63;64;65;68;63;68;62;64 19:20:29 INFO - PID 2320 | __end_tp_report 19:20:29 INFO - PID 2320 | __start_cc_report 19:20:29 INFO - PID 2320 | _x_x_mozilla_cycle_collect,962 19:20:29 INFO - PID 2320 | __end_cc_report 19:20:29 INFO - PID 2320 | __startTimestamp1503800429699__endTimestamp 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | ------- Summary: start ------- 19:20:29 INFO - PID 2320 | Number of tests: 14 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#0] ai-astar Cycles:10 Average:106.10 Median:107.00 stddev:4.65 (4.3%) stddev-sans-first:4.69 19:20:29 INFO - PID 2320 | Values: 102.0 107.0 95.0 107.0 107.0 110.0 112.0 107.0 107.0 107.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:141.50 stddev:18.32 (12.9%) stddev-sans-first:19.38 19:20:29 INFO - PID 2320 | Values: 141.0 116.0 141.0 164.0 117.0 142.0 148.0 121.0 164.0 120.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.00 stddev:2.51 (1.5%) stddev-sans-first:2.49 19:20:29 INFO - PID 2320 | Values: 166.0 161.0 163.0 163.0 165.0 162.0 161.0 162.0 163.0 169.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#3] audio-fft Cycles:10 Average:94.40 Median:75.00 stddev:39.67 (52.9%) stddev-sans-first:41.63 19:20:29 INFO - PID 2320 | Values: 78.0 75.0 75.0 165.0 77.0 78.0 74.0 73.0 174.0 75.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:79.00 stddev:25.01 (31.7%) stddev-sans-first:25.97 19:20:29 INFO - PID 2320 | Values: 80.0 78.0 135.0 79.0 79.0 79.0 125.0 80.0 79.0 132.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:114.00 stddev:2.96 (2.6%) stddev-sans-first:3.11 19:20:29 INFO - PID 2320 | Values: 114.0 114.0 113.0 113.0 119.0 114.0 114.0 114.0 122.0 114.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:1.32 (0.9%) stddev-sans-first:1.36 19:20:29 INFO - PID 2320 | Values: 147.0 147.0 148.0 146.0 147.0 148.0 148.0 147.0 150.0 150.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 19:20:29 INFO - PID 2320 | Values: 94.0 94.0 94.0 95.0 94.0 93.0 95.0 94.0 94.0 94.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.00 stddev:1.55 (2.9%) stddev-sans-first:1.64 19:20:29 INFO - PID 2320 | Values: 53.0 53.0 54.0 52.0 52.0 54.0 52.0 57.0 52.0 53.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#9] json-stringify-tinderbox Cycles:10 Average:38.00 Median:38.00 stddev:0.47 (1.2%) stddev-sans-first:0.50 19:20:29 INFO - PID 2320 | Values: 38.0 37.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 39.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:74.00 stddev:1.32 (1.8%) stddev-sans-first:0.93 19:20:29 INFO - PID 2320 | Values: 77.0 76.0 74.0 74.0 74.0 73.0 74.0 73.0 74.0 73.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:124.00 stddev:16.77 (13.5%) stddev-sans-first:17.61 19:20:29 INFO - PID 2320 | Values: 120.0 125.0 118.0 118.0 115.0 121.0 125.0 123.0 128.0 173.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.90 Median:174.00 stddev:4.61 (2.6%) stddev-sans-first:4.75 19:20:29 INFO - PID 2320 | Values: 178.0 170.0 182.0 173.0 173.0 183.0 173.0 171.0 171.0 175.0 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:64.50 stddev:2.15 (3.3%) stddev-sans-first:2.26 19:20:29 INFO - PID 2320 | Values: 64.0 67.0 63.0 64.0 65.0 68.0 63.0 68.0 62.0 64.0 19:20:29 INFO - PID 2320 | -------- Summary: end -------- 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | 19:20:29 INFO - PID 2320 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 19:20:29 INFO - PID 2320 | 19:20:30 INFO - PID 2320 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:20:30 INFO - TEST-INFO | 2320: exit 0 19:20:30 INFO - TEST-OK | kraken | took 61638ms 19:20:30 INFO - SUITE-END | took 1173s 19:20:30 INFO - Completed test suite (00:19:33) 19:20:30 INFO - javascript benchmark 19:20:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8618.389794014769, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.7011368976796, 3321.3357328534285, 3325.2850637113015, 3339.949900751489, 3340.248444100076, 6264.123022776815, 6311.558190926637, 6345.904811427827, 6359.650219237946, 6367.617942923428, 6176.567640265183, 6269.435750782429, 6270.18487596613, 6270.937290627093, 6286.057091436287, 5753.309602847664, 5787.826365209029, 5793.536517078624, 5817.767289308423, 5848.385919478457, 5776.740046697888, 5784.2769653793275, 5812.564057695668, 5840.0947853082625, 5890.087036509348, 5842.32813226478, 5898.967680655889, 5899.46904778572, 5919.467247947684, 5939.7921072762465, 5667.886642267132, 5733.168690539848, 5743.9425605744245, 5816.447437493432, 5823.097419899922, 5515.61390702651, 5719.428057194289, 5753.137029445575, 5794.768209271624, 5881.735321910524, 6240.469560087398, 6301.7164227609865, 6378.011408231731, 6384.776532821329, 6391.520635952275, 6601.075849381091, 6657.0, 6684.966575167142, 6692.665366731644, 6703.162104736908, 6795.966020169867, 6797.354251346114, 6819.113515242987, 6820.215675197366, 6820.6930688118655, 6271.027990661501, 6277.0, 6288.528360372945, 6290.0, 6353.078803573456, 6917.446604271747, 6934.653267336613, 6964.616946068015, 6974.895376569356, 7050.506464547535, 6606.735730570819, 6617.172853393326, 6663.966680166568, 6699.363560461748, 6719.697613607399, 6659.034440006172, 6730.798076057726, 6736.427403670645, 6745.325467453215, 6871.690773915086, 6628.304028077079, 6652.634105124264, 6657.667116644148, 6670.599764014175, 6679.666016699146, 6810.046593476917, 6833.931660683429, 6837.726490940307, 6867.107276054081, 6868.862622747517, 5333.679979201261, 5399.595030372738, 5408.378036525771, 5409.621326507186, 5416.2688037114485, 4635.837745678885, 4646.535346465326, 4693.976530117396, 4721.787519561628, 4752.0, 5913.083472061838, 5925.9407405925385, 5935.228420305332, 5935.821925342247, 5958.761649534057, 6780.525363224527, 6831.521793474409, 6834.829129271709, 6843.7604683835825, 6846.965765171142, 5992.610480318765, 6034.336223015464, 6040.395960403925, 6041.154238406627, 6046.244219472566, 5830.0, 5839.562032847553, 5918.141869428909, 5920.911186332209, 5928.992071347795, 6568.671566421755, 6569.967150164219, 6581.736730530821, 6670.433013193836, 6678.632675202911, 6809.2509823918335, 6834.897476537756, 6835.760748373783, 6866.210385805696, 6870.759523416857, 6561.934380656132, 6566.9014964774615, 6667.233268174222, 6667.933320666732, 6675.032120342426, 6622.801315960529, 6670.299618539983, 6717.697703603448, 6737.225219099866, 6765.42493888025, 6665.566738162004, 6742.6291553965, 6746.123004009643, 6752.493562982698, 6874.793756187322, 6467.579607325509, 6479.546431749733, 6516.283208846929, 6589.27517973013, 6607.96696016536, 6539.771108011197, 6603.735850565923, 6614.404703576702, 6625.304343044104, 6649.301823308677, 6480.805575832733, 6590.736370545316, 6621.039949207434, 6621.304763000008, 6622.1722284714415, 6596.109525214035, 6622.834429139118, 6712.966435167793, 6718.462522998246, 6733.293004234485, 6336.746530138743, 6381.1704478417505, 6388.4569811566535, 6451.516136289891, 6460.25707043696], "value": 6191.543294239619, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.184050428059, 865.9393842431031, 887.4941283468423, 894.5214310343963, 1106.789709955109, 29578.0, 39211.607883921155, 39888.60111398892, 39963.80018099905, 40069.39895901564, 39471.0, 39865.20269594606, 39981.40027899584, 40045.599544004624, 40057.0, 7525.548467091965, 7594.164641889388, 7714.421418393615, 7737.845243095135, 7739.5356278623185, 7617.5810330431805, 7731.188225236354, 7732.806679832993, 7733.342665873408, 7736.2650548197835, 7672.194419585947, 7705.0, 7718.768436946901, 7759.806004849867, 7762.495437796553, 7654.885176722353, 7703.268189521987, 7708.421868359896, 7708.961455192744, 7711.3059824615775, 7551.433642476782, 7656.770296891046, 7660.425468089916, 7670.3480204182715, 7679.961600192019, 36839.63160368389, 37101.44347834784, 37119.0, 37137.814310928276, 37144.44283335752, 3419.3674170278578, 3446.4313388290902, 3447.3967055765393, 3448.0862571418343, 3448.81031543265, 30215.848920755474, 30334.48327583634, 30387.0, 30437.23906902323, 30502.084937451913, 4846.878828029292, 5051.26756620288, 5083.389993200803, 5131.1277082895995, 5163.122269214243, 182666.0, 182879.0, 183762.0, 184542.0, 184666.0, 47607.523924761, 47634.76182619134, 47656.28515572269, 47793.044139117024, 47820.521794782304, 50168.247476287885, 50556.494435055916, 50562.0, 50597.49402505928, 50605.0, 12565.371731413576, 14653.853461465462, 14937.850621493862, 15030.774538381933, 15074.924625376803, 10334.844977325347, 11098.223124381217, 11279.774404511863, 11290.604828830952, 11305.26515776472, 13978.44086236539, 13986.440542378394, 13988.650283742987, 14024.579262622137, 14141.787873181911, 5690.174924635905, 5690.231818704505, 5744.712764361765, 5812.157237200582, 5846.7076646167525, 969.9854502182473, 972.3825370889537, 989.2531138990161, 991.7223177510309, 1006.7281833904805, 22080.558388832134, 22184.778152218594, 22286.108555657916, 22365.105395783987, 22370.0, 1083.5882364701363, 1087.2932593813925, 1088.7822435512771, 1098.3135540287321, 1100.7908497385472, 11763.470643821049, 11846.644600661994, 11878.287302761863, 11885.286882786888, 11900.642980710592, 222645.0, 223255.0, 223384.0, 224732.0, 225910.0, 50349.244761328606, 50828.0, 50961.0, 51991.74004129955, 53315.20027199595, 1372.4167228927665, 1394.853540378266, 1399.7410479061202, 1401.0262867307124, 1408.8732014118777, 26024.60963085593, 26364.472710546066, 26442.603360949986, 26876.865615672577, 27604.585931211448, 48677.75661121672, 48781.0, 48850.267245990624, 48880.51119488759, 48964.75517622389, 196441.0, 197535.0, 197742.0, 197886.0, 198228.0, 33322.33355332831, 34466.0, 34954.30091398107, 35098.473522897686, 35124.473132902516, 42336.576634233264, 42991.785041074596, 43630.12739745124, 43655.78172109119, 43852.0, 32004.679953201103, 32205.838970804994, 32217.51673724849, 32714.0, 32927.83536082304, 32198.678013220506, 32247.838760806048, 32496.187595310308, 32653.673463265062, 32937.34125317432, 36002.27995440129, 36440.81779591085, 36508.634913651586, 36630.08424789402, 36941.0, 30807.38385232238, 31088.844555777076, 31400.842995784875, 31734.365312693157, 31903.0, 23628.763712362655, 23789.286321410385, 23802.880985594962, 23830.761692382854, 24144.275671729876], "value": 17026.54055466819, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.0789213581863, 566.2921348314607, 569.9230603868477, 571.5142129190186, 574.1445246582591, 21150.153993840246, 21851.562968740614, 21871.562568748617, 21909.233176838814, 21943.6708449373, 24327.0, 25241.36896577582, 26455.206343809667, 26645.200643980712, 26772.59841102385, 6808.285130061359, 6949.305069493061, 6957.408620267258, 6963.651817409134, 6971.05890704756, 6594.109795177662, 6955.130608673916, 6975.825604359881, 6992.6154061526595, 7000.29997000301, 6916.23921368649, 6931.861362772742, 6940.340667636567, 6947.58314501129, 6949.617771022617, 6946.756863509778, 6956.026156338066, 6959.373656370925, 6963.05998690178, 6967.581945083311, 6793.184817821843, 6801.4898882584, 6811.591304521745, 6824.863502729967, 6829.3170682931805, 29613.259668508243, 30577.541336879964, 31145.22136946572, 31327.37345253105, 31546.211344716565, 2100.8949552522313, 2143.185589475989, 2145.9034343454427, 2147.119680930811, 2147.656374980011, 19886.20455181791, 20497.0, 20505.8974705127, 20507.077181526867, 20531.48671283215, 2475.5296493666146, 2492.090387008747, 2540.5427023135826, 2662.041665000598, 2671.131882138313, 130261.34869325593, 133716.99999999904, 133994.0, 134244.0, 135799.0, 19865.03374156461, 21286.0, 21329.480635694938, 21481.462963425725, 22088.558228835333, 40548.79725601412, 41158.79420602938, 41205.1758964825, 41221.38167927483, 41232.587674123475, 10352.689419317432, 10475.266731328733, 10609.734756631146, 10666.520006599567, 10916.0, 8598.269147122564, 8633.827323453495, 8727.694530691522, 8752.21230089295, 8788.648454061766, 11162.832557511643, 11229.943850280697, 11310.660680179608, 11497.252678575866, 11568.479418426192, 3796.335641262768, 3921.4706264556844, 3985.9202815943518, 4096.201240758023, 4353.49934757507, 961.1637875048752, 976.682578162086, 983.8917719050899, 987.0968064221157, 1007.3804610164696, 19378.70931935994, 19642.21431142767, 19943.60112797736, 20169.0, 20255.18979240814, 1024.6977141743203, 1027.1474676018886, 1028.6451174344838, 1041.802057609067, 1045.6235755128148, 10840.241183117263, 11033.503492342863, 11063.72340691489, 11083.556657733601, 11094.833577496343, 450122.0, 450821.0, 452609.0, 456919.0, 460047.0, 104765.0, 105630.0, 106381.0, 107534.46232768943, 108665.45667271613, 1148.5520646947698, 1155.5782139519183, 1189.6193218170276, 1189.6966273600176, 1197.0842305636195, 17131.486055418358, 17357.0, 17779.911100444417, 17925.46223613294, 18002.009889456207, 52679.20981185209, 52732.47267527276, 54100.45899540954, 54969.72515137399, 55479.0, 63759.681201593696, 64370.67814660897, 64399.67800160969, 65981.67009164924, 76116.097097573, 12557.874421256036, 12976.416061277076, 14387.42450302018, 14518.20149891766, 14916.477923272634, 23231.53536929286, 23461.76538234596, 23646.17238396717, 24241.39396515101, 24390.0, 17889.821101789337, 18140.637187255918, 18142.818571814114, 18145.27418903229, 19118.0, 15265.0, 15361.769573456184, 16030.919845400698, 16334.101624410772, 16360.100194488941, 18722.15750291213, 19480.415587532396, 19592.80407195967, 19645.607087858447, 19763.0, 14395.496157634432, 14556.92721536428, 14558.85441145575, 14562.854371456573, 14698.338574763948, 18362.357317493825, 18481.630367392845, 18497.0, 18527.81472185261, 18628.254869805056], "value": 12542.37296545566, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.9801252981206, 1348.2315080404173, 1353.7427888701145, 1359.4494229836919, 1380.675541247807, 14719.411223551071, 14802.407903683854, 15420.383184672613, 15446.61383465414, 15473.76789348158, 14887.0, 15236.390544378211, 15246.237688115578, 15247.847521524782, 15268.312925918333, 2740.6848212455625, 2795.496810574106, 2796.566532187514, 2797.8181418207855, 2798.3563780330537, 2648.8543130127837, 2679.8660066996667, 2714.1043455659683, 2799.426117645888, 2804.2708895687065, 2724.5776904579725, 2729.290384500034, 2768.5847122931623, 2771.598118272849, 2772.88908443662, 2647.6558047453805, 2657.4153686189106, 2658.029819116028, 2660.9068682596017, 2673.25148958292, 2614.8954041838306, 2640.6699162604673, 2654.694710108343, 2656.53510635638, 2675.0637276953107, 14447.494337698236, 14518.419263229458, 14572.198529080897, 14591.92704036473, 14598.489052883204, 7586.241375862425, 7893.092294386104, 7901.486403383819, 7950.681972721084, 7956.085050219183, 5171.870703232412, 5291.550218231455, 5495.752691128909, 5537.1140617501405, 5542.057850165482, 1854.6012607289497, 1899.9905000475048, 1923.7787654419642, 1944.3097700316325, 1949.541857663448, 24954.750452495424, 25668.614970775452, 25677.48645027089, 25719.22842314715, 25725.485490290088, 6995.475339349569, 7034.222391752928, 7132.215456299803, 7269.418446524265, 7303.123625165014, 16958.152092395576, 16971.91514042422, 17002.574935626708, 17148.485545433658, 17149.657006859794, 7469.215732348134, 7553.924460755432, 7587.772366829004, 7587.962060189664, 7595.734149304748, 6808.2170550386345, 6946.0622815920215, 6963.199232088274, 7055.2944705529035, 7064.152301723723, 4703.17694403478, 4954.603631709455, 5169.327987361619, 5205.401378841481, 5206.167013277857, 1422.333125389894, 1434.211183848879, 1463.8462961389114, 1479.2455847517842, 1500.5423345879612, 887.8579427291731, 896.0859922878758, 902.3412908576645, 905.2893478619252, 920.1902325953118, 8087.878681819778, 8350.540720260322, 8368.20502052304, 8844.513551754715, 8959.641614335354, 968.162539403411, 992.3103443107116, 999.3753903810118, 1001.8547310639916, 1002.04304888831, 5194.4805519447755, 5196.376434827845, 5290.81482148123, 5335.0, 5370.436104209002, 19925.501862453264, 20494.590108197754, 20664.690029649566, 20750.792492075187, 20768.169273229203, 20127.0, 20435.489112772004, 20863.582728345653, 20907.58184836295, 20912.37262882116, 11103.72240693989, 11250.774984500264, 11322.867713228612, 11380.317180969003, 11385.999999999834, 8161.877571836546, 8313.0, 8343.290820280345, 8431.28334091609, 8478.364122690946, 17745.29018839232, 17842.28630854751, 17978.910105449388, 18197.45407637773, 18543.165557549673, 18140.72788908141, 18189.727154092965, 18517.0, 18528.980906050296, 18658.160382782535, 11606.24559403636, 12005.459754311252, 12100.818487722867, 12101.878981210075, 12106.213096148722, 12037.157398981988, 12619.432125554556, 12702.682432939251, 12710.618681439571, 12745.49018039268, 10446.947765261126, 10521.947390263, 10539.57841686324, 10761.085307748928, 10790.73023174427, 10392.324498907547, 10408.0, 10485.266031378036, 10623.256372053882, 10873.619423320146, 9958.302918795904, 10071.647492337734, 10074.294799363974, 10080.798384032425, 10119.089281964658, 7288.08898887639, 7293.671784769805, 7300.26997300287, 7314.926850731425, 7371.226021267689, 7660.195679453577, 7719.575423351769, 7743.264389882952, 7763.378929685725, 7885.44801863864], "value": 6611.291802012663, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.23963575365457, 503.39592489013194, 517.7695925313236, 521.6635270250688, 529.3779808724747, 6274.84312892178, 6302.495800335973, 6336.930455635496, 6390.041493775936, 6450.741970321182, 6257.181969883187, 6336.081268216106, 6338.619682819034, 6345.587536810116, 6350.396712312335, 4950.207966725325, 5142.3829140503, 5192.299039629658, 5244.711540865251, 5261.368635763704, 5102.974485127569, 5128.923066153992, 5134.794608215667, 5141.58345577578, 5190.506901844319, 5048.974755126237, 5111.469822437898, 5114.309568208281, 5127.769250383742, 5131.794728210867, 5072.594192464631, 5076.086304465195, 5110.616703747197, 5120.308758317617, 5127.692338459686, 4574.496805351442, 4611.123886461595, 4618.445786505607, 4623.42207224097, 4626.444826620794, 6275.780347687809, 6308.968455157741, 6323.241211054703, 6357.809265722036, 6391.360863913618, 5648.830535083954, 5702.3157221133315, 5786.884262314772, 5816.302043754777, 5832.358440571534, 6750.054992301082, 6771.661416929134, 6793.0, 6814.114165158548, 6844.4182244509275, 4939.851804445872, 4941.209406494943, 4966.528179822911, 4985.326980857574, 5011.498850114996, 6730.427913627396, 6823.420009299216, 6971.58170509771, 7034.753783617549, 7045.260247674023, 5056.696598204119, 5057.6459647824295, 5084.949150508522, 5091.8981620367385, 5106.642535022513, 5009.949900500948, 5134.024535338273, 5152.355955505562, 5169.896602067937, 5172.5603323717905, 5728.914066289009, 5745.770169193269, 5762.538996880239, 5779.942200578024, 5784.582777219608, 6724.831879202961, 6749.358810912955, 6753.324667533306, 6792.864142717118, 6838.384545390939, 1793.9461816145536, 1798.651011741194, 1802.5763945472804, 1802.8107048759691, 1810.4025671528627, 1164.4759858063906, 1211.4730092409654, 1238.504598160725, 1242.1739543203669, 1256.5790460195938, 5906.084556893602, 5946.313285762768, 6031.909521357183, 6054.576179667469, 6059.969700151471, 6780.593164410053, 6786.321367863175, 6947.263184203949, 6967.756128535477, 6991.300869912968, 5942.168096466498, 5951.369664504648, 5994.250718660168, 5994.670293133832, 6010.789622363196, 3645.3438381091128, 3701.777893326409, 3703.5555733312176, 3726.757760745543, 3728.776273423549, 6621.668916554153, 6712.865742685119, 6724.696194435565, 6789.117414736152, 6818.727250909908, 6545.639989800607, 6979.267176946449, 6989.685464154125, 7047.471439642048, 7101.325374089453, 6563.9343606563325, 6649.202095748445, 6677.527685603459, 6683.4319082876555, 6714.395704386531, 6818.318168183142, 6905.516613836983, 6921.204061532787, 6938.23679395256, 6978.930210698031, 6756.0, 6810.965945170243, 6903.240643529108, 6904.93095069063, 6908.0, 6622.403983641495, 6642.4021838034805, 6667.166604174478, 6684.4318232950745, 6715.865682686416, 5054.494550544916, 5062.848114556569, 5079.212722028092, 5095.847124586268, 5121.8975620486635, 6616.603003819786, 6707.8993815091835, 6729.663516824237, 6777.423918966746, 6916.100906882073, 6539.967300163468, 6621.556790469025, 6649.63427011519, 6658.334166583303, 6701.932980670326, 6632.734690612515, 6663.46692264608, 6681.29846366144, 6685.498587605852, 6739.5619284746335, 6583.531175809072, 6609.405153536205, 6630.403263706289, 6652.401283884474, 6694.83262918431, 6722.394984451423, 6758.831029224309, 6800.829979250559, 6805.353491418307, 6811.863762724817, 6554.770583029571, 6568.0, 6577.671116444254, 6601.570897891622, 6620.536562440583], "value": 5176.941377046474, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.1143164682494, 877.7761670773953, 885.7475619448454, 896.6368620708615, 898.2320116300563, 16475.588110297234, 17242.741358879626, 17317.39389121381, 19565.804341956577, 21429.464263393424, 19676.606467870635, 19723.901380493113, 20325.0, 20327.695084573745, 20522.48693782656, 923.4551614547399, 947.313197931501, 952.0146648219095, 952.119289657068, 953.2374100719431, 922.064104933493, 942.7548837302317, 964.9421034737903, 967.351874244258, 970.0687340153461, 1000.2598077422691, 1000.9199264058858, 1001.6744558018637, 1002.8245057115012, 1003.2074661017787, 996.9850452243186, 997.0577803975247, 997.9850302245472, 998.7503124218945, 1000.1848493477829, 736.4145504324075, 738.0921466596063, 738.2617382617383, 739.0909181706477, 739.2644318902726, 19043.238270469166, 19092.23631054756, 19476.4157075288, 19567.412977610693, 19590.412287631392, 21113.5777284455, 21135.5772884543, 21511.0, 21606.567868642695, 21612.891935540378, 15866.682666346724, 15969.36122555108, 16022.839771602252, 16271.186440678037, 16296.674066518603, 3731.9253614927547, 3784.432335149722, 3794.7153963452574, 3795.7532760370486, 3795.9240815183816, 130846.0, 130885.0, 133990.0, 134202.999999999, 135627.0, 16004.679906401807, 16088.115153666427, 16099.356025759073, 16435.26041328143, 16544.586385340222, 29268.7073129266, 29653.406931861242, 29889.850550747105, 29938.85030574877, 30132.24669383283, 8537.786555336043, 8595.0, 8598.355123365647, 8653.480791152551, 8664.046954834963, 4178.749275043507, 4353.434053573015, 4392.450943632046, 4436.933445998313, 4550.385697930737, 5099.0, 5204.76578553966, 5225.4774522548205, 5228.346456692913, 5235.607329450307, 975.9024097590184, 1005.7686732051669, 1015.9847602285971, 1019.021739130443, 1021.371856308365, 691.4917535611322, 699.1679900917892, 702.0732632924593, 703.3529153178986, 704.1444644756646, 11052.889471105347, 11952.0, 11975.0, 12883.294508153205, 13040.673983150496, 689.7344522358928, 693.6531734132933, 695.3811108113783, 716.451914285572, 718.0234880562431, 5644.85887852807, 5687.488126068673, 5746.798862039808, 5834.679092649945, 5879.676617785977, 111256.0, 111757.0, 113970.43014785039, 114164.42917785358, 117416.0, 97506.5124674372, 97968.0, 98890.0, 100474.0, 101426.0, 82585.58707206546, 84398.0, 85557.57221213855, 86858.56570717233, 87945.1205487937, 11436.599719009795, 11887.28676279426, 11912.821307680219, 12019.75960480768, 12744.49022039108, 38397.0, 40224.0, 40552.59447405488, 41271.1745765077, 42121.578784212994, 67831.66084169745, 71833.6408317955, 72343.63828181036, 74768.0, 77162.61418692871, 16778.1610919456, 18293.0, 18543.907280464053, 18567.535811604277, 18603.2558697656, 17740.645187096445, 17957.820421796136, 18041.09794510243, 18132.365367212085, 18338.81661183371, 17822.287108516033, 18491.537711557317, 18649.254029839198, 18896.0, 18963.241470341032, 15427.61430964235, 15542.378304867678, 17295.221715023104, 17846.28614855443, 18063.729044064086, 17553.473395798148, 17623.0, 18248.543786404916, 18326.08369581542, 18513.0, 11564.884351156717, 12845.42195601219, 13157.671058223621, 13201.867981320063, 13587.388567514288, 13643.658908527366, 13661.86338136606, 14173.574792756233, 14562.41750329975, 15006.549803505912], "value": 9054.546741973392, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1484.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 107.0, 95.0, 107.0, 107.0, 110.0, 112.0, 107.0, 107.0, 107.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 141.0, 164.0, 117.0, 142.0, 148.0, 121.0, 164.0, 120.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 161.0, 163.0, 163.0, 165.0, 162.0, 161.0, 162.0, 163.0, 169.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 75.0, 165.0, 77.0, 78.0, 74.0, 73.0, 174.0, 75.0], "value": 94.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 135.0, 79.0, 79.0, 79.0, 125.0, 80.0, 79.0, 132.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 113.0, 119.0, 114.0, 114.0, 114.0, 122.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 146.0, 147.0, 148.0, 148.0, 147.0, 150.0, 150.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 93.0, 95.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 52.0, 52.0, 54.0, 52.0, 57.0, 52.0, 53.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0], "value": 38.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 74.0, 74.0, 74.0, 73.0, 74.0, 73.0, 74.0, 73.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 125.0, 118.0, 118.0, 115.0, 121.0, 125.0, 123.0, 128.0, 173.0], "value": 126.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 170.0, 182.0, 173.0, 173.0, 183.0, 173.0, 171.0, 171.0, 175.0], "value": 174.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 63.0, 64.0, 65.0, 68.0, 63.0, 68.0, 62.0, 64.0], "value": 64.8, "unit": "score"}]}]} 19:20:30 INFO - Return code: 0 19:20:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:20:30 INFO - # TBPL SUCCESS # 19:20:30 INFO - Running post-action listener: _package_coverage_data 19:20:30 INFO - Running post-action listener: _resource_record_post_action 19:20:30 INFO - [mozharness: 2017-08-27 02:20:30.987341Z] Finished run-tests step (success) 19:20:30 INFO - Running post-run listener: _resource_record_post_run 19:20:31 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 12288; Write bytes: 434180096; Read time: 280; Write time: 223088 19:20:31 INFO - TinderboxPrint: CPU usage
13.1% 19:20:31 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 280 19:20:31 INFO - TinderboxPrint: I/O write bytes / time
434,180,096 / 223,088 19:20:31 INFO - TinderboxPrint: CPU idle
8,250.4 (86.9%) 19:20:31 INFO - TinderboxPrint: CPU user
1,196.7 (12.6%) 19:20:31 INFO - TinderboxPrint: Swap in / out
0 / 0 19:20:31 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 110772224; Read time: 0; Write time: 80420 19:20:31 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:20:31 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8192; Write bytes: 322850816; Read time: 272; Write time: 142664 19:20:31 INFO - Running post-run listener: _upload_blobber_files 19:20:31 INFO - Blob upload gear active. 19:20:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:20:31 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 19:20:31 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'] 19:20:31 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 19:20:32 INFO - (blobuploader) - INFO - Open directory for files ... 19:20:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:20:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:20:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:32 INFO - (blobuploader) - INFO - Done attempting. 19:20:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:20:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:20:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:33 INFO - (blobuploader) - INFO - Done attempting. 19:20:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:20:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:20:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:40 INFO - (blobuploader) - INFO - Done attempting. 19:20:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:20:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:20:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:41 INFO - (blobuploader) - INFO - Done attempting. 19:20:41 INFO - (blobuploader) - INFO - Iteration through files over. 19:20:41 INFO - Return code: 0 19:20:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:20:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:20:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6febbab18d6b3e8832f96ee287140d9a2b1f3f6247e20a2133a7e018e245116e90f813443c010ccdb77d104e21b58386390163879a925e94eb3c0977816731d1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b9c0c9d400478320d2ac17e7065e13d797260a7294c7b60d96c1a143ec2a19c88d9b247cdf8528230c6e39c8042347b73e54ca9e25c5c117f053f4bea0c214e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ce4097862a51b1dbed88c1790ced980a1cf7dda98f3dfdf9852eb090398da215f7689f6b4743ab0d21b2c3ff2b0e9458f5d039b53aa055888abbeffb174f3ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76b86addc4a73b59a8ea919b6e7a0d0beb604025829583dffd8543f4e2af172f16d3cd34642a08822a9578d40f451318363f6599ec7e42a34ebd7126b0a7accc"} 19:20:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:20:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:20:41 INFO - Contents: 19:20:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6febbab18d6b3e8832f96ee287140d9a2b1f3f6247e20a2133a7e018e245116e90f813443c010ccdb77d104e21b58386390163879a925e94eb3c0977816731d1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b9c0c9d400478320d2ac17e7065e13d797260a7294c7b60d96c1a143ec2a19c88d9b247cdf8528230c6e39c8042347b73e54ca9e25c5c117f053f4bea0c214e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ce4097862a51b1dbed88c1790ced980a1cf7dda98f3dfdf9852eb090398da215f7689f6b4743ab0d21b2c3ff2b0e9458f5d039b53aa055888abbeffb174f3ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76b86addc4a73b59a8ea919b6e7a0d0beb604025829583dffd8543f4e2af172f16d3cd34642a08822a9578d40f451318363f6599ec7e42a34ebd7126b0a7accc"} 19:20:41 INFO - Running post-run listener: copy_logs_to_upload_dir 19:20:41 INFO - Copying logs to upload dir... 19:20:41 INFO - mkdir: /builds/slave/test/build/upload/logs 19:20:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.984944 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-08-26 19:20:41.568882) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-26 19:20:41.572055) ========= 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=483e410453545d07cca6935c000001ab-1503799142.311142-1854577332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6febbab18d6b3e8832f96ee287140d9a2b1f3f6247e20a2133a7e018e245116e90f813443c010ccdb77d104e21b58386390163879a925e94eb3c0977816731d1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b9c0c9d400478320d2ac17e7065e13d797260a7294c7b60d96c1a143ec2a19c88d9b247cdf8528230c6e39c8042347b73e54ca9e25c5c117f053f4bea0c214e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ce4097862a51b1dbed88c1790ced980a1cf7dda98f3dfdf9852eb090398da215f7689f6b4743ab0d21b2c3ff2b0e9458f5d039b53aa055888abbeffb174f3ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76b86addc4a73b59a8ea919b6e7a0d0beb604025829583dffd8543f4e2af172f16d3cd34642a08822a9578d40f451318363f6599ec7e42a34ebd7126b0a7accc"} build_url:https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011395 build_url: 'https://queue.taskcluster.net/v1/task/W6jm1G_FQuiUetQk6AdeiA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6febbab18d6b3e8832f96ee287140d9a2b1f3f6247e20a2133a7e018e245116e90f813443c010ccdb77d104e21b58386390163879a925e94eb3c0977816731d1", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b9c0c9d400478320d2ac17e7065e13d797260a7294c7b60d96c1a143ec2a19c88d9b247cdf8528230c6e39c8042347b73e54ca9e25c5c117f053f4bea0c214e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2ce4097862a51b1dbed88c1790ced980a1cf7dda98f3dfdf9852eb090398da215f7689f6b4743ab0d21b2c3ff2b0e9458f5d039b53aa055888abbeffb174f3ae", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76b86addc4a73b59a8ea919b6e7a0d0beb604025829583dffd8543f4e2af172f16d3cd34642a08822a9578d40f451318363f6599ec7e42a34ebd7126b0a7accc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-26 19:20:41.599136) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:20:41.599460) ========= 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=483e410453545d07cca6935c000001ab-1503799142.311142-1854577332 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:20:41.683599) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-26 19:20:41.683907) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-26 19:20:41.684194) ========= ========= Total master_lag: 0.23 =========