builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-077 starttime: 1504431783.27 results: success (0) revision: 59db725def8282e1d77e83f002d247c7d0f95237 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.268027) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.270633) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.270919) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.431878) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.432188) ========= 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=20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-03 02:43:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.8M=0s 2017-09-03 02:43:03 (46.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146471 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.667946) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.668333) ========= 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=20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.076980 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 02:43:03.821665) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 57 secs) (at 2017-09-03 02:43:03.821980) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 59db725def8282e1d77e83f002d247c7d0f95237 --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 59db725def8282e1d77e83f002d247c7d0f95237 --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=20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-03 02:43:03,986 truncating revision to first 12 chars 2017-09-03 02:43:03,986 Setting DEBUG logging. 2017-09-03 02:43:03,986 attempt 1/10 2017-09-03 02:43:03,986 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/59db725def82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-03 02:43:04,864 attempt 1/10 2017-09-03 02:43:05,836 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:05,836 sleeping for 11.00s (attempt 1/10) 2017-09-03 02:43:16,847 attempt 2/10 2017-09-03 02:43:17,500 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:17,500 sleeping for 17.50s (attempt 2/10) 2017-09-03 02:43:35,018 attempt 3/10 2017-09-03 02:43:35,548 current task status: no status available at this point. state: PENDING 2017-09-03 02:43:35,549 sleeping for 25.25s (attempt 3/10) 2017-09-03 02:44:00,824 attempt 4/10 2017-09-03 02:44:01,164 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-03 02:44:01,471 unpacking tar archive at: mozilla-central-59db725def82/testing/mozharness/ program finished with exit code 0 elapsedTime=57.789604 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 57 secs) (at 2017-09-03 02:44:01.644072) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:01.647503) ========= script_repo_revision: 59db725def8282e1d77e83f002d247c7d0f95237 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:01.647966) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:01.648256) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 02:44:01.661607) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-09-03 02:44:01.661987) ========= /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=20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:44:02 INFO - MultiFileLogger online at 20170903 02:44:02 in /builds/slave/test 02:44:02 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 02:44:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:44:02 INFO - {'append_to_log': False, 02:44:02 INFO - 'base_work_dir': '/builds/slave/test', 02:44:02 INFO - 'blob_upload_branch': 'Firefox', 02:44:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:44:02 INFO - 'branch': 'Firefox', 02:44:02 INFO - 'buildbot_json_path': 'buildprops.json', 02:44:02 INFO - 'code_coverage': False, 02:44:02 INFO - 'config_files': ('talos/linux_config.py',), 02:44:02 INFO - 'default_actions': ('clobber', 02:44:02 INFO - 'read-buildbot-config', 02:44:02 INFO - 'download-and-extract', 02:44:02 INFO - 'populate-webroot', 02:44:02 INFO - 'create-virtualenv', 02:44:02 INFO - 'install', 02:44:02 INFO - 'setup-mitmproxy', 02:44:02 INFO - 'run-tests'), 02:44:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:44:02 INFO - 'disable_ccov_upload': False, 02:44:02 INFO - 'disable_stylo': False, 02:44:02 INFO - 'download_minidump_stackwalk': True, 02:44:02 INFO - 'download_symbols': 'ondemand', 02:44:02 INFO - 'enable_stylo': False, 02:44:02 INFO - 'enable_webrender': False, 02:44:02 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:44:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:44:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:44:02 INFO - 'gecko_profile': False, 02:44:02 INFO - 'gecko_profile_interval': 0, 02:44:02 INFO - 'installer_path': 'installer.exe', 02:44:02 INFO - 'log_level': 'info', 02:44:02 INFO - 'log_name': 'talos', 02:44:02 INFO - 'log_to_console': True, 02:44:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:44:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:44:02 INFO - 'opt_config_files': (), 02:44:02 INFO - 'pip_index': False, 02:44:02 INFO - 'suite': 'dromaeojs-e10s', 02:44:02 INFO - 'system_bits': '32', 02:44:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:44:02 INFO - 'title': 'talos-linux64-ix-077', 02:44:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:44:02 INFO - 'use_talos_json': True, 02:44:02 INFO - 'verify': 'False', 02:44:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:44:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:44:02 INFO - 'work_dir': 'build'} 02:44:02 INFO - [mozharness: 2017-09-03 09:44:02.043090Z] Running clobber step. 02:44:02 INFO - Running pre-action listener: _resource_record_pre_action 02:44:02 INFO - Running main action method: clobber 02:44:02 INFO - rmtree: /builds/slave/test/build 02:44:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:44:07 INFO - Running post-action listener: _resource_record_post_action 02:44:07 INFO - [mozharness: 2017-09-03 09:44:07.570028Z] Finished clobber step (success) 02:44:07 INFO - [mozharness: 2017-09-03 09:44:07.570194Z] Running read-buildbot-config step. 02:44:07 INFO - Running pre-action listener: _resource_record_pre_action 02:44:07 INFO - Running main action method: read_buildbot_config 02:44:07 INFO - Using buildbot properties: 02:44:07 INFO - { 02:44:07 INFO - "project": "", 02:44:07 INFO - "product": "firefox", 02:44:07 INFO - "who": "archaeopteryx@coole-files.de", 02:44:07 INFO - "installer_path": "public/build/target.tar.bz2", 02:44:07 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 02:44:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:44:07 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 02:44:07 INFO - "stage_platform": "linux64-stylo", 02:44:07 INFO - "basedir": "/builds/slave/test", 02:44:07 INFO - "buildnumber": 117, 02:44:07 INFO - "platform": "ubuntu64_hw_stylo", 02:44:07 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:44:07 INFO - "slavebuilddir": "test", 02:44:07 INFO - "taskId": "ePVL1vlYRwObdCD1sQko2w", 02:44:07 INFO - "branch": "mozilla-central", 02:44:07 INFO - "script_repo_revision": "production", 02:44:07 INFO - "revision": "59db725def8282e1d77e83f002d247c7d0f95237", 02:44:07 INFO - "slavename": "talos-linux64-ix-077", 02:44:07 INFO - "repo_path": "mozilla-central" 02:44:07 INFO - } 02:44:07 INFO - Finding installer, test and symbols from parent task. 02:44:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ePVL1vlYRwObdCD1sQko2w'}, attempt #1 02:44:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ePVL1vlYRwObdCD1sQko2w'}, attempt #1 02:44:08 INFO - Task dependencies: JG8GD8vBTLSpKotpMpyhnw 02:44:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw'}, attempt #1 02:44:08 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:08 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json 02:44:08 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip 02:44:08 INFO - Running post-action listener: _resource_record_post_action 02:44:08 INFO - [mozharness: 2017-09-03 09:44:08.924157Z] Finished read-buildbot-config step (success) 02:44:08 INFO - [mozharness: 2017-09-03 09:44:08.924373Z] Running download-and-extract step. 02:44:08 INFO - Running pre-action listener: _resource_record_pre_action 02:44:08 INFO - Running main action method: download_and_extract 02:44:08 INFO - mkdir: /builds/slave/test/build/tests 02:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:08 INFO - trying https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json 02:44:08 INFO - Downloading https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:44:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:44:09 INFO - Downloaded 989 bytes. 02:44:09 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:44:09 INFO - Using the following test package requirements: 02:44:09 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:44:09 INFO - u'common': [u'target.common.tests.zip'], 02:44:09 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:44:09 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:44:09 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:44:09 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:44:09 INFO - u'mozbase': [u'target.common.tests.zip'], 02:44:09 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:44:09 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:44:09 INFO - u'web-platform': [u'target.common.tests.zip', 02:44:09 INFO - u'target.web-platform.tests.tar.gz'], 02:44:09 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:44:09 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:44:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip 02:44:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:44:09 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip into memory 02:44:10 INFO - Content-Length response header: 55022487 02:44:10 INFO - Bytes received: 55022487 02:44:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:44:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip 02:44:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:44:21 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.common.tests.zip into memory 02:44:22 INFO - Content-Length response header: 55022487 02:44:22 INFO - Bytes received: 55022487 02:44:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.talos.tests.zip 02:44:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:44:34 INFO - Fetch https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.talos.tests.zip into memory 02:44:35 INFO - Content-Length response header: 14496650 02:44:35 INFO - Bytes received: 14496650 02:44: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')]} 02:44:36 INFO - trying https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:36 INFO - Downloading https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:44:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:44:38 INFO - Downloaded 62661590 bytes. 02:44:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:38 INFO - mkdir: /builds/slave/test/properties 02:44:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:44:38 INFO - Writing to file /builds/slave/test/properties/build_url 02:44:38 INFO - Contents: 02:44:38 INFO - build_url:https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 02:44:38 INFO - Running post-action listener: _resource_record_post_action 02:44:38 INFO - Running post-action listener: find_tests_for_verification 02:44:38 INFO - Running post-action listener: set_extra_try_arguments 02:44:38 INFO - [mozharness: 2017-09-03 09:44:38.348348Z] Finished download-and-extract step (success) 02:44:38 INFO - [mozharness: 2017-09-03 09:44:38.348576Z] Running populate-webroot step. 02:44:38 INFO - Running pre-action listener: _resource_record_pre_action 02:44:38 INFO - Running main action method: populate_webroot 02:44:38 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:44:38 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:44:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:44:38 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:44:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:44:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:38 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:38 INFO - 'talos_options': ['--enable-stylo'], 02:44:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:44:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:44:38 INFO - 'tests': ['damp', 'tps']}, 02:44:38 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:38 INFO - 'talos_options': ['--enable-stylo'], 02:44:38 INFO - 'tests': ['damp', 'tps']}, 02:44:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:44:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:44:38 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:38 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:44:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:44:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:44:38 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:38 INFO - 'talos_options': ['--enable-stylo'], 02:44:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:44:38 INFO - 'other-e10s': {'tests': ['a11yr', 02:44:38 INFO - 'ts_paint', 02:44:38 INFO - 'tpaint', 02:44:38 INFO - 'sessionrestore', 02:44:38 INFO - 'sessionrestore_many_windows', 02:44:38 INFO - 'sessionrestore_no_auto_restore', 02:44:38 INFO - 'tabpaint']}, 02:44:38 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:38 INFO - 'tests': ['a11yr', 02:44:38 INFO - 'ts_paint', 02:44:38 INFO - 'tpaint', 02:44:38 INFO - 'sessionrestore', 02:44:38 INFO - 'sessionrestore_many_windows', 02:44:38 INFO - 'sessionrestore_no_auto_restore', 02:44:38 INFO - 'tabpaint']}, 02:44:38 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:44:38 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:44:38 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:38 INFO - 'tests': ['bloom_basic_singleton']}, 02:44:38 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:38 INFO - 'tests': ['bloom_basic']}, 02:44:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:44:38 INFO - 'tsvgr_opacity', 02:44:38 INFO - 'tart', 02:44:38 INFO - 'tscrollx', 02:44:38 INFO - 'tsvg_static']}, 02:44:38 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 02:44:38 INFO - 'tests': ['tsvgx', 02:44:38 INFO - 'tsvgr_opacity', 02:44:38 INFO - 'tart', 02:44:38 INFO - 'tscrollx', 02:44:38 INFO - 'tsvg_static']}, 02:44:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:44:38 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:38 INFO - 'talos_options': ['--enable-stylo'], 02:44:38 INFO - 'tests': ['tp5o']}, 02:44:38 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:38 INFO - 'talos_options': ['--mitmproxy', 02:44:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:38 INFO - '--firstNonBlankPaint'], 02:44:38 INFO - 'tests': ['tp6_google', 02:44:38 INFO - 'tp6_youtube', 02:44:38 INFO - 'tp6_amazon', 02:44:38 INFO - 'tp6_facebook']}, 02:44:38 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:38 INFO - 'talos_options': ['--enable-stylo', 02:44:38 INFO - '--mitmproxy', 02:44:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:38 INFO - '--firstNonBlankPaint'], 02:44:38 INFO - 'tests': ['tp6_google', 02:44:38 INFO - 'tp6_youtube', 02:44:38 INFO - 'tp6_amazon', 02:44:38 INFO - 'tp6_facebook']}, 02:44:38 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:44:38 INFO - 'talos_options': ['--enable-stylo', 02:44:38 INFO - '--stylo-threads=1', 02:44:38 INFO - '--mitmproxy', 02:44:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:44:38 INFO - '--firstNonBlankPaint'], 02:44:38 INFO - 'tests': ['tp6_google', 02:44:38 INFO - 'tp6_youtube', 02:44:38 INFO - 'tp6_amazon', 02:44:38 INFO - 'tp6_facebook']}, 02:44:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:44:38 INFO - 'talos_options': ['--xperf_path', 02:44:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:38 INFO - 'tests': ['tp5n']}, 02:44:38 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 02:44:38 INFO - 'talos_options': ['--enable-stylo', 02:44:38 INFO - '--xperf_path', 02:44:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:44:38 INFO - 'tests': ['tp5n']}}} 02:44:38 INFO - Running post-action listener: _resource_record_post_action 02:44:38 INFO - [mozharness: 2017-09-03 09:44:38.367718Z] Finished populate-webroot step (success) 02:44:38 INFO - [mozharness: 2017-09-03 09:44:38.367808Z] Running create-virtualenv step. 02:44:38 INFO - Running pre-action listener: _resource_record_pre_action 02:44:38 INFO - Running main action method: create_virtualenv 02:44:38 INFO - Creating virtualenv /builds/slave/test/build/venv 02:44:38 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:44:38 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:44:38 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:44:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:44:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:44:39 INFO - Installing distribute.............................................................................................................................................................................................done. 02:44:39 INFO - Installing pip...............done. 02:44:39 INFO - Return code: 0 02:44:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:44:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:44:39 INFO - Reading from file tmpfile_stdout 02:44:39 INFO - Output received: 02:44:39 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:44:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:44:39 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')]} 02:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:39 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')]} 02:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:39 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 0x1b1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8e090>, '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 0x1e8ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f90>, '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': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', '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 02:44:39 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 02:44:39 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 02:44:39 INFO - Using env: {'DISPLAY': ':0', 02:44:39 INFO - 'HOME': '/home/cltbld', 02:44:39 INFO - 'LANG': 'en_US.UTF-8', 02:44:39 INFO - 'LANGUAGE': 'en_US:en', 02:44:39 INFO - 'LOGNAME': 'cltbld', 02:44:39 INFO - 'MAIL': '/var/mail/cltbld', 02:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:39 INFO - 'MOZ_NO_REMOTE': '1', 02:44:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:39 INFO - 'NO_EM_RESTART': '1', 02:44:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:39 INFO - 'PWD': '/builds/slave/test', 02:44:39 INFO - 'SHELL': '/bin/bash', 02:44:39 INFO - 'SHLVL': '1', 02:44:39 INFO - 'TERM': 'linux', 02:44:39 INFO - 'TMOUT': '86400', 02:44:39 INFO - 'USER': 'cltbld', 02:44:39 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:39 INFO - '_': '/tools/buildbot/bin/python'} 02:44:40 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:44:40 INFO - Downloading/unpacking pip>=1.5 02:44:40 INFO - Running setup.py egg_info for package pip 02:44:40 INFO - warning: no files found matching 'pip/cacert.pem' 02:44:40 INFO - warning: no files found matching '*.html' under directory 'docs' 02:44:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:44:40 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:44:40 INFO - Installing collected packages: pip 02:44:40 INFO - Found existing installation: pip 1.1 02:44:40 INFO - Uninstalling pip: 02:44:40 INFO - Successfully uninstalled pip 02:44:40 INFO - Running setup.py install for pip 02:44:40 INFO - warning: no files found matching 'pip/cacert.pem' 02:44:40 INFO - warning: no files found matching '*.html' under directory 'docs' 02:44:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:44:40 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:44:40 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:44:40 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:44:40 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:44:40 INFO - Successfully installed pip 02:44:40 INFO - Cleaning up... 02:44:40 INFO - Return code: 0 02:44:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:44:40 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')]} 02:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:40 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')]} 02:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:40 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 0x1b1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8e090>, '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 0x1e8ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f90>, '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': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', '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 02:44:40 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 02:44:40 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 02:44:40 INFO - Using env: {'DISPLAY': ':0', 02:44:40 INFO - 'HOME': '/home/cltbld', 02:44:40 INFO - 'LANG': 'en_US.UTF-8', 02:44:40 INFO - 'LANGUAGE': 'en_US:en', 02:44:40 INFO - 'LOGNAME': 'cltbld', 02:44:40 INFO - 'MAIL': '/var/mail/cltbld', 02:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:40 INFO - 'MOZ_NO_REMOTE': '1', 02:44:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:40 INFO - 'NO_EM_RESTART': '1', 02:44:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:40 INFO - 'PWD': '/builds/slave/test', 02:44:40 INFO - 'SHELL': '/bin/bash', 02:44:40 INFO - 'SHLVL': '1', 02:44:40 INFO - 'TERM': 'linux', 02:44:40 INFO - 'TMOUT': '86400', 02:44:40 INFO - 'USER': 'cltbld', 02:44:40 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:40 INFO - '_': '/tools/buildbot/bin/python'} 02:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:41 INFO - Downloading/unpacking psutil>=3.1.1 02:44:41 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 02:44:41 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 02:44:41 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 02:44:41 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 02:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:44:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:42 INFO - Installing collected packages: psutil 02:44:42 INFO - Running setup.py install for psutil 02:44:42 INFO - building 'psutil._psutil_linux' extension 02:44:42 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 02:44:43 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 02:44:43 INFO - building 'psutil._psutil_posix' extension 02:44:43 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 02:44:43 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 02:44:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:44:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:44:43 INFO - Successfully installed psutil 02:44:43 INFO - Cleaning up... 02:44:43 INFO - Return code: 0 02:44:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:44:43 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')]} 02:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:43 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')]} 02:44:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:43 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 0x1b1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8e090>, '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 0x1e8ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f90>, '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': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', '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 02:44:43 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 02:44:43 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 02:44:43 INFO - Using env: {'DISPLAY': ':0', 02:44:43 INFO - 'HOME': '/home/cltbld', 02:44:43 INFO - 'LANG': 'en_US.UTF-8', 02:44:43 INFO - 'LANGUAGE': 'en_US:en', 02:44:43 INFO - 'LOGNAME': 'cltbld', 02:44:43 INFO - 'MAIL': '/var/mail/cltbld', 02:44:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:43 INFO - 'MOZ_NO_REMOTE': '1', 02:44:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:43 INFO - 'NO_EM_RESTART': '1', 02:44:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:43 INFO - 'PWD': '/builds/slave/test', 02:44:43 INFO - 'SHELL': '/bin/bash', 02:44:43 INFO - 'SHLVL': '1', 02:44:43 INFO - 'TERM': 'linux', 02:44:43 INFO - 'TMOUT': '86400', 02:44:43 INFO - 'USER': 'cltbld', 02:44:43 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:44:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:43 INFO - '_': '/tools/buildbot/bin/python'} 02:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:44:43 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 02:44:43 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 02:44:43 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 02:44:43 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 02:44:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:44:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:44:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:44:44 INFO - Installing collected packages: mozsystemmonitor 02:44:44 INFO - Running setup.py install for mozsystemmonitor 02:44:45 INFO - Successfully installed mozsystemmonitor 02:44:45 INFO - Cleaning up... 02:44:45 INFO - Return code: 0 02:44:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:44:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:45 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 0x1b1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8e090>, '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 0x1e8ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f90>, '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': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', '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 02:44:45 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 02:44:45 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 02:44:45 INFO - Using env: {'DISPLAY': ':0', 02:44:45 INFO - 'HOME': '/home/cltbld', 02:44:45 INFO - 'LANG': 'en_US.UTF-8', 02:44:45 INFO - 'LANGUAGE': 'en_US:en', 02:44:45 INFO - 'LOGNAME': 'cltbld', 02:44:45 INFO - 'MAIL': '/var/mail/cltbld', 02:44:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:45 INFO - 'MOZ_NO_REMOTE': '1', 02:44:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:45 INFO - 'NO_EM_RESTART': '1', 02:44:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:45 INFO - 'PWD': '/builds/slave/test', 02:44:45 INFO - 'SHELL': '/bin/bash', 02:44:45 INFO - 'SHLVL': '1', 02:44:45 INFO - 'TERM': 'linux', 02:44:45 INFO - 'TMOUT': '86400', 02:44:45 INFO - 'USER': 'cltbld', 02:44:45 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:44:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:45 INFO - '_': '/tools/buildbot/bin/python'} 02:44:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:45 INFO - Downloading/unpacking jsonschema==2.5.1 02:44:45 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 02:44:45 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 02:44:45 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 02:44:45 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 02:44:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:44:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:44:46 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 02:44:46 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 02:44:46 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 02:44:46 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 02:44:46 INFO - Downloading functools32-3.2.3-2.tar.gz 02:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:44:46 INFO - warning: no files found matching '*.txt' 02:44:46 INFO - no previously-included directories found matching 'build' 02:44:46 INFO - no previously-included directories found matching 'dist' 02:44:46 INFO - no previously-included directories found matching '.git*' 02:44:46 INFO - Installing collected packages: jsonschema, functools32 02:44:46 INFO - Running setup.py install for functools32 02:44:46 INFO - warning: no files found matching '*.txt' 02:44:46 INFO - no previously-included directories found matching 'build' 02:44:46 INFO - no previously-included directories found matching 'dist' 02:44:46 INFO - no previously-included directories found matching '.git*' 02:44:46 INFO - Successfully installed jsonschema functools32 02:44:46 INFO - Cleaning up... 02:44:46 INFO - Return code: 0 02:44:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:44:46 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')]} 02:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:46 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')]} 02:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:46 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 0x1b1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8e090>, '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 0x1e8ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f90>, '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': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', '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 02:44:46 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 02:44:46 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 02:44:46 INFO - Using env: {'DISPLAY': ':0', 02:44:46 INFO - 'HOME': '/home/cltbld', 02:44:46 INFO - 'LANG': 'en_US.UTF-8', 02:44:46 INFO - 'LANGUAGE': 'en_US:en', 02:44:46 INFO - 'LOGNAME': 'cltbld', 02:44:46 INFO - 'MAIL': '/var/mail/cltbld', 02:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:46 INFO - 'MOZ_NO_REMOTE': '1', 02:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:46 INFO - 'NO_EM_RESTART': '1', 02:44:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:46 INFO - 'PWD': '/builds/slave/test', 02:44:46 INFO - 'SHELL': '/bin/bash', 02:44:46 INFO - 'SHLVL': '1', 02:44:46 INFO - 'TERM': 'linux', 02:44:46 INFO - 'TMOUT': '86400', 02:44:46 INFO - 'USER': 'cltbld', 02:44:46 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:46 INFO - '_': '/tools/buildbot/bin/python'} 02:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:44:46 INFO - Cleaning up... 02:44:46 INFO - Return code: 0 02:44:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:44:46 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')]} 02:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:46 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')]} 02:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:46 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 0x1b1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8e090>, '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 0x1e8ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f90>, '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': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', '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 02:44:46 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 02:44:46 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 02:44:46 INFO - Using env: {'DISPLAY': ':0', 02:44:46 INFO - 'HOME': '/home/cltbld', 02:44:46 INFO - 'LANG': 'en_US.UTF-8', 02:44:46 INFO - 'LANGUAGE': 'en_US:en', 02:44:46 INFO - 'LOGNAME': 'cltbld', 02:44:46 INFO - 'MAIL': '/var/mail/cltbld', 02:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:46 INFO - 'MOZ_NO_REMOTE': '1', 02:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:46 INFO - 'NO_EM_RESTART': '1', 02:44:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:46 INFO - 'PWD': '/builds/slave/test', 02:44:46 INFO - 'SHELL': '/bin/bash', 02:44:46 INFO - 'SHLVL': '1', 02:44:46 INFO - 'TERM': 'linux', 02:44:46 INFO - 'TMOUT': '86400', 02:44:46 INFO - 'USER': 'cltbld', 02:44:46 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:46 INFO - '_': '/tools/buildbot/bin/python'} 02:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:47 INFO - Downloading/unpacking blobuploader==1.2.4 02:44:47 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 02:44:47 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 02:44:47 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 02:44:47 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 02:44:48 INFO - Downloading blobuploader-1.2.4.tar.gz 02:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:44:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:44:48 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 02:44:48 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 02:44:48 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 02:44:48 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 02:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:44:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:44:48 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 02:44:48 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 02:44:48 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 02:44:48 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 02:44:48 INFO - Downloading docopt-0.6.1.tar.gz 02:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:44:49 INFO - Installing collected packages: blobuploader, requests, docopt 02:44:49 INFO - Running setup.py install for blobuploader 02:44:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:44:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:44:49 INFO - Running setup.py install for requests 02:44:49 INFO - Running setup.py install for docopt 02:44:49 INFO - Successfully installed blobuploader requests docopt 02:44:49 INFO - Cleaning up... 02:44:49 INFO - Return code: 0 02:44:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:49 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 0x1b1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8e090>, '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 0x1e8ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f90>, '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': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', '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 02:44:49 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 02:44:49 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 02:44:49 INFO - Using env: {'DISPLAY': ':0', 02:44:49 INFO - 'HOME': '/home/cltbld', 02:44:49 INFO - 'LANG': 'en_US.UTF-8', 02:44:49 INFO - 'LANGUAGE': 'en_US:en', 02:44:49 INFO - 'LOGNAME': 'cltbld', 02:44:49 INFO - 'MAIL': '/var/mail/cltbld', 02:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:49 INFO - 'MOZ_NO_REMOTE': '1', 02:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:49 INFO - 'NO_EM_RESTART': '1', 02:44:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:49 INFO - 'PWD': '/builds/slave/test', 02:44:49 INFO - 'SHELL': '/bin/bash', 02:44:49 INFO - 'SHLVL': '1', 02:44:49 INFO - 'TERM': 'linux', 02:44:49 INFO - 'TMOUT': '86400', 02:44:49 INFO - 'USER': 'cltbld', 02:44:49 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:49 INFO - '_': '/tools/buildbot/bin/python'} 02:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:49 INFO - Running setup.py (path:/tmp/pip-vAZQgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:49 INFO - Running setup.py (path:/tmp/pip-GL6vhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:49 INFO - Running setup.py (path:/tmp/pip-ePQHjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:50 INFO - Running setup.py (path:/tmp/pip-7POBcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:50 INFO - Running setup.py (path:/tmp/pip-0CPZA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:50 INFO - Running setup.py (path:/tmp/pip-n973WA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:50 INFO - Running setup.py (path:/tmp/pip-L7qjWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:50 INFO - Running setup.py (path:/tmp/pip-01ojdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:50 INFO - Running setup.py (path:/tmp/pip-pvzpZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:50 INFO - Running setup.py (path:/tmp/pip-bRLJBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:50 INFO - Running setup.py (path:/tmp/pip-E9zC7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:50 INFO - Running setup.py (path:/tmp/pip-s5kC5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:50 INFO - Running setup.py (path:/tmp/pip-Ew953Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:50 INFO - Running setup.py (path:/tmp/pip-Evdr_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:50 INFO - Running setup.py (path:/tmp/pip-0LISZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:50 INFO - Running setup.py (path:/tmp/pip-JoT9Xl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:50 INFO - Running setup.py (path:/tmp/pip-kcCe4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:44:51 INFO - Running setup.py install for manifestparser 02:44:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:44:51 INFO - Running setup.py install for mozcrash 02:44:51 INFO - Running setup.py install for mozdebug 02:44:51 INFO - Running setup.py install for mozdevice 02:44:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:44:51 INFO - Running setup.py install for mozfile 02:44:51 INFO - Running setup.py install for mozhttpd 02:44:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:44:51 INFO - Running setup.py install for mozinfo 02:44:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:44:51 INFO - Running setup.py install for mozInstall 02:44:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:44:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:44:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:44:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:44:51 INFO - Running setup.py install for mozleak 02:44:51 INFO - Running setup.py install for mozlog 02:44:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:44:52 INFO - Running setup.py install for moznetwork 02:44:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:44:52 INFO - Running setup.py install for mozprocess 02:44:52 INFO - Running setup.py install for mozprofile 02:44:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:44:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:44:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:44:52 INFO - Running setup.py install for mozrunner 02:44:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:44:52 INFO - Running setup.py install for mozscreenshot 02:44:52 INFO - Running setup.py install for moztest 02:44:52 INFO - Running setup.py install for mozversion 02:44:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:44:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:44:52 INFO - Cleaning up... 02:44:52 INFO - Return code: 0 02:44:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:52 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 0x1b1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8e090>, '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 0x1e8ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f90>, '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': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', '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 02:44:52 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 02:44:52 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 02:44:52 INFO - Using env: {'DISPLAY': ':0', 02:44:52 INFO - 'HOME': '/home/cltbld', 02:44:52 INFO - 'LANG': 'en_US.UTF-8', 02:44:52 INFO - 'LANGUAGE': 'en_US:en', 02:44:52 INFO - 'LOGNAME': 'cltbld', 02:44:52 INFO - 'MAIL': '/var/mail/cltbld', 02:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:52 INFO - 'MOZ_NO_REMOTE': '1', 02:44:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:52 INFO - 'NO_EM_RESTART': '1', 02:44:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:52 INFO - 'PWD': '/builds/slave/test', 02:44:52 INFO - 'SHELL': '/bin/bash', 02:44:52 INFO - 'SHLVL': '1', 02:44:52 INFO - 'TERM': 'linux', 02:44:52 INFO - 'TMOUT': '86400', 02:44:52 INFO - 'USER': 'cltbld', 02:44:52 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:52 INFO - '_': '/tools/buildbot/bin/python'} 02:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:44:53 INFO - Running setup.py (path:/tmp/pip-WWHRsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:44:53 INFO - Running setup.py (path:/tmp/pip-0q7i6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:44:53 INFO - Running setup.py (path:/tmp/pip-o84pG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:44:53 INFO - Running setup.py (path:/tmp/pip-NswnbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:44:53 INFO - Running setup.py (path:/tmp/pip-k9h1yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:44:53 INFO - Running setup.py (path:/tmp/pip-ZnV5Lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:44:53 INFO - Running setup.py (path:/tmp/pip-14TcNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:44:53 INFO - Running setup.py (path:/tmp/pip-iu4HVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:44:53 INFO - Running setup.py (path:/tmp/pip-bb3pDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:44:53 INFO - Running setup.py (path:/tmp/pip-TEfs4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:44:53 INFO - Running setup.py (path:/tmp/pip-lxD2YI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:44:53 INFO - Running setup.py (path:/tmp/pip-_qP6d3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:44:53 INFO - Running setup.py (path:/tmp/pip-Wn_bz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:44:53 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)) 02:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:44:53 INFO - Running setup.py (path:/tmp/pip-v19QrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:44:54 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)) 02:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:54 INFO - Running setup.py (path:/tmp/pip-_vbWaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:44:54 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)) 02:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:44:54 INFO - Running setup.py (path:/tmp/pip-1sn10A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:44:54 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)) 02:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:44:54 INFO - Running setup.py (path:/tmp/pip-Lj2rB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:44:54 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)) 02:44:54 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)) 02:44:54 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)) 02:44:54 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)) 02:44:54 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)) 02:44:54 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)) 02:44:54 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)) 02:44:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:55 INFO - Downloading blessings-1.6.tar.gz 02:44:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:44:55 INFO - Installing collected packages: blessings 02:44:55 INFO - Running setup.py install for blessings 02:44:55 INFO - Successfully installed blessings 02:44:55 INFO - Cleaning up... 02:44:55 INFO - Return code: 0 02:44:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:44:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:55 INFO - Reading from file tmpfile_stdout 02:44:55 INFO - Current package versions: 02:44:55 INFO - argparse == 1.2.1 02:44:55 INFO - blessings == 1.6 02:44:55 INFO - blobuploader == 1.2.4 02:44:55 INFO - docopt == 0.6.1 02:44:55 INFO - functools32 == 3.2.3-2 02:44:55 INFO - jsonschema == 2.5.1 02:44:55 INFO - manifestparser == 1.1 02:44:55 INFO - mozInstall == 1.13 02:44:55 INFO - mozcrash == 1.0 02:44:55 INFO - mozdebug == 0.1 02:44:55 INFO - mozdevice == 0.51 02:44:55 INFO - mozfile == 1.2 02:44:55 INFO - mozhttpd == 0.7 02:44:55 INFO - mozinfo == 0.10 02:44:55 INFO - mozleak == 0.1 02:44:55 INFO - mozlog == 3.5 02:44:55 INFO - moznetwork == 0.27 02:44:55 INFO - mozprocess == 0.25 02:44:55 INFO - mozprofile == 0.28 02:44:55 INFO - mozrunner == 6.13 02:44:55 INFO - mozscreenshot == 0.1 02:44:55 INFO - mozsystemmonitor == 0.3 02:44:55 INFO - moztest == 0.8 02:44:55 INFO - mozversion == 1.4 02:44:55 INFO - psutil == 3.1.1 02:44:55 INFO - requests == 1.2.3 02:44:55 INFO - wsgiref == 0.1.2 02:44:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:55 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 0x1b1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8e090>, '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 0x1e8ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f90>, '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': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', '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 02:44:55 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 02:44:55 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 02:44:55 INFO - Using env: {'DISPLAY': ':0', 02:44:55 INFO - 'HOME': '/home/cltbld', 02:44:55 INFO - 'LANG': 'en_US.UTF-8', 02:44:55 INFO - 'LANGUAGE': 'en_US:en', 02:44:55 INFO - 'LOGNAME': 'cltbld', 02:44:55 INFO - 'MAIL': '/var/mail/cltbld', 02:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:55 INFO - 'MOZ_NO_REMOTE': '1', 02:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:55 INFO - 'NO_EM_RESTART': '1', 02:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:55 INFO - 'PWD': '/builds/slave/test', 02:44:55 INFO - 'SHELL': '/bin/bash', 02:44:55 INFO - 'SHLVL': '1', 02:44:55 INFO - 'TERM': 'linux', 02:44:55 INFO - 'TMOUT': '86400', 02:44:55 INFO - 'USER': 'cltbld', 02:44:55 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:55 INFO - '_': '/tools/buildbot/bin/python'} 02:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:56 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)) 02:44:56 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)) 02:44:56 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)) 02:44:56 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)) 02:44:56 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)) 02:44:56 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)) 02:44:56 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)) 02:44:56 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)) 02:44:56 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)) 02:44:56 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:44:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:44:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:44:57 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)) 02:44:57 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)) 02:44:57 INFO - Installing collected packages: simplejson 02:44:57 INFO - Running setup.py install for simplejson 02:44:57 INFO - building 'simplejson._speedups' extension 02:44:57 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 02:44:58 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 02:44:58 INFO - Successfully installed simplejson 02:44:58 INFO - Cleaning up... 02:44:58 INFO - Return code: 0 02:44:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:44:58 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')]} 02:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:44:58 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')]} 02:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:44:58 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 0x1b1e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8e090>, '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 0x1e8ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f90>, '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': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', '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 02:44:58 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 02:44:58 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 02:44:58 INFO - Using env: {'DISPLAY': ':0', 02:44:58 INFO - 'HOME': '/home/cltbld', 02:44:58 INFO - 'LANG': 'en_US.UTF-8', 02:44:58 INFO - 'LANGUAGE': 'en_US:en', 02:44:58 INFO - 'LOGNAME': 'cltbld', 02:44:58 INFO - 'MAIL': '/var/mail/cltbld', 02:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:44:58 INFO - 'MOZ_NO_REMOTE': '1', 02:44:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:44:58 INFO - 'NO_EM_RESTART': '1', 02:44:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:44:58 INFO - 'PWD': '/builds/slave/test', 02:44:58 INFO - 'SHELL': '/bin/bash', 02:44:58 INFO - 'SHLVL': '1', 02:44:58 INFO - 'TERM': 'linux', 02:44:58 INFO - 'TMOUT': '86400', 02:44:58 INFO - 'USER': 'cltbld', 02:44:58 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:44:58 INFO - '_': '/tools/buildbot/bin/python'} 02:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:44:58 INFO - Cleaning up... 02:44:58 INFO - Return code: 0 02:44:58 INFO - Running post-action listener: _resource_record_post_action 02:44:58 INFO - Running post-action listener: _start_resource_monitoring 02:44:58 INFO - Starting resource monitoring. 02:44:58 INFO - [mozharness: 2017-09-03 09:44:58.520853Z] Finished create-virtualenv step (success) 02:44:58 INFO - [mozharness: 2017-09-03 09:44:58.521852Z] Running install step. 02:44:58 INFO - Running pre-action listener: _resource_record_pre_action 02:44:58 INFO - Running main action method: install 02:44:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:44:58 INFO - Reading from file tmpfile_stdout 02:44:58 INFO - Detecting whether we're running mozinstall >=1.0... 02:44:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:44:58 INFO - Reading from file tmpfile_stdout 02:44:58 INFO - Output received: 02:44:58 INFO - Usage: mozinstall [options] installer 02:44:58 INFO - Options: 02:44:58 INFO - -h, --help show this help message and exit 02:44:58 INFO - -d DEST, --destination=DEST 02:44:58 INFO - Directory to install application into. [default: 02:44:58 INFO - "/builds/slave/test"] 02:44:58 INFO - --app=APP Application being installed. [default: firefox] 02:44:58 INFO - mkdir: /builds/slave/test/build/application 02:44:58 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'] 02:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:45:15 INFO - Reading from file tmpfile_stdout 02:45:15 INFO - Output received: 02:45:15 INFO - /builds/slave/test/build/application/firefox/firefox 02:45:15 INFO - Running post-action listener: _resource_record_post_action 02:45:15 INFO - [mozharness: 2017-09-03 09:45:15.374251Z] Finished install step (success) 02:45:15 INFO - [mozharness: 2017-09-03 09:45:15.374503Z] Running setup-mitmproxy step. 02:45:15 INFO - Running pre-action listener: _resource_record_pre_action 02:45:15 INFO - Running main action method: setup_mitmproxy 02:45:15 INFO - Skipping: mitmproxy is not required 02:45:15 INFO - Running post-action listener: _resource_record_post_action 02:45:15 INFO - [mozharness: 2017-09-03 09:45:15.375625Z] Finished setup-mitmproxy step (success) 02:45:15 INFO - [mozharness: 2017-09-03 09:45:15.375845Z] Running run-tests step. 02:45:15 INFO - Running pre-action listener: _resource_record_pre_action 02:45:15 INFO - Running pre-action listener: _set_gcov_prefix 02:45:15 INFO - Running main action method: run_tests 02:45:15 WARNING - Try message not found. 02:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:45:15 INFO - Python 2.7.3 02:45:15 INFO - Return code: 0 02:45:15 INFO - grabbing minidump binary from tooltool 02:45:15 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')]} 02:45:15 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 0x1e8e090>, '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 0x1e8ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e88f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:45:15 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 02:45:15 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 02:45:15 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 02:45:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:45:15 INFO - Return code: 0 02:45:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:45:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:45:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:45:15 INFO - ENV: RUST_BACKTRACE is now full 02:45:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:45:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:45:15 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/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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 02:45:15 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/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-077 --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 02:45:15 INFO - Using env: {'DISPLAY': ':0', 02:45:15 INFO - 'HOME': '/home/cltbld', 02:45:15 INFO - 'LANG': 'en_US.UTF-8', 02:45:15 INFO - 'LANGUAGE': 'en_US:en', 02:45:15 INFO - 'LOGNAME': 'cltbld', 02:45:15 INFO - 'MAIL': '/var/mail/cltbld', 02:45:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:15 INFO - 'MOZ_NO_REMOTE': '1', 02:45:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:15 INFO - 'NO_EM_RESTART': '1', 02:45:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:15 INFO - 'PWD': '/builds/slave/test', 02:45:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:15 INFO - 'RUST_BACKTRACE': 'full', 02:45:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:15 INFO - 'SHELL': '/bin/bash', 02:45:15 INFO - 'SHLVL': '1', 02:45:15 INFO - 'STYLO_FORCE_ENABLED': '1', 02:45:15 INFO - 'TERM': 'linux', 02:45:15 INFO - 'TMOUT': '86400', 02:45:15 INFO - 'USER': 'cltbld', 02:45:15 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:15 INFO - '_': '/tools/buildbot/bin/python'} 02:45:15 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/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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 02:45:15 INFO - mozversion application_buildid: 20170903085316 02:45:15 INFO - mozversion application_changeset: 59db725def8282e1d77e83f002d247c7d0f95237 02:45:15 INFO - mozversion application_display_name: Nightly 02:45:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:45:15 INFO - mozversion application_name: Firefox 02:45:15 INFO - mozversion application_remotingname: firefox 02:45:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:45:15 INFO - mozversion application_vendor: Mozilla 02:45:15 INFO - mozversion application_version: 57.0a1 02:45:15 INFO - mozversion platform_buildid: 20170903085316 02:45:15 INFO - mozversion platform_changeset: 59db725def8282e1d77e83f002d247c7d0f95237 02:45:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:45:15 INFO - mozversion platform_version: 57.0a1 02:45:15 INFO - using testdate: 1504431915 02:45:15 INFO - actual date: 1504431915 02:45:15 INFO - starting webserver on 'localhost:42462' 02:45:15 INFO - SUITE-START | Running 2 tests 02:45:15 INFO - TEST-START | dromaeo_css 02:45:15 INFO - Initialising browser for dromaeo_css test... 02:45:15 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42462/getInfo.html -profile /tmp/tmpkDYEFC/profile 02:45:15 INFO - TEST-INFO | started process 3683 (/builds/slave/test/build/application/firefox/firefox http://localhost:42462/getInfo.html) 02:45:22 INFO - TEST-INFO | 3683: exit 0 02:45:22 INFO - Browser initialized. 02:45:22 INFO - Running cycle 1/1 for dromaeo_css test... 02:45:22 INFO - Using env: {'DISPLAY': ':0', 02:45:22 INFO - 'HOME': '/home/cltbld', 02:45:22 INFO - 'JSGC_DISABLE_POISONING': '1', 02:45:22 INFO - 'LANG': 'en_US.UTF-8', 02:45:22 INFO - 'LANGUAGE': 'en_US:en', 02:45:22 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:45:22 INFO - 'LOGNAME': 'cltbld', 02:45:22 INFO - 'MAIL': '/var/mail/cltbld', 02:45:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:45:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:45:22 INFO - 'MOZ_CRASHREPORTER': '1', 02:45:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:22 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:45:22 INFO - 'MOZ_NO_REMOTE': '1', 02:45:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:45:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:45:22 INFO - 'NO_EM_RESTART': '1', 02:45:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:45:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:22 INFO - 'PWD': '/builds/slave/test', 02:45:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:45:22 INFO - 'RUST_BACKTRACE': 'full', 02:45:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:45:22 INFO - 'SHELL': '/bin/bash', 02:45:22 INFO - 'SHLVL': '1', 02:45:22 INFO - 'STYLO_FORCE_ENABLED': '1', 02:45:22 INFO - 'TERM': 'linux', 02:45:22 INFO - 'TMOUT': '86400', 02:45:22 INFO - 'USER': 'cltbld', 02:45:22 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 02:45:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:22 INFO - '_': '/tools/buildbot/bin/python'} 02:45:22 INFO - TEST-INFO | started process 3882 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkDYEFC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:23 INFO - PID 3882 | 02:45:23 INFO - PID 3882 | (/builds/slave/test/build/application/firefox/firefox:3934): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:45:23 INFO - PID 3882 | 02:45:33 INFO - PID 3882 | RSS: Main: 167497728 02:45:33 INFO - PID 3882 | 02:48:32 INFO - PID 3882 | Cycle 1(1): loaded http://localhost:42462/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42462/tests/dromaeo/cssquery-ext.html) 02:48:33 INFO - PID 3882 | RSS: Main: 172531712 02:48:33 INFO - PID 3882 | 02:51:35 INFO - PID 3882 | Cycle 1(1): loaded http://localhost:42462/tests/dromaeo/cssquery-ext.html (next: http://localhost:42462/tests/dromaeo/cssquery-jquery.html) 02:51:36 INFO - PID 3882 | RSS: Main: 169799680 02:51:36 INFO - PID 3882 | 02:54:37 INFO - PID 3882 | Cycle 1(1): loaded http://localhost:42462/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42462/tests/dromaeo/cssquery-mootools.html) 02:54:38 INFO - PID 3882 | RSS: Main: 172224512 02:54:38 INFO - PID 3882 | 02:57:40 INFO - PID 3882 | Cycle 1(1): loaded http://localhost:42462/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42462/tests/dromaeo/cssquery-prototype.html) 02:57:40 INFO - PID 3882 | RSS: Main: 168771584 02:57:40 INFO - PID 3882 | 03:00:44 INFO - PID 3882 | Cycle 1(1): loaded http://localhost:42462/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42462/tests/dromaeo/cssquery-yui.html) 03:00:45 INFO - PID 3882 | RSS: Main: 169512960 03:00:45 INFO - PID 3882 | 03:03:46 INFO - PID 3882 | Cycle 1(1): loaded http://localhost:42462/tests/dromaeo/cssquery-yui.html (next: http://localhost:42462/tests/dromaeo/cssquery-dojo.html) 03:03:47 INFO - PID 3882 | RSS: Main: 174338048 03:03:47 INFO - PID 3882 | 03:03:47 INFO - PID 3882 | __start_tp_report 03:03:47 INFO - PID 3882 | _x_x_mozilla_page_load 03:03:47 INFO - PID 3882 | _x_x_mozilla_page_load_details 03:03:47 INFO - PID 3882 | |i|pagename|runs| 03:03:47 INFO - PID 3882 | |0;dojo.html;3344.916377090574;3351.8156501392427;3355.4631258998556;3378.40877846377;3381.966180338196;6408.839779005515;6482.578632388897;6507.0890075389425;6527.640979746124;6553.705083271252;6349.873002539946;6401.7439302427965;6401.967990160054;6421.903671444932;6440.516961227915;5895.0862616294435;5907;5913.704314784243;5918.733656985424;6033.9396606033815;6025.307089684677;6046.909296360558;6047.213862197908;6082.81751547452;6124.724387402578;1050.4753297609432;5914.2015827863115;5958.314793798725;6028.156058151862;6070.757169713206;5882.3529411764675;5885.470307672287;5935.436133567304;6011.248593925759;6013.128096425994;5904.380040095814;5928.940710592882;5984.222051133368;5990.9700451497465;5994.2806863176265;6378.521610879203;6464.062710906939;6470.191226096738;6571.704273307712;6573.737050517973;6943.6875340609795;6970.477214208955;7028.226895041541;7046.43628509718;7074.186468556079;6815.591064536095;6876.312368763083;6971.6862741176765;7031.613261270628;7134.143902731706;6485.740570377132;6493.896037673607;6525.119108920282;6536.9673151634415;6542.214934207878;7172.175199851979;7261.237570055174;7274.59989700561;7276.163241227327;7283.890741638879;6866.141732283465;6895.34494223048;6922.480813938974;6936.2023367312395;6954;6782.762603308861;6875.656217189121;6893.965530172317;6938.5143039986715;6938.895916561256;6897.62063086525;6924.272951340036;6924.826879327957;6928.688209030605;7004.474664400191;6940;6946.965265173743;7037;7045.330693584101;7050.788476345718;5254.106801843695;5426.34883813937;5435.918461223085;5503.257060296889;5512.779488820403;4710.528947105262;4732.976335118303;4745.122152401747;4746.121967435966;4760.809567617256;6105.297890742621;6128.662923539248;6128.846778830476;6154.292256390483;6186.288576813634;7004.649767511604;7036.577805331696;7097.751578694721;7111.502194846415;7116.537425067354;6120.938790612036;6137.631742095488;6194.0708893665405;6199.56603037783;6214.5960512566535;5864.824055278348;5924.170616113747;5950.613210141327;5994.100884867305;6053.878922421615;6747.122874026345;6749;6762.69567869447;6776.966115169393;6793.286704895915;6937.271586483447;6939.548929319578;7043.964780176067;7046.330598593126;7047.647617619201;6705.966470167618;6725.0921125649365;6806.965965170142;6807.21717002551;6815.761448349284;6681.532292739462;6784.05023296729;6795.694193761194;6824.897626535507;6865.5537390069485;7013.193482749549;7064.540804847874;7070.646467676493;7091.503594748318;7093.964530177316;6776.864462710817;6836;6843.623600702009;6872.10662613857;6882.380585747307;6760.628165450948;6782.728690852311;6792.830179245559;6854.897176542256;6870.828229294308;6520.347965203442;6629.602223866391;6647.567908085959;6698.330166983263;6793.218779840184;6766.18805743314;6808.489363297872;6850.246472888078;6859.07402500676;6892.379685828299;6557.180352455943;6656.10142630758;6664.300248473841;6676.165479315086;6680.766173183915 03:03:47 INFO - PID 3882 | |1;ext.html;798.433112490132;837.7319257837487;859.3812455032377;879.7492714576347;884.2748945864394;38740.41889371658;40015.79992100043;40091;40107.99730006748;40192.39711404331;36482.27035459289;36512.452313215326;36519.26961460769;36582.085447863814;36700.26599468016;6835.213950395706;6940.7917762467205;6951.513394062417;6959.0257363969085;6961.930380696204;6725.159355080615;6782.559133656321;6799.626020568867;6813.3527314905;6813.420859226947;7494.775156745279;7534.510256833289;7536.284053014955;7543.69825206991;7549.207333230014;7469.365103966143;7516.173220945691;7517.398608111338;7518.436117291225;7518.849623007509;7466.701331946716;7472.701091956315;7599.658015389321;7648.2351764823625;7669.079710434784;37007;37165.81417092924;37222.81388593067;37371.06572335686;37440.43839342412;3382.830858457092;3408.199073217792;3412.5051867479324;3418.453047512386;3442.7245820334315;30898.845505772326;31041.84479077613;31071.067867963997;31086.689133108604;31092.689073109206;5166.250893620441;5167;5186.92219616706;5188.195829646411;5224.92162617561;186979;188925;191433;193062.9999999986;193751;47651;48000.51999479961;48118.03763924772;48149.518504815205;48228.27657585139;49995.500044999084;50002;50076;50103.24845127326;50129.498705012484;15767.842321576638;15906.443274485337;15908.602284942968;15914.840851591567;16119.27463264156;13190.802137967938;13193.60419187426;13211.273379964003;13220.801687974688;13237.801432978513;14328.49850255236;14346.067505612102;14350.21073840928;14384.856151438351;14503.637409064646;5800.5939584229545;5849.298084229835;5892.381299963527;5907.409259074144;5922.49658779;1006.2101250518308;1018.5314755212537;1021.8262895307815;1035.668586052471;1036.2228328753436;22591.43521411945;22757.203497877494;22960.65559016616;23189.072437102663;23218.071277148723;1100.9944950275358;1101.658485869383;1119.1997721629075;1122.6519778868576;1138.2032577195996;12153.331566763913;12235;12258.325792081343;12357.814632780515;12374.381280936097;226566;227900;228682;228972;229269;51048.48951510512;51310.230346544835;51328;51406;51455.22817157746;1432.0548438030849;1433.6702558411623;1437.1377173695698;1438.2377340009812;1466.8899832512604;22508.887455562617;22586.774132258466;22950;22983.65524517167;23235.535289294457;36196;36221.81889090538;36253.818730906176;36265;36324.27351453009;193919;194217;194239;194835;194896;30415.54376684307;30865.8456707724;30874.228144295674;33328.66671333255;33769.15577110494;40696.59303406928;41322.380164296956;41361.172776543695;41840;41968.370474442294;30615;30669.8466507666;30791.692083078884;30843.537346940262;30892.536611951287;30608.84695576508;30823.53764693576;30858.69141308558;30891.536626951063;30983.380332392775;34602.48096278508;34639.30721385609;34865.82567087148;34903.650963491054;35079.12302192466;29502.409951800415;30012.099637010924;30229.244268893453;30448.695513044586;30493;22639.88680056589;22679.546409072056;22916.312510624706;22939;23000.309990700305 03:03:47 INFO - PID 3882 | |2;jquery.html;564.7402194990304;571.9142128680697;574.414097620427;575.9913601295979;587.8412828536294;16246.350145994145;21259.255926042573;21982.89008554957;22143.335699929026;22245.55508889821;18104.185311660975;18336.266549338012;18342.174602142903;21341.25305614304;23574.882125589393;6233.625982441045;6414.454771344442;6417.294097649255;6418.711157997878;6422.582532135396;6291.748330066792;6367.981123020318;6380.3300653431415;6381.489480841522;6394.104825324458;6030.276366835986;6208.78269260576;6241.06384042395;6241.563090583661;6243.344448832875;6204.47261982732;6233.688315584202;6235.251769787609;6236.844078898019;6237.282712488031;5763.250777398953;5767;5772.855678608026;5794.391588883149;5808.680522571257;25903;26868.59697104545;27330.863345683145;27463.725362746318;27501.449971000668;1890.1116475256608;1929.6333696597742;1929.7394851695121;1931.1502938706924;1931.6523025855481;18165.7275140873;18719.251229950787;18750.1562429691;18768.155433005548;18779.154938027823;2272.2842304673973;2300.091463871778;2329.0800133947164;2348.154664320843;2360.988195059014;138956;142520.28739856233;142753.2862335692;142871;143023;13123.671908202372;13770.380332885044;14113.153210807179;14178;14524.491642792664;22119.225827095976;22137.778622213573;22213.333599992027;22225.666615000788;22293.777062229496;9634.229261659051;9699.612015519442;9769.218462522982;9819.705408837746;9823.410595364301;9066.410683305565;9074.45553266806;9118.179363857285;9229.769255768526;9259.814803703888;7353.76434368876;8223.588820558949;8753.168448997336;8781.912180878237;9116.1339672731;2524.6465494830736;2549.528337257613;2596.662433883583;2607.29603007187;2705.8071475658517;933.7712260496222;940.8400571902793;953.6519170502855;958.4632605740808;975.7853272280087;17959.55101122456;18105.275788968556;18359.63280734378;18513.81486185148;18520.351787687367;1010.2221289607108;1010.6816352848829;1017.1252722658508;1021.1473419694489;1027.753339198587;10270.383776973405;10430.42632655196;10549.103326217357;10753;10843.349399035926;445470;448735;451402;452369;461862;112765;113233;116153;116365;117991;1135.4436326200102;1157.7510835170478;1170.795110855614;1173.4836671864352;1174.307158776309;16072.758908616614;16457.588560286087;16488.505344839676;16536.255868485707;16579.253933573258;54995.85551216952;55342;56145;57127.143092852806;57680;69180;69229;69584.30415695778;69873.65063174651;70271.29728702648;13370.331483425984;13586.45654173822;13767.931160344135;13787;13851.30743462843;21130.260440884496;21258.149674012868;21376.679349810078;21437.892810535846;21447.142114315877;17144.74282885733;17162.742558861875;17265.22306496236;17428.215730291915;17438;15838.604034898759;16113.919430402773;16116.113613751357;16232;16273.186340683154;18507.259709611466;18678.066096695384;18700.158492868126;18711.345102921332;18750.71873921865;13611.863881361456;13984.720305593628;14148.292585370897;14221.288935552973;14250.786238206229;18144.18351174174;18155.54611134732;18230.635387292445;18257.08714564293;18299.359522416653 03:03:47 INFO - PID 3882 | |3;mootools.html;1326.4428939845263;1347.158026233604;1370.9245991470477;1372.322176566801;1374.8762611364975;14737.189454579993;15339.386424543005;15419.614509637264;15419.691606167884;15514.844851551481;15795.526134215963;16089.83910160901;16092.356305747757;16099.758503622455;16100.59748506285;2609.7520735530143;2657.9069732559365;2663.400734834666;2663.986680066607;2664.2273740615196;2629.947401051978;2743.9298673517346;2782.0680072175846;2788.3586775041754;2804.6914839367655;2778.0832325332694;2783.958240626392;2819.1542537238847;2833.2916770807296;2835.3620435402077;2667.8799454024615;2676.210517897225;2694.7709444697225;2711.52548304046;2726.209399274208;2681.731826817322;2683.9597406038924;2686.6238726578295;2690.9730902691113;2693.7036925938132;15064.322105505278;15110.848891511165;15265.084094954338;15283.312250948735;15285.082895026335;7833.569153696544;7951.801204969865;8149.348052155813;8188.812622169752;8254.752357429286;5209.453007434203;5279.287296215;5382.327209098863;5385.811496597639;5501.944980550184;1845.833874951261;1891.2529550827346;1892.4701083696586;1901.7147427885789;1931.951701207467;26386.20841374762;26517.73482265172;26534.734652653613;26578;26641.467170656477;6485.092087107809;6561.573497722633;6697.397234248941;6728.226253980757;6779.389854912983;16182.352705891868;16186;16194.109323987293;16196.271167797477;16197.757033644504;7760.767176984699;7831.6475758591005;7849.529028258323;7866.252705993036;7868.173841746649;7156.105486814148;7200.351968322771;7220.927790722026;7288.234735352818;7309.780706578811;4764.285357196448;5060.089183946925;5209.062368773657;5428.728563571805;5440.646357986719;1422.6941207640407;1762.1893928792642;1772.299941523091;1789.4899953513186;1805.404216608516;889.2441424788877;892.7187935800202;898.2275243290895;914.4650379527918;915.9175674189222;8749.606267717842;8997.875265591802;9128.726138215865;9145.90854091464;9192.17270445663;976.9364991275544;989.3173710140125;993.1161266472845;1011.2971484818128;1015.1929216272933;5410.621256512085;5489.368722596872;5514.5538174805215;5610.859728506738;5723.513501352431;21171.470713231985;21753.129874804832;21955.67066494004;21956.23153189631;21962.780372196394;20810.167593296403;21627;21647.35057948264;21717.457063573536;22062;11142.554297827995;11391.088712902947;11418.828717569244;11429.085673146128;11443.084553235887;8902.688405905763;9021.458712477272;9261.583228754587;9328.580213890256;9439.103285187895;17866.91066544659;18870.433886983414;18927.621447571248;18972.24111035543;19050;18721;18872;18981.24075036983;19157.233710651977;19292.517687057934;12276.693082673004;12632.494700211888;12635.557755478514;12649.304288263866;12652.493900243888;14068.366923488673;14132.576022719335;14138;14303.928480357532;14334.49829255971;11379.260348077349;11517.42412879336;11631.53473861036;11722.41387930583;11829.231099978473;10504.737381565523;10527.631532896312;10937.234393592373;10937.289076210021;11124.110071194124;10290.228232882713;10337.896621033693;10405.115565177044;10421.010004049604;10452.895471045496;7547.773566793005;7742.961285193764;7759.689612415666;7765.572893490912;7830.33442157423;7789.7273595423885;7970.4819186752675;8016.799580010313;8088.8786668198845;8093.473924194908 03:03:47 INFO - PID 3882 | |4;prototype.html;506.08398798175284;515.8478248916571;534.9465053494654;540.9729513524322;541.6560484092603;6378.712957916899;6415.273508758771;6466.870662586745;6470.15887934569;6526.902096468557;6148.938510614893;6259.749610015594;6283.371662833714;6315.621062736239;6332.480204750861;5114.181730923053;5133.561558259834;5153.123968925273;5158.40678321994;5163;5076.898462030757;5080.720560369178;5089.2620570017325;5110.6422550421485;5120.590352771788;4898.14282500561;4962.702237865721;5051.823186188484;5054.3176671149295;5058.064258112261;5007.699538027694;5048.829003528482;5059.772310246048;5112.079825631385;5124.180131178993;4522.570355816225;4546.65900057497;4548.612673134692;4572.474165470981;4574.702644328108;6144.662043587601;6151.969240153815;6179.62922224668;6224.408681175281;6239.407256310643;5706.376450953633;5754.971225143848;5800.53595712342;5805.767769289223;5817.243758311435;6706.429953453962;6718.227403848571;6749.56127851693;6768.153980752406;6771.695273712695;4945.2829339745895;4947.233178857283;4962.925556116661;4968.254761785725;4968.627352948507;7027.683754231072;7098.716051357939;7134.750283740044;7143.357097861165;7151.535150215272;5054.241863720398;5099.464556221617;5104.902445974133;5105.5149760772665;5110.66780659256;5101.51535604117;5109.233614957786;5111;5126.256692779526;5141.126008578551;5772.769089236467;5806.883862322815;5808;5817.185594016841;5838.948989181903;6709.664516774142;6713.328667133248;6781.525293229525;6799.4900382471515;6842.965785171142;1664.592174917153;1756.5169578366051;1769.407248571743;1783.5986902946993;1786.5618177367085;1159.62891874601;1180.2977228549;1192.070185255502;1220.9145359824904;1245.2030700351781;5914.852128696731;5919.822405327847;5948.226730525004;5972.283326000907;5987.221661184019;6784.809620640214;6808.727650894008;6838.281980391987;6841;6889.758858439931;5807.216025836543;5927.628948420666;5986.700664966735;6021.337652858269;6027.48766354856;3635.8909232723063;3660.524131862841;3672.9348488938444;3693.575238847567;3697;6684.532082754161;6690.966545167243;6703.491714364327;6727.260001399842;6743.730250790011;7074.71701131949;7104.396126329217;7112.431005519546;7120.145582530026;7122.2521635228595;6673.766418175341;6725.461963042945;6732.2931092235585;6736.383268015641;6742;6881.380675739208;6896.586189836064;6946.79159625212;6947.930520694728;6987.650617469208;6783.626900520519;6784.423324017514;6811.89782153278;6853.965730171517;6868.347506986828;6572.474202063919;6620.080176748689;6657.833554161185;6718.798436046926;6731.32686731323;5019.322391477104;5039.546440820343;5049.368828896388;5094.617903657315;5095.566876815512;6846.726130954706;6931.618760968196;6940.41006514452;6966.860662786818;7048.929510704827;6647.036179754005;6666.200055993312;6676.465882729273;6686.9331306688255;6746.966265168643;6554.541182117206;6558.344165583403;6563.45388147015;6582.934170658232;6627.900581491378;6463.224413070461;6474.287828338973;6550.344965503602;6571.310012448624;6608.447014951386;6414.775482858077;6497.967510162419;6516.478681705547;6603.471722262111;6624.561543765592;6429.2284925807335;6486.351364863476;6491.4806815455595;6499.1876015498065;6509.902351464637 03:03:47 INFO - PID 3882 | |5;yui.html;911.4121391702348;919.650532797537;929.688554334298;933.6358820060174;944.7968686732353;18284.177212025457;19313.4205973821;19362.12870420829;19401.417957461264;19778.109985050673;19986.200551977905;20867.791322086774;20878.269260575882;20977.16091356348;20987.47531311718;928.442934239456;958.9376690515129;959.2997112108166;965.4689920543708;966.9903300967006;970.6845275285525;1007.1992765751208;1007.6825799873053;1009.4347165587294;1014.122783792022;1049.716576524338;1058.3755584205317;1058.6506452870574;1058.7723639417525;1058.8729352477676;1050.9579616815317;1051.0435503691642;1055.1084333738017;1055.250771951915;1055.2718624149352;773.4431209529187;776.6932061835599;777.9805504862367;780.6252998560666;781.0198201257385;16938.23777929996;17099.05955172465;17227.827721722748;17262.65474690512;19787.406377808544;21110.366688999202;21586.244481443075;21695.566088678137;22232.110715571358;22285;16980.49058528234;17303.221355039055;17481.125943702766;17498.387556435464;17692;3772.6793222576116;3796.468494410785;3798.2593394288124;3798.6391292827416;3798.772073675588;128341;128684;129054;130878.6912130876;133019;13860.584182474542;14175.22036288014;14278.428862845578;16938.23777929996;17373.13134343278;30384.54423183654;30702.232444189074;30972.535411968838;30977.225569360948;30992.070237892898;7101.751438699621;7349.338559529668;8542.658293668308;8767.254783343487;8866.42368246062;4168.2080404723;4242.342436922282;4247.00195454068;4250.3836943643;4273.63674087706;5126.666766660155;5154.819581314636;5243.737813109329;5267.894642107136;5278.366596008504;1040.6305761454576;1063.5267306048888;1073.4418102586767;1074.8334008228737;1082.0532034469838;702.7786247332074;719.2627556754369;723.8805597076529;733.6955163607043;747.4805010517638;11769.88230117705;11973.640790776291;12036.699082522831;12076.275423474619;12515.249085054926;690.5787469643514;703.8733028054896;710.8578284343153;725.7314793526429;729.8722723523363;5689.743961521659;5708.71456427177;5803.941960580424;5946.989011867993;5947.5539334550085;103752.48123759484;104252;104528.47735761425;105346;108844;93571.53214233885;96063;99562;100386.99613003776;101052.49473752585;84218.5789071063;84325.57837210897;86438.135618643;88436.5578172105;89154;10827;10939.577854878815;10956.671299861016;10960.068394186583;11192;41235.38146927738;41644;42075.15849682928;42541;42571.3614295792;49597.75201123971;50511.74744126256;50530;52217;71418;18607.06964651735;19392.90303548473;19608.60782784308;19840.70238946388;19915.502112447306;19694.8030519693;20105.597888042448;20113.597728045064;20125;20334.89832550828;17632.55918602045;17960.640787184446;17998.37005704794;18152.27390904349;18251.996140212417;17767.644647107245;18229.908850455664;18304.908475457538;19481.123349449026;19848.206071757544;17345;17754.289828407236;17771.73342399839;17983.370582029565;17985.910070449565;13180.868191317964;13380.799288010883;13422.664433389244;13790.241536715175;13873.4450621974;14048.508302209373;14060.226687532284;14423.855761442672;14619.122852628876;15003.024803387745 03:03:47 INFO - PID 3882 | __end_tp_report 03:03:47 INFO - PID 3882 | __start_cc_report 03:03:47 INFO - PID 3882 | _x_x_mozilla_cycle_collect,348 03:03:47 INFO - PID 3882 | __end_cc_report 03:03:47 INFO - PID 3882 | __startTimestamp1504433027053__endTimestamp 03:03:47 INFO - PID 3882 | 03:03:47 INFO - PID 3882 | ------- Summary: start ------- 03:03:47 INFO - PID 3882 | Number of tests: 6 03:03:47 INFO - PID 3882 | 03:03:47 INFO - PID 3882 | [#0] dojo.html Cycles:165 Average:6389.57 Median:6680.77 stddev:871.18 (13.0%) stddev-sans-first:840.48 03:03:47 INFO - PID 3882 | Values: 3344.9 3351.8 3355.5 3378.4 3382.0 6408.8 6482.6 6507.1 6527.6 6553.7 6349.9 6401.7 6402.0 6421.9 6440.5 5895.1 5907.0 5913.7 5918.7 6033.9 6025.3 6046.9 6047.2 6082.8 6124.7 1050.5 5914.2 5958.3 6028.2 6070.8 5882.4 5885.5 5935.4 6011.2 6013.1 5904.4 5928.9 5984.2 5991.0 5994.3 6378.5 6464.1 6470.2 6571.7 6573.7 6943.7 6970.5 7028.2 7046.4 7074.2 6815.6 6876.3 6971.7 7031.6 7134.1 6485.7 6493.9 6525.1 6537.0 6542.2 7172.2 7261.2 7274.6 7276.2 7283.9 6866.1 6895.3 6922.5 6936.2 6954.0 6782.8 6875.7 6894.0 6938.5 6938.9 6897.6 6924.3 6924.8 6928.7 7004.5 6940.0 6947.0 7037.0 7045.3 7050.8 5254.1 5426.3 5435.9 5503.3 5512.8 4710.5 4733.0 4745.1 4746.1 4760.8 6105.3 6128.7 6128.8 6154.3 6186.3 7004.6 7036.6 7097.8 7111.5 7116.5 6120.9 6137.6 6194.1 6199.6 6214.6 5864.8 5924.2 5950.6 5994.1 6053.9 6747.1 6749.0 6762.7 6777.0 6793.3 6937.3 6939.5 7044.0 7046.3 7047.6 6706.0 6725.1 6807.0 6807.2 6815.8 6681.5 6784.1 6795.7 6824.9 6865.6 7013.2 7064.5 7070.6 7091.5 7094.0 6776.9 6836.0 6843.6 6872.1 6882.4 6760.6 6782.7 6792.8 6854.9 6870.8 6520.3 6629.6 6647.6 6698.3 6793.2 6766.2 6808.5 6850.2 6859.1 6892.4 6557.2 6656.1 6664.3 6676.2 6680.8 03:03:47 INFO - PID 3882 | 03:03:47 INFO - PID 3882 | [#1] ext.html Cycles:180 Average:36759.69 Median:3395.51 stddev:53160.39 (1565.6%) stddev-sans-first:53240.94 03:03:47 INFO - PID 3882 | Values: 798.4 837.7 859.4 879.7 884.3 38740.4 40015.8 40091.0 40108.0 40192.4 36482.3 36512.5 36519.3 36582.1 36700.3 6835.2 6940.8 6951.5 6959.0 6961.9 6725.2 6782.6 6799.6 6813.4 6813.4 7494.8 7534.5 7536.3 7543.7 7549.2 7469.4 7516.2 7517.4 7518.4 7518.8 7466.7 7472.7 7599.7 7648.2 7669.1 37007.0 37165.8 37222.8 37371.1 37440.4 3382.8 3408.2 3412.5 3418.5 3442.7 30898.8 31041.8 31071.1 31086.7 31092.7 5166.3 5167.0 5186.9 5188.2 5224.9 186979.0 188925.0 191433.0 193063.0 193751.0 47651.0 48000.5 48118.0 48149.5 48228.3 49995.5 50002.0 50076.0 50103.2 50129.5 15767.8 15906.4 15908.6 15914.8 16119.3 13190.8 13193.6 13211.3 13220.8 13237.8 14328.5 14346.1 14350.2 14384.9 14503.6 5800.6 5849.3 5892.4 5907.4 5922.5 1006.2 1018.5 1021.8 1035.7 1036.2 22591.4 22757.2 22960.7 23189.1 23218.1 1101.0 1101.7 1119.2 1122.7 1138.2 12153.3 12235.0 12258.3 12357.8 12374.4 226566.0 227900.0 228682.0 228972.0 229269.0 51048.5 51310.2 51328.0 51406.0 51455.2 1432.1 1433.7 1437.1 1438.2 1466.9 22508.9 22586.8 22950.0 22983.7 23235.5 36196.0 36221.8 36253.8 36265.0 36324.3 193919.0 194217.0 194239.0 194835.0 194896.0 30415.5 30865.8 30874.2 33328.7 33769.2 40696.6 41322.4 41361.2 41840.0 41968.4 30615.0 30669.8 30791.7 30843.5 30892.5 30608.8 30823.5 30858.7 30891.5 30983.4 34602.5 34639.3 34865.8 34903.7 35079.1 29502.4 30012.1 30229.2 30448.7 30493.0 22639.9 22679.5 22916.3 22939.0 23000.3 03:03:47 INFO - PID 3882 | 03:03:47 INFO - PID 3882 | [#2] jquery.html Cycles:180 Average:33087.48 Median:21715.02 stddev:76996.52 (354.6%) stddev-sans-first:77173.80 03:03:47 INFO - PID 3882 | Values: 564.7 571.9 574.4 576.0 587.8 16246.4 21259.3 21982.9 22143.3 22245.6 18104.2 18336.3 18342.2 21341.3 23574.9 6233.6 6414.5 6417.3 6418.7 6422.6 6291.7 6368.0 6380.3 6381.5 6394.1 6030.3 6208.8 6241.1 6241.6 6243.3 6204.5 6233.7 6235.3 6236.8 6237.3 5763.3 5767.0 5772.9 5794.4 5808.7 25903.0 26868.6 27330.9 27463.7 27501.4 1890.1 1929.6 1929.7 1931.2 1931.7 18165.7 18719.3 18750.2 18768.2 18779.2 2272.3 2300.1 2329.1 2348.2 2361.0 138956.0 142520.3 142753.3 142871.0 143023.0 13123.7 13770.4 14113.2 14178.0 14524.5 22119.2 22137.8 22213.3 22225.7 22293.8 9634.2 9699.6 9769.2 9819.7 9823.4 9066.4 9074.5 9118.2 9229.8 9259.8 7353.8 8223.6 8753.2 8781.9 9116.1 2524.6 2549.5 2596.7 2607.3 2705.8 933.8 940.8 953.7 958.5 975.8 17959.6 18105.3 18359.6 18513.8 18520.4 1010.2 1010.7 1017.1 1021.1 1027.8 10270.4 10430.4 10549.1 10753.0 10843.3 445470.0 448735.0 451402.0 452369.0 461862.0 112765.0 113233.0 116153.0 116365.0 117991.0 1135.4 1157.8 1170.8 1173.5 1174.3 16072.8 16457.6 16488.5 16536.3 16579.3 54995.9 55342.0 56145.0 57127.1 57680.0 69180.0 69229.0 69584.3 69873.7 70271.3 13370.3 13586.5 13767.9 13787.0 13851.3 21130.3 21258.1 21376.7 21437.9 21447.1 17144.7 17162.7 17265.2 17428.2 17438.0 15838.6 16113.9 16116.1 16232.0 16273.2 18507.3 18678.1 18700.2 18711.3 18750.7 13611.9 13984.7 14148.3 14221.3 14250.8 18144.2 18155.5 18230.6 18257.1 18299.4 03:03:47 INFO - PID 3882 | 03:03:47 INFO - PID 3882 | [#3] mootools.html Cycles:180 Average:9487.30 Median:26526.23 stddev:6673.53 (25.2%) stddev-sans-first:6664.08 03:03:47 INFO - PID 3882 | Values: 1326.4 1347.2 1370.9 1372.3 1374.9 14737.2 15339.4 15419.6 15419.7 15514.8 15795.5 16089.8 16092.4 16099.8 16100.6 2609.8 2657.9 2663.4 2664.0 2664.2 2629.9 2743.9 2782.1 2788.4 2804.7 2778.1 2784.0 2819.2 2833.3 2835.4 2667.9 2676.2 2694.8 2711.5 2726.2 2681.7 2684.0 2686.6 2691.0 2693.7 15064.3 15110.8 15265.1 15283.3 15285.1 7833.6 7951.8 8149.3 8188.8 8254.8 5209.5 5279.3 5382.3 5385.8 5501.9 1845.8 1891.3 1892.5 1901.7 1932.0 26386.2 26517.7 26534.7 26578.0 26641.5 6485.1 6561.6 6697.4 6728.2 6779.4 16182.4 16186.0 16194.1 16196.3 16197.8 7760.8 7831.6 7849.5 7866.3 7868.2 7156.1 7200.4 7220.9 7288.2 7309.8 4764.3 5060.1 5209.1 5428.7 5440.6 1422.7 1762.2 1772.3 1789.5 1805.4 889.2 892.7 898.2 914.5 915.9 8749.6 8997.9 9128.7 9145.9 9192.2 976.9 989.3 993.1 1011.3 1015.2 5410.6 5489.4 5514.6 5610.9 5723.5 21171.5 21753.1 21955.7 21956.2 21962.8 20810.2 21627.0 21647.4 21717.5 22062.0 11142.6 11391.1 11418.8 11429.1 11443.1 8902.7 9021.5 9261.6 9328.6 9439.1 17866.9 18870.4 18927.6 18972.2 19050.0 18721.0 18872.0 18981.2 19157.2 19292.5 12276.7 12632.5 12635.6 12649.3 12652.5 14068.4 14132.6 14138.0 14303.9 14334.5 11379.3 11517.4 11631.5 11722.4 11829.2 10504.7 10527.6 10937.2 10937.3 11124.1 10290.2 10337.9 10405.1 10421.0 10452.9 7547.8 7743.0 7759.7 7765.6 7830.3 7789.7 7970.5 8016.8 8088.9 8093.5 03:03:47 INFO - PID 3882 | 03:03:47 INFO - PID 3882 | [#4] prototype.html Cycles:180 Average:5633.66 Median:6249.58 stddev:1593.86 (25.5%) stddev-sans-first:1551.17 03:03:47 INFO - PID 3882 | Values: 506.1 515.8 534.9 541.0 541.7 6378.7 6415.3 6466.9 6470.2 6526.9 6148.9 6259.7 6283.4 6315.6 6332.5 5114.2 5133.6 5153.1 5158.4 5163.0 5076.9 5080.7 5089.3 5110.6 5120.6 4898.1 4962.7 5051.8 5054.3 5058.1 5007.7 5048.8 5059.8 5112.1 5124.2 4522.6 4546.7 4548.6 4572.5 4574.7 6144.7 6152.0 6179.6 6224.4 6239.4 5706.4 5755.0 5800.5 5805.8 5817.2 6706.4 6718.2 6749.6 6768.2 6771.7 4945.3 4947.2 4962.9 4968.3 4968.6 7027.7 7098.7 7134.8 7143.4 7151.5 5054.2 5099.5 5104.9 5105.5 5110.7 5101.5 5109.2 5111.0 5126.3 5141.1 5772.8 5806.9 5808.0 5817.2 5838.9 6709.7 6713.3 6781.5 6799.5 6843.0 1664.6 1756.5 1769.4 1783.6 1786.6 1159.6 1180.3 1192.1 1220.9 1245.2 5914.9 5919.8 5948.2 5972.3 5987.2 6784.8 6808.7 6838.3 6841.0 6889.8 5807.2 5927.6 5986.7 6021.3 6027.5 3635.9 3660.5 3672.9 3693.6 3697.0 6684.5 6691.0 6703.5 6727.3 6743.7 7074.7 7104.4 7112.4 7120.1 7122.3 6673.8 6725.5 6732.3 6736.4 6742.0 6881.4 6896.6 6946.8 6947.9 6987.7 6783.6 6784.4 6811.9 6854.0 6868.3 6572.5 6620.1 6657.8 6718.8 6731.3 5019.3 5039.5 5049.4 5094.6 5095.6 6846.7 6931.6 6940.4 6966.9 7048.9 6647.0 6666.2 6676.5 6686.9 6747.0 6554.5 6558.3 6563.5 6582.9 6627.9 6463.2 6474.3 6550.3 6571.3 6608.4 6414.8 6498.0 6516.5 6603.5 6624.6 6429.2 6486.4 6491.5 6499.2 6509.9 03:03:47 INFO - PID 3882 | 03:03:47 INFO - PID 3882 | [#5] yui.html Cycles:180 Average:23341.72 Median:19881.85 stddev:31726.01 (159.6%) stddev-sans-first:31770.30 03:03:47 INFO - PID 3882 | Values: 911.4 919.7 929.7 933.6 944.8 18284.2 19313.4 19362.1 19401.4 19778.1 19986.2 20867.8 20878.3 20977.2 20987.5 928.4 958.9 959.3 965.5 967.0 970.7 1007.2 1007.7 1009.4 1014.1 1049.7 1058.4 1058.7 1058.8 1058.9 1051.0 1051.0 1055.1 1055.3 1055.3 773.4 776.7 778.0 780.6 781.0 16938.2 17099.1 17227.8 17262.7 19787.4 21110.4 21586.2 21695.6 22232.1 22285.0 16980.5 17303.2 17481.1 17498.4 17692.0 3772.7 3796.5 3798.3 3798.6 3798.8 128341.0 128684.0 129054.0 130878.7 133019.0 13860.6 14175.2 14278.4 16938.2 17373.1 30384.5 30702.2 30972.5 30977.2 30992.1 7101.8 7349.3 8542.7 8767.3 8866.4 4168.2 4242.3 4247.0 4250.4 4273.6 5126.7 5154.8 5243.7 5267.9 5278.4 1040.6 1063.5 1073.4 1074.8 1082.1 702.8 719.3 723.9 733.7 747.5 11769.9 11973.6 12036.7 12076.3 12515.2 690.6 703.9 710.9 725.7 729.9 5689.7 5708.7 5803.9 5947.0 5947.6 103752.5 104252.0 104528.5 105346.0 108844.0 93571.5 96063.0 99562.0 100387.0 101052.5 84218.6 84325.6 86438.1 88436.6 89154.0 10827.0 10939.6 10956.7 10960.1 11192.0 41235.4 41644.0 42075.2 42541.0 42571.4 49597.8 50511.7 50530.0 52217.0 71418.0 18607.1 19392.9 19608.6 19840.7 19915.5 19694.8 20105.6 20113.6 20125.0 20334.9 17632.6 17960.6 17998.4 18152.3 18252.0 17767.6 18229.9 18304.9 19481.1 19848.2 17345.0 17754.3 17771.7 17983.4 17985.9 13180.9 13380.8 13422.7 13790.2 13873.4 14048.5 14060.2 14423.9 14619.1 15003.0 03:03:47 INFO - PID 3882 | -------- Summary: end -------- 03:03:47 INFO - PID 3882 | 03:03:47 INFO - TEST-INFO | 3882: exit 0 03:03:47 INFO - TEST-OK | dromaeo_css | took 1111969ms 03:03:47 INFO - TEST-START | kraken 03:03:47 INFO - Initialising browser for kraken test... 03:03:47 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:42462/getInfo.html -profile /tmp/tmpQHuu3P/profile 03:03:47 INFO - TEST-INFO | started process 4265 (/builds/slave/test/build/application/firefox/firefox http://localhost:42462/getInfo.html) 03:03:53 INFO - TEST-INFO | 4265: exit 0 03:03:53 INFO - Browser initialized. 03:03:53 INFO - Running cycle 1/1 for kraken test... 03:03:53 INFO - Using env: {'DISPLAY': ':0', 03:03:53 INFO - 'HOME': '/home/cltbld', 03:03:53 INFO - 'JSGC_DISABLE_POISONING': '1', 03:03:53 INFO - 'LANG': 'en_US.UTF-8', 03:03:53 INFO - 'LANGUAGE': 'en_US:en', 03:03:53 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:03:53 INFO - 'LOGNAME': 'cltbld', 03:03:53 INFO - 'MAIL': '/var/mail/cltbld', 03:03:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:03:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:03:53 INFO - 'MOZ_CRASHREPORTER': '1', 03:03:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:53 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:03:53 INFO - 'MOZ_NO_REMOTE': '1', 03:03:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:03:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:53 INFO - 'NO_EM_RESTART': '1', 03:03:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:53 INFO - 'PWD': '/builds/slave/test', 03:03:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:03:53 INFO - 'RUST_BACKTRACE': 'full', 03:03:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:03:53 INFO - 'SHELL': '/bin/bash', 03:03:53 INFO - 'SHLVL': '1', 03:03:53 INFO - 'STYLO_FORCE_ENABLED': '1', 03:03:53 INFO - 'TERM': 'linux', 03:03:53 INFO - 'TMOUT': '86400', 03:03:53 INFO - 'USER': 'cltbld', 03:03:53 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482', 03:03:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:53 INFO - '_': '/tools/buildbot/bin/python'} 03:03:53 INFO - TEST-INFO | started process 4461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQHuu3P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:03:54 INFO - PID 4461 | 03:03:54 INFO - PID 4461 | (/builds/slave/test/build/application/firefox/firefox:4514): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:03:54 INFO - PID 4461 | 03:04:04 INFO - PID 4461 | RSS: Main: 172625920 03:04:04 INFO - PID 4461 | 03:04:07 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42462/tests/kraken/driver.html?testName=audio-beat-detection) 03:04:08 INFO - PID 4461 | RSS: Main: 176963584 03:04:08 INFO - PID 4461 | 03:04:10 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42462/tests/kraken/driver.html?testName=audio-dft) 03:04:11 INFO - PID 4461 | RSS: Main: 180957184 03:04:11 INFO - PID 4461 | 03:04:14 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42462/tests/kraken/driver.html?testName=audio-fft) 03:04:15 INFO - PID 4461 | RSS: Main: 174841856 03:04:15 INFO - PID 4461 | 03:04:17 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42462/tests/kraken/driver.html?testName=audio-oscillator) 03:04:17 INFO - PID 4461 | RSS: Main: 175452160 03:04:17 INFO - PID 4461 | 03:04:19 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42462/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:04:20 INFO - PID 4461 | RSS: Main: 175648768 03:04:20 INFO - PID 4461 | 03:04:23 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42462/tests/kraken/driver.html?testName=imaging-darkroom) 03:04:24 INFO - PID 4461 | RSS: Main: 176648192 03:04:24 INFO - PID 4461 | 03:04:27 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42462/tests/kraken/driver.html?testName=imaging-desaturate) 03:04:28 INFO - PID 4461 | RSS: Main: 176693248 03:04:28 INFO - PID 4461 | 03:04:31 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42462/tests/kraken/driver.html?testName=json-parse-financial) 03:04:31 INFO - PID 4461 | RSS: Main: 176951296 03:04:31 INFO - PID 4461 | 03:04:33 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42462/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:04:34 INFO - PID 4461 | RSS: Main: 176873472 03:04:34 INFO - PID 4461 | 03:04:35 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42462/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:04:36 INFO - PID 4461 | RSS: Main: 176930816 03:04:36 INFO - PID 4461 | 03:04:38 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42462/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:04:39 INFO - PID 4461 | RSS: Main: 173649920 03:04:39 INFO - PID 4461 | 03:04:41 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:04:42 INFO - PID 4461 | RSS: Main: 173875200 03:04:42 INFO - PID 4461 | 03:04:45 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:04:46 INFO - PID 4461 | RSS: Main: 173924352 03:04:46 INFO - PID 4461 | 03:04:47 INFO - PID 4461 | Cycle 1(1): loaded http://localhost:42462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42462/tests/kraken/driver.html?testName=ai-astar) 03:04:48 INFO - PID 4461 | RSS: Main: 175624192 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | __start_tp_report 03:04:48 INFO - PID 4461 | _x_x_mozilla_page_load 03:04:48 INFO - PID 4461 | _x_x_mozilla_page_load_details 03:04:48 INFO - PID 4461 | |i|pagename|runs| 03:04:48 INFO - PID 4461 | |0;ai-astar;103;109;96;107;115;110;116;109;109;108 03:04:48 INFO - PID 4461 | |1;audio-beat-detection;151;115;143;174;116;149;142;115;172;116 03:04:48 INFO - PID 4461 | |2;audio-dft;163;163;166;161;161;163;164;164;170;164 03:04:48 INFO - PID 4461 | |3;audio-fft;75;74;74;153;74;74;75;88;99;73 03:04:48 INFO - PID 4461 | |4;audio-oscillator;78;79;130;80;78;80;130;79;78;140 03:04:48 INFO - PID 4461 | |5;imaging-gaussian-blur;114;114;113;114;114;114;113;114;114;114 03:04:48 INFO - PID 4461 | |6;imaging-darkroom;147;147;147;154;147;147;148;147;148;148 03:04:48 INFO - PID 4461 | |7;imaging-desaturate;94;93;93;97;94;94;94;95;95;93 03:04:48 INFO - PID 4461 | |8;json-parse-financial;51;53;52;53;53;52;52;52;52;52 03:04:48 INFO - PID 4461 | |9;json-stringify-tinderbox;38;39;38;38;38;37;38;38;38;38 03:04:48 INFO - PID 4461 | |10;stanford-crypto-aes;78;74;76;75;79;77;80;73;83;77 03:04:48 INFO - PID 4461 | |11;stanford-crypto-ccm;116;124;118;117;116;120;121;123;117;158 03:04:48 INFO - PID 4461 | |12;stanford-crypto-pbkdf2;180;171;171;168;170;170;171;170;172;167 03:04:48 INFO - PID 4461 | |13;stanford-crypto-sha256-iterative;64;67;65;65;65;64;62;64;64;64 03:04:48 INFO - PID 4461 | __end_tp_report 03:04:48 INFO - PID 4461 | __start_cc_report 03:04:48 INFO - PID 4461 | _x_x_mozilla_cycle_collect,946 03:04:48 INFO - PID 4461 | __end_cc_report 03:04:48 INFO - PID 4461 | __startTimestamp1504433088134__endTimestamp 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | ------- Summary: start ------- 03:04:48 INFO - PID 4461 | Number of tests: 14 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#0] ai-astar Cycles:10 Average:108.20 Median:109.50 stddev:5.67 (5.2%) stddev-sans-first:5.70 03:04:48 INFO - PID 4461 | Values: 103.0 109.0 96.0 107.0 115.0 110.0 116.0 109.0 109.0 108.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:146.00 stddev:23.07 (15.8%) stddev-sans-first:24.08 03:04:48 INFO - PID 4461 | Values: 151.0 115.0 143.0 174.0 116.0 149.0 142.0 115.0 172.0 116.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#2] audio-dft Cycles:10 Average:163.90 Median:164.00 stddev:2.60 (1.6%) stddev-sans-first:2.74 03:04:48 INFO - PID 4461 | Values: 163.0 163.0 166.0 161.0 161.0 163.0 164.0 164.0 170.0 164.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#3] audio-fft Cycles:10 Average:85.90 Median:74.50 stddev:25.05 (33.6%) stddev-sans-first:26.26 03:04:48 INFO - PID 4461 | Values: 75.0 74.0 74.0 153.0 74.0 74.0 75.0 88.0 99.0 73.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:78.50 stddev:26.47 (33.7%) stddev-sans-first:27.33 03:04:48 INFO - PID 4461 | Values: 78.0 79.0 130.0 80.0 78.0 80.0 130.0 79.0 78.0 140.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:04:48 INFO - PID 4461 | Values: 114.0 114.0 113.0 114.0 114.0 114.0 113.0 114.0 114.0 114.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:147.50 stddev:2.16 (1.5%) stddev-sans-first:2.26 03:04:48 INFO - PID 4461 | Values: 147.0 147.0 147.0 154.0 147.0 147.0 148.0 147.0 148.0 148.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:1.23 (1.3%) stddev-sans-first:1.30 03:04:48 INFO - PID 4461 | Values: 94.0 93.0 93.0 97.0 94.0 94.0 94.0 95.0 95.0 93.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.00 stddev:0.63 (1.2%) stddev-sans-first:0.50 03:04:48 INFO - PID 4461 | Values: 51.0 53.0 52.0 53.0 53.0 52.0 52.0 52.0 52.0 52.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#9] json-stringify-tinderbox Cycles:10 Average:38.00 Median:38.00 stddev:0.47 (1.2%) stddev-sans-first:0.50 03:04:48 INFO - PID 4461 | Values: 38.0 39.0 38.0 38.0 38.0 37.0 38.0 38.0 38.0 38.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#10] stanford-crypto-aes Cycles:10 Average:77.20 Median:77.50 stddev:2.97 (3.8%) stddev-sans-first:3.14 03:04:48 INFO - PID 4461 | Values: 78.0 74.0 76.0 75.0 79.0 77.0 80.0 73.0 83.0 77.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#11] stanford-crypto-ccm Cycles:10 Average:123.00 Median:120.50 stddev:12.62 (10.5%) stddev-sans-first:13.13 03:04:48 INFO - PID 4461 | Values: 116.0 124.0 118.0 117.0 116.0 120.0 121.0 123.0 117.0 158.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.00 Median:171.00 stddev:3.50 (2.0%) stddev-sans-first:1.58 03:04:48 INFO - PID 4461 | Values: 180.0 171.0 171.0 168.0 170.0 170.0 171.0 170.0 172.0 167.0 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.50 stddev:1.26 (2.0%) stddev-sans-first:1.33 03:04:48 INFO - PID 4461 | Values: 64.0 67.0 65.0 65.0 65.0 64.0 62.0 64.0 64.0 64.0 03:04:48 INFO - PID 4461 | -------- Summary: end -------- 03:04:48 INFO - PID 4461 | 03:04:48 INFO - PID 4461 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:04:48 INFO - TEST-INFO | 4461: exit 0 03:04:48 INFO - TEST-OK | kraken | took 60990ms 03:04:48 INFO - SUITE-END | took 1172s 03:04:48 INFO - Completed test suite (00:19:32) 03:04:48 INFO - javascript benchmark 03:04:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8536.754194642022, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.916377090574, 3351.8156501392427, 3355.4631258998556, 3378.40877846377, 3381.966180338196, 6408.839779005515, 6482.578632388897, 6507.0890075389425, 6527.640979746124, 6553.705083271252, 6349.873002539946, 6401.7439302427965, 6401.967990160054, 6421.903671444932, 6440.516961227915, 5895.0862616294435, 5907.0, 5913.704314784243, 5918.733656985424, 6033.9396606033815, 6025.307089684677, 6046.909296360558, 6047.213862197908, 6082.81751547452, 6124.724387402578, 1050.4753297609432, 5914.2015827863115, 5958.314793798725, 6028.156058151862, 6070.757169713206, 5882.3529411764675, 5885.470307672287, 5935.436133567304, 6011.248593925759, 6013.128096425994, 5904.380040095814, 5928.940710592882, 5984.222051133368, 5990.9700451497465, 5994.2806863176265, 6378.521610879203, 6464.062710906939, 6470.191226096738, 6571.704273307712, 6573.737050517973, 6943.6875340609795, 6970.477214208955, 7028.226895041541, 7046.43628509718, 7074.186468556079, 6815.591064536095, 6876.312368763083, 6971.6862741176765, 7031.613261270628, 7134.143902731706, 6485.740570377132, 6493.896037673607, 6525.119108920282, 6536.9673151634415, 6542.214934207878, 7172.175199851979, 7261.237570055174, 7274.59989700561, 7276.163241227327, 7283.890741638879, 6866.141732283465, 6895.34494223048, 6922.480813938974, 6936.2023367312395, 6954.0, 6782.762603308861, 6875.656217189121, 6893.965530172317, 6938.5143039986715, 6938.895916561256, 6897.62063086525, 6924.272951340036, 6924.826879327957, 6928.688209030605, 7004.474664400191, 6940.0, 6946.965265173743, 7037.0, 7045.330693584101, 7050.788476345718, 5254.106801843695, 5426.34883813937, 5435.918461223085, 5503.257060296889, 5512.779488820403, 4710.528947105262, 4732.976335118303, 4745.122152401747, 4746.121967435966, 4760.809567617256, 6105.297890742621, 6128.662923539248, 6128.846778830476, 6154.292256390483, 6186.288576813634, 7004.649767511604, 7036.577805331696, 7097.751578694721, 7111.502194846415, 7116.537425067354, 6120.938790612036, 6137.631742095488, 6194.0708893665405, 6199.56603037783, 6214.5960512566535, 5864.824055278348, 5924.170616113747, 5950.613210141327, 5994.100884867305, 6053.878922421615, 6747.122874026345, 6749.0, 6762.69567869447, 6776.966115169393, 6793.286704895915, 6937.271586483447, 6939.548929319578, 7043.964780176067, 7046.330598593126, 7047.647617619201, 6705.966470167618, 6725.0921125649365, 6806.965965170142, 6807.21717002551, 6815.761448349284, 6681.532292739462, 6784.05023296729, 6795.694193761194, 6824.897626535507, 6865.5537390069485, 7013.193482749549, 7064.540804847874, 7070.646467676493, 7091.503594748318, 7093.964530177316, 6776.864462710817, 6836.0, 6843.623600702009, 6872.10662613857, 6882.380585747307, 6760.628165450948, 6782.728690852311, 6792.830179245559, 6854.897176542256, 6870.828229294308, 6520.347965203442, 6629.602223866391, 6647.567908085959, 6698.330166983263, 6793.218779840184, 6766.18805743314, 6808.489363297872, 6850.246472888078, 6859.07402500676, 6892.379685828299, 6557.180352455943, 6656.10142630758, 6664.300248473841, 6676.165479315086, 6680.766173183915], "value": 6327.665709359729, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.433112490132, 837.7319257837487, 859.3812455032377, 879.7492714576347, 884.2748945864394, 38740.41889371658, 40015.79992100043, 40091.0, 40107.99730006748, 40192.39711404331, 36482.27035459289, 36512.452313215326, 36519.26961460769, 36582.085447863814, 36700.26599468016, 6835.213950395706, 6940.7917762467205, 6951.513394062417, 6959.0257363969085, 6961.930380696204, 6725.159355080615, 6782.559133656321, 6799.626020568867, 6813.3527314905, 6813.420859226947, 7494.775156745279, 7534.510256833289, 7536.284053014955, 7543.69825206991, 7549.207333230014, 7469.365103966143, 7516.173220945691, 7517.398608111338, 7518.436117291225, 7518.849623007509, 7466.701331946716, 7472.701091956315, 7599.658015389321, 7648.2351764823625, 7669.079710434784, 37007.0, 37165.81417092924, 37222.81388593067, 37371.06572335686, 37440.43839342412, 3382.830858457092, 3408.199073217792, 3412.5051867479324, 3418.453047512386, 3442.7245820334315, 30898.845505772326, 31041.84479077613, 31071.067867963997, 31086.689133108604, 31092.689073109206, 5166.250893620441, 5167.0, 5186.92219616706, 5188.195829646411, 5224.92162617561, 186979.0, 188925.0, 191433.0, 193062.9999999986, 193751.0, 47651.0, 48000.51999479961, 48118.03763924772, 48149.518504815205, 48228.27657585139, 49995.500044999084, 50002.0, 50076.0, 50103.24845127326, 50129.498705012484, 15767.842321576638, 15906.443274485337, 15908.602284942968, 15914.840851591567, 16119.27463264156, 13190.802137967938, 13193.60419187426, 13211.273379964003, 13220.801687974688, 13237.801432978513, 14328.49850255236, 14346.067505612102, 14350.21073840928, 14384.856151438351, 14503.637409064646, 5800.5939584229545, 5849.298084229835, 5892.381299963527, 5907.409259074144, 5922.49658779, 1006.2101250518308, 1018.5314755212537, 1021.8262895307815, 1035.668586052471, 1036.2228328753436, 22591.43521411945, 22757.203497877494, 22960.65559016616, 23189.072437102663, 23218.071277148723, 1100.9944950275358, 1101.658485869383, 1119.1997721629075, 1122.6519778868576, 1138.2032577195996, 12153.331566763913, 12235.0, 12258.325792081343, 12357.814632780515, 12374.381280936097, 226566.0, 227900.0, 228682.0, 228972.0, 229269.0, 51048.48951510512, 51310.230346544835, 51328.0, 51406.0, 51455.22817157746, 1432.0548438030849, 1433.6702558411623, 1437.1377173695698, 1438.2377340009812, 1466.8899832512604, 22508.887455562617, 22586.774132258466, 22950.0, 22983.65524517167, 23235.535289294457, 36196.0, 36221.81889090538, 36253.818730906176, 36265.0, 36324.27351453009, 193919.0, 194217.0, 194239.0, 194835.0, 194896.0, 30415.54376684307, 30865.8456707724, 30874.228144295674, 33328.66671333255, 33769.15577110494, 40696.59303406928, 41322.380164296956, 41361.172776543695, 41840.0, 41968.370474442294, 30615.0, 30669.8466507666, 30791.692083078884, 30843.537346940262, 30892.536611951287, 30608.84695576508, 30823.53764693576, 30858.69141308558, 30891.536626951063, 30983.380332392775, 34602.48096278508, 34639.30721385609, 34865.82567087148, 34903.650963491054, 35079.12302192466, 29502.409951800415, 30012.099637010924, 30229.244268893453, 30448.695513044586, 30493.0, 22639.88680056589, 22679.546409072056, 22916.312510624706, 22939.0, 23000.309990700305], "value": 16724.70181168432, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.7402194990304, 571.9142128680697, 574.414097620427, 575.9913601295979, 587.8412828536294, 16246.350145994145, 21259.255926042573, 21982.89008554957, 22143.335699929026, 22245.55508889821, 18104.185311660975, 18336.266549338012, 18342.174602142903, 21341.25305614304, 23574.882125589393, 6233.625982441045, 6414.454771344442, 6417.294097649255, 6418.711157997878, 6422.582532135396, 6291.748330066792, 6367.981123020318, 6380.3300653431415, 6381.489480841522, 6394.104825324458, 6030.276366835986, 6208.78269260576, 6241.06384042395, 6241.563090583661, 6243.344448832875, 6204.47261982732, 6233.688315584202, 6235.251769787609, 6236.844078898019, 6237.282712488031, 5763.250777398953, 5767.0, 5772.855678608026, 5794.391588883149, 5808.680522571257, 25903.0, 26868.59697104545, 27330.863345683145, 27463.725362746318, 27501.449971000668, 1890.1116475256608, 1929.6333696597742, 1929.7394851695121, 1931.1502938706924, 1931.6523025855481, 18165.7275140873, 18719.251229950787, 18750.1562429691, 18768.155433005548, 18779.154938027823, 2272.2842304673973, 2300.091463871778, 2329.0800133947164, 2348.154664320843, 2360.988195059014, 138956.0, 142520.28739856233, 142753.2862335692, 142871.0, 143023.0, 13123.671908202372, 13770.380332885044, 14113.153210807179, 14178.0, 14524.491642792664, 22119.225827095976, 22137.778622213573, 22213.333599992027, 22225.666615000788, 22293.777062229496, 9634.229261659051, 9699.612015519442, 9769.218462522982, 9819.705408837746, 9823.410595364301, 9066.410683305565, 9074.45553266806, 9118.179363857285, 9229.769255768526, 9259.814803703888, 7353.76434368876, 8223.588820558949, 8753.168448997336, 8781.912180878237, 9116.1339672731, 2524.6465494830736, 2549.528337257613, 2596.662433883583, 2607.29603007187, 2705.8071475658517, 933.7712260496222, 940.8400571902793, 953.6519170502855, 958.4632605740808, 975.7853272280087, 17959.55101122456, 18105.275788968556, 18359.63280734378, 18513.81486185148, 18520.351787687367, 1010.2221289607108, 1010.6816352848829, 1017.1252722658508, 1021.1473419694489, 1027.753339198587, 10270.383776973405, 10430.42632655196, 10549.103326217357, 10753.0, 10843.349399035926, 445470.0, 448735.0, 451402.0, 452369.0, 461862.0, 112765.0, 113233.0, 116153.0, 116365.0, 117991.0, 1135.4436326200102, 1157.7510835170478, 1170.795110855614, 1173.4836671864352, 1174.307158776309, 16072.758908616614, 16457.588560286087, 16488.505344839676, 16536.255868485707, 16579.253933573258, 54995.85551216952, 55342.0, 56145.0, 57127.143092852806, 57680.0, 69180.0, 69229.0, 69584.30415695778, 69873.65063174651, 70271.29728702648, 13370.331483425984, 13586.45654173822, 13767.931160344135, 13787.0, 13851.30743462843, 21130.260440884496, 21258.149674012868, 21376.679349810078, 21437.892810535846, 21447.142114315877, 17144.74282885733, 17162.742558861875, 17265.22306496236, 17428.215730291915, 17438.0, 15838.604034898759, 16113.919430402773, 16116.113613751357, 16232.0, 16273.186340683154, 18507.259709611466, 18678.066096695384, 18700.158492868126, 18711.345102921332, 18750.71873921865, 13611.863881361456, 13984.720305593628, 14148.292585370897, 14221.288935552973, 14250.786238206229, 18144.18351174174, 18155.54611134732, 18230.635387292445, 18257.08714564293, 18299.359522416653], "value": 11418.929009601536, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1326.4428939845263, 1347.158026233604, 1370.9245991470477, 1372.322176566801, 1374.8762611364975, 14737.189454579993, 15339.386424543005, 15419.614509637264, 15419.691606167884, 15514.844851551481, 15795.526134215963, 16089.83910160901, 16092.356305747757, 16099.758503622455, 16100.59748506285, 2609.7520735530143, 2657.9069732559365, 2663.400734834666, 2663.986680066607, 2664.2273740615196, 2629.947401051978, 2743.9298673517346, 2782.0680072175846, 2788.3586775041754, 2804.6914839367655, 2778.0832325332694, 2783.958240626392, 2819.1542537238847, 2833.2916770807296, 2835.3620435402077, 2667.8799454024615, 2676.210517897225, 2694.7709444697225, 2711.52548304046, 2726.209399274208, 2681.731826817322, 2683.9597406038924, 2686.6238726578295, 2690.9730902691113, 2693.7036925938132, 15064.322105505278, 15110.848891511165, 15265.084094954338, 15283.312250948735, 15285.082895026335, 7833.569153696544, 7951.801204969865, 8149.348052155813, 8188.812622169752, 8254.752357429286, 5209.453007434203, 5279.287296215, 5382.327209098863, 5385.811496597639, 5501.944980550184, 1845.833874951261, 1891.2529550827346, 1892.4701083696586, 1901.7147427885789, 1931.951701207467, 26386.20841374762, 26517.73482265172, 26534.734652653613, 26578.0, 26641.467170656477, 6485.092087107809, 6561.573497722633, 6697.397234248941, 6728.226253980757, 6779.389854912983, 16182.352705891868, 16186.0, 16194.109323987293, 16196.271167797477, 16197.757033644504, 7760.767176984699, 7831.6475758591005, 7849.529028258323, 7866.252705993036, 7868.173841746649, 7156.105486814148, 7200.351968322771, 7220.927790722026, 7288.234735352818, 7309.780706578811, 4764.285357196448, 5060.089183946925, 5209.062368773657, 5428.728563571805, 5440.646357986719, 1422.6941207640407, 1762.1893928792642, 1772.299941523091, 1789.4899953513186, 1805.404216608516, 889.2441424788877, 892.7187935800202, 898.2275243290895, 914.4650379527918, 915.9175674189222, 8749.606267717842, 8997.875265591802, 9128.726138215865, 9145.90854091464, 9192.17270445663, 976.9364991275544, 989.3173710140125, 993.1161266472845, 1011.2971484818128, 1015.1929216272933, 5410.621256512085, 5489.368722596872, 5514.5538174805215, 5610.859728506738, 5723.513501352431, 21171.470713231985, 21753.129874804832, 21955.67066494004, 21956.23153189631, 21962.780372196394, 20810.167593296403, 21627.0, 21647.35057948264, 21717.457063573536, 22062.0, 11142.554297827995, 11391.088712902947, 11418.828717569244, 11429.085673146128, 11443.084553235887, 8902.688405905763, 9021.458712477272, 9261.583228754587, 9328.580213890256, 9439.103285187895, 17866.91066544659, 18870.433886983414, 18927.621447571248, 18972.24111035543, 19050.0, 18721.0, 18872.0, 18981.24075036983, 19157.233710651977, 19292.517687057934, 12276.693082673004, 12632.494700211888, 12635.557755478514, 12649.304288263866, 12652.493900243888, 14068.366923488673, 14132.576022719335, 14138.0, 14303.928480357532, 14334.49829255971, 11379.260348077349, 11517.42412879336, 11631.53473861036, 11722.41387930583, 11829.231099978473, 10504.737381565523, 10527.631532896312, 10937.234393592373, 10937.289076210021, 11124.110071194124, 10290.228232882713, 10337.896621033693, 10405.115565177044, 10421.010004049604, 10452.895471045496, 7547.773566793005, 7742.961285193764, 7759.689612415666, 7765.572893490912, 7830.33442157423, 7789.7273595423885, 7970.4819186752675, 8016.799580010313, 8088.8786668198845, 8093.473924194908], "value": 6813.195915940265, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.08398798175284, 515.8478248916571, 534.9465053494654, 540.9729513524322, 541.6560484092603, 6378.712957916899, 6415.273508758771, 6466.870662586745, 6470.15887934569, 6526.902096468557, 6148.938510614893, 6259.749610015594, 6283.371662833714, 6315.621062736239, 6332.480204750861, 5114.181730923053, 5133.561558259834, 5153.123968925273, 5158.40678321994, 5163.0, 5076.898462030757, 5080.720560369178, 5089.2620570017325, 5110.6422550421485, 5120.590352771788, 4898.14282500561, 4962.702237865721, 5051.823186188484, 5054.3176671149295, 5058.064258112261, 5007.699538027694, 5048.829003528482, 5059.772310246048, 5112.079825631385, 5124.180131178993, 4522.570355816225, 4546.65900057497, 4548.612673134692, 4572.474165470981, 4574.702644328108, 6144.662043587601, 6151.969240153815, 6179.62922224668, 6224.408681175281, 6239.407256310643, 5706.376450953633, 5754.971225143848, 5800.53595712342, 5805.767769289223, 5817.243758311435, 6706.429953453962, 6718.227403848571, 6749.56127851693, 6768.153980752406, 6771.695273712695, 4945.2829339745895, 4947.233178857283, 4962.925556116661, 4968.254761785725, 4968.627352948507, 7027.683754231072, 7098.716051357939, 7134.750283740044, 7143.357097861165, 7151.535150215272, 5054.241863720398, 5099.464556221617, 5104.902445974133, 5105.5149760772665, 5110.66780659256, 5101.51535604117, 5109.233614957786, 5111.0, 5126.256692779526, 5141.126008578551, 5772.769089236467, 5806.883862322815, 5808.0, 5817.185594016841, 5838.948989181903, 6709.664516774142, 6713.328667133248, 6781.525293229525, 6799.4900382471515, 6842.965785171142, 1664.592174917153, 1756.5169578366051, 1769.407248571743, 1783.5986902946993, 1786.5618177367085, 1159.62891874601, 1180.2977228549, 1192.070185255502, 1220.9145359824904, 1245.2030700351781, 5914.852128696731, 5919.822405327847, 5948.226730525004, 5972.283326000907, 5987.221661184019, 6784.809620640214, 6808.727650894008, 6838.281980391987, 6841.0, 6889.758858439931, 5807.216025836543, 5927.628948420666, 5986.700664966735, 6021.337652858269, 6027.48766354856, 3635.8909232723063, 3660.524131862841, 3672.9348488938444, 3693.575238847567, 3697.0, 6684.532082754161, 6690.966545167243, 6703.491714364327, 6727.260001399842, 6743.730250790011, 7074.71701131949, 7104.396126329217, 7112.431005519546, 7120.145582530026, 7122.2521635228595, 6673.766418175341, 6725.461963042945, 6732.2931092235585, 6736.383268015641, 6742.0, 6881.380675739208, 6896.586189836064, 6946.79159625212, 6947.930520694728, 6987.650617469208, 6783.626900520519, 6784.423324017514, 6811.89782153278, 6853.965730171517, 6868.347506986828, 6572.474202063919, 6620.080176748689, 6657.833554161185, 6718.798436046926, 6731.32686731323, 5019.322391477104, 5039.546440820343, 5049.368828896388, 5094.617903657315, 5095.566876815512, 6846.726130954706, 6931.618760968196, 6940.41006514452, 6966.860662786818, 7048.929510704827, 6647.036179754005, 6666.200055993312, 6676.465882729273, 6686.9331306688255, 6746.966265168643, 6554.541182117206, 6558.344165583403, 6563.45388147015, 6582.934170658232, 6627.900581491378, 6463.224413070461, 6474.287828338973, 6550.344965503602, 6571.310012448624, 6608.447014951386, 6414.775482858077, 6497.967510162419, 6516.478681705547, 6603.471722262111, 6624.561543765592, 6429.2284925807335, 6486.351364863476, 6491.4806815455595, 6499.1876015498065, 6509.902351464637], "value": 5160.889878429766, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [911.4121391702348, 919.650532797537, 929.688554334298, 933.6358820060174, 944.7968686732353, 18284.177212025457, 19313.4205973821, 19362.12870420829, 19401.417957461264, 19778.109985050673, 19986.200551977905, 20867.791322086774, 20878.269260575882, 20977.16091356348, 20987.47531311718, 928.442934239456, 958.9376690515129, 959.2997112108166, 965.4689920543708, 966.9903300967006, 970.6845275285525, 1007.1992765751208, 1007.6825799873053, 1009.4347165587294, 1014.122783792022, 1049.716576524338, 1058.3755584205317, 1058.6506452870574, 1058.7723639417525, 1058.8729352477676, 1050.9579616815317, 1051.0435503691642, 1055.1084333738017, 1055.250771951915, 1055.2718624149352, 773.4431209529187, 776.6932061835599, 777.9805504862367, 780.6252998560666, 781.0198201257385, 16938.23777929996, 17099.05955172465, 17227.827721722748, 17262.65474690512, 19787.406377808544, 21110.366688999202, 21586.244481443075, 21695.566088678137, 22232.110715571358, 22285.0, 16980.49058528234, 17303.221355039055, 17481.125943702766, 17498.387556435464, 17692.0, 3772.6793222576116, 3796.468494410785, 3798.2593394288124, 3798.6391292827416, 3798.772073675588, 128341.0, 128684.0, 129054.0, 130878.6912130876, 133019.0, 13860.584182474542, 14175.22036288014, 14278.428862845578, 16938.23777929996, 17373.13134343278, 30384.54423183654, 30702.232444189074, 30972.535411968838, 30977.225569360948, 30992.070237892898, 7101.751438699621, 7349.338559529668, 8542.658293668308, 8767.254783343487, 8866.42368246062, 4168.2080404723, 4242.342436922282, 4247.00195454068, 4250.3836943643, 4273.63674087706, 5126.666766660155, 5154.819581314636, 5243.737813109329, 5267.894642107136, 5278.366596008504, 1040.6305761454576, 1063.5267306048888, 1073.4418102586767, 1074.8334008228737, 1082.0532034469838, 702.7786247332074, 719.2627556754369, 723.8805597076529, 733.6955163607043, 747.4805010517638, 11769.88230117705, 11973.640790776291, 12036.699082522831, 12076.275423474619, 12515.249085054926, 690.5787469643514, 703.8733028054896, 710.8578284343153, 725.7314793526429, 729.8722723523363, 5689.743961521659, 5708.71456427177, 5803.941960580424, 5946.989011867993, 5947.5539334550085, 103752.48123759484, 104252.0, 104528.47735761425, 105346.0, 108844.0, 93571.53214233885, 96063.0, 99562.0, 100386.99613003776, 101052.49473752585, 84218.5789071063, 84325.57837210897, 86438.135618643, 88436.5578172105, 89154.0, 10827.0, 10939.577854878815, 10956.671299861016, 10960.068394186583, 11192.0, 41235.38146927738, 41644.0, 42075.15849682928, 42541.0, 42571.3614295792, 49597.75201123971, 50511.74744126256, 50530.0, 52217.0, 71418.0, 18607.06964651735, 19392.90303548473, 19608.60782784308, 19840.70238946388, 19915.502112447306, 19694.8030519693, 20105.597888042448, 20113.597728045064, 20125.0, 20334.89832550828, 17632.55918602045, 17960.640787184446, 17998.37005704794, 18152.27390904349, 18251.996140212417, 17767.644647107245, 18229.908850455664, 18304.908475457538, 19481.123349449026, 19848.206071757544, 17345.0, 17754.289828407236, 17771.73342399839, 17983.370582029565, 17985.910070449565, 13180.868191317964, 13380.799288010883, 13422.664433389244, 13790.241536715175, 13873.4450621974, 14048.508302209373, 14060.226687532284, 14423.855761442672, 14619.122852628876, 15003.024803387745], "value": 9108.166106057926, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1474.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 109.0, 96.0, 107.0, 115.0, 110.0, 116.0, 109.0, 109.0, 108.0], "value": 108.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 143.0, 174.0, 116.0, 149.0, 142.0, 115.0, 172.0, 116.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 166.0, 161.0, 161.0, 163.0, 164.0, 164.0, 170.0, 164.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 74.0, 153.0, 74.0, 74.0, 75.0, 88.0, 99.0, 73.0], "value": 85.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 130.0, 80.0, 78.0, 80.0, 130.0, 79.0, 78.0, 140.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 154.0, 147.0, 147.0, 148.0, 147.0, 148.0, 148.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 97.0, 94.0, 94.0, 94.0, 95.0, 95.0, 93.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 53.0, 52.0, 53.0, 53.0, 52.0, 52.0, 52.0, 52.0, 52.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 38.0, 38.0, 37.0, 38.0, 38.0, 38.0, 38.0], "value": 38.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 76.0, 75.0, 79.0, 77.0, 80.0, 73.0, 83.0, 77.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 124.0, 118.0, 117.0, 116.0, 120.0, 121.0, 123.0, 117.0, 158.0], "value": 123.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 171.0, 171.0, 168.0, 170.0, 170.0, 171.0, 170.0, 172.0, 167.0], "value": 171.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 65.0, 65.0, 65.0, 64.0, 62.0, 64.0, 64.0, 64.0], "value": 64.4, "unit": "score"}]}]} 03:04:48 INFO - Return code: 0 03:04:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:04:48 INFO - # TBPL SUCCESS # 03:04:48 INFO - Running post-action listener: _package_coverage_data 03:04:48 INFO - Running post-action listener: _resource_record_post_action 03:04:48 INFO - [mozharness: 2017-09-03 10:04:48.955639Z] Finished run-tests step (success) 03:04:48 INFO - Running post-run listener: _resource_record_post_run 03:04:49 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 6221824; Write bytes: 395472896; Read time: 2704; Write time: 204768 03:04:49 INFO - TinderboxPrint: CPU usage
13.1% 03:04:49 INFO - TinderboxPrint: I/O read bytes / time
6,221,824 / 2,704 03:04:49 INFO - TinderboxPrint: I/O write bytes / time
395,472,896 / 204,768 03:04:49 INFO - TinderboxPrint: CPU idle
8,244.7 (86.9%) 03:04:49 INFO - TinderboxPrint: CPU user
1,193.6 (12.6%) 03:04:49 INFO - TinderboxPrint: Swap in / out
0 / 0 03:04:49 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106594304; Read time: 0; Write time: 59412 03:04:49 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:04:49 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4476928; Write bytes: 288878592; Read time: 2672; Write time: 145356 03:04:49 INFO - Running post-run listener: _upload_blobber_files 03:04:49 INFO - Blob upload gear active. 03:04:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:04:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:04:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:04:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:04:49 INFO - (blobuploader) - INFO - Open directory for files ... 03:04:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:04:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:04:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:51 INFO - (blobuploader) - INFO - Done attempting. 03:04:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:04:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:04:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:57 INFO - (blobuploader) - INFO - Done attempting. 03:04:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:04:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:04:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:58 INFO - (blobuploader) - INFO - Done attempting. 03:04:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:04:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:04:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:04:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:04:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:04:59 INFO - (blobuploader) - INFO - Done attempting. 03:04:59 INFO - (blobuploader) - INFO - Iteration through files over. 03:04:59 INFO - Return code: 0 03:04:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:04:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:04:59 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbcd9ebb50757a0ca97f316df34856600496c06018c0d6a6572321b568b4cea200cc386305949da6c620fe2594463d803192e26e3a7c5474528f86ac90a830ff", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/683ac0664e83e6526e603bb828cffc8cd297558099b13f6d70c345c60c2c144d0cab8e292b08a6af114fa86f3c9d2bc2422d1003c72c53909282922775ba79de", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ad7c040afa91d11e305de07978cdf9a14202530949a980746bedb120f072eccee0383334d0692d7ba7c3f2eea0340d8543b47e0fa9e4a1e58c9d0bdfa660d46", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/715c985de7e0fefca0d62947d2761bdeb81a65c679faf5c146b2ed49b2e1f4610b53482f1843821c67b91d8e9a97f3f328081262435727ddb36e4a565544a99b"} 03:04:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:04:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:04:59 INFO - Contents: 03:04:59 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbcd9ebb50757a0ca97f316df34856600496c06018c0d6a6572321b568b4cea200cc386305949da6c620fe2594463d803192e26e3a7c5474528f86ac90a830ff", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/683ac0664e83e6526e603bb828cffc8cd297558099b13f6d70c345c60c2c144d0cab8e292b08a6af114fa86f3c9d2bc2422d1003c72c53909282922775ba79de", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ad7c040afa91d11e305de07978cdf9a14202530949a980746bedb120f072eccee0383334d0692d7ba7c3f2eea0340d8543b47e0fa9e4a1e58c9d0bdfa660d46", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/715c985de7e0fefca0d62947d2761bdeb81a65c679faf5c146b2ed49b2e1f4610b53482f1843821c67b91d8e9a97f3f328081262435727ddb36e4a565544a99b"} 03:04:59 INFO - Running post-run listener: copy_logs_to_upload_dir 03:04:59 INFO - Copying logs to upload dir... 03:04:59 INFO - mkdir: /builds/slave/test/build/upload/logs 03:04:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.171689 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-09-03 03:04:59.896010) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 03:04:59.903712) ========= 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=20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbcd9ebb50757a0ca97f316df34856600496c06018c0d6a6572321b568b4cea200cc386305949da6c620fe2594463d803192e26e3a7c5474528f86ac90a830ff", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/683ac0664e83e6526e603bb828cffc8cd297558099b13f6d70c345c60c2c144d0cab8e292b08a6af114fa86f3c9d2bc2422d1003c72c53909282922775ba79de", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ad7c040afa91d11e305de07978cdf9a14202530949a980746bedb120f072eccee0383334d0692d7ba7c3f2eea0340d8543b47e0fa9e4a1e58c9d0bdfa660d46", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/715c985de7e0fefca0d62947d2761bdeb81a65c679faf5c146b2ed49b2e1f4610b53482f1843821c67b91d8e9a97f3f328081262435727ddb36e4a565544a99b"} build_url:https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/JG8GD8vBTLSpKotpMpyhnw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbcd9ebb50757a0ca97f316df34856600496c06018c0d6a6572321b568b4cea200cc386305949da6c620fe2594463d803192e26e3a7c5474528f86ac90a830ff", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/683ac0664e83e6526e603bb828cffc8cd297558099b13f6d70c345c60c2c144d0cab8e292b08a6af114fa86f3c9d2bc2422d1003c72c53909282922775ba79de", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ad7c040afa91d11e305de07978cdf9a14202530949a980746bedb120f072eccee0383334d0692d7ba7c3f2eea0340d8543b47e0fa9e4a1e58c9d0bdfa660d46", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/715c985de7e0fefca0d62947d2761bdeb81a65c679faf5c146b2ed49b2e1f4610b53482f1843821c67b91d8e9a97f3f328081262435727ddb36e4a565544a99b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 03:04:59.935413) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 03:04:59.935831) ========= 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=20e20d796406ba5117c4a6f1000001ba-1504426701.600352-1796336482 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 03:05:00.017400) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 03:05:00.017706) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 03:05:00.018015) ========= ========= Total master_lag: 0.36 =========