builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-065 starttime: 1490014440.99 results: success (0) revision: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:00.992556) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:00.993356) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:00.993874) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:01.010572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:01.011249) ========= 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=523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-20 05:54:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2017-03-20 05:54:02 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084536 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:01.114536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:01.115285) ========= 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=523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017326 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:01.166275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:01.166632) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ac9d58dd3c7cc772b252044c1a3fcca878fbada4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ac9d58dd3c7cc772b252044c1a3fcca878fbada4 --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=523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-20 05:54:02,928 truncating revision to first 12 chars 2017-03-20 05:54:02,928 Setting DEBUG logging. 2017-03-20 05:54:02,928 attempt 1/10 2017-03-20 05:54:02,928 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ac9d58dd3c7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 05:54:03,249 unpacking tar archive at: mozilla-aurora-ac9d58dd3c7c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.628492 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:01.809079) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:01.809737) ========= script_repo_revision: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:01.810333) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:01.810854) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-20 05:54:01.820397) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-03-20 05:54:01.820706) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:54:03 INFO - MultiFileLogger online at 20170320 05:54:03 in /builds/slave/test 05:54:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:54:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:54:03 INFO - {'append_to_log': False, 05:54:03 INFO - 'base_work_dir': '/builds/slave/test', 05:54:03 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:54:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:54:03 INFO - 'branch': 'Mozilla-Aurora', 05:54:03 INFO - 'buildbot_json_path': 'buildprops.json', 05:54:03 INFO - 'config_files': ('talos/linux_config.py',), 05:54:03 INFO - 'default_actions': ('clobber', 05:54:03 INFO - 'read-buildbot-config', 05:54:03 INFO - 'download-and-extract', 05:54:03 INFO - 'populate-webroot', 05:54:03 INFO - 'create-virtualenv', 05:54:03 INFO - 'install', 05:54:03 INFO - 'run-tests'), 05:54:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:54:03 INFO - 'download_minidump_stackwalk': True, 05:54:03 INFO - 'download_symbols': 'ondemand', 05:54:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:54:03 INFO - 'tooltool.py': '/tools/tooltool.py', 05:54:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:54:03 INFO - '/tools/misc-python/virtualenv.py')}, 05:54:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:54:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:54:03 INFO - 'gecko_profile': False, 05:54:03 INFO - 'gecko_profile_interval': 0, 05:54:03 INFO - 'installer_path': 'installer.exe', 05:54:03 INFO - 'log_level': 'info', 05:54:03 INFO - 'log_name': 'talos', 05:54:03 INFO - 'log_to_console': True, 05:54:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:54:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:54:03 INFO - 'opt_config_files': (), 05:54:03 INFO - 'pip_index': False, 05:54:03 INFO - 'suite': 'dromaeojs-e10s', 05:54:03 INFO - 'system_bits': '32', 05:54:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:54:03 INFO - 'title': 'talos-linux64-ix-065', 05:54:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:54:03 INFO - 'use_talos_json': True, 05:54:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:54:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:54:03 INFO - 'work_dir': 'build'} 05:54:03 INFO - [mozharness: 2017-03-20 12:54:03.687156Z] Running clobber step. 05:54:03 INFO - Running pre-action listener: _resource_record_pre_action 05:54:03 INFO - Running main action method: clobber 05:54:03 INFO - rmtree: /builds/slave/test/build 05:54:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:54:04 INFO - Running post-action listener: _resource_record_post_action 05:54:04 INFO - [mozharness: 2017-03-20 12:54:04.623855Z] Finished clobber step (success) 05:54:04 INFO - [mozharness: 2017-03-20 12:54:04.623955Z] Running read-buildbot-config step. 05:54:04 INFO - Running pre-action listener: _resource_record_pre_action 05:54:04 INFO - Running main action method: read_buildbot_config 05:54:04 INFO - Using buildbot properties: 05:54:04 INFO - { 05:54:04 INFO - "project": "", 05:54:04 INFO - "product": "firefox", 05:54:04 INFO - "who": "cbook@mozilla.com", 05:54:04 INFO - "installer_path": "public/build/target.tar.bz2", 05:54:04 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 05:54:04 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 05:54:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 05:54:04 INFO - "stage_platform": "linux64-pgo", 05:54:04 INFO - "basedir": "/builds/slave/test", 05:54:04 INFO - "buildnumber": 854, 05:54:04 INFO - "platform": "ubuntu64_hw", 05:54:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:54:04 INFO - "slavebuilddir": "test", 05:54:04 INFO - "taskId": "esfw2cCKTqe0gsA0gPNG0A", 05:54:04 INFO - "branch": "mozilla-aurora", 05:54:04 INFO - "script_repo_revision": "production", 05:54:04 INFO - "revision": "ac9d58dd3c7cc772b252044c1a3fcca878fbada4", 05:54:04 INFO - "slavename": "talos-linux64-ix-065", 05:54:04 INFO - "repo_path": "releases/mozilla-aurora" 05:54:04 INFO - } 05:54:04 INFO - Finding installer, test and symbols from parent task. 05:54:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/esfw2cCKTqe0gsA0gPNG0A'}, attempt #1 05:54:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/esfw2cCKTqe0gsA0gPNG0A'}, attempt #1 05:54:05 INFO - Task dependencies: Kd9sa4hAR8aIRawEfiPrYQ 05:54:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ'}, attempt #1 05:54:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.tar.bz2 05:54:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/test_packages.json 05:54:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.crashreporter-symbols.zip 05:54:05 INFO - Running post-action listener: _resource_record_post_action 05:54:05 INFO - [mozharness: 2017-03-20 12:54:05.995706Z] Finished read-buildbot-config step (success) 05:54:05 INFO - [mozharness: 2017-03-20 12:54:05.995923Z] Running download-and-extract step. 05:54:05 INFO - Running pre-action listener: _resource_record_pre_action 05:54:05 INFO - Running main action method: download_and_extract 05:54:05 INFO - mkdir: /builds/slave/test/build/tests 05:54:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:05 INFO - trying https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.test_packages.json 05:54:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:54:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:54:06 INFO - Downloaded 896 bytes. 05:54:06 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:54:06 INFO - Using the following test package requirements: 05:54:06 INFO - {u'common': [u'target.common.tests.zip'], 05:54:06 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:54:06 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:54:06 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:54:06 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:54:06 INFO - u'mozbase': [u'target.common.tests.zip'], 05:54:06 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:54:06 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:54:06 INFO - u'web-platform': [u'target.common.tests.zip', 05:54:06 INFO - u'target.web-platform.tests.zip'], 05:54:06 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:54:06 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:54:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.common.tests.zip 05:54:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:54:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.common.tests.zip into memory 05:54:07 INFO - Expected file size: 59508598 05:54:07 INFO - Obtained file size: 59508598 05:54:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:54:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.common.tests.zip 05:54:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:54:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.common.tests.zip into memory 05:54:18 INFO - Expected file size: 59508598 05:54:18 INFO - Obtained file size: 59508598 05:54:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.talos.tests.zip 05:54:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:54:29 INFO - Fetch https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.talos.tests.zip into memory 05:54:29 INFO - Expected file size: 14404438 05:54:29 INFO - Obtained file size: 14404438 05:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:30 INFO - trying https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.tar.bz2 05:54:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:54:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:54:31 INFO - Downloaded 66673486 bytes. 05:54:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.tar.bz2 05:54:31 INFO - mkdir: /builds/slave/test/properties 05:54:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:54:31 INFO - Writing to file /builds/slave/test/properties/build_url 05:54:31 INFO - Contents: 05:54:31 INFO - build_url:https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.tar.bz2 05:54:31 INFO - Running post-action listener: _resource_record_post_action 05:54:31 INFO - Running post-action listener: set_extra_try_arguments 05:54:31 INFO - [mozharness: 2017-03-20 12:54:31.184604Z] Finished download-and-extract step (success) 05:54:31 INFO - [mozharness: 2017-03-20 12:54:31.184701Z] Running populate-webroot step. 05:54:31 INFO - Running pre-action listener: _resource_record_pre_action 05:54:31 INFO - Running main action method: populate_webroot 05:54:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:54:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:54:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:54:31 INFO - '--tppagecycles', 05:54:31 INFO - '7'], 05:54:31 INFO - 'tests': ['tsvgm']}}, 05:54:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:54:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:54:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:54:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:54:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:54:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:54:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:54:31 INFO - 'talos_options': ['--disable-e10s'], 05:54:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:54:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:54:31 INFO - 'talos_options': ['--disable-e10s'], 05:54:31 INFO - 'tests': ['damp', 'tps']}, 05:54:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:54:31 INFO - 'tests': ['damp', 'tps']}, 05:54:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:54:31 INFO - 'tests': ['dromaeo_dom']}, 05:54:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:54:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:54:31 INFO - 'tests': ['basic_compositor_video']}, 05:54:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:54:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:54:31 INFO - 'tests': ['a11yr', 05:54:31 INFO - 'ts_paint', 05:54:31 INFO - 'tpaint', 05:54:31 INFO - 'sessionrestore', 05:54:31 INFO - 'sessionrestore_no_auto_restore', 05:54:31 INFO - 'tabpaint']}, 05:54:31 INFO - 'other-e10s': {'tests': ['a11yr', 05:54:31 INFO - 'ts_paint', 05:54:31 INFO - 'tpaint', 05:54:31 INFO - 'sessionrestore', 05:54:31 INFO - 'sessionrestore_no_auto_restore', 05:54:31 INFO - 'tabpaint']}, 05:54:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:54:31 INFO - 'ts_paint', 05:54:31 INFO - 'tpaint', 05:54:31 INFO - 'sessionrestore', 05:54:31 INFO - 'sessionrestore_no_auto_restore', 05:54:31 INFO - 'tabpaint']}, 05:54:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:54:31 INFO - 'ts_paint', 05:54:31 INFO - 'tpaint', 05:54:31 INFO - 'sessionrestore', 05:54:31 INFO - 'sessionrestore_no_auto_restore', 05:54:31 INFO - 'tabpaint']}, 05:54:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:54:31 INFO - 'tests': ['a11yr', 05:54:31 INFO - 'ts_paint', 05:54:31 INFO - 'tpaint', 05:54:31 INFO - 'sessionrestore', 05:54:31 INFO - 'sessionrestore_no_auto_restore', 05:54:31 INFO - 'tabpaint']}, 05:54:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:54:31 INFO - 'tests': ['a11yr', 05:54:31 INFO - 'ts_paint', 05:54:31 INFO - 'tpaint', 05:54:31 INFO - 'sessionrestore', 05:54:31 INFO - 'sessionrestore_no_auto_restore', 05:54:31 INFO - 'tabpaint']}, 05:54:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:54:31 INFO - 'tests': ['tsvgx', 05:54:31 INFO - 'tsvgr_opacity', 05:54:31 INFO - 'tart', 05:54:31 INFO - 'tscrollx', 05:54:31 INFO - 'cart', 05:54:31 INFO - 'tsvg_static']}, 05:54:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:54:31 INFO - 'tsvgr_opacity', 05:54:31 INFO - 'tart', 05:54:31 INFO - 'tscrollx', 05:54:31 INFO - 'cart', 05:54:31 INFO - 'tsvg_static']}, 05:54:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:54:31 INFO - 'talos_options': ['--disable-e10s'], 05:54:31 INFO - 'tests': ['tp5o']}, 05:54:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:54:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:54:31 INFO - 'talos_options': ['--disable-e10s', 05:54:31 INFO - '--xperf_path', 05:54:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:31 INFO - 'tests': ['tp5n']}, 05:54:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:54:31 INFO - 'talos_options': ['--xperf_path', 05:54:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:31 INFO - 'tests': ['tp5n']}}, 05:54:31 INFO - 'talos.zip': {'path': '', 05:54:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:54:31 INFO - Running post-action listener: _resource_record_post_action 05:54:31 INFO - [mozharness: 2017-03-20 12:54:31.197783Z] Finished populate-webroot step (success) 05:54:31 INFO - [mozharness: 2017-03-20 12:54:31.197873Z] Running create-virtualenv step. 05:54:31 INFO - Running pre-action listener: _resource_record_pre_action 05:54:31 INFO - Running main action method: create_virtualenv 05:54:31 INFO - Creating virtualenv /builds/slave/test/build/venv 05:54:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:54:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:54:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:54:31 INFO - Using real prefix '/usr' 05:54:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:54:31 INFO - Installing distribute.............................................................................................................................................................................................done. 05:54:33 INFO - Installing pip.................done. 05:54:33 INFO - Return code: 0 05:54:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:54:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:54:34 INFO - Reading from file tmpfile_stdout 05:54:34 INFO - Output received: 05:54:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:54:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfacb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa290>, '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 0xfaab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab040>, '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': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', '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 05:54:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:54:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:54:34 INFO - Using env: {'DISPLAY': ':0', 05:54:34 INFO - 'HOME': '/home/cltbld', 05:54:34 INFO - 'LANG': 'en_US.UTF-8', 05:54:34 INFO - 'LANGUAGE': 'en_US:en', 05:54:34 INFO - 'LOGNAME': 'cltbld', 05:54:34 INFO - 'MAIL': '/var/mail/cltbld', 05:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:34 INFO - 'MOZ_NO_REMOTE': '1', 05:54:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:34 INFO - 'NO_EM_RESTART': '1', 05:54:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:34 INFO - 'PWD': '/builds/slave/test', 05:54:34 INFO - 'SHELL': '/bin/bash', 05:54:34 INFO - 'SHLVL': '1', 05:54:34 INFO - 'TERM': 'linux', 05:54:34 INFO - 'TMOUT': '86400', 05:54:34 INFO - 'USER': 'cltbld', 05:54:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', 05:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:34 INFO - '_': '/tools/buildbot/bin/python'} 05:54:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:54:34 INFO - Cleaning up... 05:54:34 INFO - Return code: 0 05:54:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfacb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa290>, '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 0xfaab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab040>, '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': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', '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 05:54:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:54:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:54:34 INFO - Using env: {'DISPLAY': ':0', 05:54:34 INFO - 'HOME': '/home/cltbld', 05:54:34 INFO - 'LANG': 'en_US.UTF-8', 05:54:34 INFO - 'LANGUAGE': 'en_US:en', 05:54:34 INFO - 'LOGNAME': 'cltbld', 05:54:34 INFO - 'MAIL': '/var/mail/cltbld', 05:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:34 INFO - 'MOZ_NO_REMOTE': '1', 05:54:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:34 INFO - 'NO_EM_RESTART': '1', 05:54:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:34 INFO - 'PWD': '/builds/slave/test', 05:54:34 INFO - 'SHELL': '/bin/bash', 05:54:34 INFO - 'SHLVL': '1', 05:54:34 INFO - 'TERM': 'linux', 05:54:34 INFO - 'TMOUT': '86400', 05:54:34 INFO - 'USER': 'cltbld', 05:54:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', 05:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:34 INFO - '_': '/tools/buildbot/bin/python'} 05:54:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:34 INFO - Downloading/unpacking psutil>=3.1.1 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:54:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:54:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:54:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:54:36 INFO - Installing collected packages: psutil 05:54:36 INFO - Running setup.py install for psutil 05:54:36 INFO - building 'psutil._psutil_linux' extension 05:54:36 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 05:54:36 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 05:54:36 INFO - building 'psutil._psutil_posix' extension 05:54:36 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 05:54:36 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 05:54:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:54:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:54:37 INFO - Successfully installed psutil 05:54:37 INFO - Cleaning up... 05:54:37 INFO - Return code: 0 05:54:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:37 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfacb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa290>, '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 0xfaab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab040>, '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': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', '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 05:54:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:54:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:54:37 INFO - Using env: {'DISPLAY': ':0', 05:54:37 INFO - 'HOME': '/home/cltbld', 05:54:37 INFO - 'LANG': 'en_US.UTF-8', 05:54:37 INFO - 'LANGUAGE': 'en_US:en', 05:54:37 INFO - 'LOGNAME': 'cltbld', 05:54:37 INFO - 'MAIL': '/var/mail/cltbld', 05:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:37 INFO - 'MOZ_NO_REMOTE': '1', 05:54:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:37 INFO - 'NO_EM_RESTART': '1', 05:54:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:37 INFO - 'PWD': '/builds/slave/test', 05:54:37 INFO - 'SHELL': '/bin/bash', 05:54:37 INFO - 'SHLVL': '1', 05:54:37 INFO - 'TERM': 'linux', 05:54:37 INFO - 'TMOUT': '86400', 05:54:37 INFO - 'USER': 'cltbld', 05:54:37 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', 05:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:37 INFO - '_': '/tools/buildbot/bin/python'} 05:54:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:54:37 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 05:54:37 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 05:54:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:37 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 05:54:37 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 05:54:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:54:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:54:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:54:39 INFO - Installing collected packages: mozsystemmonitor 05:54:39 INFO - Running setup.py install for mozsystemmonitor 05:54:39 INFO - Successfully installed mozsystemmonitor 05:54:39 INFO - Cleaning up... 05:54:39 INFO - Return code: 0 05:54:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfacb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa290>, '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 0xfaab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab040>, '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': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', '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 05:54:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:54:39 INFO - Using env: {'DISPLAY': ':0', 05:54:39 INFO - 'HOME': '/home/cltbld', 05:54:39 INFO - 'LANG': 'en_US.UTF-8', 05:54:39 INFO - 'LANGUAGE': 'en_US:en', 05:54:39 INFO - 'LOGNAME': 'cltbld', 05:54:39 INFO - 'MAIL': '/var/mail/cltbld', 05:54:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:39 INFO - 'MOZ_NO_REMOTE': '1', 05:54:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:39 INFO - 'NO_EM_RESTART': '1', 05:54:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:39 INFO - 'PWD': '/builds/slave/test', 05:54:39 INFO - 'SHELL': '/bin/bash', 05:54:39 INFO - 'SHLVL': '1', 05:54:39 INFO - 'TERM': 'linux', 05:54:39 INFO - 'TMOUT': '86400', 05:54:39 INFO - 'USER': 'cltbld', 05:54:39 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', 05:54:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:39 INFO - '_': '/tools/buildbot/bin/python'} 05:54:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:39 INFO - Downloading/unpacking jsonschema==2.5.1 05:54:39 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 05:54:39 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 05:54:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:39 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 05:54:39 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 05:54:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:54:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:54: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 05:54: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 05:54:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54: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 05:54: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 05:54:42 INFO - Downloading functools32-3.2.3-2.tar.gz 05:54:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:54:42 INFO - warning: no files found matching '*.txt' 05:54:42 INFO - no previously-included directories found matching 'build' 05:54:42 INFO - no previously-included directories found matching 'dist' 05:54:42 INFO - no previously-included directories found matching '.git*' 05:54:42 INFO - Installing collected packages: jsonschema, functools32 05:54:42 INFO - Running setup.py install for functools32 05:54:42 INFO - warning: no files found matching '*.txt' 05:54:42 INFO - no previously-included directories found matching 'build' 05:54:42 INFO - no previously-included directories found matching 'dist' 05:54:42 INFO - no previously-included directories found matching '.git*' 05:54:42 INFO - Successfully installed jsonschema functools32 05:54:42 INFO - Cleaning up... 05:54:42 INFO - Return code: 0 05:54:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:42 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfacb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa290>, '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 0xfaab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab040>, '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': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', '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 05:54:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:54:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:54:42 INFO - Using env: {'DISPLAY': ':0', 05:54:42 INFO - 'HOME': '/home/cltbld', 05:54:42 INFO - 'LANG': 'en_US.UTF-8', 05:54:42 INFO - 'LANGUAGE': 'en_US:en', 05:54:42 INFO - 'LOGNAME': 'cltbld', 05:54:42 INFO - 'MAIL': '/var/mail/cltbld', 05:54:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:42 INFO - 'MOZ_NO_REMOTE': '1', 05:54:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:42 INFO - 'NO_EM_RESTART': '1', 05:54:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:42 INFO - 'PWD': '/builds/slave/test', 05:54:42 INFO - 'SHELL': '/bin/bash', 05:54:42 INFO - 'SHLVL': '1', 05:54:42 INFO - 'TERM': 'linux', 05:54:42 INFO - 'TMOUT': '86400', 05:54:42 INFO - 'USER': 'cltbld', 05:54:42 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', 05:54:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:42 INFO - '_': '/tools/buildbot/bin/python'} 05:54:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:54:42 INFO - Cleaning up... 05:54:42 INFO - Return code: 0 05:54:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:42 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfacb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa290>, '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 0xfaab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab040>, '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': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', '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 05:54:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:54:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:54:42 INFO - Using env: {'DISPLAY': ':0', 05:54:42 INFO - 'HOME': '/home/cltbld', 05:54:42 INFO - 'LANG': 'en_US.UTF-8', 05:54:42 INFO - 'LANGUAGE': 'en_US:en', 05:54:42 INFO - 'LOGNAME': 'cltbld', 05:54:42 INFO - 'MAIL': '/var/mail/cltbld', 05:54:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:42 INFO - 'MOZ_NO_REMOTE': '1', 05:54:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:42 INFO - 'NO_EM_RESTART': '1', 05:54:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:42 INFO - 'PWD': '/builds/slave/test', 05:54:42 INFO - 'SHELL': '/bin/bash', 05:54:42 INFO - 'SHLVL': '1', 05:54:42 INFO - 'TERM': 'linux', 05:54:42 INFO - 'TMOUT': '86400', 05:54:42 INFO - 'USER': 'cltbld', 05:54:42 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', 05:54:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:42 INFO - '_': '/tools/buildbot/bin/python'} 05:54:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:42 INFO - Downloading/unpacking blobuploader==1.2.4 05:54:42 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 05:54:42 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 05:54:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:42 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 05:54:42 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 05:54:44 INFO - Downloading blobuploader-1.2.4.tar.gz 05:54:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:54:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:54:44 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 05:54:44 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 05:54:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:44 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 05:54:44 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 05:54:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:54:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:54: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 05:54: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 05:54:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54: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 05:54: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 05:54:45 INFO - Downloading docopt-0.6.1.tar.gz 05:54:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:54:45 INFO - Installing collected packages: blobuploader, requests, docopt 05:54:45 INFO - Running setup.py install for blobuploader 05:54:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:54:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:54:45 INFO - Running setup.py install for requests 05:54:46 INFO - Running setup.py install for docopt 05:54:46 INFO - Successfully installed blobuploader requests docopt 05:54:46 INFO - Cleaning up... 05:54:46 INFO - Return code: 0 05:54:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:46 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfacb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa290>, '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 0xfaab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab040>, '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': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', '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 05:54:46 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:54:46 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:54:46 INFO - Using env: {'DISPLAY': ':0', 05:54:46 INFO - 'HOME': '/home/cltbld', 05:54:46 INFO - 'LANG': 'en_US.UTF-8', 05:54:46 INFO - 'LANGUAGE': 'en_US:en', 05:54:46 INFO - 'LOGNAME': 'cltbld', 05:54:46 INFO - 'MAIL': '/var/mail/cltbld', 05:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:46 INFO - 'MOZ_NO_REMOTE': '1', 05:54:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:46 INFO - 'NO_EM_RESTART': '1', 05:54:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:46 INFO - 'PWD': '/builds/slave/test', 05:54:46 INFO - 'SHELL': '/bin/bash', 05:54:46 INFO - 'SHLVL': '1', 05:54:46 INFO - 'TERM': 'linux', 05:54:46 INFO - 'TMOUT': '86400', 05:54:46 INFO - 'USER': 'cltbld', 05:54:46 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', 05:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:46 INFO - '_': '/tools/buildbot/bin/python'} 05:54:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:54:46 INFO - Running setup.py (path:/tmp/pip-Kz06vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:54:46 INFO - Running setup.py (path:/tmp/pip-VZW5_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:54:46 INFO - Running setup.py (path:/tmp/pip-H3potd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:54:46 INFO - Running setup.py (path:/tmp/pip-fcZ0D1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:54:46 INFO - Running setup.py (path:/tmp/pip-Qqvppz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:46 INFO - Running setup.py (path:/tmp/pip-J9vnMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:46 INFO - Running setup.py (path:/tmp/pip-ZA0cSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:46 INFO - Running setup.py (path:/tmp/pip-lotcbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:46 INFO - Running setup.py (path:/tmp/pip-ntU0R3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:47 INFO - Running setup.py (path:/tmp/pip-qjBjm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:47 INFO - Running setup.py (path:/tmp/pip-7EPm67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:47 INFO - Running setup.py (path:/tmp/pip-BLZvfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:47 INFO - Running setup.py (path:/tmp/pip-NAEDfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:47 INFO - Running setup.py (path:/tmp/pip-2DaHdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:47 INFO - Running setup.py (path:/tmp/pip-b4irfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:47 INFO - Running setup.py (path:/tmp/pip-6d324S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:47 INFO - Running setup.py (path:/tmp/pip-4NwcLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:54:47 INFO - Running setup.py install for manifestparser 05:54:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:54:47 INFO - Running setup.py install for mozcrash 05:54:47 INFO - Running setup.py install for mozdebug 05:54:47 INFO - Running setup.py install for mozdevice 05:54:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Running setup.py install for mozfile 05:54:48 INFO - Running setup.py install for mozhttpd 05:54:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Running setup.py install for mozinfo 05:54:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Running setup.py install for mozInstall 05:54:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Running setup.py install for mozleak 05:54:48 INFO - Running setup.py install for mozlog 05:54:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Running setup.py install for moznetwork 05:54:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Running setup.py install for mozprocess 05:54:48 INFO - Running setup.py install for mozprofile 05:54:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:54:48 INFO - Running setup.py install for mozrunner 05:54:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:54:49 INFO - Running setup.py install for mozscreenshot 05:54:49 INFO - Running setup.py install for moztest 05:54:49 INFO - Running setup.py install for mozversion 05:54:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:54:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:54:49 INFO - Cleaning up... 05:54:49 INFO - Return code: 0 05:54:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:49 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfacb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa290>, '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 0xfaab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab040>, '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': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', '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 05:54:49 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:54:49 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:54:49 INFO - Using env: {'DISPLAY': ':0', 05:54:49 INFO - 'HOME': '/home/cltbld', 05:54:49 INFO - 'LANG': 'en_US.UTF-8', 05:54:49 INFO - 'LANGUAGE': 'en_US:en', 05:54:49 INFO - 'LOGNAME': 'cltbld', 05:54:49 INFO - 'MAIL': '/var/mail/cltbld', 05:54:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:49 INFO - 'MOZ_NO_REMOTE': '1', 05:54:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:49 INFO - 'NO_EM_RESTART': '1', 05:54:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:49 INFO - 'PWD': '/builds/slave/test', 05:54:49 INFO - 'SHELL': '/bin/bash', 05:54:49 INFO - 'SHLVL': '1', 05:54:49 INFO - 'TERM': 'linux', 05:54:49 INFO - 'TMOUT': '86400', 05:54:49 INFO - 'USER': 'cltbld', 05:54:49 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', 05:54:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:49 INFO - '_': '/tools/buildbot/bin/python'} 05:54:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:54:49 INFO - Running setup.py (path:/tmp/pip-uGLQsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:54:49 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)) 05:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:54:49 INFO - Running setup.py (path:/tmp/pip-2YZmQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:54:49 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)) 05:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:54:49 INFO - Running setup.py (path:/tmp/pip-YFDuat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:54:49 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)) 05:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:54:49 INFO - Running setup.py (path:/tmp/pip-tBdxuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 05:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:54:49 INFO - Running setup.py (path:/tmp/pip-D4LYsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:54:50 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:54:50 INFO - Running setup.py (path:/tmp/pip-ObKIGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:54:50 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:54:50 INFO - Running setup.py (path:/tmp/pip-Z7MPzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:54:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:54:50 INFO - Running setup.py (path:/tmp/pip-e8RH5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:54:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:54:50 INFO - Running setup.py (path:/tmp/pip-MnikvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:54:50 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:54:50 INFO - Running setup.py (path:/tmp/pip-RhmBWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:54:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:54:50 INFO - Running setup.py (path:/tmp/pip-VtWQzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:54:50 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:54:50 INFO - Running setup.py (path:/tmp/pip-fl7AjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:54:50 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:54:50 INFO - Running setup.py (path:/tmp/pip-2LlAGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:54:50 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:54:50 INFO - Running setup.py (path:/tmp/pip-s_L_Cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:54:50 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:50 INFO - Running setup.py (path:/tmp/pip-3S1Het-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:54:50 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:54:50 INFO - Running setup.py (path:/tmp/pip-nkJGwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:54:50 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)) 05:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:54:50 INFO - Running setup.py (path:/tmp/pip-UNbfSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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)) 05:54:50 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:54:50 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:54:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:54:50 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 05:54:50 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 05:54:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:54:50 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 05:54:50 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 05:54:52 INFO - Downloading blessings-1.6.tar.gz 05:54:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:54:53 INFO - Installing collected packages: blessings 05:54:53 INFO - Running setup.py install for blessings 05:54:53 INFO - Successfully installed blessings 05:54:53 INFO - Cleaning up... 05:54:53 INFO - Return code: 0 05:54:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:54:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:53 INFO - Reading from file tmpfile_stdout 05:54:53 INFO - Current package versions: 05:54:53 INFO - argparse == 1.2.1 05:54:53 INFO - blessings == 1.6 05:54:53 INFO - blobuploader == 1.2.4 05:54:53 INFO - docopt == 0.6.1 05:54:53 INFO - functools32 == 3.2.3-2 05:54:53 INFO - jsonschema == 2.5.1 05:54:53 INFO - manifestparser == 1.1 05:54:53 INFO - mozInstall == 1.12 05:54:53 INFO - mozcrash == 1.0 05:54:53 INFO - mozdebug == 0.1 05:54:53 INFO - mozdevice == 0.49 05:54:53 INFO - mozfile == 1.2 05:54:53 INFO - mozhttpd == 0.7 05:54:53 INFO - mozinfo == 0.9 05:54:53 INFO - mozleak == 0.1 05:54:53 INFO - mozlog == 3.4 05:54:53 INFO - moznetwork == 0.27 05:54:53 INFO - mozprocess == 0.25 05:54:53 INFO - mozprofile == 0.28 05:54:53 INFO - mozrunner == 6.13 05:54:53 INFO - mozscreenshot == 0.1 05:54:53 INFO - mozsystemmonitor == 0.3 05:54:53 INFO - moztest == 0.8 05:54:53 INFO - mozversion == 1.4 05:54:53 INFO - psutil == 3.1.1 05:54:53 INFO - requests == 1.2.3 05:54:53 INFO - wsgiref == 0.1.2 05:54:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:53 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfacb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa290>, '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 0xfaab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab040>, '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': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', '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 05:54:53 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 05:54:53 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:54:53 INFO - Using env: {'DISPLAY': ':0', 05:54:53 INFO - 'HOME': '/home/cltbld', 05:54:53 INFO - 'LANG': 'en_US.UTF-8', 05:54:53 INFO - 'LANGUAGE': 'en_US:en', 05:54:53 INFO - 'LOGNAME': 'cltbld', 05:54:53 INFO - 'MAIL': '/var/mail/cltbld', 05:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:53 INFO - 'MOZ_NO_REMOTE': '1', 05:54:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:53 INFO - 'NO_EM_RESTART': '1', 05:54:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:53 INFO - 'PWD': '/builds/slave/test', 05:54:53 INFO - 'SHELL': '/bin/bash', 05:54:53 INFO - 'SHLVL': '1', 05:54:53 INFO - 'TERM': 'linux', 05:54:53 INFO - 'TMOUT': '86400', 05:54:53 INFO - 'USER': 'cltbld', 05:54:53 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', 05:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:53 INFO - '_': '/tools/buildbot/bin/python'} 05:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:53 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)) 05:54:53 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)) 05:54:53 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)) 05:54:53 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)) 05:54:53 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)) 05:54:53 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)) 05:54:53 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)) 05:54:53 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)) 05:54:53 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)) 05:54:53 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)) 05:54:53 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)) 05:54:53 INFO - Cleaning up... 05:54:53 INFO - Return code: 0 05:54:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:54:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:54:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:54:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:54:53 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0xd0f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe07a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfacb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa290>, '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 0xfaab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab040>, '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': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', '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 05:54:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:54:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:54:53 INFO - Using env: {'DISPLAY': ':0', 05:54:53 INFO - 'HOME': '/home/cltbld', 05:54:53 INFO - 'LANG': 'en_US.UTF-8', 05:54:53 INFO - 'LANGUAGE': 'en_US:en', 05:54:53 INFO - 'LOGNAME': 'cltbld', 05:54:53 INFO - 'MAIL': '/var/mail/cltbld', 05:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:54:53 INFO - 'MOZ_NO_REMOTE': '1', 05:54:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:54:53 INFO - 'NO_EM_RESTART': '1', 05:54:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:54:53 INFO - 'PWD': '/builds/slave/test', 05:54:53 INFO - 'SHELL': '/bin/bash', 05:54:53 INFO - 'SHLVL': '1', 05:54:53 INFO - 'TERM': 'linux', 05:54:53 INFO - 'TMOUT': '86400', 05:54:53 INFO - 'USER': 'cltbld', 05:54:53 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', 05:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:54:53 INFO - '_': '/tools/buildbot/bin/python'} 05:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:54:53 INFO - Cleaning up... 05:54:53 INFO - Return code: 0 05:54:53 INFO - Running post-action listener: _resource_record_post_action 05:54:53 INFO - Running post-action listener: _start_resource_monitoring 05:54:53 INFO - Starting resource monitoring. 05:54:53 INFO - [mozharness: 2017-03-20 12:54:53.920208Z] Finished create-virtualenv step (success) 05:54:53 INFO - [mozharness: 2017-03-20 12:54:53.920725Z] Running install step. 05:54:53 INFO - Running pre-action listener: _resource_record_pre_action 05:54:53 INFO - Running main action method: install 05:54:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:54:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:54:54 INFO - Reading from file tmpfile_stdout 05:54:54 INFO - Detecting whether we're running mozinstall >=1.0... 05:54:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:54:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:54:54 INFO - Reading from file tmpfile_stdout 05:54:54 INFO - Output received: 05:54:54 INFO - Usage: mozinstall [options] installer 05:54:54 INFO - Options: 05:54:54 INFO - -h, --help show this help message and exit 05:54:54 INFO - -d DEST, --destination=DEST 05:54:54 INFO - Directory to install application into. [default: 05:54:54 INFO - "/builds/slave/test"] 05:54:54 INFO - --app=APP Application being installed. [default: firefox] 05:54:54 INFO - mkdir: /builds/slave/test/build/application 05:54:54 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'] 05:54:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:55:10 INFO - Reading from file tmpfile_stdout 05:55:10 INFO - Output received: 05:55:10 INFO - /builds/slave/test/build/application/firefox/firefox 05:55:10 INFO - Running post-action listener: _resource_record_post_action 05:55:10 INFO - [mozharness: 2017-03-20 12:55:10.816612Z] Finished install step (success) 05:55:10 INFO - [mozharness: 2017-03-20 12:55:10.816892Z] Running run-tests step. 05:55:10 INFO - Running pre-action listener: _resource_record_pre_action 05:55:10 INFO - Running main action method: run_tests 05:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:55:10 INFO - Python 2.7.3 05:55:10 INFO - Return code: 0 05:55:10 INFO - grabbing minidump binary from tooltool 05:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 05:55:10 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaa290>, '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 0xfaab90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfab040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:55:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:55:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:55:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:55:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:55:10 INFO - Return code: 0 05:55:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:55:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:55:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:55:10 INFO - ENV: RUST_BACKTRACE is now 1 05:55:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:55:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 05:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-065 --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 05:55:10 INFO - Using env: {'DISPLAY': ':0', 05:55:10 INFO - 'HOME': '/home/cltbld', 05:55:10 INFO - 'LANG': 'en_US.UTF-8', 05:55:10 INFO - 'LANGUAGE': 'en_US:en', 05:55:10 INFO - 'LOGNAME': 'cltbld', 05:55:10 INFO - 'MAIL': '/var/mail/cltbld', 05:55:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:55:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:10 INFO - 'MOZ_NO_REMOTE': '1', 05:55:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:55:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:10 INFO - 'NO_EM_RESTART': '1', 05:55:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:10 INFO - 'PWD': '/builds/slave/test', 05:55:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:55:10 INFO - 'RUST_BACKTRACE': '1', 05:55:10 INFO - 'SHELL': '/bin/bash', 05:55:10 INFO - 'SHLVL': '1', 05:55:10 INFO - 'TERM': 'linux', 05:55:10 INFO - 'TMOUT': '86400', 05:55:10 INFO - 'USER': 'cltbld', 05:55:10 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063', 05:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:10 INFO - '_': '/tools/buildbot/bin/python'} 05:55:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 05:55:11 INFO - mozversion application_buildid: 20170320120519 05:55:11 INFO - mozversion application_changeset: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 05:55:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:55:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:55:11 INFO - mozversion application_name: Firefox 05:55:11 INFO - mozversion application_remotingname: firefox-dev 05:55:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:55:11 INFO - mozversion application_vendor: Mozilla 05:55:11 INFO - mozversion application_version: 54.0a2 05:55:11 INFO - mozversion platform_buildid: 20170320120519 05:55:11 INFO - mozversion platform_changeset: ac9d58dd3c7cc772b252044c1a3fcca878fbada4 05:55:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:55:11 INFO - mozversion platform_version: 54.0a2 05:55:11 INFO - using testdate: 1490014511 05:55:11 INFO - actual date: 1490014511 05:55:11 INFO - starting webserver on 'localhost:56145' 05:55:11 INFO - SUITE-START | Running 2 tests 05:55:11 INFO - TEST-START | dromaeo_css 05:55:11 INFO - operating with platform_type : linux_ 05:55:11 INFO - Initialising browser for dromaeo_css test... 05:55:11 INFO - TEST-INFO | started process 1045 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIFbhKi/profile http://localhost:56145/getInfo.html) 05:55:12 INFO - PROCESS | 1045 | JavaScript error: resource://gre/components/TelemetryStartup.js, line 32: ReferenceError: TelemetryEnvironment is not defined 05:55:15 INFO - PROCESS | 1045 | __metrics Screen width/height:1600/1200 05:55:15 INFO - PROCESS | 1045 | colorDepth:24 05:55:15 INFO - PROCESS | 1045 | Browser inner width/height: 1024/697 05:55:15 INFO - PROCESS | 1045 | __metrics 05:55:16 ERROR - PROCESS | 1045 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 05:55:16 INFO - PROCESS | 1045 | ************************* 05:55:16 INFO - PROCESS | 1045 | A coding exception was thrown and uncaught in a Task. 05:55:16 INFO - PROCESS | 1045 | 05:55:16 INFO - PROCESS | 1045 | Full message: ReferenceError: TelemetryEnvironment is not defined 05:55:16 INFO - PROCESS | 1045 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 05:55:16 INFO - PROCESS | 1045 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:55:16 INFO - PROCESS | 1045 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:55:16 INFO - PROCESS | 1045 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:55:16 INFO - PROCESS | 1045 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 05:55:16 INFO - PROCESS | 1045 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 05:55:16 INFO - PROCESS | 1045 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 05:55:16 INFO - PROCESS | 1045 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 05:55:16 INFO - PROCESS | 1045 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 05:55:16 INFO - PROCESS | 1045 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 05:55:16 INFO - PROCESS | 1045 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 05:55:16 INFO - PROCESS | 1045 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:55:16 INFO - PROCESS | 1045 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:55:16 INFO - PROCESS | 1045 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:55:16 INFO - PROCESS | 1045 | Task_spawn@resource://gre/modules/Task.jsm:166:12 05:55:16 INFO - PROCESS | 1045 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 05:55:16 INFO - PROCESS | 1045 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 05:55:16 INFO - PROCESS | 1045 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 05:55:16 INFO - PROCESS | 1045 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 05:55:16 INFO - PROCESS | 1045 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 05:55:16 INFO - PROCESS | 1045 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 05:55:16 INFO - PROCESS | 1045 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 05:55:16 INFO - PROCESS | 1045 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 05:55:16 INFO - PROCESS | 1045 | 05:55:16 INFO - PROCESS | 1045 | ************************* 05:55:16 INFO - PROCESS | 1045 | WARNING: A blocker encountered an error while we were waiting. 05:55:16 INFO - PROCESS | 1045 | Blocker: TelemetryController: shutting down 05:55:16 INFO - PROCESS | 1045 | Phase: profile-before-change-telemetry 05:55:16 INFO - PROCESS | 1045 | State: {"initialized":false,"initStarted":false,"haveDelayedInitTask":false,"shutdownBarrier":"Not started","connectionsBarrier":"Not started","sendModule":{"sendingEnabled":false,"pendingPingRequestCount":0,"pendingPingActivityCount":0,"unpersistedPingCount":0,"persistedPingCount":0,"schedulerState":{"shutdown":false,"hasSendTask":false,"sendsFailed":false,"sendTaskState":null,"backoffDelay":60000}}} 05:55:16 INFO - PROCESS | 1045 | WARNING: ReferenceError: TelemetryEnvironment is not defined 05:55:16 INFO - PROCESS | 1045 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 05:55:16 INFO - PROCESS | 1045 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:55:16 INFO - PROCESS | 1045 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:55:16 INFO - PROCESS | 1045 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:55:16 INFO - PROCESS | 1045 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 05:55:16 INFO - PROCESS | 1045 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 05:55:16 INFO - PROCESS | 1045 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 05:55:16 INFO - PROCESS | 1045 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 05:55:16 INFO - PROCESS | 1045 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 05:55:16 INFO - PROCESS | 1045 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 05:55:16 INFO - PROCESS | 1045 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 05:55:16 INFO - PROCESS | 1045 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 05:55:16 INFO - PROCESS | 1045 | TaskImpl@resource://gre/modules/Task.jsm:277:3 05:55:16 INFO - PROCESS | 1045 | asyncFunction@resource://gre/modules/Task.jsm:252:14 05:55:16 INFO - PROCESS | 1045 | Task_spawn@resource://gre/modules/Task.jsm:166:12 05:55:16 INFO - PROCESS | 1045 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 05:55:16 INFO - PROCESS | 1045 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 05:55:16 INFO - PROCESS | 1045 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 05:55:16 INFO - PROCESS | 1045 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 05:55:16 INFO - PROCESS | 1045 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 05:55:16 INFO - PROCESS | 1045 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 05:55:16 INFO - PROCESS | 1045 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 05:55:16 INFO - PROCESS | 1045 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 05:55:16 INFO - PROCESS | 1045 | 05:55:16 INFO - TEST-INFO | 1045: exit 0 05:55:16 INFO - Browser initialized. 05:55:16 INFO - Running cycle 1/1 for dromaeo_css test... 05:55:16 INFO - TEST-INFO | started process 1181 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIFbhKi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:55:28 INFO - PROCESS | 1181 | RSS: Main: 144363520 05:55:28 INFO - PROCESS | 1181 | 05:58:28 INFO - PROCESS | 1181 | Cycle 1(1): loaded http://localhost:56145/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56145/tests/dromaeo/cssquery-ext.html) 05:58:30 INFO - PROCESS | 1181 | RSS: Main: 149704704 05:58:30 INFO - PROCESS | 1181 | 06:00:17 INFO - PROCESS | 1181 | ************************* 06:00:17 INFO - PROCESS | 1181 | A coding exception was thrown and uncaught in a Task. 06:00:17 INFO - PROCESS | 1181 | 06:00:17 ERROR - PROCESS | 1181 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:00:17 INFO - PROCESS | 1181 | Full stack: 06:00:17 INFO - PROCESS | 1181 | ************************* 06:01:32 INFO - PROCESS | 1181 | Cycle 1(1): loaded http://localhost:56145/tests/dromaeo/cssquery-ext.html (next: http://localhost:56145/tests/dromaeo/cssquery-jquery.html) 06:01:32 INFO - PROCESS | 1181 | RSS: Main: 148766720 06:01:32 INFO - PROCESS | 1181 | 06:04:34 INFO - PROCESS | 1181 | Cycle 1(1): loaded http://localhost:56145/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56145/tests/dromaeo/cssquery-mootools.html) 06:04:35 INFO - PROCESS | 1181 | RSS: Main: 150974464 06:04:35 INFO - PROCESS | 1181 | 06:07:37 INFO - PROCESS | 1181 | Cycle 1(1): loaded http://localhost:56145/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56145/tests/dromaeo/cssquery-prototype.html) 06:07:37 INFO - PROCESS | 1181 | RSS: Main: 148684800 06:07:37 INFO - PROCESS | 1181 | 06:10:40 INFO - PROCESS | 1181 | Cycle 1(1): loaded http://localhost:56145/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56145/tests/dromaeo/cssquery-yui.html) 06:10:40 INFO - PROCESS | 1181 | RSS: Main: 152698880 06:10:40 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | Cycle 1(1): loaded http://localhost:56145/tests/dromaeo/cssquery-yui.html (next: http://localhost:56145/tests/dromaeo/cssquery-dojo.html) 06:13:43 INFO - PROCESS | 1181 | RSS: Main: 151580672 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | __start_tp_report 06:13:43 INFO - PROCESS | 1181 | _x_x_mozilla_page_load 06:13:43 INFO - PROCESS | 1181 | _x_x_mozilla_page_load_details 06:13:43 INFO - PROCESS | 1181 | |i|pagename|runs| 06:13:43 INFO - PROCESS | 1181 | |0;dojo.html;3474.0620032591196;3485.024193225896;3500.212452198254;3506.6843984041448;3522.383582873;5850.356460789315;5956.9999999999945;5962.672053037092;5964.940350596493;5986.760529578823;5915.231019967409;5970.522358211343;5997.580169388145;5999.76000959961;6018.428249316308;5293.179557168645;5346.91979620306;5346.946530534684;5384.1923711443205;5387.326584176978;5341.786328546854;5404.83785486434;5406.324209473816;5406.567474602022;5425;5105.974470127644;5125.974370128144;5280.023195708787;5398.811041613544;5436.864078398052;5396.19057141428;5398;5400.78396864125;5418.91871621926;5424;5284.392294886099;5377.7311134443125;5383.353997520284;5385.407605163429;5389.164679474688;5711.514521265697;5729;5957.493613042897;5984.970075149597;6090.147379366892;6285.654289014103;6292.056191571255;6307.0539419087045;6316.557840951135;6316.652584107872;6482.481401487869;6495.447886929616;6499.122618446497;6505.284418713938;6523.608583485006;6016.42843929826;6016.669083200379;6030;6043.969780151071;6048.0625503047095;6196.845078872974;6271.184745982993;6348.174737284124;6395.808125756142;6431.678416079178;6284.622922624657;6298.370162983757;6304.873902522016;6309.432151106423;6309.968450157811;6316.273628532777;6410.935890641033;6411.679416029273;6434.067060276234;6446.742130314735;6135.9079613805825;6148.508119350441;6269.561130720897;6394.968025159845;6414.486841052704;6400.039994000937;6431.807045788634;6433.356664333529;6452.838679033061;6453.322401147906;4865.854024379274;4872.171730805771;4880.1215781159735;4885.951140488621;4905.141600219948;4155.106652069759;4204.07510347723;4330.891727706769;4357.041450880864;4363.80362883671;6116.792316687851;6132.814448934826;6141.508679305645;6144.9553575892205;6215.12988181655;6433.260175079926;6448.097266382711;6450.8064758057335;6462.903056454157;6473.643949582724;5955.940440595626;6004.949133901549;6074.453299203093;6123.265208174958;6140.986737188403;5436.3476382834315;5439.619226654176;5451.64564303319;5456.290682211366;5477.370102438271;6654.933450665529;6691.732330706717;6692.397684208445;6693.330666933268;6713.630750308682;6147.84630384244;6169.722362493609;6313.337099604475;6335.208098987627;6432.549721519449;6690.966545167243;6723.630200339028;6739.865202696017;6740.157480314961;6743.2582415935185;6382.106505089198;6389.808305750835;6397;6400.07198956158;6411;6132.78385080153;6184.165137706352;6234.781782637586;6404.487640988803;6500.609963402211;6138.570300078952;6202.875942481215;6204.472619827366;6213.689315534296;6237.438630523275;6164.291106522807;6208.286047104641;6213.782517611862;6216.751329946752;6220.440160385587;5963.821085367446;6017.338092809875;6146.119813464082;6176.474999625082;6220.222472190976;6177.011678131477;6213.751449941951;6356.2054743157105;6369.808905732835;6375.5537112403545;6172.135900973957;6189.102580125947;6199.16311297969;6211;6232.688365581612 06:13:43 INFO - PROCESS | 1181 | |1;ext.html;773.2885745114494;827.9668813247471;846.5936350551735;911.1207684584375;923.4320892651014;43195.568044319545;44484.55515444845;49041.75479122599;49107.01785964283;49221.75389123058;45572.772136139356;45735.313970290474;45860.54139458604;45905;46253;9044.457332560034;9174.266058715286;9191.540422978858;9971;10312.948435257851;9948.801023979517;9998.450085245346;10009.799804003915;10012.799744005115;10024.147947424477;9959.950200248988;10039.498025098752;10073.194144468462;10079.44563049032;10106.393616383004;9871.555779989918;9961.203103751719;10003.34978226413;10047.94976025119;10062.849057264148;9996.500174991294;10070;10077.596896124147;10107.3430227035;10136.13842823361;36293.81853090744;36639;36679.44980825263;36840.078998025;36862.44706329407;4149.668026557868;4164.083901541656;4210.45264115663;4238.554951730086;4247.447831781848;27037.72962270372;27111.322216944536;27141;27495.725042749516;27633.585496217573;4393.648508119343;4413.404190434282;4445.266531022377;4482.8655140345845;4559.224931761609;218843;221795;221961.56076878533;251838.4448466549;254439;46345;46701.53298466972;46833.5316646836;47035.294470582965;47057.29414058792;48119.75940120277;48288;48524.27213591799;48597.51402486001;48774.756126219145;15733.134677592841;15864.206789660702;15981;15983.680326393407;15988.76016859748;13803.17180969145;13873.514426995007;13896.235707036009;13911.860881391056;14014.089084209494;13069.15050521732;13205.537806176737;13226.867731322756;13286.867131328756;13360;5452.536534394541;5572;5593.440655934455;5598.10430331145;5761.279840019997;1025.8358662613946;1041.3595638682307;1044.9791004179874;1074.2856000759407;1078.8112080385902;26861;27083.1875043749;27259.318517036838;27390;27781.86109069482;1123.1800785426522;1126.3917484558247;1132.6432173865371;1140.8060629692973;1153.7923173828792;13995.86004139966;14191.503297384543;14286.928565357315;14451.566453006426;14483.855161448462;315394;432951;438184;438668;438804;50325.24512132321;50638.49361506338;50742.492575073775;50818.74590627023;50842.74578627083;1442.1684460735019;1447.949321773754;1473.2780937340626;1478.5019837812752;1489.2256026865969;27688.723112768614;28099.297517562223;28391.29021774472;28562.57156142618;28870.133895983156;36801.63198367982;36990.26019479542;37163.81418092892;37279.627203727614;37287.627123728416;333145;337210;337915;338089;338300;29448.26379340448;30095.548566771955;30995.380092398478;32445.837770810995;33073;39753.20493590054;40035.3994690074;40037.599624003386;40064.1987160261;40086.398704020045;34606.13484662904;34610.653893460745;34700.47949280813;34963;35034;34148.31703365869;34166.316673666886;34317.14207144841;34426.48360274548;34471.138221544665;34825.65174348224;34963.42664580049;35552.46671299881;35900.461493077106;36019.45970810492;28748.42503149967;30241.546376804807;30245.69754302429;30422.695773041985;30515.389692206474;23895.283141505086;24008;24215.152469663477;24332.75667243305;24520.386990325384 06:13:43 INFO - PROCESS | 1181 | |2;jquery.html;440.32849903896573;449.8358099293759;463.84461205496154;469.60083928660634;479.6905995632817;19975.40073797785;20973.790262097395;21003.94980250987;21737.456563585918;21844.672329915065;21382.679259811117;21708;22066.4483387915;22378.664320035212;23401;6599.967000164993;6759.324067593226;6760.763373281936;6804.18349798025;6851.725930962781;6392.872142557146;6622.9668851655915;6663.600183988976;6671.899921501182;6678.465722742193;6431.710573024202;6573.605583664971;6624.403803657669;6628.569143005713;6652.035454859042;6612.404883560479;6633.2703402625675;6672.132622759059;6690.297518760533;7153.713851445936;7315.4879158458925;7361.852762944764;7468.813279668052;7475.364594009516;7483.139438964535;25616.48767024649;25703;25784.355391115183;25931.481370372676;26390;3045.6649768525444;3117.1272043827767;3164.9525257121163;3189.585353904001;3199.1362332170183;15467;16571.66856662872;16619.584510387336;16661.583460413585;17408.477745667522;2126.893655317228;2184.912603495874;2194.7037149984867;2215.667649852519;2240.8543444676043;100263;123427;123569;124308.37845810804;125068.37465812704;17204.31182752676;17789.288428462976;18227.63544729098;18393.264269429073;18642.7628684556;31617.841910790758;31672.366552668816;31897.52153717696;31925.52111718326;32054.519182212287;10104.494775261355;10121.746956326004;10137;10174.949125254327;10454.63408780689;8453.746387608382;8508;8558.572071396406;8576.656933722581;8669.783255418664;9049.592768325441;9121;9258.120478554662;9328.533573321307;9459.52702364879;3836.5779764225913;3930.8820735377412;3935.193285376491;3980.3233450313514;4036.4348991141264;1184.6682928779956;1192.6183621241294;1214.5384753793503;1216.501234493871;1224.9448774805155;20038.699419508717;20205.39383818488;20310.796892031187;20865.791342086384;20888.791112088686;1101.1191047162238;1144.8797876222877;1146.0774076868036;1175.2302242031483;1217.6317063311774;11155.832662510069;11315.264507806965;11363.261388009752;11455.541778328774;11519.539218431337;636688;661607;687061;697841;725108;91822.54088729648;121915;122622;123724.38137809253;126376;1326.721388508424;1360.9115407498482;1378.23507953087;1382.1706975814432;1393.4426229508279;17910.28358865683;18066.277348906424;18231;18365.3572124975;18579.535511612317;37616.62383376131;49521.00957980748;49617.751911240215;50727.49272507375;51499;69581;71081;73650.63174684093;76135.61932190304;77418.6129069351;12150.392480376122;12689;12692.555760548337;12990.935045324713;13014.80477792815;13884.236366999508;13920.791188131983;14293.499727509487;14893.627659308171;14904.850951490347;12437.875621244033;12674.746505069663;12961.675958100746;13039.804402933774;13053.869461305645;9821.607135714492;11947.82078268844;12170.878291216974;12343.382830858602;13037.739245215233;13303.201807891557;13331.066825322134;13556.93221533886;13679.794803077762;13758.518451854137;12868.356582171042;13040.347982600642;13110.606681799562;13156.210627362389;13183.802242966554;12588.622341329774;12889.613311600668;13194.208347499181;13260.138091024053;13779.379927903468 06:13:43 INFO - PROCESS | 1181 | |3;mootools.html;1557.1824791984207;1558.2131023832962;1579.8025246844145;1588.0551072112096;1603.2464741571457;9484.430934143957;9705.757356066102;10799.974002469762;11584.710382240448;11616.767664646703;10684.572617095308;11021.889781102187;11335.036521895629;11381.715457113576;11389.886101138985;2461.9138330158494;2506.1980166346775;2508.9372765680914;2509.836860604055;2515.434027343851;2340.496793189464;2398.3524448399007;2402.2432933625937;2408.6868707068056;2413.3363325085566;2313.571989181998;2352.0121548949483;2352.647102934565;2364.5034542746043;2369.644553316999;2244.5510897820336;2279.908803647852;2291.129370839079;2294.300238427279;2295.644175152854;2249.8425110242288;2252.2004688335733;2268.5236100418933;2291.8968646410954;2314.525522267931;11231.157663175294;11291;11327.886721132765;11361.261518001385;11392.088632909348;5780.566457515703;5854.297484301868;5929.525637948954;5965.343812180657;5983.072623743326;3610.277944411102;3629.1834337274167;3642.3990041643096;3694.5935947045805;3789.2989796887664;1332.5202926946292;1371.2595198592749;1373.2858913365094;1379.9586012419643;1417.8227721534809;16182.271797769128;16285.755713664186;16482.175891205392;16596.668066638722;16797.160141992972;4757.5718185363485;4794.993051459163;4818.349522814376;4849.127157111754;4936.3829521309835;13734.587962361145;13813.102148360324;13846.169229846242;13862.445502180002;13900.652483687989;5942.940570594239;5967.76128954837;5989.850253743692;5992.580519363602;6009.909851352233;4558.088382323548;5285.735713214324;5297.258383826267;5393.460653934575;5518.420565840609;4162.229987452331;4202.117555313357;4255.468066491689;4281.400603915455;4304.935425968613;1132.2131118872305;1133.1274918313018;1133.4729350851894;1140.937248451343;1147.6040765935738;1100.2518287564374;1110.5946329589804;1120.4453795371344;1122.2817396866012;1145.3872178384524;7260.165081015751;7292.489525733254;7302.744403945836;7323.633818309063;7427.85144297111;1035.9896401036044;1054.852320675106;1065.9520321585546;1074.1031238915593;1081.5457507846722;4628.352030715702;4700.059988002345;4716.787744551503;4762.285657151386;4792.424909010941;14154.504592339219;14414.207218602867;14447.205403702897;14574.489892853911;14580.781288280685;13225.603231903058;13690.726185476435;14080.859191408159;14154.150750954976;14435.133891966516;10594.470276486145;10662.466876656292;10733.141348692243;10793.514291856885;10795.244332896778;7346.228645992308;7544.094708634999;7591.278828511283;7699;7778.338841198335;12820.294883781482;12930.482780688668;12958.963282937531;13159;13185.208887466399;13058.673533161747;13163.60509184726;13334.33328333599;13438.059335846398;13442.663933401353;8426.536540490331;8598.183172598594;9620.230381569598;9800.11798938099;10017.949910250694;10882.29265097766;10926.72683182895;10962.671119866416;10971.725706857073;11128.332300062022;9503.524823758646;9531.57107930131;9568.425894446076;9643.517824108907;9714.174295184706;9262.39794413361;9321.906780932104;9342.112499312554;9500;9623.518824058909;8248.257656810916;8476.533790641573;8478.703245386629;8509.87235191485;8518.531480768617;6401.263854656774;6502.219733631994;6516.73933042673;6537.803865884031;6539;6340.809775706552;6428.839279018062;6442.291347951628;6653;6675.766348177985 06:13:43 INFO - PROCESS | 1181 | |4;prototype.html;399.45075521158407;405.4992084775303;416.7228792852753;420.2058110171774;420.5353084841252;5510.614257002011;5527.894421115773;5533.063427800268;5537.640053396526;5554.527865131468;4935.8272460463895;5354.544863686591;5387.730613469331;5401.837944861651;5446.782128714856;4580.908381832362;4591.51789062147;4601.895545069193;4628.305754136873;4634.976825115887;4574.911934444253;4621.273448665287;4627.583517483426;4628.236341003747;4652.255639097745;4229.7885105744745;4372.825086996516;4484.1928452878465;4494.820207191725;4610.20202121173;4444.31113177458;4446.844360447369;4454.97772511137;4489.977550112261;4497;4225.338037036678;4235.301175306071;4272.5086615039045;4295.548967358445;4318.848840290575;5279.736013199325;5337.583946274388;5342.732863356817;5344.85085706575;5453.481919217668;5262.210848311593;5277.525022747933;5307.416184219733;5315.574754019669;5338.733063346856;5643.379228284885;5687.203791469197;5728.281515545362;5732.5987180897355;5747.3677895431465;4571.748553829538;4669.929951050737;4680.882977925545;4691.530846915315;4700.905981880377;5753.539716822645;5893.381194974509;5905.675187864708;5940.702964851784;5956.51087228193;4854.757262136879;4867.367242258484;4873.148203682649;4887.413510378707;4898.53463920927;4980.8620569042;4986.825461108916;4996.375453068366;5007.048660754518;5007.624428167902;5085.431856912236;5214.217867319932;5273.551748101454;5357.785688572491;5370.543503802221;5646.6894320811925;5655.624637652196;5661.264035675335;5664;5684.715764211773;1703.2250326101594;1717.2959086774613;1721.397510871185;1728.4037007232484;1732.2724455728624;1268.1566758105757;1326.1181314425799;1327.7097007661707;1344.3211178354868;1345.2937207965897;5597.720113994284;5674.829755107354;5677.574181936288;5708.572856785803;5726.226959360516;5792.729816484163;5842.056611787027;5866.562692140453;5905.763769449174;5927.288725352985;5454;5589.608727389043;5600.551955843523;5622.526088521037;5667.376588575254;3081.4145312390574;3093.876244950222;3108.891188808381;3109.2382366319944;3116.7065667748084;5784.091249887527;5991.131285963511;6031.984007996002;6070.939290607037;6076.210092687922;5898.557608179403;5941.376155503696;5959.927213101683;5960.552958528127;6024.608400453956;5904.468597826216;6016.69916504173;6039.607425517327;6063.848403789941;6133.29467111279;5804;5823.79616713413;5879.147523609138;5911.527077833849;5915.91126133117;5608.242887210175;5863.20846685692;5895.498882595028;5899.764009439575;5903.527717782653;5975.551833612583;6021.578489505692;6039.245094363205;6058.758564168149;6098.695065246809;4855.854324370274;4860.274602516056;4889.951100488949;4893.877653058702;4917.311576379238;5738.507987923086;5817.447342502455;5877.206577112206;5877.970610147093;5906.822795316148;5717.028105222205;5721.595650587894;5728.312602487728;5737.56968227393;5759.51044161251;5511.366192887869;5744.736158045223;5748.930385061458;5784.797532086357;5785.218995435731;5664.433556644467;5724.223751480612;5726.455986681259;5776.855578610568;5812.3025236971835;5746.56335916021;5760.222369980167;5775.036487594238;5784.479396854135;5818;5467.644603100945;5543;5546.667199968015;5572.219889215594;5675.77296908119 06:13:43 INFO - PROCESS | 1181 | |5;yui.html;844.0125053687185;872.7469033980146;923.6628630549848;923.9630414783409;924.6994726713815;16992.91503542482;17677.911610441963;17703.203355848986;17918.641627167483;18097.36659216926;16763.83236167638;17022.148892555386;17032.318707251696;17209.91395043023;17223.569410764736;845.4124383553433;856.8543347630903;880.6477409036372;883.7834730491006;886.7960369115108;862.3704695572236;877.6533269358599;877.8244351129767;879.6041781198454;881;923.6536298887917;927.2350310993423;929.1126973740096;929.525941769699;933.785229397239;917.1745429113818;926.6710317837205;929.0152438415302;929.5677509957868;929.9628014879397;682.0791930893304;682.3313153109931;682.443808296242;685.6880119545594;688.8484533402643;17409.47771566855;17649.38227162056;17964.101794910333;18044.54888627782;18387.540311492186;15850.603734906605;16206.83793162065;16213.756793648105;16450.34198632065;16659;13517;13921.860781392157;13985.860141398558;14052.297385130703;14123;4043.6765058795227;4052.068024354415;4062.9390559141343;4104.261232978063;4179.310413781723;78306.21693783047;81900.9999999994;82523.17476825275;84102.57948710279;91292;13080.476780928846;13202.669933251746;13249.271290078945;13455.663608409672;13478.52825151115;23889.402764931016;23917.880410597834;24028.27915162548;24158;24274.87862560711;9012.41419307743;9079.319051071196;9212.12484813955;9302.95348523253;9368.016358282419;5124.871878203;5239.083160446907;5261.605379596545;5265.894682106336;5421.620486565903;3653.4702468141973;3750.718696097804;3781.621837816196;3784.1863999240295;3955.3869150281753;748.6107224243472;751.9097708275042;754.8188434775615;763.050002746584;764.4266799900075;802.9839403211903;830.8338332333558;833.2708879730236;838.7567605394368;841.7138173021202;10929;11534.480948357177;11664.591739289084;12170.634880953585;12532.686682833002;818.6914778963227;823.283743143469;830.9667613295521;843.8944982073381;845.6490556419017;5297.339358932155;5306.310179676694;5688.601797874192;5757.317504697284;5853.648781073064;80635;81108;81120;83326.58336708277;85664;71481.6425917867;71984;72967.27032729711;72986;73019.26980730124;54699;57440.13839792406;61838.0724289127;64157.358426416075;64564.35435645677;10830.18773591968;11044.981457111915;11307.434628268718;11318.72563516586;11335.319880807177;38204.42693359546;40545.594544054184;41208;41892.79053604713;42934.35598465963;47466.76266618783;48890.266645999625;49491.75254123707;50159.74920125376;50775;14341;14423.891063761765;14476.927615361856;14510.419583216553;14639.853601463848;16012.599685007968;16034.67930641404;16171.191440427696;16508.17459127063;17220.913895430444;15297.694046118793;15649.217539123227;15700;15764.684706305581;16293.26278765035;14179.716405671623;14618.28225778493;14621.707565848836;15120;15307.311170997109;14936.775948361;15577.442255774786;16049.679006419572;16166;16256.837431625532;12242.693932651755;12330.876691232972;12507.812382814447;12958.935205323913;13325.40272614622;13484.393202306115;13511.662208444868;13526.120802147829;13564.440089389453;13735.656608584864 06:13:43 INFO - PROCESS | 1181 | __end_tp_report 06:13:43 INFO - PROCESS | 1181 | __start_cc_report 06:13:43 INFO - PROCESS | 1181 | _x_x_mozilla_cycle_collect,279 06:13:43 INFO - PROCESS | 1181 | __end_cc_report 06:13:43 INFO - PROCESS | 1181 | __startTimestamp1490015623324__endTimestamp 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | ------- Summary: start ------- 06:13:43 INFO - PROCESS | 1181 | Number of tests: 6 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | [#0] dojo.html Cycles:165 Average:5919.87 Median:6164.29 stddev:687.94 (11.2%) stddev-sans-first:662.76 06:13:43 INFO - PROCESS | 1181 | Values: 3474.1 3485.0 3500.2 3506.7 3522.4 5850.4 5957.0 5962.7 5964.9 5986.8 5915.2 5970.5 5997.6 5999.8 6018.4 5293.2 5346.9 5346.9 5384.2 5387.3 5341.8 5404.8 5406.3 5406.6 5425.0 5106.0 5126.0 5280.0 5398.8 5436.9 5396.2 5398.0 5400.8 5418.9 5424.0 5284.4 5377.7 5383.4 5385.4 5389.2 5711.5 5729.0 5957.5 5985.0 6090.1 6285.7 6292.1 6307.1 6316.6 6316.7 6482.5 6495.4 6499.1 6505.3 6523.6 6016.4 6016.7 6030.0 6044.0 6048.1 6196.8 6271.2 6348.2 6395.8 6431.7 6284.6 6298.4 6304.9 6309.4 6310.0 6316.3 6410.9 6411.7 6434.1 6446.7 6135.9 6148.5 6269.6 6395.0 6414.5 6400.0 6431.8 6433.4 6452.8 6453.3 4865.9 4872.2 4880.1 4886.0 4905.1 4155.1 4204.1 4330.9 4357.0 4363.8 6116.8 6132.8 6141.5 6145.0 6215.1 6433.3 6448.1 6450.8 6462.9 6473.6 5955.9 6004.9 6074.5 6123.3 6141.0 5436.3 5439.6 5451.6 5456.3 5477.4 6654.9 6691.7 6692.4 6693.3 6713.6 6147.8 6169.7 6313.3 6335.2 6432.5 6691.0 6723.6 6739.9 6740.2 6743.3 6382.1 6389.8 6397.0 6400.1 6411.0 6132.8 6184.2 6234.8 6404.5 6500.6 6138.6 6202.9 6204.5 6213.7 6237.4 6164.3 6208.3 6213.8 6216.8 6220.4 5963.8 6017.3 6146.1 6176.5 6220.2 6177.0 6213.8 6356.2 6369.8 6375.6 6172.1 6189.1 6199.2 6211.0 6232.7 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | [#1] ext.html Cycles:180 Average:48185.64 Median:186224.16 stddev:88935.76 (47.8%) stddev-sans-first:89114.01 06:13:43 INFO - PROCESS | 1181 | Values: 773.3 828.0 846.6 911.1 923.4 43195.6 44484.6 49041.8 49107.0 49221.8 45572.8 45735.3 45860.5 45905.0 46253.0 9044.5 9174.3 9191.5 9971.0 10312.9 9948.8 9998.5 10009.8 10012.8 10024.1 9960.0 10039.5 10073.2 10079.4 10106.4 9871.6 9961.2 10003.3 10047.9 10062.8 9996.5 10070.0 10077.6 10107.3 10136.1 36293.8 36639.0 36679.4 36840.1 36862.4 4149.7 4164.1 4210.5 4238.6 4247.4 27037.7 27111.3 27141.0 27495.7 27633.6 4393.6 4413.4 4445.3 4482.9 4559.2 218843.0 221795.0 221961.6 251838.4 254439.0 46345.0 46701.5 46833.5 47035.3 47057.3 48119.8 48288.0 48524.3 48597.5 48774.8 15733.1 15864.2 15981.0 15983.7 15988.8 13803.2 13873.5 13896.2 13911.9 14014.1 13069.2 13205.5 13226.9 13286.9 13360.0 5452.5 5572.0 5593.4 5598.1 5761.3 1025.8 1041.4 1045.0 1074.3 1078.8 26861.0 27083.2 27259.3 27390.0 27781.9 1123.2 1126.4 1132.6 1140.8 1153.8 13995.9 14191.5 14286.9 14451.6 14483.9 315394.0 432951.0 438184.0 438668.0 438804.0 50325.2 50638.5 50742.5 50818.7 50842.7 1442.2 1447.9 1473.3 1478.5 1489.2 27688.7 28099.3 28391.3 28562.6 28870.1 36801.6 36990.3 37163.8 37279.6 37287.6 333145.0 337210.0 337915.0 338089.0 338300.0 29448.3 30095.5 30995.4 32445.8 33073.0 39753.2 40035.4 40037.6 40064.2 40086.4 34606.1 34610.7 34700.5 34963.0 35034.0 34148.3 34166.3 34317.1 34426.5 34471.1 34825.7 34963.4 35552.5 35900.5 36019.5 28748.4 30241.5 30245.7 30422.7 30515.4 23895.3 24008.0 24215.2 24332.8 24520.4 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | [#2] jquery.html Cycles:180 Average:38678.43 Median:11565.42 stddev:112556.17 (973.2%) stddev-sans-first:112835.30 06:13:43 INFO - PROCESS | 1181 | Values: 440.3 449.8 463.8 469.6 479.7 19975.4 20973.8 21003.9 21737.5 21844.7 21382.7 21708.0 22066.4 22378.7 23401.0 6600.0 6759.3 6760.8 6804.2 6851.7 6392.9 6623.0 6663.6 6671.9 6678.5 6431.7 6573.6 6624.4 6628.6 6652.0 6612.4 6633.3 6672.1 6690.3 7153.7 7315.5 7361.9 7468.8 7475.4 7483.1 25616.5 25703.0 25784.4 25931.5 26390.0 3045.7 3117.1 3165.0 3189.6 3199.1 15467.0 16571.7 16619.6 16661.6 17408.5 2126.9 2184.9 2194.7 2215.7 2240.9 100263.0 123427.0 123569.0 124308.4 125068.4 17204.3 17789.3 18227.6 18393.3 18642.8 31617.8 31672.4 31897.5 31925.5 32054.5 10104.5 10121.7 10137.0 10174.9 10454.6 8453.7 8508.0 8558.6 8576.7 8669.8 9049.6 9121.0 9258.1 9328.5 9459.5 3836.6 3930.9 3935.2 3980.3 4036.4 1184.7 1192.6 1214.5 1216.5 1224.9 20038.7 20205.4 20310.8 20865.8 20888.8 1101.1 1144.9 1146.1 1175.2 1217.6 11155.8 11315.3 11363.3 11455.5 11519.5 636688.0 661607.0 687061.0 697841.0 725108.0 91822.5 121915.0 122622.0 123724.4 126376.0 1326.7 1360.9 1378.2 1382.2 1393.4 17910.3 18066.3 18231.0 18365.4 18579.5 37616.6 49521.0 49617.8 50727.5 51499.0 69581.0 71081.0 73650.6 76135.6 77418.6 12150.4 12689.0 12692.6 12990.9 13014.8 13884.2 13920.8 14293.5 14893.6 14904.9 12437.9 12674.7 12961.7 13039.8 13053.9 9821.6 11947.8 12170.9 12343.4 13037.7 13303.2 13331.1 13556.9 13679.8 13758.5 12868.4 13040.3 13110.6 13156.2 13183.8 12588.6 12889.6 13194.2 13260.1 13779.4 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | [#3] mootools.html Cycles:180 Average:7140.42 Median:2346.25 stddev:4493.48 (191.5%) stddev-sans-first:4486.50 06:13:43 INFO - PROCESS | 1181 | Values: 1557.2 1558.2 1579.8 1588.1 1603.2 9484.4 9705.8 10800.0 11584.7 11616.8 10684.6 11021.9 11335.0 11381.7 11389.9 2461.9 2506.2 2508.9 2509.8 2515.4 2340.5 2398.4 2402.2 2408.7 2413.3 2313.6 2352.0 2352.6 2364.5 2369.6 2244.6 2279.9 2291.1 2294.3 2295.6 2249.8 2252.2 2268.5 2291.9 2314.5 11231.2 11291.0 11327.9 11361.3 11392.1 5780.6 5854.3 5929.5 5965.3 5983.1 3610.3 3629.2 3642.4 3694.6 3789.3 1332.5 1371.3 1373.3 1380.0 1417.8 16182.3 16285.8 16482.2 16596.7 16797.2 4757.6 4795.0 4818.3 4849.1 4936.4 13734.6 13813.1 13846.2 13862.4 13900.7 5942.9 5967.8 5989.9 5992.6 6009.9 4558.1 5285.7 5297.3 5393.5 5518.4 4162.2 4202.1 4255.5 4281.4 4304.9 1132.2 1133.1 1133.5 1140.9 1147.6 1100.3 1110.6 1120.4 1122.3 1145.4 7260.2 7292.5 7302.7 7323.6 7427.9 1036.0 1054.9 1066.0 1074.1 1081.5 4628.4 4700.1 4716.8 4762.3 4792.4 14154.5 14414.2 14447.2 14574.5 14580.8 13225.6 13690.7 14080.9 14154.2 14435.1 10594.5 10662.5 10733.1 10793.5 10795.2 7346.2 7544.1 7591.3 7699.0 7778.3 12820.3 12930.5 12959.0 13159.0 13185.2 13058.7 13163.6 13334.3 13438.1 13442.7 8426.5 8598.2 9620.2 9800.1 10017.9 10882.3 10926.7 10962.7 10971.7 11128.3 9503.5 9531.6 9568.4 9643.5 9714.2 9262.4 9321.9 9342.1 9500.0 9623.5 8248.3 8476.5 8478.7 8509.9 8518.5 6401.3 6502.2 6516.7 6537.8 6539.0 6340.8 6428.8 6442.3 6653.0 6675.8 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | [#4] prototype.html Cycles:180 Average:4989.15 Median:5535.35 stddev:1329.77 (24.0%) stddev-sans-first:1288.10 06:13:43 INFO - PROCESS | 1181 | Values: 399.5 405.5 416.7 420.2 420.5 5510.6 5527.9 5533.1 5537.6 5554.5 4935.8 5354.5 5387.7 5401.8 5446.8 4580.9 4591.5 4601.9 4628.3 4635.0 4574.9 4621.3 4627.6 4628.2 4652.3 4229.8 4372.8 4484.2 4494.8 4610.2 4444.3 4446.8 4455.0 4490.0 4497.0 4225.3 4235.3 4272.5 4295.5 4318.8 5279.7 5337.6 5342.7 5344.9 5453.5 5262.2 5277.5 5307.4 5315.6 5338.7 5643.4 5687.2 5728.3 5732.6 5747.4 4571.7 4669.9 4680.9 4691.5 4700.9 5753.5 5893.4 5905.7 5940.7 5956.5 4854.8 4867.4 4873.1 4887.4 4898.5 4980.9 4986.8 4996.4 5007.0 5007.6 5085.4 5214.2 5273.6 5357.8 5370.5 5646.7 5655.6 5661.3 5664.0 5684.7 1703.2 1717.3 1721.4 1728.4 1732.3 1268.2 1326.1 1327.7 1344.3 1345.3 5597.7 5674.8 5677.6 5708.6 5726.2 5792.7 5842.1 5866.6 5905.8 5927.3 5454.0 5589.6 5600.6 5622.5 5667.4 3081.4 3093.9 3108.9 3109.2 3116.7 5784.1 5991.1 6032.0 6070.9 6076.2 5898.6 5941.4 5959.9 5960.6 6024.6 5904.5 6016.7 6039.6 6063.8 6133.3 5804.0 5823.8 5879.1 5911.5 5915.9 5608.2 5863.2 5895.5 5899.8 5903.5 5975.6 6021.6 6039.2 6058.8 6098.7 4855.9 4860.3 4890.0 4893.9 4917.3 5738.5 5817.4 5877.2 5878.0 5906.8 5717.0 5721.6 5728.3 5737.6 5759.5 5511.4 5744.7 5748.9 5784.8 5785.2 5664.4 5724.2 5726.5 5776.9 5812.3 5746.6 5760.2 5775.0 5784.5 5818.0 5467.6 5543.0 5546.7 5572.2 5675.8 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | [#5] yui.html Cycles:180 Average:18648.89 Median:22656.13 stddev:22703.77 (100.2%) stddev-sans-first:22728.09 06:13:43 INFO - PROCESS | 1181 | Values: 844.0 872.7 923.7 924.0 924.7 16992.9 17677.9 17703.2 17918.6 18097.4 16763.8 17022.1 17032.3 17209.9 17223.6 845.4 856.9 880.6 883.8 886.8 862.4 877.7 877.8 879.6 881.0 923.7 927.2 929.1 929.5 933.8 917.2 926.7 929.0 929.6 930.0 682.1 682.3 682.4 685.7 688.8 17409.5 17649.4 17964.1 18044.5 18387.5 15850.6 16206.8 16213.8 16450.3 16659.0 13517.0 13921.9 13985.9 14052.3 14123.0 4043.7 4052.1 4062.9 4104.3 4179.3 78306.2 81901.0 82523.2 84102.6 91292.0 13080.5 13202.7 13249.3 13455.7 13478.5 23889.4 23917.9 24028.3 24158.0 24274.9 9012.4 9079.3 9212.1 9303.0 9368.0 5124.9 5239.1 5261.6 5265.9 5421.6 3653.5 3750.7 3781.6 3784.2 3955.4 748.6 751.9 754.8 763.1 764.4 803.0 830.8 833.3 838.8 841.7 10929.0 11534.5 11664.6 12170.6 12532.7 818.7 823.3 831.0 843.9 845.6 5297.3 5306.3 5688.6 5757.3 5853.6 80635.0 81108.0 81120.0 83326.6 85664.0 71481.6 71984.0 72967.3 72986.0 73019.3 54699.0 57440.1 61838.1 64157.4 64564.4 10830.2 11045.0 11307.4 11318.7 11335.3 38204.4 40545.6 41208.0 41892.8 42934.4 47466.8 48890.3 49491.8 50159.7 50775.0 14341.0 14423.9 14476.9 14510.4 14639.9 16012.6 16034.7 16171.2 16508.2 17220.9 15297.7 15649.2 15700.0 15764.7 16293.3 14179.7 14618.3 14621.7 15120.0 15307.3 14936.8 15577.4 16049.7 16166.0 16256.8 12242.7 12330.9 12507.8 12958.9 13325.4 13484.4 13511.7 13526.1 13564.4 13735.7 06:13:43 INFO - PROCESS | 1181 | -------- Summary: end -------- 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | ************************* 06:13:43 INFO - PROCESS | 1181 | A coding exception was thrown and uncaught in a Task. 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | Full message: ReferenceError: TelemetryEnvironment is not defined 06:13:43 INFO - PROCESS | 1181 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:13:43 INFO - PROCESS | 1181 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:13:43 INFO - PROCESS | 1181 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:13:43 INFO - PROCESS | 1181 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:13:43 INFO - PROCESS | 1181 | shutdown@resource://gre/modules/TelemetryController.jsm:845:14 06:13:43 INFO - PROCESS | 1181 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:13:43 INFO - PROCESS | 1181 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:13:43 INFO - PROCESS | 1181 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:13:43 INFO - PROCESS | 1181 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:13:43 INFO - PROCESS | 1181 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - PROCESS | 1181 | ************************* 06:13:43 INFO - PROCESS | 1181 | WARNING: A blocker encountered an error while we were waiting. 06:13:43 INFO - PROCESS | 1181 | Blocker: TelemetryController: shutting down 06:13:43 INFO - PROCESS | 1181 | Phase: profile-before-change-telemetry 06:13:43 INFO - PROCESS | 1181 | State: {"initialized":false,"initStarted":false,"haveDelayedInitTask":false,"shutdownBarrier":"Not started","connectionsBarrier":"Not started","sendModule":{"sendingEnabled":false,"pendingPingRequestCount":0,"pendingPingActivityCount":0,"unpersistedPingCount":0,"persistedPingCount":0,"schedulerState":{"shutdown":false,"hasSendTask":false,"sendsFailed":false,"sendTaskState":null,"backoffDelay":60000}}} 06:13:43 INFO - PROCESS | 1181 | WARNING: ReferenceError: TelemetryEnvironment is not defined 06:13:43 INFO - PROCESS | 1181 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:13:43 INFO - PROCESS | 1181 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:13:43 INFO - PROCESS | 1181 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:13:43 INFO - PROCESS | 1181 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:13:43 INFO - PROCESS | 1181 | shutdown@resource://gre/modules/TelemetryController.jsm:845:14 06:13:43 INFO - PROCESS | 1181 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:13:43 INFO - PROCESS | 1181 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:13:43 INFO - PROCESS | 1181 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:13:43 INFO - PROCESS | 1181 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:13:43 INFO - PROCESS | 1181 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:13:43 INFO - PROCESS | 1181 | 06:13:43 INFO - TEST-INFO | 1181: exit 0 06:13:43 INFO - TEST-OK | dromaeo_css | took 1112806ms 06:13:43 INFO - TEST-START | kraken 06:13:43 INFO - operating with platform_type : linux_ 06:13:43 INFO - Initialising browser for kraken test... 06:13:43 INFO - TEST-INFO | started process 1553 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsFFEt5/profile http://localhost:56145/getInfo.html) 06:13:45 INFO - PROCESS | 1553 | JavaScript error: resource://gre/components/TelemetryStartup.js, line 32: ReferenceError: TelemetryEnvironment is not defined 06:13:48 INFO - PROCESS | 1553 | __metrics Screen width/height:1600/1200 06:13:48 INFO - PROCESS | 1553 | colorDepth:24 06:13:48 INFO - PROCESS | 1553 | Browser inner width/height: 1024/697 06:13:48 INFO - PROCESS | 1553 | __metrics 06:13:48 ERROR - PROCESS | 1553 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 06:13:49 INFO - PROCESS | 1553 | ************************* 06:13:49 INFO - PROCESS | 1553 | A coding exception was thrown and uncaught in a Task. 06:13:49 INFO - PROCESS | 1553 | 06:13:49 INFO - PROCESS | 1553 | Full message: ReferenceError: TelemetryEnvironment is not defined 06:13:49 INFO - PROCESS | 1553 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:13:49 INFO - PROCESS | 1553 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:13:49 INFO - PROCESS | 1553 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:13:49 INFO - PROCESS | 1553 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:13:49 INFO - PROCESS | 1553 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:13:49 INFO - PROCESS | 1553 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:13:49 INFO - PROCESS | 1553 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:13:49 INFO - PROCESS | 1553 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:13:49 INFO - PROCESS | 1553 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:13:49 INFO - PROCESS | 1553 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:13:49 INFO - PROCESS | 1553 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:13:49 INFO - PROCESS | 1553 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:13:49 INFO - PROCESS | 1553 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:13:49 INFO - PROCESS | 1553 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:13:49 INFO - PROCESS | 1553 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:13:49 INFO - PROCESS | 1553 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:13:49 INFO - PROCESS | 1553 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:13:49 INFO - PROCESS | 1553 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:13:49 INFO - PROCESS | 1553 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:13:49 INFO - PROCESS | 1553 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:13:49 INFO - PROCESS | 1553 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:13:49 INFO - PROCESS | 1553 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:13:49 INFO - PROCESS | 1553 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:13:49 INFO - PROCESS | 1553 | 06:13:49 INFO - PROCESS | 1553 | ************************* 06:13:49 INFO - PROCESS | 1553 | WARNING: A blocker encountered an error while we were waiting. 06:13:49 INFO - PROCESS | 1553 | Blocker: TelemetryController: shutting down 06:13:49 INFO - PROCESS | 1553 | Phase: profile-before-change-telemetry 06:13:49 INFO - PROCESS | 1553 | State: {"initialized":false,"initStarted":false,"haveDelayedInitTask":false,"shutdownBarrier":"Not started","connectionsBarrier":"Not started","sendModule":{"sendingEnabled":false,"pendingPingRequestCount":0,"pendingPingActivityCount":0,"unpersistedPingCount":0,"persistedPingCount":0,"schedulerState":{"shutdown":false,"hasSendTask":false,"sendsFailed":false,"sendTaskState":null,"backoffDelay":60000}}} 06:13:49 INFO - PROCESS | 1553 | WARNING: ReferenceError: TelemetryEnvironment is not defined 06:13:49 INFO - PROCESS | 1553 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:13:49 INFO - PROCESS | 1553 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:13:49 INFO - PROCESS | 1553 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:13:49 INFO - PROCESS | 1553 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:13:49 INFO - PROCESS | 1553 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:13:49 INFO - PROCESS | 1553 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:13:49 INFO - PROCESS | 1553 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:13:49 INFO - PROCESS | 1553 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:13:49 INFO - PROCESS | 1553 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:13:49 INFO - PROCESS | 1553 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:13:49 INFO - PROCESS | 1553 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:13:49 INFO - PROCESS | 1553 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:13:49 INFO - PROCESS | 1553 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:13:49 INFO - PROCESS | 1553 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:13:49 INFO - PROCESS | 1553 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:13:49 INFO - PROCESS | 1553 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:13:49 INFO - PROCESS | 1553 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:13:49 INFO - PROCESS | 1553 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:13:49 INFO - PROCESS | 1553 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:13:49 INFO - PROCESS | 1553 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:13:49 INFO - PROCESS | 1553 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:13:49 INFO - PROCESS | 1553 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:13:49 INFO - PROCESS | 1553 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:13:49 INFO - PROCESS | 1553 | 06:13:49 INFO - TEST-INFO | 1553: exit 0 06:13:49 INFO - Browser initialized. 06:13:49 INFO - Running cycle 1/1 for kraken test... 06:13:49 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsFFEt5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:14:01 INFO - PROCESS | 1683 | RSS: Main: 143777792 06:14:01 INFO - PROCESS | 1683 | 06:14:03 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56145/tests/kraken/driver.html?testName=audio-beat-detection) 06:14:04 INFO - PROCESS | 1683 | RSS: Main: 150319104 06:14:04 INFO - PROCESS | 1683 | 06:14:07 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56145/tests/kraken/driver.html?testName=audio-dft) 06:14:07 INFO - PROCESS | 1683 | RSS: Main: 149794816 06:14:07 INFO - PROCESS | 1683 | 06:14:10 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56145/tests/kraken/driver.html?testName=audio-fft) 06:14:11 INFO - PROCESS | 1683 | RSS: Main: 150274048 06:14:11 INFO - PROCESS | 1683 | 06:14:13 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56145/tests/kraken/driver.html?testName=audio-oscillator) 06:14:14 INFO - PROCESS | 1683 | RSS: Main: 150720512 06:14:14 INFO - PROCESS | 1683 | 06:14:16 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56145/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:14:16 INFO - PROCESS | 1683 | RSS: Main: 150138880 06:14:16 INFO - PROCESS | 1683 | 06:14:19 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56145/tests/kraken/driver.html?testName=imaging-darkroom) 06:14:20 INFO - PROCESS | 1683 | RSS: Main: 154300416 06:14:20 INFO - PROCESS | 1683 | 06:14:23 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56145/tests/kraken/driver.html?testName=imaging-desaturate) 06:14:24 INFO - PROCESS | 1683 | RSS: Main: 153808896 06:14:24 INFO - PROCESS | 1683 | 06:14:27 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56145/tests/kraken/driver.html?testName=json-parse-financial) 06:14:28 INFO - PROCESS | 1683 | RSS: Main: 153128960 06:14:28 INFO - PROCESS | 1683 | 06:14:29 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56145/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:14:30 INFO - PROCESS | 1683 | RSS: Main: 153251840 06:14:30 INFO - PROCESS | 1683 | 06:14:31 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56145/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:14:32 INFO - PROCESS | 1683 | RSS: Main: 153292800 06:14:32 INFO - PROCESS | 1683 | 06:14:34 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56145/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:14:35 INFO - PROCESS | 1683 | RSS: Main: 153657344 06:14:35 INFO - PROCESS | 1683 | 06:14:37 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:14:38 INFO - PROCESS | 1683 | RSS: Main: 154132480 06:14:38 INFO - PROCESS | 1683 | 06:14:41 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:14:42 INFO - PROCESS | 1683 | RSS: Main: 154034176 06:14:42 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:56145/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56145/tests/kraken/driver.html?testName=ai-astar) 06:14:43 INFO - PROCESS | 1683 | RSS: Main: 155783168 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | __start_tp_report 06:14:43 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 06:14:43 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 06:14:43 INFO - PROCESS | 1683 | |i|pagename|runs| 06:14:43 INFO - PROCESS | 1683 | |0;ai-astar;104;113;116;115;113;115;113;119;113;115 06:14:43 INFO - PROCESS | 1683 | |1;audio-beat-detection;145;119;120;162;119;118;118;117;147;118 06:14:43 INFO - PROCESS | 1683 | |2;audio-dft;164;165;171;163;164;163;168;163;164;163 06:14:43 INFO - PROCESS | 1683 | |3;audio-fft;76;76;77;119;79;77;76;75;99;77 06:14:43 INFO - PROCESS | 1683 | |4;audio-oscillator;83;82;100;82;81;81;101;82;86;106 06:14:43 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;113;115;113;113;119;113;114;114;113;114 06:14:43 INFO - PROCESS | 1683 | |6;imaging-darkroom;147;148;150;148;148;148;148;148;148;149 06:14:43 INFO - PROCESS | 1683 | |7;imaging-desaturate;95;96;96;94;95;95;95;95;95;94 06:14:43 INFO - PROCESS | 1683 | |8;json-parse-financial;62;61;67;61;62;62;62;64;61;62 06:14:43 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;54;54;55;55;54;54;54;55;55;55 06:14:43 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;81;82;78;80;86;75;79;80;75;76 06:14:43 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;126;127;143;137;125;128;121;123;168;127 06:14:43 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;162;169;162;162;163;169;163;161;159;159 06:14:43 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;63;61;60;66;66;59;62;59;61;61 06:14:43 INFO - PROCESS | 1683 | __end_tp_report 06:14:43 INFO - PROCESS | 1683 | __start_cc_report 06:14:43 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,759 06:14:43 INFO - PROCESS | 1683 | __end_cc_report 06:14:43 INFO - PROCESS | 1683 | __startTimestamp1490015683969__endTimestamp 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | ------- Summary: start ------- 06:14:43 INFO - PROCESS | 1683 | Number of tests: 14 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:113.60 Median:115.00 stddev:3.86 (3.4%) stddev-sans-first:2.00 06:14:43 INFO - PROCESS | 1683 | Values: 104.0 113.0 116.0 115.0 113.0 115.0 113.0 119.0 113.0 115.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:119.50 stddev:16.51 (13.8%) stddev-sans-first:16.36 06:14:43 INFO - PROCESS | 1683 | Values: 145.0 119.0 120.0 162.0 119.0 118.0 118.0 117.0 147.0 118.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:164.80 Median:164.00 stddev:2.66 (1.6%) stddev-sans-first:2.80 06:14:43 INFO - PROCESS | 1683 | Values: 164.0 165.0 171.0 163.0 164.0 163.0 168.0 163.0 164.0 163.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:83.10 Median:77.00 stddev:14.48 (18.8%) stddev-sans-first:15.13 06:14:43 INFO - PROCESS | 1683 | Values: 76.0 76.0 77.0 119.0 79.0 77.0 76.0 75.0 99.0 77.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:82.00 stddev:9.83 (12.0%) stddev-sans-first:10.23 06:14:43 INFO - PROCESS | 1683 | Values: 83.0 82.0 100.0 82.0 81.0 81.0 101.0 82.0 86.0 106.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:1.85 (1.6%) stddev-sans-first:1.92 06:14:43 INFO - PROCESS | 1683 | Values: 113.0 115.0 113.0 113.0 119.0 113.0 114.0 114.0 113.0 114.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.00 stddev:0.79 (0.5%) stddev-sans-first:0.71 06:14:43 INFO - PROCESS | 1683 | Values: 147.0 148.0 150.0 148.0 148.0 148.0 148.0 148.0 148.0 149.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 06:14:43 INFO - PROCESS | 1683 | Values: 95.0 96.0 96.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:62.00 stddev:1.84 (3.0%) stddev-sans-first:1.94 06:14:43 INFO - PROCESS | 1683 | Values: 62.0 61.0 67.0 61.0 62.0 62.0 62.0 64.0 61.0 62.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:54.50 Median:55.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 06:14:43 INFO - PROCESS | 1683 | Values: 54.0 54.0 55.0 55.0 54.0 54.0 54.0 55.0 55.0 55.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:80.00 stddev:3.43 (4.3%) stddev-sans-first:3.57 06:14:43 INFO - PROCESS | 1683 | Values: 81.0 82.0 78.0 80.0 86.0 75.0 79.0 80.0 75.0 76.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:132.50 Median:127.50 stddev:14.11 (11.1%) stddev-sans-first:14.77 06:14:43 INFO - PROCESS | 1683 | Values: 126.0 127.0 143.0 137.0 125.0 128.0 121.0 123.0 168.0 127.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.90 Median:162.50 stddev:3.51 (2.2%) stddev-sans-first:3.71 06:14:43 INFO - PROCESS | 1683 | Values: 162.0 169.0 162.0 162.0 163.0 169.0 163.0 161.0 159.0 159.0 06:14:43 INFO - PROCESS | 1683 | 06:14:43 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:61.50 stddev:2.53 (4.1%) stddev-sans-first:2.65 06:14:43 INFO - PROCESS | 1683 | Values: 63.0 61.0 60.0 66.0 66.0 59.0 62.0 59.0 61.0 61.0 06:14:43 INFO - PROCESS | 1683 | -------- Summary: end -------- 06:14:43 INFO - PROCESS | 1683 | 06:14:44 INFO - PROCESS | 1683 | ************************* 06:14:44 INFO - PROCESS | 1683 | A coding exception was thrown and uncaught in a Task. 06:14:44 INFO - PROCESS | 1683 | 06:14:44 INFO - PROCESS | 1683 | Full message: ReferenceError: TelemetryEnvironment is not defined 06:14:44 INFO - PROCESS | 1683 | Full stack: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:14:44 INFO - PROCESS | 1683 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:14:44 INFO - PROCESS | 1683 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:14:44 INFO - PROCESS | 1683 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:14:44 INFO - PROCESS | 1683 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:14:44 INFO - PROCESS | 1683 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:14:44 INFO - PROCESS | 1683 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:14:44 INFO - PROCESS | 1683 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:14:44 INFO - PROCESS | 1683 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:14:44 INFO - PROCESS | 1683 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:14:44 INFO - PROCESS | 1683 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:14:44 INFO - PROCESS | 1683 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:14:44 INFO - PROCESS | 1683 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:14:44 INFO - PROCESS | 1683 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:14:44 INFO - PROCESS | 1683 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:14:44 INFO - PROCESS | 1683 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:14:44 INFO - PROCESS | 1683 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:14:44 INFO - PROCESS | 1683 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:14:44 INFO - PROCESS | 1683 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:14:44 INFO - PROCESS | 1683 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:14:44 INFO - PROCESS | 1683 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:14:44 INFO - PROCESS | 1683 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:14:44 INFO - PROCESS | 1683 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:14:44 INFO - PROCESS | 1683 | 06:14:44 INFO - PROCESS | 1683 | ************************* 06:14:44 INFO - PROCESS | 1683 | WARNING: A blocker encountered an error while we were waiting. 06:14:44 INFO - PROCESS | 1683 | Blocker: TelemetryController: shutting down 06:14:44 INFO - PROCESS | 1683 | Phase: profile-before-change-telemetry 06:14:44 INFO - PROCESS | 1683 | State: {"initialized":false,"initStarted":false,"haveDelayedInitTask":false,"shutdownBarrier":"Not started","connectionsBarrier":"Not started","sendModule":{"sendingEnabled":false,"pendingPingRequestCount":0,"pendingPingActivityCount":0,"unpersistedPingCount":0,"persistedPingCount":0,"schedulerState":{"shutdown":false,"hasSendTask":false,"sendsFailed":false,"sendTaskState":null,"backoffDelay":60000}}} 06:14:44 INFO - PROCESS | 1683 | WARNING: ReferenceError: TelemetryEnvironment is not defined 06:14:44 INFO - PROCESS | 1683 | WARNING: Impl._cleanupOnShutdown<@resource://gre/modules/TelemetryController.jsm:803:7 06:14:44 INFO - PROCESS | 1683 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:14:44 INFO - PROCESS | 1683 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:14:44 INFO - PROCESS | 1683 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:14:44 INFO - PROCESS | 1683 | shutdown/<@resource://gre/modules/TelemetryController.jsm:849:56 06:14:44 INFO - PROCESS | 1683 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23 06:14:44 INFO - PROCESS | 1683 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7 06:14:44 INFO - PROCESS | 1683 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11 06:14:44 INFO - PROCESS | 1683 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7 06:14:44 INFO - PROCESS | 1683 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:455:5 06:14:44 INFO - PROCESS | 1683 | _timerCallback/<@resource://gre/modules/DeferredTask.jsm:277:13 06:14:44 INFO - PROCESS | 1683 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:14:44 INFO - PROCESS | 1683 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:14:44 INFO - PROCESS | 1683 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:14:44 INFO - PROCESS | 1683 | Task_spawn@resource://gre/modules/Task.jsm:166:12 06:14:44 INFO - PROCESS | 1683 | _timerCallback@resource://gre/modules/DeferredTask.jsm:275:29 06:14:44 INFO - PROCESS | 1683 | finalize@resource://gre/modules/DeferredTask.jsm:249:7 06:14:44 INFO - PROCESS | 1683 | shutdown@resource://gre/modules/TelemetryController.jsm:849:12 06:14:44 INFO - PROCESS | 1683 | setupTelemetry/<@resource://gre/modules/TelemetryController.jsm:767:50 06:14:44 INFO - PROCESS | 1683 | trigger@resource://gre/modules/AsyncShutdown.jsm:719:23 06:14:44 INFO - PROCESS | 1683 | _wait@resource://gre/modules/AsyncShutdown.jsm:866:7 06:14:44 INFO - PROCESS | 1683 | wait@resource://gre/modules/AsyncShutdown.jsm:850:28 06:14:44 INFO - PROCESS | 1683 | observe@resource://gre/modules/AsyncShutdown.jsm:533:17 06:14:44 INFO - PROCESS | 1683 | 06:14:44 INFO - TEST-INFO | 1683: exit 0 06:14:44 INFO - TEST-OK | kraken | took 60763ms 06:14:44 INFO - SUITE-END | took 1173s 06:14:45 INFO - Completed test suite (00:19:34) 06:14:45 INFO - javascript benchmark 06:14:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7981.503142136878, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3474.0620032591196, 3485.024193225896, 3500.212452198254, 3506.6843984041448, 3522.383582873, 5850.356460789315, 5956.9999999999945, 5962.672053037092, 5964.940350596493, 5986.760529578823, 5915.231019967409, 5970.522358211343, 5997.580169388145, 5999.76000959961, 6018.428249316308, 5293.179557168645, 5346.91979620306, 5346.946530534684, 5384.1923711443205, 5387.326584176978, 5341.786328546854, 5404.83785486434, 5406.324209473816, 5406.567474602022, 5425.0, 5105.974470127644, 5125.974370128144, 5280.023195708787, 5398.811041613544, 5436.864078398052, 5396.19057141428, 5398.0, 5400.78396864125, 5418.91871621926, 5424.0, 5284.392294886099, 5377.7311134443125, 5383.353997520284, 5385.407605163429, 5389.164679474688, 5711.514521265697, 5729.0, 5957.493613042897, 5984.970075149597, 6090.147379366892, 6285.654289014103, 6292.056191571255, 6307.0539419087045, 6316.557840951135, 6316.652584107872, 6482.481401487869, 6495.447886929616, 6499.122618446497, 6505.284418713938, 6523.608583485006, 6016.42843929826, 6016.669083200379, 6030.0, 6043.969780151071, 6048.0625503047095, 6196.845078872974, 6271.184745982993, 6348.174737284124, 6395.808125756142, 6431.678416079178, 6284.622922624657, 6298.370162983757, 6304.873902522016, 6309.432151106423, 6309.968450157811, 6316.273628532777, 6410.935890641033, 6411.679416029273, 6434.067060276234, 6446.742130314735, 6135.9079613805825, 6148.508119350441, 6269.561130720897, 6394.968025159845, 6414.486841052704, 6400.039994000937, 6431.807045788634, 6433.356664333529, 6452.838679033061, 6453.322401147906, 4865.854024379274, 4872.171730805771, 4880.1215781159735, 4885.951140488621, 4905.141600219948, 4155.106652069759, 4204.07510347723, 4330.891727706769, 4357.041450880864, 4363.80362883671, 6116.792316687851, 6132.814448934826, 6141.508679305645, 6144.9553575892205, 6215.12988181655, 6433.260175079926, 6448.097266382711, 6450.8064758057335, 6462.903056454157, 6473.643949582724, 5955.940440595626, 6004.949133901549, 6074.453299203093, 6123.265208174958, 6140.986737188403, 5436.3476382834315, 5439.619226654176, 5451.64564303319, 5456.290682211366, 5477.370102438271, 6654.933450665529, 6691.732330706717, 6692.397684208445, 6693.330666933268, 6713.630750308682, 6147.84630384244, 6169.722362493609, 6313.337099604475, 6335.208098987627, 6432.549721519449, 6690.966545167243, 6723.630200339028, 6739.865202696017, 6740.157480314961, 6743.2582415935185, 6382.106505089198, 6389.808305750835, 6397.0, 6400.07198956158, 6411.0, 6132.78385080153, 6184.165137706352, 6234.781782637586, 6404.487640988803, 6500.609963402211, 6138.570300078952, 6202.875942481215, 6204.472619827366, 6213.689315534296, 6237.438630523275, 6164.291106522807, 6208.286047104641, 6213.782517611862, 6216.751329946752, 6220.440160385587, 5963.821085367446, 6017.338092809875, 6146.119813464082, 6176.474999625082, 6220.222472190976, 6177.011678131477, 6213.751449941951, 6356.2054743157105, 6369.808905732835, 6375.5537112403545, 6172.135900973957, 6189.102580125947, 6199.16311297969, 6211.0, 6232.688365581612], "value": 5872.9954778811825, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.2885745114494, 827.9668813247471, 846.5936350551735, 911.1207684584375, 923.4320892651014, 43195.568044319545, 44484.55515444845, 49041.75479122599, 49107.01785964283, 49221.75389123058, 45572.772136139356, 45735.313970290474, 45860.54139458604, 45905.0, 46253.0, 9044.457332560034, 9174.266058715286, 9191.540422978858, 9971.0, 10312.948435257851, 9948.801023979517, 9998.450085245346, 10009.799804003915, 10012.799744005115, 10024.147947424477, 9959.950200248988, 10039.498025098752, 10073.194144468462, 10079.44563049032, 10106.393616383004, 9871.555779989918, 9961.203103751719, 10003.34978226413, 10047.94976025119, 10062.849057264148, 9996.500174991294, 10070.0, 10077.596896124147, 10107.3430227035, 10136.13842823361, 36293.81853090744, 36639.0, 36679.44980825263, 36840.078998025, 36862.44706329407, 4149.668026557868, 4164.083901541656, 4210.45264115663, 4238.554951730086, 4247.447831781848, 27037.72962270372, 27111.322216944536, 27141.0, 27495.725042749516, 27633.585496217573, 4393.648508119343, 4413.404190434282, 4445.266531022377, 4482.8655140345845, 4559.224931761609, 218843.0, 221795.0, 221961.56076878533, 251838.4448466549, 254439.0, 46345.0, 46701.53298466972, 46833.5316646836, 47035.294470582965, 47057.29414058792, 48119.75940120277, 48288.0, 48524.27213591799, 48597.51402486001, 48774.756126219145, 15733.134677592841, 15864.206789660702, 15981.0, 15983.680326393407, 15988.76016859748, 13803.17180969145, 13873.514426995007, 13896.235707036009, 13911.860881391056, 14014.089084209494, 13069.15050521732, 13205.537806176737, 13226.867731322756, 13286.867131328756, 13360.0, 5452.536534394541, 5572.0, 5593.440655934455, 5598.10430331145, 5761.279840019997, 1025.8358662613946, 1041.3595638682307, 1044.9791004179874, 1074.2856000759407, 1078.8112080385902, 26861.0, 27083.1875043749, 27259.318517036838, 27390.0, 27781.86109069482, 1123.1800785426522, 1126.3917484558247, 1132.6432173865371, 1140.8060629692973, 1153.7923173828792, 13995.86004139966, 14191.503297384543, 14286.928565357315, 14451.566453006426, 14483.855161448462, 315394.0, 432951.0, 438184.0, 438668.0, 438804.0, 50325.24512132321, 50638.49361506338, 50742.492575073775, 50818.74590627023, 50842.74578627083, 1442.1684460735019, 1447.949321773754, 1473.2780937340626, 1478.5019837812752, 1489.2256026865969, 27688.723112768614, 28099.297517562223, 28391.29021774472, 28562.57156142618, 28870.133895983156, 36801.63198367982, 36990.26019479542, 37163.81418092892, 37279.627203727614, 37287.627123728416, 333145.0, 337210.0, 337915.0, 338089.0, 338300.0, 29448.26379340448, 30095.548566771955, 30995.380092398478, 32445.837770810995, 33073.0, 39753.20493590054, 40035.3994690074, 40037.599624003386, 40064.1987160261, 40086.398704020045, 34606.13484662904, 34610.653893460745, 34700.47949280813, 34963.0, 35034.0, 34148.31703365869, 34166.316673666886, 34317.14207144841, 34426.48360274548, 34471.138221544665, 34825.65174348224, 34963.42664580049, 35552.46671299881, 35900.461493077106, 36019.45970810492, 28748.42503149967, 30241.546376804807, 30245.69754302429, 30422.695773041985, 30515.389692206474, 23895.283141505086, 24008.0, 24215.152469663477, 24332.75667243305, 24520.386990325384], "value": 18658.82716809203, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.32849903896573, 449.8358099293759, 463.84461205496154, 469.60083928660634, 479.6905995632817, 19975.40073797785, 20973.790262097395, 21003.94980250987, 21737.456563585918, 21844.672329915065, 21382.679259811117, 21708.0, 22066.4483387915, 22378.664320035212, 23401.0, 6599.967000164993, 6759.324067593226, 6760.763373281936, 6804.18349798025, 6851.725930962781, 6392.872142557146, 6622.9668851655915, 6663.600183988976, 6671.899921501182, 6678.465722742193, 6431.710573024202, 6573.605583664971, 6624.403803657669, 6628.569143005713, 6652.035454859042, 6612.404883560479, 6633.2703402625675, 6672.132622759059, 6690.297518760533, 7153.713851445936, 7315.4879158458925, 7361.852762944764, 7468.813279668052, 7475.364594009516, 7483.139438964535, 25616.48767024649, 25703.0, 25784.355391115183, 25931.481370372676, 26390.0, 3045.6649768525444, 3117.1272043827767, 3164.9525257121163, 3189.585353904001, 3199.1362332170183, 15467.0, 16571.66856662872, 16619.584510387336, 16661.583460413585, 17408.477745667522, 2126.893655317228, 2184.912603495874, 2194.7037149984867, 2215.667649852519, 2240.8543444676043, 100263.0, 123427.0, 123569.0, 124308.37845810804, 125068.37465812704, 17204.31182752676, 17789.288428462976, 18227.63544729098, 18393.264269429073, 18642.7628684556, 31617.841910790758, 31672.366552668816, 31897.52153717696, 31925.52111718326, 32054.519182212287, 10104.494775261355, 10121.746956326004, 10137.0, 10174.949125254327, 10454.63408780689, 8453.746387608382, 8508.0, 8558.572071396406, 8576.656933722581, 8669.783255418664, 9049.592768325441, 9121.0, 9258.120478554662, 9328.533573321307, 9459.52702364879, 3836.5779764225913, 3930.8820735377412, 3935.193285376491, 3980.3233450313514, 4036.4348991141264, 1184.6682928779956, 1192.6183621241294, 1214.5384753793503, 1216.501234493871, 1224.9448774805155, 20038.699419508717, 20205.39383818488, 20310.796892031187, 20865.791342086384, 20888.791112088686, 1101.1191047162238, 1144.8797876222877, 1146.0774076868036, 1175.2302242031483, 1217.6317063311774, 11155.832662510069, 11315.264507806965, 11363.261388009752, 11455.541778328774, 11519.539218431337, 636688.0, 661607.0, 687061.0, 697841.0, 725108.0, 91822.54088729648, 121915.0, 122622.0, 123724.38137809253, 126376.0, 1326.721388508424, 1360.9115407498482, 1378.23507953087, 1382.1706975814432, 1393.4426229508279, 17910.28358865683, 18066.277348906424, 18231.0, 18365.3572124975, 18579.535511612317, 37616.62383376131, 49521.00957980748, 49617.751911240215, 50727.49272507375, 51499.0, 69581.0, 71081.0, 73650.63174684093, 76135.61932190304, 77418.6129069351, 12150.392480376122, 12689.0, 12692.555760548337, 12990.935045324713, 13014.80477792815, 13884.236366999508, 13920.791188131983, 14293.499727509487, 14893.627659308171, 14904.850951490347, 12437.875621244033, 12674.746505069663, 12961.675958100746, 13039.804402933774, 13053.869461305645, 9821.607135714492, 11947.82078268844, 12170.878291216974, 12343.382830858602, 13037.739245215233, 13303.201807891557, 13331.066825322134, 13556.93221533886, 13679.794803077762, 13758.518451854137, 12868.356582171042, 13040.347982600642, 13110.606681799562, 13156.210627362389, 13183.802242966554, 12588.622341329774, 12889.613311600668, 13194.208347499181, 13260.138091024053, 13779.379927903468], "value": 11719.137472092316, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1557.1824791984207, 1558.2131023832962, 1579.8025246844145, 1588.0551072112096, 1603.2464741571457, 9484.430934143957, 9705.757356066102, 10799.974002469762, 11584.710382240448, 11616.767664646703, 10684.572617095308, 11021.889781102187, 11335.036521895629, 11381.715457113576, 11389.886101138985, 2461.9138330158494, 2506.1980166346775, 2508.9372765680914, 2509.836860604055, 2515.434027343851, 2340.496793189464, 2398.3524448399007, 2402.2432933625937, 2408.6868707068056, 2413.3363325085566, 2313.571989181998, 2352.0121548949483, 2352.647102934565, 2364.5034542746043, 2369.644553316999, 2244.5510897820336, 2279.908803647852, 2291.129370839079, 2294.300238427279, 2295.644175152854, 2249.8425110242288, 2252.2004688335733, 2268.5236100418933, 2291.8968646410954, 2314.525522267931, 11231.157663175294, 11291.0, 11327.886721132765, 11361.261518001385, 11392.088632909348, 5780.566457515703, 5854.297484301868, 5929.525637948954, 5965.343812180657, 5983.072623743326, 3610.277944411102, 3629.1834337274167, 3642.3990041643096, 3694.5935947045805, 3789.2989796887664, 1332.5202926946292, 1371.2595198592749, 1373.2858913365094, 1379.9586012419643, 1417.8227721534809, 16182.271797769128, 16285.755713664186, 16482.175891205392, 16596.668066638722, 16797.160141992972, 4757.5718185363485, 4794.993051459163, 4818.349522814376, 4849.127157111754, 4936.3829521309835, 13734.587962361145, 13813.102148360324, 13846.169229846242, 13862.445502180002, 13900.652483687989, 5942.940570594239, 5967.76128954837, 5989.850253743692, 5992.580519363602, 6009.909851352233, 4558.088382323548, 5285.735713214324, 5297.258383826267, 5393.460653934575, 5518.420565840609, 4162.229987452331, 4202.117555313357, 4255.468066491689, 4281.400603915455, 4304.935425968613, 1132.2131118872305, 1133.1274918313018, 1133.4729350851894, 1140.937248451343, 1147.6040765935738, 1100.2518287564374, 1110.5946329589804, 1120.4453795371344, 1122.2817396866012, 1145.3872178384524, 7260.165081015751, 7292.489525733254, 7302.744403945836, 7323.633818309063, 7427.85144297111, 1035.9896401036044, 1054.852320675106, 1065.9520321585546, 1074.1031238915593, 1081.5457507846722, 4628.352030715702, 4700.059988002345, 4716.787744551503, 4762.285657151386, 4792.424909010941, 14154.504592339219, 14414.207218602867, 14447.205403702897, 14574.489892853911, 14580.781288280685, 13225.603231903058, 13690.726185476435, 14080.859191408159, 14154.150750954976, 14435.133891966516, 10594.470276486145, 10662.466876656292, 10733.141348692243, 10793.514291856885, 10795.244332896778, 7346.228645992308, 7544.094708634999, 7591.278828511283, 7699.0, 7778.338841198335, 12820.294883781482, 12930.482780688668, 12958.963282937531, 13159.0, 13185.208887466399, 13058.673533161747, 13163.60509184726, 13334.33328333599, 13438.059335846398, 13442.663933401353, 8426.536540490331, 8598.183172598594, 9620.230381569598, 9800.11798938099, 10017.949910250694, 10882.29265097766, 10926.72683182895, 10962.671119866416, 10971.725706857073, 11128.332300062022, 9503.524823758646, 9531.57107930131, 9568.425894446076, 9643.517824108907, 9714.174295184706, 9262.39794413361, 9321.906780932104, 9342.112499312554, 9500.0, 9623.518824058909, 8248.257656810916, 8476.533790641573, 8478.703245386629, 8509.87235191485, 8518.531480768617, 6401.263854656774, 6502.219733631994, 6516.73933042673, 6537.803865884031, 6539.0, 6340.809775706552, 6428.839279018062, 6442.291347951628, 6653.0, 6675.766348177985], "value": 5434.60904691424, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.45075521158407, 405.4992084775303, 416.7228792852753, 420.2058110171774, 420.5353084841252, 5510.614257002011, 5527.894421115773, 5533.063427800268, 5537.640053396526, 5554.527865131468, 4935.8272460463895, 5354.544863686591, 5387.730613469331, 5401.837944861651, 5446.782128714856, 4580.908381832362, 4591.51789062147, 4601.895545069193, 4628.305754136873, 4634.976825115887, 4574.911934444253, 4621.273448665287, 4627.583517483426, 4628.236341003747, 4652.255639097745, 4229.7885105744745, 4372.825086996516, 4484.1928452878465, 4494.820207191725, 4610.20202121173, 4444.31113177458, 4446.844360447369, 4454.97772511137, 4489.977550112261, 4497.0, 4225.338037036678, 4235.301175306071, 4272.5086615039045, 4295.548967358445, 4318.848840290575, 5279.736013199325, 5337.583946274388, 5342.732863356817, 5344.85085706575, 5453.481919217668, 5262.210848311593, 5277.525022747933, 5307.416184219733, 5315.574754019669, 5338.733063346856, 5643.379228284885, 5687.203791469197, 5728.281515545362, 5732.5987180897355, 5747.3677895431465, 4571.748553829538, 4669.929951050737, 4680.882977925545, 4691.530846915315, 4700.905981880377, 5753.539716822645, 5893.381194974509, 5905.675187864708, 5940.702964851784, 5956.51087228193, 4854.757262136879, 4867.367242258484, 4873.148203682649, 4887.413510378707, 4898.53463920927, 4980.8620569042, 4986.825461108916, 4996.375453068366, 5007.048660754518, 5007.624428167902, 5085.431856912236, 5214.217867319932, 5273.551748101454, 5357.785688572491, 5370.543503802221, 5646.6894320811925, 5655.624637652196, 5661.264035675335, 5664.0, 5684.715764211773, 1703.2250326101594, 1717.2959086774613, 1721.397510871185, 1728.4037007232484, 1732.2724455728624, 1268.1566758105757, 1326.1181314425799, 1327.7097007661707, 1344.3211178354868, 1345.2937207965897, 5597.720113994284, 5674.829755107354, 5677.574181936288, 5708.572856785803, 5726.226959360516, 5792.729816484163, 5842.056611787027, 5866.562692140453, 5905.763769449174, 5927.288725352985, 5454.0, 5589.608727389043, 5600.551955843523, 5622.526088521037, 5667.376588575254, 3081.4145312390574, 3093.876244950222, 3108.891188808381, 3109.2382366319944, 3116.7065667748084, 5784.091249887527, 5991.131285963511, 6031.984007996002, 6070.939290607037, 6076.210092687922, 5898.557608179403, 5941.376155503696, 5959.927213101683, 5960.552958528127, 6024.608400453956, 5904.468597826216, 6016.69916504173, 6039.607425517327, 6063.848403789941, 6133.29467111279, 5804.0, 5823.79616713413, 5879.147523609138, 5911.527077833849, 5915.91126133117, 5608.242887210175, 5863.20846685692, 5895.498882595028, 5899.764009439575, 5903.527717782653, 5975.551833612583, 6021.578489505692, 6039.245094363205, 6058.758564168149, 6098.695065246809, 4855.854324370274, 4860.274602516056, 4889.951100488949, 4893.877653058702, 4917.311576379238, 5738.507987923086, 5817.447342502455, 5877.206577112206, 5877.970610147093, 5906.822795316148, 5717.028105222205, 5721.595650587894, 5728.312602487728, 5737.56968227393, 5759.51044161251, 5511.366192887869, 5744.736158045223, 5748.930385061458, 5784.797532086357, 5785.218995435731, 5664.433556644467, 5724.223751480612, 5726.455986681259, 5776.855578610568, 5812.3025236971835, 5746.56335916021, 5760.222369980167, 5775.036487594238, 5784.479396854135, 5818.0, 5467.644603100945, 5543.0, 5546.667199968015, 5572.219889215594, 5675.77296908119], "value": 4597.786265653066, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [844.0125053687185, 872.7469033980146, 923.6628630549848, 923.9630414783409, 924.6994726713815, 16992.91503542482, 17677.911610441963, 17703.203355848986, 17918.641627167483, 18097.36659216926, 16763.83236167638, 17022.148892555386, 17032.318707251696, 17209.91395043023, 17223.569410764736, 845.4124383553433, 856.8543347630903, 880.6477409036372, 883.7834730491006, 886.7960369115108, 862.3704695572236, 877.6533269358599, 877.8244351129767, 879.6041781198454, 881.0, 923.6536298887917, 927.2350310993423, 929.1126973740096, 929.525941769699, 933.785229397239, 917.1745429113818, 926.6710317837205, 929.0152438415302, 929.5677509957868, 929.9628014879397, 682.0791930893304, 682.3313153109931, 682.443808296242, 685.6880119545594, 688.8484533402643, 17409.47771566855, 17649.38227162056, 17964.101794910333, 18044.54888627782, 18387.540311492186, 15850.603734906605, 16206.83793162065, 16213.756793648105, 16450.34198632065, 16659.0, 13517.0, 13921.860781392157, 13985.860141398558, 14052.297385130703, 14123.0, 4043.6765058795227, 4052.068024354415, 4062.9390559141343, 4104.261232978063, 4179.310413781723, 78306.21693783047, 81900.9999999994, 82523.17476825275, 84102.57948710279, 91292.0, 13080.476780928846, 13202.669933251746, 13249.271290078945, 13455.663608409672, 13478.52825151115, 23889.402764931016, 23917.880410597834, 24028.27915162548, 24158.0, 24274.87862560711, 9012.41419307743, 9079.319051071196, 9212.12484813955, 9302.95348523253, 9368.016358282419, 5124.871878203, 5239.083160446907, 5261.605379596545, 5265.894682106336, 5421.620486565903, 3653.4702468141973, 3750.718696097804, 3781.621837816196, 3784.1863999240295, 3955.3869150281753, 748.6107224243472, 751.9097708275042, 754.8188434775615, 763.050002746584, 764.4266799900075, 802.9839403211903, 830.8338332333558, 833.2708879730236, 838.7567605394368, 841.7138173021202, 10929.0, 11534.480948357177, 11664.591739289084, 12170.634880953585, 12532.686682833002, 818.6914778963227, 823.283743143469, 830.9667613295521, 843.8944982073381, 845.6490556419017, 5297.339358932155, 5306.310179676694, 5688.601797874192, 5757.317504697284, 5853.648781073064, 80635.0, 81108.0, 81120.0, 83326.58336708277, 85664.0, 71481.6425917867, 71984.0, 72967.27032729711, 72986.0, 73019.26980730124, 54699.0, 57440.13839792406, 61838.0724289127, 64157.358426416075, 64564.35435645677, 10830.18773591968, 11044.981457111915, 11307.434628268718, 11318.72563516586, 11335.319880807177, 38204.42693359546, 40545.594544054184, 41208.0, 41892.79053604713, 42934.35598465963, 47466.76266618783, 48890.266645999625, 49491.75254123707, 50159.74920125376, 50775.0, 14341.0, 14423.891063761765, 14476.927615361856, 14510.419583216553, 14639.853601463848, 16012.599685007968, 16034.67930641404, 16171.191440427696, 16508.17459127063, 17220.913895430444, 15297.694046118793, 15649.217539123227, 15700.0, 15764.684706305581, 16293.26278765035, 14179.716405671623, 14618.28225778493, 14621.707565848836, 15120.0, 15307.311170997109, 14936.775948361, 15577.442255774786, 16049.679006419572, 16166.0, 16256.837431625532, 12242.693932651755, 12330.876691232972, 12507.812382814447, 12958.935205323913, 13325.40272614622, 13484.393202306115, 13511.662208444868, 13526.120802147829, 13564.440089389453, 13735.656608584864], "value": 8055.923127430604, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1488.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 116.0, 115.0, 113.0, 115.0, 113.0, 119.0, 113.0, 115.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 119.0, 120.0, 162.0, 119.0, 118.0, 118.0, 117.0, 147.0, 118.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 171.0, 163.0, 164.0, 163.0, 168.0, 163.0, 164.0, 163.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 119.0, 79.0, 77.0, 76.0, 75.0, 99.0, 77.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 100.0, 82.0, 81.0, 81.0, 101.0, 82.0, 86.0, 106.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 113.0, 113.0, 119.0, 113.0, 114.0, 114.0, 113.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 150.0, 148.0, 148.0, 148.0, 148.0, 148.0, 148.0, 149.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 67.0, 61.0, 62.0, 62.0, 62.0, 64.0, 61.0, 62.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 55.0, 54.0, 54.0, 54.0, 55.0, 55.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 78.0, 80.0, 86.0, 75.0, 79.0, 80.0, 75.0, 76.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 127.0, 143.0, 137.0, 125.0, 128.0, 121.0, 123.0, 168.0, 127.0], "value": 132.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 169.0, 162.0, 162.0, 163.0, 169.0, 163.0, 161.0, 159.0, 159.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 60.0, 66.0, 66.0, 59.0, 62.0, 59.0, 61.0, 61.0], "value": 61.8, "unit": "score"}]}]} 06:14:45 INFO - Return code: 0 06:14:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:14:45 ERROR - # TBPL SUCCESS # 06:14:45 INFO - Running post-action listener: _resource_record_post_action 06:14:45 INFO - [mozharness: 2017-03-20 13:14:45.272950Z] Finished run-tests step (success) 06:14:45 INFO - Running post-run listener: _resource_record_post_run 06:14:45 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 266240; Write bytes: 366718976; Read time: 36; Write time: 263836 06:14:45 INFO - TinderboxPrint: CPU usage
13.5% 06:14:45 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 36 06:14:45 INFO - TinderboxPrint: I/O write bytes / time
366,718,976 / 263,836 06:14:45 INFO - TinderboxPrint: CPU idle
8,223.4 (86.5%) 06:14:45 INFO - TinderboxPrint: CPU user
1,221.9 (12.9%) 06:14:45 INFO - TinderboxPrint: Swap in / out
0 / 0 06:14:45 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110231552; Read time: 0; Write time: 105580 06:14:45 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 266240; Write bytes: 256487424; Read time: 36; Write time: 158256 06:14:45 INFO - Running post-run listener: _upload_blobber_files 06:14:45 INFO - Blob upload gear active. 06:14:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:14:45 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 06:14:45 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:14:45 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:14:46 INFO - (blobuploader) - INFO - Open directory for files ... 06:14:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:14:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:14:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:46 INFO - (blobuploader) - INFO - Done attempting. 06:14:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:14:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:14:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:47 INFO - (blobuploader) - INFO - Done attempting. 06:14:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:14:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:14:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:49 INFO - (blobuploader) - INFO - Done attempting. 06:14:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:14:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:14:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:14:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:14:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:14:52 INFO - (blobuploader) - INFO - Done attempting. 06:14:52 INFO - (blobuploader) - INFO - Iteration through files over. 06:14:52 INFO - Return code: 0 06:14:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:14:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:14:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0329795b28419d0924b7902d9892b9a4529de857a5f298efbdbf8ef700ebd740ef487432880cbb4fffdc757942804a22989172d2a88ce9f974826f900c425c4f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c7e0da638c4f7d4159ebd6e3c2621e942d6909de78c02a50f2d4f516401434293657d7fed633cc1a71633ea77d1e7499da5ef551dc93dfc0ce8839f10dcabe4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bd4304602bde2b3904ecdce669f4b2980e07f18b9600e1e0a4fb0024a1d5811abd645e9f8ddabe58f5dc4132e2349c817619e049ba4b414e09a2eda06b83a0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/375af5a8f42c95269152acc7f86743efe2d9b91e66d3b7e2366611765c871e535ea7996bb91c62a53774db6c4e39cfb6805d3de625556b19abb0b8caf22b9ca6"} 06:14:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:14:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:14:52 INFO - Contents: 06:14:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0329795b28419d0924b7902d9892b9a4529de857a5f298efbdbf8ef700ebd740ef487432880cbb4fffdc757942804a22989172d2a88ce9f974826f900c425c4f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c7e0da638c4f7d4159ebd6e3c2621e942d6909de78c02a50f2d4f516401434293657d7fed633cc1a71633ea77d1e7499da5ef551dc93dfc0ce8839f10dcabe4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bd4304602bde2b3904ecdce669f4b2980e07f18b9600e1e0a4fb0024a1d5811abd645e9f8ddabe58f5dc4132e2349c817619e049ba4b414e09a2eda06b83a0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/375af5a8f42c95269152acc7f86743efe2d9b91e66d3b7e2366611765c871e535ea7996bb91c62a53774db6c4e39cfb6805d3de625556b19abb0b8caf22b9ca6"} 06:14:52 INFO - Running post-run listener: copy_logs_to_upload_dir 06:14:52 INFO - Copying logs to upload dir... 06:14:52 INFO - mkdir: /builds/slave/test/build/upload/logs 06:14:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.513485 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-03-20 06:14:50.335572) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 06:14:50.338664) ========= 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=523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0329795b28419d0924b7902d9892b9a4529de857a5f298efbdbf8ef700ebd740ef487432880cbb4fffdc757942804a22989172d2a88ce9f974826f900c425c4f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c7e0da638c4f7d4159ebd6e3c2621e942d6909de78c02a50f2d4f516401434293657d7fed633cc1a71633ea77d1e7499da5ef551dc93dfc0ce8839f10dcabe4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bd4304602bde2b3904ecdce669f4b2980e07f18b9600e1e0a4fb0024a1d5811abd645e9f8ddabe58f5dc4132e2349c817619e049ba4b414e09a2eda06b83a0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/375af5a8f42c95269152acc7f86743efe2d9b91e66d3b7e2366611765c871e535ea7996bb91c62a53774db6c4e39cfb6805d3de625556b19abb0b8caf22b9ca6"} build_url:https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011342 build_url: 'https://queue.taskcluster.net/v1/task/Kd9sa4hAR8aIRawEfiPrYQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0329795b28419d0924b7902d9892b9a4529de857a5f298efbdbf8ef700ebd740ef487432880cbb4fffdc757942804a22989172d2a88ce9f974826f900c425c4f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c7e0da638c4f7d4159ebd6e3c2621e942d6909de78c02a50f2d4f516401434293657d7fed633cc1a71633ea77d1e7499da5ef551dc93dfc0ce8839f10dcabe4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bd4304602bde2b3904ecdce669f4b2980e07f18b9600e1e0a4fb0024a1d5811abd645e9f8ddabe58f5dc4132e2349c817619e049ba4b414e09a2eda06b83a0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/375af5a8f42c95269152acc7f86743efe2d9b91e66d3b7e2366611765c871e535ea7996bb91c62a53774db6c4e39cfb6805d3de625556b19abb0b8caf22b9ca6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 06:14:50.363259) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 06:14:50.363573) ========= 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=523ca41ca1e0623de333ad52000001bf-1490014442.14304-1909950063 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004603 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 06:14:50.448106) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 06:14:50.448423) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 06:14:50.448761) ========= ========= Total master_lag: 0.16 =========